Acct-Status-Type = Start Calling-Station-Id = "d4-9a-20-9e-cd-c5" Called-Station-Id = "00-19-30-fb-bd-c0" Acct-Unique-Session-Id = "6c91a548f2eb560c" Stripped-User-Name = "A" Realm = "bris.ac.uk" Packet-Original-Timestamp = "Jun 7 2010 11:35:19 UTC" Acct-Delay-Time = 1 server eduroamlocal-bsql { +- entering group preacct {...} ++[preprocess] returns ok ++[acct_unique] returns noop +- entering group accounting {...} ++? if (User-Name =~ /\\\\?([^@\\\\]*)@?([-[:alnum:].]+\\.[-[:alnum:].]+)?$/) ? Evaluating (User-Name =~ /\\\\?([^@\\\\]*)@?([-[:alnum:].]+\\.[-[:alnum:].]+)?$/) -> TRUE ++? if (User-Name =~ /\\\\?([^@\\\\]*)@?([-[:alnum:].]+\\.[-[:alnum:].]+)?$/) -> TRUE ++- entering if (User-Name =~ /\\\\?([^@\\\\]*)@?([-[:alnum:].]+\\.[-[:alnum:].]+)?$/) {...} expand: %{1} -> A expand: %{2} -> bris.ac.uk +++[request] returns noop ++- if (User-Name =~ /\\\\?([^@\\\\]*)@?([-[:alnum:].]+\\.[-[:alnum:].]+)?$/) returns noop ++? if (User-Name =~ /^([^@\\\\]+)\\\\+[^@\\\\]+/) ? Evaluating (User-Name =~ /^([^@\\\\]+)\\\\+[^@\\\\]+/) -> FALSE ++? if (User-Name =~ /^([^@\\\\]+)\\\\+[^@\\\\]+/) -> FALSE [logsql] expand: %{Stripped-User-Name} -> A [logsql] expand: %{%{Stripped-User-Name}:-%{User-Name}} -> A [logsql] sql_set_user escaped user --> 'A' [logsql] expand: %{Acct-Delay-Time} -> 1 [logsql] expand: 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, nasidentifier, tunnelprivategroupid, mschapdomain, acctstartdelay, acctstopdelay, servicename, radiusip) VALUES ('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}', '%{SQL-User-Name}', '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}', '%{NAS-Port-Type}', unix_timestamp('%S'), NULL, '0', '%{Acct-Authentic}', '%{Connect-Info}', '', '0', '0', '%{Called-Station-Id}', '%{Calling-Station-Id}', ' rlm_sql (logsql): Reserving sql socket id: 2 rlm_sql (logsql): Released sql socket id: 2 ++[logsql] returns ok ++? if (noop) ? Evaluating (noop) -> FALSE ++? if (noop) -> FALSE } # server eduroamlocal-bsql Finished request 26505. Cleaning up request 26505 ID 7207 with timestamp +2931 Going to the next request Detail listener /var/log/radius/radacct/eduroamlocal-bsql/detail-bsql.log state replied signalled 0 waiting 0.048159 sec Cleaning up request 26416 ID 97 with timestamp +2927 Cleaning up request 26417 ID 160 with timestamp +2927 Detail - unlinking /var/log/radius/radacct/eduroamlocal-bsql/detail-bsql.log.work Detail listener /var/log/radius/radacct/eduroamlocal-bsql/detail-bsql.log state unopened signalled 0 waiting 10.012545 sec Cleaning up request 26418 ID 98 with timestamp +2927 Cleaning up request 26419 ID 161 with timestamp +2927 rad_recv: Accounting-Request packet from host 172.17.107.208 port 32770, id=28, length=166 User-Name = "B" NAS-Port = 29 NAS-IP-Address = 172.17.107.208 Framed-IP-Address = 172.21.97.41 NAS-Identifier = "wism8" Airespace-Wlan-Id = 2 Acct-Session-Id = "4c0cd978/00:1d:e0:98:92:03/27101" Acct-Authentic = RADIUS Tunnel-Type:0 = VLAN Tunnel-Medium-Type:0 = IEEE-802 Tunnel-Private-Group-Id:0 = "504" Acct-Status-Type = Start Calling-Station-Id = "00-1d-e0-98-92-03" Called-Station-Id = "00-1e-f7-d7-06-40" server uobroam { +- entering group preacct {...} ++[preprocess] returns ok [acct_unique] Hashing 'NAS-Port = 29,Client-IP-Address = 172.17.107.208,NAS-IP-Address = 172.17.107.208,Acct-Session-Id = "4c0cd978/00:1d:e0:98:92:03/27101",User-Name = "B"' [acct_unique] Acct-Unique-Session-ID = "67468389b00deb11". ++[acct_unique] returns ok ++[files] returns noop +- entering group accounting {...} [detail] expand: %{Virtual-Server} -> uobroam [detail] expand: /var/log/radius/radacct/%{%{Virtual-Server}:-UNKNOWN}/detail-%Y%m%d.log -> /var/log/radius/radacct/uobroam/detail-20100607.log [detail] /var/log/radius/radacct/%{%{Virtual-Server}:-UNKNOWN}/detail-%Y%m%d.log expands to /var/log/radius/radacct/uobroam/detail-20100607.log [detail] expand: %t -> Mon Jun 7 11:35:20 2010 ++[detail] returns ok [detail-bsql] expand: %{Virtual-Server} -> uobroam [detail-bsql] expand: /var/log/radius/radacct/%{%{Virtual-Server}:-UNKNOWN}-bsql/detail-bsql.log -> /var/log/radius/radacct/uobroam-bsql/detail-bsql.log [detail-bsql] /var/log/radius/radacct/%{%{Virtual-Server}:-UNKNOWN}-bsql/detail-bsql.log expands to /var/log/radius/radacct/uobroam-bsql/detail-bsql.log [detail-bsql] expand: %t -> Mon Jun 7 11:35:20 2010 ++[detail-bsql] returns ok [attr_filter.accounting_response] expand: %{User-Name} -> B attr_filter: Matched entry DEFAULT at line 12 ++[attr_filter.accounting_response] returns updated } # server uobroam Sending Accounting-Response of id 28 to 172.17.107.208 port 32770 Finished request 26506. Cleaning up request 26506 ID 28 with timestamp +2931 Going to the next request Cleaning up request 26421 ID 182 with timestamp +2927 Cleaning up request 26423 ID 162 with timestamp +2927 Marking home server 194.82.174.185 port 1812 as zombie (it looks like it is dead). Sending Status-Server of id 142 to 194.82.174.185 port 1812 Message-Authenticator := 0x00000000000000000000000000000000 NAS-Identifier := "Status Check. Are you alive?" Waking up in 0.1 seconds. rad_recv: Access-Request packet from host 172.17.107.203 port 32770, id=235, length=378 Discarding duplicate request from client WISM3 port 32770 - ID: 235 due to unfinished request 26270 Waking up in 0.1 seconds. Cleaning up request 26424 ID 163 with timestamp +2927 Cleaning up request 26425 ID 183 with timestamp +2928 rad_recv: Access-Accept packet from host 194.82.174.185 port 1812, id=142, length=840