sql returns fail for some stop requests

Amir Tal amir at ccc.co.il
Thu Jul 19 10:59:13 CEST 2012


I have increased the value of MAX_QUERY_LEN in freeradius-server-2.1.12/src/modules/rlm_sql/conf.h
(#define MAX_QUERY_LEN                   8192)

There are still duplicated connections/sessions , example sqltrace:
INSERT INTO radacct (acctsessionid,    acctuniqueid,     username,  realm,            nasipaddress,     nasportid,  nasporttype,      acctstarttime,    acctstoptime,  acctsessiontime,  acctauthentic,    connectinfo_start,  connectinfo_stop, acctinputoctets,  acctoutputoctets,  calledstationid,  callingstationid, acctterminatecause,  servicetype,      framedprotocol,   framedipaddress,  acctstartdelay,   acctstopdelay,    xascendsessionsvrkey) VALUES  ('erx ip:109.226.0.17:147.235.134.47:ef47:632c:606b:498b:4fdbe98:0050515007', 'e2faac6ede73a716',  '37444332',  'ccc', '109.226.1.20', '23437230',  'Virtual', '2012-07-19 01:54:07', NULL,  '0', 'RADIUS', '',  '', '0', '0',  '', '47 5/1 vlan-id 1421:32', '',  'Framed-User', 'PPP', '109.226.50.244',  '0', '0', '');
 UPDATE radacct SET  acctstoptime       = '2012-07-19 01:54:55',  acctsessiontime    = '48',  acctinputoctets    = '0' << 32 | '60',  acctoutputoctets   = '0' << 32 | '60',  acctterminatecause = 'NAS-Request',  acctstopdelay      = '0',  connectinfo_stop   = '' WHERE acctsessionid   = 'erx ip:109.226.0.17:147.235.134.47:ef47:632c:606b:498b:4fdbe98:0050515007' AND username          = '37444332' AND nasipaddress      = '109.226.1.20';
 INSERT INTO radacct (acctsessionid,    acctuniqueid,     username,  realm,            nasipaddress,     nasportid,  nasporttype,      acctstarttime,    acctstoptime,  acctsessiontime,  acctauthentic,    connectinfo_start,  connectinfo_stop, acctinputoctets,  acctoutputoctets,  calledstationid,  callingstationid, acctterminatecause,  servicetype,      framedprotocol,   framedipaddress,  acctstartdelay,   acctstopdelay,    xascendsessionsvrkey) VALUES  ('erx ip:109.226.0.17:147.235.134.47:ef47:632c:35a4:4aaa:4fdc13c:0050515459', 'ec2da39d0766abef',  '37444332',  'ccc', '109.226.1.20', '23438030',  'Virtual', '2012-07-19 01:56:02', NULL,  '0', 'RADIUS', '',  '', '0', '0',  '', '47 5/1 vlan-id 1421:32', '',  'Framed-User', 'PPP', '109.226.50.185',  '0', '0', '');
 UPDATE radacct SET  acctstoptime       = '2012-07-19 01:56:52',  acctsessiontime    = '50',  acctinputoctets    = '0' << 32 | '60',  acctoutputoctets   = '0' << 32 | '510',  acctterminatecause = 'NAS-Request',  acctstopdelay      = '0',  connectinfo_stop   = '' WHERE acctsessionid   = 'erx ip:109.226.0.17:147.235.134.47:ef47:632c:35a4:4aaa:4fdc13c:0050515459' AND username          = '37444332' AND nasipaddress      = '109.226.1.20';
 INSERT INTO radacct (acctsessionid,    acctuniqueid,     username,  realm,            nasipaddress,     nasportid,  nasporttype,      acctstarttime,    acctstoptime,  acctsessiontime,  acctauthentic,    connectinfo_start,  connectinfo_stop, acctinputoctets,  acctoutputoctets,  calledstationid,  callingstationid, acctterminatecause,  servicetype,      framedprotocol,   framedipaddress,  acctstartdelay,   acctstopdelay,    xascendsessionsvrkey) VALUES  ('erx ip:109.226.0.17:147.235.134.47:ef47:632c:cfbc:4aff:4fdc2b2:0050515722', 'bfede13c66b4f238',  '37444332',  'ccc', '109.226.1.20', '23438472',  'Virtual', '2012-07-19 01:57:05', NULL,  '0', 'RADIUS', '',  '', '0', '0',  '', '47 5/1 vlan-id 1421:32', '',  'Framed-User', 'PPP', '37.19.123.248',  '0', '0', '');
 UPDATE radacct SET  acctstoptime       = '2012-07-19 01:57:54',  acctsessiontime    = '49',  acctinputoctets    = '0' << 32 | '60',  acctoutputoctets   = '0' << 32 | '8366',  acctterminatecause = 'NAS-Request',  acctstopdelay      = '0',  connectinfo_stop   = '' WHERE acctsessionid   = 'erx ip:109.226.0.17:147.235.134.47:ef47:632c:cfbc:4aff:4fdc2b2:0050515722' AND username          = '37444332' AND nasipaddress      = '109.226.1.20';
 INSERT INTO radacct (acctsessionid,    acctuniqueid,     username,  realm,            nasipaddress,     nasportid,  nasporttype,      acctstarttime,    acctstoptime,  acctsessiontime,  acctauthentic,    connectinfo_start,  connectinfo_stop, acctinputoctets,  acctoutputoctets,  calledstationid,  callingstationid, acctterminatecause,  servicetype,      framedprotocol,   framedipaddress,  acctstartdelay,   acctstopdelay,    xascendsessionsvrkey) VALUES  ('erx ip:109.226.0.17:147.235.134.47:ef47:632c:3374:4b57:4fdc420:0050515966', '457b5c3e9fc92416',  '37444332',  'ccc', '109.226.1.20', '23438894',  'Virtual', '2012-07-19 01:58:07', NULL,  '0', 'RADIUS', '',  '', '0', '0',  '', '47 5/1 vlan-id 1421:32', '',  'Framed-User', 'PPP', '109.226.50.123',  '0', '0', '');
 UPDATE radacct SET  acctstoptime       = '2012-07-19 01:58:55',  acctsessiontime    = '48',  acctinputoctets    = '0' << 32 | '60',  acctoutputoctets   = '0' << 32 | '60',  acctterminatecause = 'NAS-Request',  acctstopdelay      = '0',  connectinfo_stop   = '' WHERE acctsessionid   = 'erx ip:109.226.0.17:147.235.134.47:ef47:632c:3374:4b57:4fdc420:0050515966' AND username          = '37444332' AND nasipaddress      = '109.226.1.20';
 INSERT INTO radacct (acctsessionid,    acctuniqueid,     username,  realm,            nasipaddress,     nasportid,  nasporttype,      acctstarttime,    acctstoptime,  acctsessiontime,  acctauthentic,    connectinfo_start,  connectinfo_stop, acctinputoctets,  acctoutputoctets,  calledstationid,  callingstationid, acctterminatecause,  servicetype,      framedprotocol,   framedipaddress,  acctstartdelay,   acctstopdelay,    xascendsessionsvrkey) VALUES  ('erx ip:109.226.0.17:147.235.134.47:ef47:632c:a3cc:4c38:4fdc6dc:0050516423', '8a877ece67a03d30',  '37444332',  'ccc', '109.226.1.20', '23439680',  'Virtual', '2012-07-19 02:00:02', NULL,  '0', 'RADIUS', '',  '', '0', '0',  '', '47 5/1 vlan-id 1421:32', '',  'Framed-User', 'PPP', '37.19.123.3',  '0', '0', '');

UPDATE radacct SET  acctstoptime       = '2012-07-19 02:00:51',  acctsessiontime    = '50',  acctinputoctets    = '0' << 32 | '884',  acctoutputoctets   = '0' << 32 | '2533',  acctterminatecause = 'NAS-Request',  acctstopdelay      = '0',  connectinfo_stop   = '' WHERE acctsessionid   = 'erx ip:109.226.0.17:147.235.134.47:ef47:632c:a3cc:4c38:4fdc6dc:0050516423' AND username          = '37444332' AND nasipaddress      = '109.226.1.20';
 INSERT INTO radacct (acctsessionid,    acctuniqueid,     username,  realm,            nasipaddress,     nasportid,  nasporttype,      acctstarttime,    acctstoptime,  acctsessiontime,  acctauthentic,    connectinfo_start,  connectinfo_stop, acctinputoctets,  acctoutputoctets,  calledstationid,  callingstationid, acctterminatecause,  servicetype,      framedprotocol,   framedipaddress,  acctstartdelay,   acctstopdelay,    xascendsessionsvrkey) VALUES  ('erx ip:109.226.0.17:147.235.134.47:ef47:632c:5906:4cba:4fdc862:0050516674', '33c5a9b6537fe1a7',  '37444332',  'ccc', '109.226.1.20', '23440112',  'Virtual', '2012-07-19 02:01:05', NULL,  '0', 'RADIUS', '',  '', '0', '0',  '', '47 5/1 vlan-id 1421:32', '',  'Framed-User', 'PPP', '37.19.123.60',  '0', '0', '');
 UPDATE radacct SET  acctstoptime       = '2012-07-19 02:01:53',  acctsessiontime    = '49',  acctinputoctets    = '0' << 32 | '60',  acctoutputoctets   = '0' << 32 | '60',  acctterminatecause = 'NAS-Request',  acctstopdelay      = '0',  connectinfo_stop   = '' WHERE acctsessionid   = 'erx ip:109.226.0.17:147.235.134.47:ef47:632c:5906:4cba:4fdc862:0050516674' AND username          = '37444332' AND nasipaddress      = '109.226.1.20';
 INSERT INTO radacct (acctsessionid,    acctuniqueid,     username,  realm,            nasipaddress,     nasportid,  nasporttype,      acctstarttime,    acctstoptime,  acctsessiontime,  acctauthentic,    connectinfo_start,  connectinfo_stop, acctinputoctets,  acctoutputoctets,  calledstationid,  callingstationid, acctterminatecause,  servicetype,      framedprotocol,   framedipaddress,  acctstartdelay,   acctstopdelay,    xascendsessionsvrkey) VALUES  ('erx ip:109.226.0.17:147.235.134.47:ef47:632c:8b2c:4d2b:4fdc9cf:0050516921', '030f231a545e7ca8',  '37444332',  'ccc', '109.226.1.20', '23440548',  'Virtual', '2012-07-19 02:02:07', NULL,  '0', 'RADIUS', '',  '', '0', '0',  '', '47 5/1 vlan-id 1421:32', '',  'Framed-User', 'PPP', '109.226.50.106',  '0', '0', '');
 INSERT INTO radacct (acctsessionid,    acctuniqueid,     username,  realm,            nasipaddress,     nasportid,  nasporttype,      acctstarttime,    acctstoptime,  acctsessiontime,  acctauthentic,    connectinfo_start,  connectinfo_stop, acctinputoctets,  acctoutputoctets,  calledstationid,  callingstationid, acctterminatecause,  servicetype,      framedprotocol,   framedipaddress,  acctstartdelay,   acctstopdelay,    xascendsessionsvrkey) VALUES  ('erx ip:109.226.0.17:147.235.134.47:ef47:632c:8b2c:4d2b:4fdc9cf:0050516921', '030f231a545e7ca8',  '37444332',  'ccc', '109.226.1.20', '23440548',  'Virtual', '2012-07-19 02:02:10', NULL,  '0', 'RADIUS', '',  '', '0', '0',  '', '47 5/1 vlan-id 1421:32', '',  'Framed-User', 'PPP', '109.226.50.106',  '3', '0', '');
 INSERT INTO radacct (acctsessionid,    acctuniqueid,     username,  realm,            nasipaddress,     nasportid,  nasporttype,      acctstarttime,    acctstoptime,  acctsessiontime,  acctauthentic,    connectinfo_start,  connectinfo_stop, acctinputoctets,  acctoutputoctets,  calledstationid,  callingstationid, acctterminatecause,  servicetype,      framedprotocol,   framedipaddress,  acctstartdelay,   acctstopdelay,    xascendsessionsvrkey) VALUES  ('erx ip:109.226.0.17:147.235.134.47:ef47:632c:8b2c:4d2b:4fdc9cf:0050516921', '030f231a545e7ca8',  '37444332',  'ccc', '109.226.1.20', '23440548',  'Virtual', '2012-07-19 02:02:13', NULL,  '0', 'RADIUS', '',  '', '0', '0',  '', '47 5/1 vlan-id 1421:32', '',  'Framed-User', 'PPP', '109.226.50.106',  '6', '0', '');
 INSERT INTO radacct (acctsessionid,    acctuniqueid,     username,  realm,            nasipaddress,     nasportid,  nasporttype,      acctstarttime,    acctstoptime,  acctsessiontime,  acctauthentic,    connectinfo_start,  connectinfo_stop, acctinputoctets,  acctoutputoctets,  calledstationid,  callingstationid, acctterminatecause,  servicetype,      framedprotocol,   framedipaddress,  acctstartdelay,   acctstopdelay,    xascendsessionsvrkey) VALUES  ('erx ip:109.226.0.17:147.235.134.47:ef47:632c:8b2c:4d2b:4fdc9cf:0050516921', '030f231a545e7ca8',  '37444332',  'ccc', '109.226.1.20', '23440548',  'Virtual', '2012-07-19 02:02:16', NULL,  '0', 'RADIUS', '',  '', '0', '0',  '', '47 5/1 vlan-id 1421:32', '',  'Framed-User', 'PPP', '109.226.50.106',  '9', '0', '');
 UPDATE radacct SET  acctstoptime       = '2012-07-19 02:05:20',  acctsessiontime    = '0',  acctinputoctets    = '0' << 32 | '0',  acctoutputoctets   = '0' << 32 | '0',  acctterminatecause = 'User-Error',  acctstopdelay      = '9',  connectinfo_stop   = '' WHERE acctsessionid   = 'erx ip:109.226.0.17:147.235.134.47:ef47:632c:631f:4e86:4fdcdb0:0050517366' AND username          = '37444332' AND nasipaddress      = '109.226.1.20';
 UPDATE radacct SET  acctstoptime       = '2012-07-19 02:06:30',  acctsessiontime    = '0',  acctinputoctets    = '0' << 32 | '0',  acctoutputoctets   = '0' << 32 | '0',  acctterminatecause = 'User-Error',  acctstopdelay      = '0',  connectinfo_stop   = '' WHERE acctsessionid   = 'erx ip:109.226.0.17:147.235.134.47:ef47:632c:6cdc:4f2e:4fdcf3a:0050517571' AND username          = '37444332' AND nasipaddress      = '109.226.1.20';
 UPDATE radacct SET  acctstoptime       = '2012-07-19 02:09:27',  acctsessiontime    = '0',  acctinputoctets    = '0' << 32 | '0',  acctoutputoctets   = '0' << 32 | '0',  acctterminatecause = 'User-Error',  acctstopdelay      = '0',  connectinfo_stop   = '' WHERE acctsessionid   = 'erx ip:109.226.0.17:147.235.134.47:ef47:632c:54bf:50a1:4fdd34a:0050518062' AND username          = '37444332' AND nasipaddress      = '109.226.1.20';
 UPDATE radacct SET  acctstoptime       = '2012-07-19 02:09:34',  acctsessiontime    = '0',  acctinputoctets    = '0' << 32 | '0',  acctoutputoctets   = '0' << 32 | '0',  acctterminatecause = 'User-Error',  acctstopdelay      = '3',  connectinfo_stop   = '' WHERE acctsessionid   = 'erx ip:109.226.0.17:147.235.134.47:ef47:632c:54bf:50a1:4fdd34a:0050518062' AND username          = '37444332' AND nasipaddress      = '109.226.1.20';
 UPDATE radacct SET  acctstoptime       = '2012-07-19 02:09:39',  acctsessiontime    = '0',  acctinputoctets    = '0' << 32 | '0',  acctoutputoctets   = '0' << 32 | '0',  acctterminatecause = 'User-Error',  acctstopdelay      = '6',  connectinfo_stop   = '' WHERE acctsessionid   = 'erx ip:109.226.0.17:147.235.134.47:ef47:632c:54bf:50a1:4fdd34a:0050518062' AND username          = '37444332' AND nasipaddress      = '109.226.1.20';
 UPDATE radacct SET  acctstoptime       = '2012-07-19 02:09:42',  acctsessiontime    = '0',  acctinputoctets    = '0' << 32 | '0',  acctoutputoctets   = '0' << 32 | '0',  acctterminatecause = 'User-Error',  acctstopdelay      = '9',  connectinfo_stop   = '' WHERE acctsessionid   = 'erx ip:109.226.0.17:147.235.134.47:ef47:632c:54bf:50a1:4fdd34a:0050518062' AND username          = '37444332' AND nasipaddress      = '109.226.1.20';
 INSERT INTO radacct (acctsessionid,    acctuniqueid,     username,  realm,            nasipaddress,     nasportid,  nasporttype,      acctstarttime,    acctstoptime,  acctsessiontime,  acctauthentic,    connectinfo_start,  connectinfo_stop, acctinputoctets,  acctoutputoctets,  calledstationid,  callingstationid, acctterminatecause,  servicetype,      framedprotocol,   framedipaddress,  acctstartdelay,   acctstopdelay,    xascendsessionsvrkey) VALUES  ('erx ip:109.226.0.17:147.235.134.47:ef47:632c:16c2:5208:4fdd690:0050518554', 'b7a5fb189479f0cb',  '37444332',  'ccc', '109.226.1.20', '23443434',  'Virtual', '2012-07-19 02:11:08', NULL,  '0', 'RADIUS', '',  '', '0', '0',  '', '47 5/1 vlan-id 1421:32', '',  'Framed-User', 'PPP', '109.226.50.139',  '0', '0', '');
 UPDATE radacct SET  acctstoptime       = '2012-07-19 02:11:10',  acctsessiontime    = '0',  acctinputoctets    = '0' << 32 | '0',  acctoutputoctets   = '0' << 32 | '0',  acctterminatecause = 'NAS-Request',  acctstopdelay      = '0',  connectinfo_stop   = '' WHERE acctsessionid   = 'erx ip:109.226.0.17:147.235.134.47:ef47:632c:608c:513e:4fdd4b8:0050518250' AND username          = '37444332' AND nasipaddress      = '109.226.1.20';
 UPDATE radacct SET  acctstoptime       = '2012-07-19 02:11:56',  acctsessiontime    = '48',  acctinputoctets    = '0' << 32 | '60',  acctoutputoctets   = '0' << 32 | '60',  acctterminatecause = 'NAS-Request',  acctstopdelay      = '0',  connectinfo_stop   = '' WHERE acctsessionid   = 'erx ip:109.226.0.17:147.235.134.47:ef47:632c:16c2:5208:4fdd690:0050518554' AND username          = '37444332' AND nasipaddress      = '109.226.1.20';
 INSERT INTO radacct (acctsessionid,    acctuniqueid,     username,  realm,            nasipaddress,     nasportid,  nasporttype,      acctstarttime,    acctstoptime,  acctsessiontime,  acctauthentic,    connectinfo_start,  connectinfo_stop, acctinputoctets,  acctoutputoctets,  calledstationid,  callingstationid, acctterminatecause,  servicetype,      framedprotocol,   framedipaddress,  acctstartdelay,   acctstopdelay,    xascendsessionsvrkey) VALUES  ('erx ip:109.226.0.17:147.235.134.47:ef47:632c:a25c:5339:4fdd952:0050519038', '41eb8518f08ff00c',  '37444332',  'ccc', '109.226.1.20', '23444298',  'Virtual', '2012-07-19 02:13:02', NULL,  '0', 'RADIUS', '',  '', '0', '0',  '', '47 5/1 vlan-id 1421:32', '',  'Framed-User', 'PPP', '109.226.30.184',  '0', '0', '');
 UPDATE radacct SET  acctstoptime       = '2012-07-19 02:13:53',  acctsessiontime    = '51',  acctinputoctets    = '0' << 32 | '60',  acctoutputoctets   = '0' << 32 | '60',  acctterminatecause = 'NAS-Request',  acctstopdelay      = '0',  connectinfo_stop   = '' WHERE acctsessionid   = 'erx ip:109.226.0.17:147.235.134.47:ef47:632c:a25c:5339:4fdd952:0050519038' AND username          = '37444332' AND nasipaddress      = '109.226.1.20';
 INSERT INTO radacct (acctsessionid,    acctuniqueid,     username,  realm,            nasipaddress,     nasportid,  nasporttype,      acctstarttime,    acctstoptime,  acctsessiontime,  acctauthentic,    connectinfo_start,  connectinfo_stop, acctinputoctets,  acctoutputoctets,  calledstationid,  callingstationid, acctterminatecause,  servicetype,      framedprotocol,   framedipaddress,  acctstartdelay,   acctstopdelay,    xascendsessionsvrkey) VALUES  ('erx ip:109.226.0.17:147.235.134.47:ef47:632c:cdc4:53dc:4fddae5:0050519308', 'd12247653bf5ec9c',  '37444332',  'ccc', '109.226.1.20', '23444766',  'Virtual', '2012-07-19 02:14:06', NULL,  '0', 'RADIUS', '',  '', '0', '0',  '', '47 5/1 vlan-id 1421:32', '',  'Framed-User', 'PPP', '37.19.123.251',  '0', '0', '');
 UPDATE radacct SET  acctstoptime       = '2012-07-19 02:14:54',  acctsessiontime    = '48',  acctinputoctets    = '0' << 32 | '60',  acctoutputoctets   = '0' << 32 | '60',  acctterminatecause = 'NAS-Request',  acctstopdelay      = '0',  connectinfo_stop   = '' WHERE acctsessionid   = 'erx ip:109.226.0.17:147.235.134.47:ef47:632c:cdc4:53dc:4fddae5:0050519308' AND username          = '37444332' AND nasipaddress      = '109.226.1.20';

This seems to be the case for duplicated connections, there are several accounting start requests sent within a few seconds from each other, all of them create a new accounting record, and no stop request is sent for these sessions.


Could this be related to server load? Host is running on a VM with 4GB ram and 2 CPUs, running freeradius and a local ldap service (being replicated from master).
Server load seems to be close to zero during operation.

There are also some cases where ldap returns an error:

[ldap] ldap_search() failed: Timed out while waiting for server to respond. Please increase the timeout.
[ldap] search failed
  [ldap] ldap_release_conn: Release Id: 11
++[ldap] returns fail

[ldap] ldap_search() failed: Timed out while waiting for server to respond. Please increase the timeout.
[ldap] search failed
  [ldap] ldap_release_conn: Release Id: 15
++[ldap] returns fail


Also attached radius -X output:

radiusd: #### Instantiating modules ####
 instantiate {
 Module: Linked to module rlm_exec
 Module: Instantiating module "exec" from file /etc/raddb/modules/exec
  exec {
        wait = no
        input_pairs = "request"
        shell_escape = yes
  }
 Module: Linked to module rlm_expr
 Module: Instantiating module "expr" from file /etc/raddb/modules/expr
 Module: Linked to module rlm_expiration
 Module: Instantiating module "expiration" from file /etc/raddb/modules/expiration
  expiration {
        reply-message = "Password Has Expired  "
  }
 Module: Linked to module rlm_logintime
 Module: Instantiating module "logintime" from file /etc/raddb/modules/logintime
  logintime {
        reply-message = "You are calling outside your allowed timespan  "
        minimum-timeout = 60
  }
 }
radiusd: #### Loading Virtual Servers ####
server { # from file /etc/raddb/radiusd.conf
 modules {
 Module: Checking authenticate {...} for more modules to load
 Module: Linked to module rlm_pap
 Module: Instantiating module "pap" from file /etc/raddb/modules/pap.rpmsave
  pap {
        encryption_scheme = "auto"
        auto_header = yes
  }
 Module: Linked to module rlm_chap
 Module: Instantiating module "chap" from file /etc/raddb/modules/chap
 Module: Linked to module rlm_mschap
 Module: Instantiating module "mschap" from file /etc/raddb/modules/mschap
  mschap {
        use_mppe = yes
        require_encryption = no
        require_strong = no
        with_ntdomain_hack = no
        allow_retry = yes
  }
 Module: Linked to module rlm_unix
 Module: Instantiating module "unix" from file /etc/raddb/modules/unix
  unix {
        radwtmp = "/var/log/radius/radwtmp"
  }
 Module: Linked to module rlm_ldap
 Module: Instantiating module "ldap" from file /etc/raddb/modules/ldap
  ldap {
        server = "127.0.0.1"
        port = 389
        password = "freeradius"
        identity = "cn=freeradius,ou=admins,ou=radius,dc=ccc,dc=net,dc=il"
        net_timeout = 1
        timeout = 20
        timelimit = 20
        tls_mode = no
        start_tls = no
        tls_require_cert = "allow"
   tls {
        start_tls = no
        require_cert = "allow"
   }
        basedn = "ou=users,ou=radius,dc=ccc,dc=net,dc=il"
        filter = "(uid=%{%{Stripped-User-Name}:-%{User-Name}})"
        base_filter = "(objectclass=radiusprofile)"
        default_profile = "uid=dialin_0Mb,ou=profiles,ou=radius,dc=ccc,dc=net,dc=il"
        profile_attribute = "radiusProfileDn"
        password_attribute = "userPassword"
        auto_header = no
        access_attr = "dialupAccess"
        access_attr_used_for_allow = yes
        groupname_attribute = "cn"
        groupmembership_filter = "(|(&(objectClass=GroupOfNames)(member=%{Ldap-UserDn}))(&(objectClass=GroupOfUniqueNames)(uniquemember=%{Ldap-UserDn})))"
        dictionary_mapping = "/etc/raddb/ldap.attrmap"
        ldap_debug = 0
        ldap_connections_number = 32
        compare_check_items = no
        do_xlat = yes
        set_auth_type = yes
  }
rlm_ldap: Registering ldap_groupcmp for Ldap-Group
rlm_ldap: Registering ldap_xlat with xlat_name ldap
rlm_ldap: reading ldap<->radius mappings from file /etc/raddb/ldap.attrmap
rlm_ldap: LDAP radiusCheckItem mapped to RADIUS $GENERIC$
rlm_ldap: LDAP radiusReplyItem mapped to RADIUS $GENERIC$
rlm_ldap: LDAP radiusAuthType mapped to RADIUS Auth-Type
rlm_ldap: LDAP radiusSimultaneousUse mapped to RADIUS Simultaneous-Use
rlm_ldap: LDAP radiusCalledStationId mapped to RADIUS Called-Station-Id
rlm_ldap: LDAP radiusCallingStationId mapped to RADIUS Calling-Station-Id
rlm_ldap: LDAP lmPassword mapped to RADIUS LM-Password
rlm_ldap: LDAP ntPassword mapped to RADIUS NT-Password
rlm_ldap: LDAP sambaLmPassword mapped to RADIUS LM-Password
rlm_ldap: LDAP sambaNtPassword mapped to RADIUS NT-Password
rlm_ldap: LDAP dBCSPwd mapped to RADIUS LM-Password
rlm_ldap: LDAP userPassword mapped to RADIUS Password-With-Header
rlm_ldap: LDAP acctFlags mapped to RADIUS SMB-Account-CTRL-TEXT
rlm_ldap: LDAP radiusExpiration mapped to RADIUS Expiration
rlm_ldap: LDAP radiusNASIpAddress mapped to RADIUS NAS-IP-Address
rlm_ldap: LDAP radiusServiceType mapped to RADIUS Service-Type
rlm_ldap: LDAP radiusFramedProtocol mapped to RADIUS Framed-Protocol
rlm_ldap: LDAP radiusFramedIPAddress mapped to RADIUS Framed-IP-Address
rlm_ldap: LDAP radiusFramedIPNetmask mapped to RADIUS Framed-IP-Netmask
rlm_ldap: LDAP radiusFramedRoute mapped to RADIUS Framed-Route
rlm_ldap: LDAP radiusFramedRouting mapped to RADIUS Framed-Routing
rlm_ldap: LDAP radiusFilterId mapped to RADIUS Filter-Id
rlm_ldap: LDAP radiusFramedMTU mapped to RADIUS Framed-MTU
rlm_ldap: LDAP radiusFramedCompression mapped to RADIUS Framed-Compression
rlm_ldap: LDAP radiusLoginIPHost mapped to RADIUS Login-IP-Host
rlm_ldap: LDAP radiusLoginService mapped to RADIUS Login-Service
rlm_ldap: LDAP radiusLoginTCPPort mapped to RADIUS Login-TCP-Port
rlm_ldap: LDAP radiusCallbackNumber mapped to RADIUS Callback-Number
rlm_ldap: LDAP radiusCallbackId mapped to RADIUS Callback-Id
rlm_ldap: LDAP radiusFramedIPXNetwork mapped to RADIUS Framed-IPX-Network
rlm_ldap: LDAP radiusClass mapped to RADIUS Class
rlm_ldap: LDAP radiusSessionTimeout mapped to RADIUS Session-Timeout
rlm_ldap: LDAP radiusIdleTimeout mapped to RADIUS Idle-Timeout
rlm_ldap: LDAP radiusTerminationAction mapped to RADIUS Termination-Action
rlm_ldap: LDAP radiusLoginLATService mapped to RADIUS Login-LAT-Service
rlm_ldap: LDAP radiusLoginLATNode mapped to RADIUS Login-LAT-Node
rlm_ldap: LDAP radiusLoginLATGroup mapped to RADIUS Login-LAT-Group
rlm_ldap: LDAP radiusFramedAppleTalkLink mapped to RADIUS Framed-AppleTalk-Link
rlm_ldap: LDAP radiusFramedAppleTalkNetwork mapped to RADIUS Framed-AppleTalk-Network
rlm_ldap: LDAP radiusFramedAppleTalkZone mapped to RADIUS Framed-AppleTalk-Zone
rlm_ldap: LDAP radiusPortLimit mapped to RADIUS Port-Limit
rlm_ldap: LDAP radiusLoginLATPort mapped to RADIUS Login-LAT-Port
rlm_ldap: LDAP radiusReplyMessage mapped to RADIUS Reply-Message
rlm_ldap: LDAP radiusTunnelType mapped to RADIUS Tunnel-Type
rlm_ldap: LDAP radiusTunnelMediumType mapped to RADIUS Tunnel-Medium-Type
rlm_ldap: LDAP radiusTunnelPrivateGroupId mapped to RADIUS Tunnel-Private-Group-Id
conns: 0x12ac5c50
 Module: Linked to module rlm_eap
 Module: Instantiating module "eap" from file /etc/raddb/eap.conf
  eap {
        default_eap_type = "md5"
        timer_expire = 60
        ignore_unknown_eap_types = no
        cisco_accounting_username_bug = no
        max_sessions = 4096
  }
 Module: Linked to sub-module rlm_eap_md5
 Module: Instantiating eap-md5
 Module: Linked to sub-module rlm_eap_leap
 Module: Instantiating eap-leap
 Module: Linked to sub-module rlm_eap_gtc
 Module: Instantiating eap-gtc
   gtc {
        challenge = "Password: "
        auth_type = "PAP"
   }
 Module: Linked to sub-module rlm_eap_tls
 Module: Instantiating eap-tls
   tls {
        rsa_key_exchange = no
        dh_key_exchange = yes
        rsa_key_length = 512
        dh_key_length = 512
        verify_depth = 0
        CA_path = "/etc/raddb/certs"
        pem_file_type = yes
        private_key_file = "/etc/raddb/certs/server.pem"
        certificate_file = "/etc/raddb/certs/server.pem"
        CA_file = "/etc/raddb/certs/ca.pem"
        private_key_password = "whatever"
        dh_file = "/etc/raddb/certs/dh"
        random_file = "/etc/raddb/certs/random"
        fragment_size = 1024
        include_length = yes
        check_crl = no
        cipher_list = "DEFAULT"
        make_cert_command = "/etc/raddb/certs/bootstrap"
    cache {
        enable = no
        lifetime = 24
        max_entries = 255
    }
    verify {
    }
    ocsp {
        enable = no
        override_cert_url = yes
        url = "http://127.0.0.1/ocsp/"
    }
   }
 Module: Linked to sub-module rlm_eap_ttls
 Module: Instantiating eap-ttls
   ttls {
        default_eap_type = "md5"
        copy_request_to_tunnel = no
        use_tunneled_reply = no
        virtual_server = "inner-tunnel"
        include_length = yes
   }
 Module: Linked to sub-module rlm_eap_peap
 Module: Instantiating eap-peap
   peap {
        default_eap_type = "mschapv2"
        copy_request_to_tunnel = no
        use_tunneled_reply = no
        proxy_tunneled_request_as_eap = yes
        virtual_server = "inner-tunnel"
        soh = no
   }
 Module: Linked to sub-module rlm_eap_mschapv2
 Module: Instantiating eap-mschapv2
   mschapv2 {
        with_ntdomain_hack = no
        send_error = no
   }
 Module: Checking authorize {...} for more modules to load
 Module: Linked to module rlm_preprocess
 Module: Instantiating module "preprocess" from file /etc/raddb/modules/preprocess
  preprocess {
        huntgroups = "/etc/raddb/huntgroups"
        hints = "/etc/raddb/hints"
        with_ascend_hack = no
        ascend_channels_per_line = 23
        with_ntdomain_hack = no
        with_specialix_jetstream_hack = no
        with_cisco_vsa_hack = no
        with_alvarion_vsa_hack = no
  }
 Module: Linked to module rlm_realm
 Module: Instantiating module "suffix" from file /etc/raddb/modules/realm
  realm suffix {
        format = "suffix"
        delimiter = "@"
        ignore_default = no
        ignore_null = no
  }
 Module: Linked to module rlm_files
 Module: Instantiating module "files" from file /etc/raddb/modules/files
  files {
        usersfile = "/etc/raddb/users"
        acctusersfile = "/etc/raddb/acct_users"
        preproxy_usersfile = "/etc/raddb/preproxy_users"
        compat = "no"
  }
 Module: Checking preacct {...} for more modules to load
 Module: Linked to module rlm_acct_unique
 Module: Instantiating module "acct_unique" from file /etc/raddb/modules/acct_unique
  acct_unique {
        key = "User-Name, Acct-Session-Id, NAS-IP-Address, Client-IP-Address, NAS-Port"
  }
 Module: Checking accounting {...} for more modules to load
 Module: Linked to module rlm_detail
 Module: Instantiating module "detail" from file /etc/raddb/modules/detail
  detail {
        detailfile = "/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d"
        header = "%t"
        detailperm = 384
        dirperm = 493
        locking = no
        log_packet_header = no
  }
 Module: Instantiating module "detail.moreshet" from file /etc/raddb/modules/detail.moreshet
  detail detail.moreshet {
        detailfile = "/var/log/radius/radacct/moreshet.relay"
        header = "%t"
        detailperm = 384
        dirperm = 493
        locking = no
        log_packet_header = no
  }
 Module: Linked to module rlm_sql
 Module: Instantiating module "sql" from file /etc/raddb/sql.conf
  sql {
        driver = "rlm_sql_mysql"
        server = "xxx.xxx.xxx.xxx"
        port = ""
        login = "root"
        password = "xxxxxx"
        radius_db = "radius"
        read_groups = yes
        sqltrace = yes
        sqltracefile = "/var/log/radius/sqltrace.sql"
        readclients = no
        deletestalesessions = yes
        num_sql_socks = 32
        lifetime = 60
        max_queries = 10
        sql_user_name = "%{%{Stripped-User-Name}:-%{%{User-Name}:-DEFAULT}}"
        default_user_profile = ""
        nas_query = "SELECT id, nasname, shortname, type, secret FROM nas"
        authorize_check_query = "SELECT id, username, attribute, value, op  FROM radcheck  WHERE username = '%{SQL-User-Name}'  ORDER BY id"
        authorize_reply_query = "SELECT id, username, attribute, value, op  FROM radreply  WHERE username = '%{SQL-User-Name}'  ORDER BY id"
        authorize_group_check_query = "SELECT id, groupname, attribute,  Value, op  FROM radgroupcheck  WHERE groupname = '%{Sql-Group}'  ORDER BY id"
        authorize_group_reply_query = "SELECT id, groupname, attribute,  value, op  FROM radgroupreply  WHERE groupname = '%{Sql-Group}'  ORDER BY id"
        accounting_onoff_query = "UPDATE radacct SET  acctstoptime       =  '%S',  acctsessiontime    =  unix_timestamp('%S') - unix_timestamp(acctstarttime),  acctterminatecause =  '%{Acct-Terminate-Cause}',  acctstopdelay      =  %{%{Acct-Delay-Time}:-0} WHERE acctstoptime IS NULL AND nasipaddress      =  '%{NAS-IP-Address}' AND acctstarttime     <= '%S'"
        accounting_update_query = " UPDATE radacct SET  framedipaddress = '%{Framed-IP-Address}',  acctsessiontime     = '%{Acct-Session-Time}',  acctinputoctets     = '%{%{Acct-Input-Gigawords}:-0}'  << 32 | '%{%{Acct-Input-Octets}:-0}',  acctoutputoctets    = '%{%{Acct-Output-Gigawords}:-0}' << 32 | '%{%{Acct-Output-Octets}:-0}' WHERE acctsessionid = '%{Acct-Session-Id}' AND username        = '%{SQL-User-Name}' AND nasipaddress    = '%{NAS-IP-Address}'"
        accounting_update_query_alt = " INSERT INTO radacct (acctsessionid,    acctuniqueid,      username,  realm,            nasipaddress,      nasportid,  nasporttype,      acctstarttime,     acctsessiontime,  acctauthentic,    connectinfo_start, acctinputoctets,  acctoutputoctets, calledstationid,   callingstationid,  servicetype,      framedprotocol,    framedipaddress,  acctstartdelay,   xascendsessionsvrkey) VALUES ('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}',  '%{SQL-User-Name}',  '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}',  '%{NAS-Port-Type}',  DATE_SUB('%S',  INTERVAL (%{%{Acct-Session-Time}:-0} + %{%{Acct-Delay-Time}:-0}) SECOND), '%{Acct-Session-Time}',  '%{Acct-Authentic}', '',  '%{%{Acct-Input-Gigawords}:-0}' << 32 |  '%{%{Acct-Input-Octets}:-0}',  '%{%{Acct-Output-Gigawords}:-0}' << 32 |  '%{%{Acct-Output-Octets}:-0}',  '%{Called-Station-Id}', '%{Calling-Station-Id}',  '%{Service-Type}', '%{Framed-Protocol}',  '%{Framed-IP-Address}',  '0', '%{X-Ascend-Session-Svr-Key}')"
        accounting_start_query = " INSERT INTO radacct (acctsessionid,    acctuniqueid,     username,  realm,            nasipaddress,     nasportid,  nasporttype,      acctstarttime,    acctstoptime,  acctsessiontime,  acctauthentic,    connectinfo_start,  connectinfo_stop, acctinputoctets,  acctoutputoctets,  calledstationid,  callingstationid, acctterminatecause,  servicetype,      framedprotocol,   framedipaddress,  acctstartdelay,   acctstopdelay,    xascendsessionsvrkey) VALUES  ('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}',  '%{SQL-User-Name}',  '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}',  '%{NAS-Port-Type}', '%S', NULL,  '0', '%{Acct-Authentic}', '%{Connect-Info}',  '', '0', '0',  '%{Called-Station-Id}', '%{Calling-Station-Id}', '',  '%{Service-Type}', '%{Framed-Protocol}', '%{Framed-IP-Address}',  '%{%{Acct-Delay-Time}:-0}', '0', '%{X-Ascend-Session-Svr-Key}')"
        accounting_start_query_alt = " UPDATE radacct SET  acctstarttime     = '%S',  acctstartdelay    = '%{%{Acct-Delay-Time}:-0}',  connectinfo_start = '%{Connect-Info}' WHERE acctsessionid  = '%{Acct-Session-Id}' AND username         = '%{SQL-User-Name}' AND nasipaddress     = '%{NAS-IP-Address}'"
        accounting_stop_query = " UPDATE radacct SET  acctstoptime       = '%S',  acctsessiontime    = '%{Acct-Session-Time}',  acctinputoctets    = '%{%{Acct-Input-Gigawords}:-0}' << 32 | '%{%{Acct-Input-Octets}:-0}',  acctoutputoctets   = '%{%{Acct-Output-Gigawords}:-0}' << 32 | '%{%{Acct-Output-Octets}:-0}',  acctterminatecause = '%{Acct-Terminate-Cause}',  acctstopdelay      = '%{%{Acct-Delay-Time}:-0}',  connectinfo_stop   = '%{Connect-Info}' WHERE acctsessionid   = '%{Acct-Session-Id}' AND username          = '%{SQL-User-Name}' AND nasipaddress      = '%{NAS-IP-Address}'"
        accounting_stop_query_alt = " INSERT INTO radacct (acctsessionid, acctuniqueid, username,  realm, nasipaddress, nasportid,  nasporttype, acctstarttime, acctstoptime,  acctsessiontime, acctauthentic, connectinfo_start,  connectinfo_stop, acctinputoctets, acctoutputoctets,  calledstationid, callingstationid, acctterminatecause,  servicetype, framedprotocol, framedipaddress,  acctstartdelay, acctstopdelay) VALUES ('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}',  '%{SQL-User-Name}',  '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}',  '%{NAS-Port-Type}',  DATE_SUB('%S',  INTERVAL (%{%{Acct-Session-Time}:-0} +  %{%{Acct-Delay-Time}:-0}) SECOND),  '%S', '%{Acct-Session-Time}', '%{Acct-Authentic}', '',  '%{Connect-Info}',  '%{%{Acct-Input-Gigawords}:-0}' << 32 |  '%{%{Acct-Input-Octets}:-0}',  '%{%{Acct-Output-Gigawords}:-0}' << 32 |  '%{%{Acct-Output-Octets}:-0}',  '%{Called-Station-Id}', '%{Calling-Station-Id}',  '%{Acct-Terminate-Cause}',  '%{Service-Type}', '%{Framed-Protocol}', '%{Framed-IP-Address}',  '0', '%{%{Acct-Delay-Time}:-0}')"
        group_membership_query = "SELECT groupname  FROM radusergroup  WHERE username = '%{SQL-User-Name}'  ORDER BY priority"
        connect_failure_retry_delay = 3
        simul_count_query = ""
        simul_verify_query = ""
        postauth_query = " INSERT INTO radpostauth  (username, pass, reply, authdate)  VALUES (  '%{User-Name}',  '%{%{User-Password}:-%{Chap-Password}}',  '%{reply:Packet-Type}', '%S')"
        safe-characters = "@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /"
  }
rlm_sql (sql): Driver rlm_sql_mysql (module rlm_sql_mysql) loaded and linked
rlm_sql (sql): Attempting to connect to root at xxx.xxx.xxx.xxx:/radius
rlm_sql (sql): starting 0
rlm_sql (sql): Attempting to connect rlm_sql_mysql #0
rlm_sql_mysql: Starting connect to MySQL server for #0
rlm_sql (sql): Connected new DB handle, #0
rlm_sql (sql): starting 1
rlm_sql (sql): Attempting to connect rlm_sql_mysql #1
rlm_sql_mysql: Starting connect to MySQL server for #1
rlm_sql (sql): Connected new DB handle, #1
rlm_sql (sql): starting 2
rlm_sql (sql): Attempting to connect rlm_sql_mysql #2
rlm_sql_mysql: Starting connect to MySQL server for #2
rlm_sql (sql): Connected new DB handle, #2
rlm_sql (sql): starting 3
rlm_sql (sql): Attempting to connect rlm_sql_mysql #3
rlm_sql_mysql: Starting connect to MySQL server for #3
rlm_sql (sql): Connected new DB handle, #3
rlm_sql (sql): starting 4
rlm_sql (sql): Attempting to connect rlm_sql_mysql #4
rlm_sql_mysql: Starting connect to MySQL server for #4
rlm_sql (sql): Connected new DB handle, #4
rlm_sql (sql): starting 5
rlm_sql (sql): Attempting to connect rlm_sql_mysql #5
rlm_sql_mysql: Starting connect to MySQL server for #5
rlm_sql (sql): Connected new DB handle, #5
rlm_sql (sql): starting 6
rlm_sql (sql): Attempting to connect rlm_sql_mysql #6
rlm_sql_mysql: Starting connect to MySQL server for #6
rlm_sql (sql): Connected new DB handle, #6
rlm_sql (sql): starting 7
rlm_sql (sql): Attempting to connect rlm_sql_mysql #7
rlm_sql_mysql: Starting connect to MySQL server for #7
rlm_sql (sql): Connected new DB handle, #7
rlm_sql (sql): starting 8
rlm_sql (sql): Attempting to connect rlm_sql_mysql #8
rlm_sql_mysql: Starting connect to MySQL server for #8
rlm_sql (sql): Connected new DB handle, #8
rlm_sql (sql): starting 9
rlm_sql (sql): Attempting to connect rlm_sql_mysql #9
rlm_sql_mysql: Starting connect to MySQL server for #9
rlm_sql (sql): Connected new DB handle, #9
rlm_sql (sql): starting 10
rlm_sql (sql): Attempting to connect rlm_sql_mysql #10
rlm_sql_mysql: Starting connect to MySQL server for #10
rlm_sql (sql): Connected new DB handle, #10
rlm_sql (sql): starting 11
rlm_sql (sql): Attempting to connect rlm_sql_mysql #11
rlm_sql_mysql: Starting connect to MySQL server for #11
rlm_sql (sql): Connected new DB handle, #11
rlm_sql (sql): starting 12
rlm_sql (sql): Attempting to connect rlm_sql_mysql #12
rlm_sql_mysql: Starting connect to MySQL server for #12
rlm_sql (sql): Connected new DB handle, #12
rlm_sql (sql): starting 13
rlm_sql (sql): Attempting to connect rlm_sql_mysql #13
rlm_sql_mysql: Starting connect to MySQL server for #13
rlm_sql (sql): Connected new DB handle, #13
rlm_sql (sql): starting 14
rlm_sql (sql): Attempting to connect rlm_sql_mysql #14
rlm_sql_mysql: Starting connect to MySQL server for #14
rlm_sql (sql): Connected new DB handle, #14
rlm_sql (sql): starting 15
rlm_sql (sql): Attempting to connect rlm_sql_mysql #15
rlm_sql_mysql: Starting connect to MySQL server for #15
rlm_sql (sql): Connected new DB handle, #15
rlm_sql (sql): starting 16
rlm_sql (sql): Attempting to connect rlm_sql_mysql #16
rlm_sql_mysql: Starting connect to MySQL server for #16
rlm_sql (sql): Connected new DB handle, #16
rlm_sql (sql): starting 17
rlm_sql (sql): Attempting to connect rlm_sql_mysql #17
rlm_sql_mysql: Starting connect to MySQL server for #17
rlm_sql (sql): Connected new DB handle, #17
rlm_sql (sql): starting 18
rlm_sql (sql): Attempting to connect rlm_sql_mysql #18
rlm_sql_mysql: Starting connect to MySQL server for #18
rlm_sql (sql): Connected new DB handle, #18
rlm_sql (sql): starting 19
rlm_sql (sql): Attempting to connect rlm_sql_mysql #19
rlm_sql_mysql: Starting connect to MySQL server for #19
rlm_sql (sql): Connected new DB handle, #19
rlm_sql (sql): starting 20
rlm_sql (sql): Attempting to connect rlm_sql_mysql #20
rlm_sql_mysql: Starting connect to MySQL server for #20
rlm_sql (sql): Connected new DB handle, #20
rlm_sql (sql): starting 21
rlm_sql (sql): Attempting to connect rlm_sql_mysql #21
rlm_sql_mysql: Starting connect to MySQL server for #21
rlm_sql (sql): Connected new DB handle, #21
rlm_sql (sql): starting 22
rlm_sql (sql): Attempting to connect rlm_sql_mysql #22
rlm_sql_mysql: Starting connect to MySQL server for #22
rlm_sql (sql): Connected new DB handle, #22
rlm_sql (sql): starting 23
rlm_sql (sql): Attempting to connect rlm_sql_mysql #23
rlm_sql_mysql: Starting connect to MySQL server for #23
rlm_sql (sql): Connected new DB handle, #23
rlm_sql (sql): starting 24
rlm_sql (sql): Attempting to connect rlm_sql_mysql #24
rlm_sql_mysql: Starting connect to MySQL server for #24
rlm_sql (sql): Connected new DB handle, #24
rlm_sql (sql): starting 25
rlm_sql (sql): Attempting to connect rlm_sql_mysql #25
rlm_sql_mysql: Starting connect to MySQL server for #25
rlm_sql (sql): Connected new DB handle, #25
rlm_sql (sql): starting 26
rlm_sql (sql): Attempting to connect rlm_sql_mysql #26
rlm_sql_mysql: Starting connect to MySQL server for #26
rlm_sql (sql): Connected new DB handle, #26
rlm_sql (sql): starting 27
rlm_sql (sql): Attempting to connect rlm_sql_mysql #27
rlm_sql_mysql: Starting connect to MySQL server for #27
rlm_sql (sql): Connected new DB handle, #27
rlm_sql (sql): starting 28
rlm_sql (sql): Attempting to connect rlm_sql_mysql #28
rlm_sql_mysql: Starting connect to MySQL server for #28
rlm_sql (sql): Connected new DB handle, #28
rlm_sql (sql): starting 29
rlm_sql (sql): Attempting to connect rlm_sql_mysql #29
rlm_sql_mysql: Starting connect to MySQL server for #29
rlm_sql (sql): Connected new DB handle, #29
rlm_sql (sql): starting 30
rlm_sql (sql): Attempting to connect rlm_sql_mysql #30
rlm_sql_mysql: Starting connect to MySQL server for #30
rlm_sql (sql): Connected new DB handle, #30
rlm_sql (sql): starting 31
rlm_sql (sql): Attempting to connect rlm_sql_mysql #31
rlm_sql_mysql: Starting connect to MySQL server for #31
rlm_sql (sql): Connected new DB handle, #31
 Module: Linked to module rlm_attr_filter
 Module: Instantiating module "attr_filter.accounting_response" from file /etc/raddb/modules/attr_filter
  attr_filter attr_filter.accounting_response {
        attrsfile = "/etc/raddb/attrs.accounting_response"
        key = "%{User-Name}"
        relaxed = no
  }
 Module: Checking session {...} for more modules to load
 Module: Checking pre-proxy {...} for more modules to load
 Module: Instantiating module "pre_proxy_log" from file /etc/raddb/modules/detail.log
  detail pre_proxy_log {
        detailfile = "/var/log/radius/radacct/%{Client-IP-Address}/pre-proxy-detail-%Y%m%d"
        header = "%t"
        detailperm = 384
        dirperm = 493
        locking = no
        log_packet_header = no
  }
 Module: Checking post-proxy {...} for more modules to load
 Module: Checking post-auth {...} for more modules to load
 Module: Instantiating module "attr_filter.access_reject" from file /etc/raddb/modules/attr_filter
  attr_filter attr_filter.access_reject {
        attrsfile = "/etc/raddb/attrs.access_reject"
        key = "%{User-Name}"
        relaxed = no
  }
 } # modules
} # server
server moreshet.relay_server { # from file /etc/raddb/sites-enabled/moreshet.relay_relay_server
 modules {
 Module: Checking preacct {...} for more modules to load
 Module: Instantiating module "moreshet.realm" from file /etc/raddb/modules/realm
  realm moreshet.realm {
        format = "prefix"
        delimiter = "#"
        ignore_default = no
        ignore_null = no
  }
 Module: Checking accounting {...} for more modules to load
 Module: Linked to module rlm_always
 Module: Instantiating module "ok" from file /etc/raddb/modules/always
  always ok {
        rcode = "ok"
        simulcount = 0
        mpp = no
  }
 } # modules
} # server
server inner-tunnel { # from file /etc/raddb/sites-enabled/inner-tunnel
 modules {
 Module: Checking authenticate {...} for more modules to load
 Module: Checking authorize {...} for more modules to load
 Module: Checking session {...} for more modules to load
 Module: Linked to module rlm_radutmp
 Module: Instantiating module "radutmp" from file /etc/raddb/modules/radutmp
  radutmp {
        filename = "/var/log/radius/radutmp"
        username = "%{User-Name}"
        case_sensitive = yes
        check_with_nas = yes
        perm = 384
        callerid = yes
  }
 Module: Checking post-proxy {...} for more modules to load
 Module: Checking post-auth {...} for more modules to load
 } # modules
} # server
 thread pool {
        start_servers = 5
        max_servers = 32
        min_spare_servers = 3
        max_spare_servers = 10
        max_requests_per_server = 0
        cleanup_delay = 5
        max_queue_size = 65536
 }
Thread spawned new child 1. Total threads in pool: 1
Thread 2 waiting to be assigned a request
Thread spawned new child 2. Total threads in pool: 2
Thread spawned new child 3. Total threads in pool: 3
Thread spawned new child 4. Total threads in pool: 4
Thread spawned new child 5. Total threads in pool: 5
Thread pool initialized
radiusd: #### Opening IP addresses and Ports ####
listen {
        type = "auth"
        ipaddr = *
        port = 0
Thread 3 waiting to be assigned a request
Thread 4 waiting to be assigned a request
Thread 5 waiting to be assigned a request
Thread 1 waiting to be assigned a request
Re-wait 2
Re-wait 5
Re-wait 4
Re-wait 3
Re-wait 1
Re-wait 2
Re-wait 1
Re-wait 5
Re-wait 4
}
listen {
        type = "acct"
        ipaddr = *
        port = 0
Re-wait 2
Re-wait 1
Re-wait 3
Re-wait 5
Re-wait 2
Re-wait 1
Re-wait 4
Re-wait 5
}
listen {
        type = "control"
 listen {
        socket = "/var/run/radiusd/radiusd.sock"
 }
}
listen {
        type = "detail"
  listen {
        filename = "/var/log/radius/radacct/moreshet.relay"
        load_factor = 10
        poll_interval = 1
        retry_interval = 30
  }
}
listen {
        type = "auth"
        ipaddr = 127.0.0.1
        port = 18120
Re-wait 2
Re-wait 1
Re-wait 3
Re-wait 5
Re-wait 2
Re-wait 1
Re-wait 4
Re-wait 5
}
Re-wait 2
Re-wait 1
Re-wait 3
Re-wait 5
Re-wait 2
Re-wait 1
Re-wait 4
Re-wait 5
Re-wait 2
Re-wait 1
Re-wait 3
Re-wait 5
Re-wait 2
Re-wait 1
Re-wait 4
Re-wait 5
 ... adding new socket proxy address * port 37357
Re-wait 2
Re-wait 1
Re-wait 3
Re-wait 5
Re-wait 2
Re-wait 1
Re-wait 4
Re-wait 5
 ... adding new socket proxy address * port 56858
Re-wait 2
Re-wait 1
Re-wait 3
Re-wait 5
Listening on authentication address * port 1812
Listening on accounting address * port 1813
Listening on command file /var/run/radiusd/radiusd.sock
Listening on detail file /var/log/radius/radacct/moreshet.relay as server moreshet.relay_server
Detail listener /var/log/radius/radacct/moreshet.relay state unopened signalled 0 waiting 1.000000 sec
Listening on authentication address 127.0.0.1 port 18120 as server inner-tunnel
Listening on proxy address * port 1814
Waking up in 0.9 seconds.
Re-wait 4
Threads: total/active/spare threads = 5/0/5
Thread 2 got semaphore
Waking up in 0.9 seconds.
Thread 2 handling request 0, (1 handled so far)
[<thread>] # Executing section authorize from file /etc/raddb/sites-enabled/default
[<thread>] +- entering group authorize {...}


Amir.


More information about the Freeradius-Users mailing list