t Mon Feb 19 09:36:44 2001: DEBUG: Packet dump: *** Received from 127.0.0.1 port 1799 .... Code: Access-Request Identifier: 134 Authentic: 1234567890123456 Attributes: User-Name = "safwan@zajil.com" Service-Type = Framed-User NAS-IP-Address = 203.63.154.1 NAS-Port = 1234 NAS-Port-Type = Async User-Password = "<138><234>9<218><206>2<4><246><188>8<9><160><216>}x<153>" Mon Feb 19 09:36:44 2001: DEBUG: Handling request with Handler 'Realm=zajil.com' Mon Feb 19 09:36:44 2001: DEBUG: SDB1 Deleting session for safwan@zajil.com, 203.63.154.1, 1234 Mon Feb 19 09:36:44 2001: DEBUG: do query is: delete from RADONLINE where NASIDENTIFIER='203.63.154.1' and NASPORT=01234 Mon Feb 19 09:36:44 2001: DEBUG: Handling with Radius::AuthSQL Mon Feb 19 09:36:44 2001: DEBUG: Handling with Radius::AuthSQL Mon Feb 19 09:36:44 2001: DEBUG: Query is: select password, status from logins Where username='safwan@zajil.com' and status=0 Mon Feb 19 09:36:44 2001: ERR: Bad attribute=value pair: 0 Mon Feb 19 09:36:44 2001: DEBUG: Radius::AuthSQL looks for match with safwan@zajil.com Mon Feb 19 09:36:44 2001: DEBUG: Radius::AuthSQL ACCEPT: Mon Feb 19 09:36:44 2001: DEBUG: Handling with Radius::AuthDYNADDRESS Mon Feb 19 09:36:44 2001: DEBUG: Query is: select YIADDR, SUBNETMASK, DNSSERVER from RADPOOL where POOL='login' and STATE=0 order by TIME_STAMP Mon Feb 19 09:36:44 2001: DEBUG: do query is: update RADPOOL set STATE=1, TIME_STAMP=982564604, EXPIRY=982651004, USERNAME='safwan@zajil.com' where YIADDR='172.16.1.108 ' Mon Feb 19 09:36:44 2001: DEBUG: Access accepted for safwan@zajil.com Mon Feb 19 09:36:44 2001: DEBUG: Packet dump: *** Sending to 127.0.0.1 port 1799 .... Code: Access-Accept Identifier: 134 Authentic: 1234567890123456 Attributes: Framed-IP-Netmask = 255.255.255.192 Framed-IP-Address = 172.16.1.108 Mon Feb 19 09:36:44 2001: DEBUG: Packet dump: *** Received from 127.0.0.1 port 1799 .... Code: Accounting-Request Identifier: 135 Authentic: jS<218><165><144><199>b<204>s<165>'<149><168><238><22><241> Attributes: User-Name = "safwan@zajil.com" Service-Type = Framed-User NAS-IP-Address = 203.63.154.1 NAS-Port = 1234 NAS-Port-Type = Async Acct-Session-Id = "00001234" Acct-Status-Type = Start Framed-IP-Address = 172.16.1.108 Mon Feb 19 09:36:44 2001: DEBUG: Handling request with Handler 'Realm=zajil.com' Mon Feb 19 09:36:44 2001: DEBUG: SDB1 Adding session for safwan@zajil.com, 203.63.154.1, 1234 Mon Feb 19 09:36:44 2001: DEBUG: do query is: delete from RADONLINE where NASIDENTIFIER='203.63.154.1' and NASPORT=01234 Mon Feb 19 09:36:45 2001: DEBUG: do query is: insert into RADONLINE (USERNAME, NASIDENTIFIER, NASPORT, ACCTSESSIONID, TIME_STAMP, FRAMEDIPADDRESS, NASPORTTYPE, SERVICETYPE) values ('safwan@zajil.com', '203.63.154.1', 1234, '00001234', 982564604, '172.16.1.108', '', 'Framed-User') Mon Feb 19 09:36:45 2001: DEBUG: Handling with Radius::AuthSQL Mon Feb 19 09:36:45 2001: DEBUG: Handling accounting with Radius::AuthSQL Mon Feb 19 09:36:45 2001: DEBUG: do query is: insert into UsageOnlinehours (USERNAME, TIME_STAMP, ACCTSTATUSTYPE, ACCTSESSIONID, NASPORT, FRAMEDIPADDRESS) values ('safwan@zajil.com', 'Feb 19, 2001 09:36', 'Start', '00001234', 1234, '172.16.1.108') Mon Feb 19 09:36:45 2001: DEBUG: Handling with Radius::AuthDYNADDRESS Mon Feb 19 09:36:45 2001: DEBUG: Accounting accepted Mon Feb 19 09:36:45 2001: DEBUG: Packet dump: *** Sending to 127.0.0.1 port 1799 .... Code: Accounting-Response Identifier: 135 Authentic: jS<218><165><144><199>b<204>s<165>'<149><168><238><22><241> Attributes: Mon Feb 19 09:36:45 2001: DEBUG: Packet dump: *** Received from 127.0.0.1 port 1799 .... Code: Accounting-Request Identifier: 136 Authentic: /<7><255>*!<205><128><190>|M<183><130><142><9>xI Attributes: User-Name = "safwan@zajil.com" Service-Type = Framed-User NAS-IP-Address = 203.63.154.1 NAS-Port = 1234 NAS-Port-Type = Async Acct-Session-Id = "00001234" Acct-Status-Type = Stop Framed-IP-Address = 172.16.1.108 Acct-Delay-Time = 0 Acct-Session-Time = 1000 Acct-Input-Octets = 20000 Acct-Output-Octets = 30000 Mon Feb 19 09:36:45 2001: DEBUG: Handling request with Handler 'Realm=zajil.com' Mon Feb 19 09:36:45 2001: DEBUG: SDB1 Deleting session for safwan@zajil.com, 203.63.154.1, 1234 Mon Feb 19 09:36:45 2001: DEBUG: do query is: delete from RADONLINE where NASIDENTIFIER='203.63.154.1' and NASPORT=01234 Mon Feb 19 09:36:45 2001: DEBUG: Handling with Radius::AuthSQL Mon Feb 19 09:36:45 2001: DEBUG: Handling accounting with Radius::AuthSQL Mon Feb 19 09:36:45 2001: DEBUG: do query is: insert into UsageOnlinehours (USERNAME, TIME_STAMP, ACCTSTATUSTYPE, ACCTDELAYTIME, ACCTINPUTOCTETS, ACCTOUTPUTOCTETS, ACCTSESSIONID, ACCTSESSIONTIME, NASPORT, FRAMEDIPADDRESS) values ('safwan@zajil.com', 'Feb 19, 2001 09:36', 'Stop', 0, '20000', '30000', '00001234', 1000, 1234, '172.16.1.108') Mon Feb 19 09:36:45 2001: DEBUG: Handling with Radius::AuthDYNADDRESS Mon Feb 19 09:36:45 2001: DEBUG: do query is: update RADPOOL set STATE=0, TIME_STAMP=982564605 where YIADDR='172.16.1.108' Mon Feb 19 09:36:45 2001: DEBUG: Accounting accepted Mon Feb 19 09:36:45 2001: DEBUG: Packet dump: *** Sending to 127.0.0.1 port 1799 .... Code: Accounting-Response Identifier: 136 Authentic: /<7><255>*!<205><128><190>|M<183><130><142><9>xI Attributes: Mon Feb 19 09:42:53 2001: DEBUG: Packet dump: *** Received from 212.24.225.118 port 1645 .... Code: Access-Request Identifier: 20 Authentic: <147>2<238><21>4J<5><134><144><133>N~Q<237>@0 Attributes: NAS-IP-Address = 212.24.225.118 NAS-Port = 42 Cisco-NAS-Port = "Async42" NAS-Port-Type = Async User-Name = "safwan@zajil.com" User-Password = "<194><173><164>\<131><135><24><139><133>|HM<141><213>>S" Service-Type = Framed-User Framed-Protocol = PPP Mon Feb 19 09:42:53 2001: DEBUG: Handling request with Handler 'Realm=zajil.com' Mon Feb 19 09:42:53 2001: DEBUG: SDB1 Deleting session for safwan@zajil.com, 212.24.225.118, 42 Mon Feb 19 09:42:53 2001: DEBUG: do query is: delete from RADONLINE where NASIDENTIFIER='212.24.225.118' and NASPORT=042 Mon Feb 19 09:42:53 2001: DEBUG: Handling with Radius::AuthSQL Mon Feb 19 09:42:53 2001: DEBUG: Handling with Radius::AuthSQL Mon Feb 19 09:42:53 2001: DEBUG: Query is: select password, status from logins Where username='safwan@zajil.com' and status=0 Mon Feb 19 09:42:53 2001: ERR: Bad attribute=value pair: 0 Mon Feb 19 09:42:53 2001: DEBUG: Radius::AuthSQL looks for match with safwan@zajil.com Mon Feb 19 09:42:53 2001: DEBUG: Radius::AuthSQL ACCEPT: Mon Feb 19 09:42:53 2001: DEBUG: Handling with Radius::AuthDYNADDRESS Mon Feb 19 09:42:53 2001: DEBUG: Query is: select YIADDR, SUBNETMASK, DNSSERVER from RADPOOL where POOL='login' and STATE=0 order by TIME_STAMP Mon Feb 19 09:42:53 2001: DEBUG: do query is: update RADPOOL set STATE=1, TIME_STAMP=982564973, EXPIRY=982651373, USERNAME='safwan@zajil.com' where YIADDR='172.16.1.106 ' Mon Feb 19 09:42:53 2001: DEBUG: Access accepted for safwan@zajil.com Mon Feb 19 09:42:53 2001: DEBUG: Packet dump: *** Sending to 212.24.225.118 port 1645 .... Code: Access-Accept Identifier: 20 Authentic: <147>2<238><21>4J<5><134><144><133>N~Q<237>@0 Attributes: Framed-IP-Netmask = 255.255.255.192 Framed-IP-Address = 172.16.1.106 Mon Feb 19 09:42:58 2001: DEBUG: Packet dump: *** Received from 212.24.225.118 port 1646 .... Code: Accounting-Request Identifier: 21 Authentic: B<193>h<159><144><29>7<182><230><255><206><193><158><28><243>H Attributes: NAS-IP-Address = 212.24.225.118 NAS-Port = 42 Cisco-NAS-Port = "Async42" NAS-Port-Type = Async User-Name = "safwan@zajil.com" Acct-Status-Type = Start Acct-Authentic = RADIUS Service-Type = Framed-User Acct-Session-Id = "0000000A" Framed-Protocol = PPP Acct-Delay-Time = 0 Mon Feb 19 09:42:58 2001: DEBUG: Handling request with Handler 'Realm=zajil.com' Mon Feb 19 09:42:58 2001: DEBUG: SDB1 Adding session for safwan@zajil.com, 212.24.225.118, 42 Mon Feb 19 09:42:58 2001: DEBUG: do query is: delete from RADONLINE where NASIDENTIFIER='212.24.225.118' and NASPORT=042 Mon Feb 19 09:42:58 2001: DEBUG: do query is: insert into RADONLINE (USERNAME, NASIDENTIFIER, NASPORT, ACCTSESSIONID, TIME_STAMP, FRAMEDIPADDRESS, NASPORTTYPE, SERVICETYPE) values ('safwan@zajil.com', '212.24.225.118', 42, '0000000A', 982564978, '', '', 'Framed-User') Mon Feb 19 09:42:58 2001: DEBUG: Handling with Radius::AuthSQL Mon Feb 19 09:42:58 2001: DEBUG: Handling accounting with Radius::AuthSQL Mon Feb 19 09:42:58 2001: DEBUG: do query is: insert into UsageOnlinehours (USERNAME, TIME_STAMP, ACCTSTATUSTYPE, ACCTDELAYTIME, ACCTSESSIONID, NASPORT) values ('safwan@zajil.com', 'Feb 19, 2001 09:42', 'Start', 0, '0000000A', 42) Mon Feb 19 09:42:58 2001: DEBUG: Handling with Radius::AuthDYNADDRESS Mon Feb 19 09:42:58 2001: DEBUG: Accounting accepted Mon Feb 19 09:42:58 2001: DEBUG: Packet dump: *** Sending to 212.24.225.118 port 1646 .... Code: Accounting-Response Identifier: 21 Authentic: B<193>h<159><144><29>7<182><230><255><206><193><158><28><243>H Attributes: Mon Feb 19 09:43:02 2001: DEBUG: Packet dump: *** Received from 212.24.225.118 port 1646 .... Code: Accounting-Request Identifier: 22 Authentic: S<139><137>f<255><153>M<253><177><251>T<203><169><214>;Y Attributes: NAS-IP-Address = 212.24.225.118 NAS-Port = 42 Cisco-NAS-Port = "Async42" NAS-Port-Type = Async User-Name = "safwan@zajil.com" Acct-Status-Type = Stop Acct-Authentic = RADIUS Service-Type = Framed-User Acct-Session-Id = "0000000A" Framed-Protocol = PPP Acct-Link-Count = "<0><0><0><1>" Acct-Multi-Session-Id = "0" Acct-Terminate-Cause = Lost-Carrier Acct-Input-Octets = 96 Acct-Output-Octets = 102 Acct-Input-Packets = 5 Acct-Output-Packets = 6 Acct-Session-Time = 4 Acct-Delay-Time = 0 Mon Feb 19 09:43:02 2001: DEBUG: Handling request with Handler 'Realm=zajil.com' Mon Feb 19 09:43:02 2001: DEBUG: SDB1 Deleting session for safwan@zajil.com, 212.24.225.118, 42 Mon Feb 19 09:43:02 2001: DEBUG: do query is: delete from RADONLINE where NASIDENTIFIER='212.24.225.118' and NASPORT=042 Mon Feb 19 09:43:02 2001: DEBUG: Handling with Radius::AuthSQL Mon Feb 19 09:43:02 2001: DEBUG: Handling accounting with Radius::AuthSQL Mon Feb 19 09:43:02 2001: DEBUG: do query is: insert into UsageOnlinehours (USERNAME, TIME_STAMP, ACCTSTATUSTYPE, ACCTDELAYTIME, ACCTINPUTOCTETS, ACCTOUTPUTOCTETS, ACCTSESSIONID, ACCTSESSIONTIME, ACCTTERMINATECAUSE, NASPORT) values ('safwan@zajil.com', 'Feb 19, 2001 09:43', 'Stop', 0, '96', '102', '0000000A', 4, 'Lost-Carrier', 42) Mon Feb 19 09:43:02 2001: DEBUG: Handling with Radius::AuthDYNADDRESS Mon Feb 19 09:43:02 2001: DEBUG: do query is: update RADPOOL set STATE=0, TIME_STAMP=982564982 where YIADDR='' Mon Feb 19 09:43:02 2001: DEBUG: Accounting accepted Mon Feb 19 09:43:02 2001: DEBUG: Packet dump: *** Sending to 212.24.225.118 port 1646 .... Code: Accounting-Response Identifier: 22 Authentic: S<139><137>f<255><153>M<253><177><251>T<203><169><214>;Y Attributes: Mon Feb 19 09:43:08 2001: DEBUG: Packet dump: *** Received from 212.24.225.118 port 1646 .... Code: Accounting-Request Identifier: 23 Authentic: r2<149>Y8<208><128><191><147><31><180>On<136>K<244> Attributes: NAS-IP-Address = 212.24.225.118 NAS-Port = 42 Cisco-NAS-Port = "Async42" NAS-Port-Type = Async User-Name = "safwan@zajil.com" Acct-Status-Type = Stop Acct-Authentic = RADIUS Service-Type = Framed-User Acct-Session-Id = "0000000A" Framed-Protocol = PPP Acct-Link-Count = "<0><0><0><1>" Acct-Multi-Session-Id = "0" Acct-Terminate-Cause = Lost-Carrier Acct-Input-Octets = 96 Acct-Output-Octets = 102 Acct-Input-Packets = 5 Acct-Output-Packets = 6 Acct-Session-Time = 4 Acct-Delay-Time = 5 Mon Feb 19 09:43:08 2001: DEBUG: Handling request with Handler 'Realm=zajil.com' Mon Feb 19 09:43:08 2001: DEBUG: SDB1 Deleting session for safwan@zajil.com, 212.24.225.118, 42 Mon Feb 19 09:43:08 2001: DEBUG: do query is: delete from RADONLINE where NASIDENTIFIER='212.24.225.118' and NASPORT=042 Mon Feb 19 09:43:08 2001: DEBUG: Handling with Radius::AuthSQL Mon Feb 19 09:43:08 2001: DEBUG: Handling accounting with Radius::AuthSQL Mon Feb 19 09:43:08 2001: DEBUG: do query is: insert into UsageOnlinehours (USERNAME, TIME_STAMP, ACCTSTATUSTYPE, ACCTDELAYTIME, ACCTINPUTOCTETS, ACCTOUTPUTOCTETS, ACCTSESSIONID, ACCTSESSIONTIME, ACCTTERMINATECAUSE, NASPORT) values ('safwan@zajil.com', 'Feb 19, 2001 09:43', 'Stop', 5, '96', '102', '0000000A', 4, 'Lost-Carrier', 42) Mon Feb 19 09:43:08 2001: DEBUG: Handling with Radius::AuthDYNADDRESS Mon Feb 19 09:43:08 2001: DEBUG: do query is: update RADPOOL set STATE=0, TIME_STAMP=982564988 where YIADDR='' Mon Feb 19 09:43:08 2001: DEBUG: Accounting accepted Mon Feb 19 09:43:08 2001: DEBUG: Packet dump: *** Sending to 212.24.225.118 port 1646 .... Code: Accounting-Response Identifier: 23 Authentic: r2<149>Y8<208><128><191><147><31><180>On<136>K<244> Attributes: Mon Feb 19 09:43:15 2001: DEBUG: Packet dump: *** Received from 212.24.225.118 port 1646 .... Code: Accounting-Request Identifier: 24 Authentic: '<208>s"<250>4<228><136><250><216><154><29>j<196><248>Q Attributes: NAS-IP-Address = 212.24.225.118 NAS-Port = 42 Cisco-NAS-Port = "Async42" NAS-Port-Type = Async User-Name = "safwan@zajil.com" Acct-Status-Type = Stop Acct-Authentic = RADIUS Service-Type = Framed-User Acct-Session-Id = "0000000A" Framed-Protocol = PPP Acct-Link-Count = "<0><0><0><1>" Acct-Multi-Session-Id = "0" Acct-Terminate-Cause = Lost-Carrier Acct-Input-Octets = 96 Acct-Output-Octets = 102 Acct-Input-Packets = 5 Acct-Output-Packets = 6 Acct-Session-Time = 4 Acct-Delay-Time = 10 Mon Feb 19 09:43:15 2001: DEBUG: Handling request with Handler 'Realm=zajil.com' Mon Feb 19 09:43:16 2001: DEBUG: SDB1 Deleting session for safwan@zajil.com, 212.24.225.118, 42 Mon Feb 19 09:43:16 2001: DEBUG: do query is: delete from RADONLINE where NASIDENTIFIER='212.24.225.118' and NASPORT=042 Mon Feb 19 09:43:16 2001: DEBUG: Handling with Radius::AuthSQL Mon Feb 19 09:43:16 2001: DEBUG: Handling accounting with Radius::AuthSQL Mon Feb 19 09:43:16 2001: DEBUG: do query is: insert into UsageOnlinehours (USERNAME, TIME_STAMP, ACCTSTATUSTYPE, ACCTDELAYTIME, ACCTINPUTOCTETS, ACCTOUTPUTOCTETS, ACCTSESSIONID, ACCTSESSIONTIME, ACCTTERMINATECAUSE, NASPORT) values ('safwan@zajil.com', 'Feb 19, 2001 09:43', 'Stop', 10, '96', '102', '0000000A', 4, 'Lost-Carrier', 42) Mon Feb 19 09:43:16 2001: DEBUG: Handling with Radius::AuthDYNADDRESS Mon Feb 19 09:43:16 2001: DEBUG: do query is: update RADPOOL set STATE=0, TIME_STAMP=982564996 where YIADDR='' Mon Feb 19 09:43:16 2001: DEBUG: Accounting accepted Mon Feb 19 09:43:16 2001: DEBUG: Packet dump: *** Sending to 212.24.225.118 port 1646 .... Code: Accounting-Response Identifier: 24 Authentic: '<208>s"<250>4<228><136><250><216><154><29>j<196><248>Q Attributes: Mon Feb 19 09:43:20 2001: DEBUG: Packet dump: *** Received from 212.24.225.118 port 1646 .... Code: Accounting-Request Identifier: 25 Authentic: <247>p<173>r<1><0><183><8><19>5pf<159><150>w<168> Attributes: NAS-IP-Address = 212.24.225.118 NAS-Port = 42 Cisco-NAS-Port = "Async42" NAS-Port-Type = Async User-Name = "safwan@zajil.com" Acct-Status-Type = Stop Acct-Authentic = RADIUS Service-Type = Framed-User Acct-Session-Id = "0000000A" Framed-Protocol = PPP Acct-Link-Count = "<0><0><0><1>" Acct-Multi-Session-Id = "0" Acct-Terminate-Cause = Lost-Carrier Acct-Input-Octets = 96 Acct-Output-Octets = 102 Acct-Input-Packets = 5 Acct-Output-Packets = 6 Acct-Session-Time = 4 Acct-Delay-Time = 15 Mon Feb 19 09:43:20 2001: DEBUG: Handling request with Handler 'Realm=zajil.com' Mon Feb 19 09:43:20 2001: DEBUG: SDB1 Deleting session for safwan@zajil.com, 212.24.225.118, 42 Mon Feb 19 09:43:20 2001: DEBUG: do query is: delete from RADONLINE where NASIDENTIFIER='212.24.225.118' and NASPORT=042 Mon Feb 19 09:43:20 2001: DEBUG: Handling with Radius::AuthSQL Mon Feb 19 09:43:20 2001: DEBUG: Handling accounting with Radius::AuthSQL Mon Feb 19 09:43:20 2001: DEBUG: do query is: insert into UsageOnlinehours (USERNAME, TIME_STAMP, ACCTSTATUSTYPE, ACCTDELAYTIME, ACCTINPUTOCTETS, ACCTOUTPUTOCTETS, ACCTSESSIONID, ACCTSESSIONTIME, ACCTTERMINATECAUSE, NASPORT) values ('safwan@zajil.com', 'Feb 19, 2001 09:43', 'Stop', 15, '96', '102', '0000000A', 4, 'Lost-Carrier', 42) Mon Feb 19 09:43:20 2001: DEBUG: Handling with Radius::AuthDYNADDRESS Mon Feb 19 09:43:20 2001: DEBUG: do query is: update RADPOOL set STATE=0, TIME_STAMP=982565000 where YIADDR='' Mon Feb 19 09:43:20 2001: DEBUG: Accounting accepted Mon Feb 19 09:43:20 2001: DEBUG: Packet dump: *** Sending to 212.24.225.118 port 1646 .... Code: Accounting-Response Identifier: 25 Authentic: <247>p<173>r<1><0><183><8><19>5pf<159><150>w<168> Attributes: