Sat Jun 11 14:40:55 2011 : Info: [suffix] No such realm "NULL"
Sat Jun 11 14:40:55 2011 : Info: ++[suffix] returns noop
Sat Jun 11 14:40:55 2011 : Info: +- entering group accounting {...}
Sat Jun 11 14:40:55 2011 : Debug: 	expand: /var/log/freeradius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/freeradius/radacct/58.8.83.241/detail-20110611
Sat Jun 11 14:40:55 2011 : Info: [detail] /var/log/freeradius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/freeradius/radacct/58.8.83.241/detail-20110611
Sat Jun 11 14:40:55 2011 : Debug: 	expand: %t -> Sat Jun 11 14:40:55 2011
Sat Jun 11 14:40:55 2011 : Info: ++[detail] returns ok
Sat Jun 11 14:40:55 2011 : Info: ++[unix] returns ok
Sat Jun 11 14:40:55 2011 : Debug: 	expand: %{User-Name} -> Rpp205
Sat Jun 11 14:40:55 2011 : Info: [sql] sql_set_user escaped user --> 'Rpp205'
Sat Jun 11 14:40:55 2011 : Debug: 	expand: UPDATE radacct SET ClientIPAddress='%{Client-IP-Address}',AcctStopTime = '%S', AcctSessionTime = '%{Acct-Session-Time}', AcctInputOctets = '%{Acct-Input-Octets}', AcctOutputOctets = '%{Acct-Output-Octets}', AcctTerminateCause = '%{Acct-Terminate-Cause}', AcctStopDelay = '%{Acct-Delay-Time}', ConnectInfo_stop = '%{Connect-Info}'  ,AcctStatusType='%{Acct-Status-Type}',NASIdentifier='%{NAS-Identifier}',stop_stamp=unix_timestamp('%S'),now_stamp=unix_timestamp('%S') WHERE AcctSessionId = '%{Acct-Session-Id}' AND UserName = '%{SQL-User-Name}' AND NASIPAddress = '%{NAS-IP-Address}' -> UPDATE radacct SET ClientIPAddress='58.8.83.241',AcctStopTime = '2011-06-11 14:40:55', AcctSessionTime = '20049', AcctInputOctets = '10312906', AcctOutputOctets = '294327530', AcctTerminateCause = 'Lost-Carrier', AcctStopDelay = '', ConnectInfo_stop = ''  ,AcctStatusType='Stop',NASIdentifier='101',stop_stamp=unix_timestamp('2011-06-11 14:40:55'),now_stamp=u
nix_timestamp('2011-06-11 14:40:55') WHERE AcctSessionId = '4df2e7cc00000001' AND UserName = 'Rpp205' AND NASIPAddress = '0.0.0.0'
Sat Jun 11 14:40:55 2011 : Debug: rlm_sql (sql): Reserving sql socket id: 2
Sat Jun 11 14:40:55 2011 : Debug: WARNING: Deprecated conditional expansion ":-".  See "man unlang" for details
Sat Jun 11 14:40:55 2011 : Debug: WARNING: Deprecated conditional expansion ":-".  See "man unlang" for details
Sat Jun 11 14:40:55 2011 : Debug: 	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, AcctStartDelay, AcctStopDelay ,AcctStatusType,NASIdentifier,now_stamp,ClientIPAddress) 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-Octets}', '%{Acct-Output-Octets}', '%{Called-Station-Id}', '%{Calling-Station-Id}', '%{Acct-Terminate-Cause}', '%{Service-Type}', '%{Framed-Protocol}', '%{Framed-IP-Address}', '0', '%{Acct-Delay-Time}' ,'
%{Acct-Status-Type}','%{NAS-Identifier}',unix_timestamp('%S'),'%{Client-IP-Address}') -> 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 ,AcctStatusType,NASIdentifier,now_stamp,ClientIPAddress) values('4df2e7cc00000001', '2664aa3d46f31092', 'Rpp205', '', '0.0.0.0', '1', 'Wireless-802.11', DATE_SUB('2011-06-11 14:40:55', INTERVAL (20049 + 0) SECOND), '2011-06-11 14:40:55', '20049', '', '', '', '10312906', '294327530', '68-7F-74-26-F4-00', '00-1F-3C-09-17-A1', 'Lost-Carrier', '', '', '192.168.182.14', '0', '' ,'Stop','101',unix_timestamp('2011-06-11 14:40:55'),'58.8.83.241')
Sat Jun 11 14:40:55 2011 : Debug: rlm_sql (sql): Released sql socket id: 2
Sat Jun 11 14:40:55 2011 : Info: ++[sql] returns ok
Sat Jun 11 14:40:55 2011 : Debug: 	expand: %{User-Name} -> Rpp205
Sat Jun 11 14:40:55 2011 : Debug:  attr_filter: Matched entry DEFAULT at line 12
Sat Jun 11 14:40:55 2011 : Info: ++[attr_filter.accounting_response] returns updated
Sending Accounting-Response of id 43 to 58.8.83.241 port 3779
Sat Jun 11 14:40:55 2011 : Info: Finished request 1794.
Sat Jun 11 14:40:55 2011 : Info: Cleaning up request 1794 ID 43 with timestamp +3156
Sat Jun 11 14:40:55 2011 : Debug: Going to the next request
Sat Jun 11 14:40:55 2011 : Debug: Waking up in 1.8 seconds.