[RADIATOR] Idle timeout issue
Mike Puchol
puchol at me.com
Fri Apr 13 08:42:27 CDT 2012
Acct terminate cause is User-Request, meaning the hotspot received a session end instruction from the device, eg. logoff URL, or a disassociation which the hotspot translates as User-Request (eg. laptop going to sleep). There is no Session-Timeout or Idle-Timeout in the logs that would correspond to what you describe.
On Apr 13, 2012, at 3:26 PM, Jennings Tuala <jtuala at blueskysamoa.com> wrote:
> Hi there,
>
> I’m having some issues with idle timeouts in radiator. Users are suddenly being disconnected after say 25 minutes of inactivity. This never used to happen before so I attached a trace 4 debug for you to have a look at. Would greatly appreciate your assistance please.
>
> Thanks,
> Jay
>
>
> Tue Apr 10 15:48:32 2012: DEBUG: Packet dump:
> *** Received from 110.5.112.85 port 32817 ....
> Code: Access-Request
> Identifier: 29
> Authentic: <137><202><239><165><163>W<22><229>Xfg<168>&<144><174><216>
> Attributes:
> User-Name = "6100510"
> User-Password = @[<4>=<161><221><154>u<141><0><143><5><1><165>_<250>
> NAS-IP-Address = 110.5.112.85
> Service-Type = Login-User
> Framed-IP-Address = 10.17.4.212
> Called-Station-Id = "00:90:0B:05:6B:14"
> Calling-Station-Id = "38:59:f9:80:c8:5d"
> NAS-Identifier = "110.5.112.85"
> Acct-Session-Id = "00:90:0B:05:6B:14:133411720333317"
> NAS-Port-Type = Wireless-IEEE-802-11
>
> Tue Apr 10 15:48:32 2012: DEBUG: Handling request with Handler '', Identifier ''
> Tue Apr 10 15:48:32 2012: DEBUG: Deleting session for 6100510, 110.5.112.85,
> Tue Apr 10 15:48:32 2012: DEBUG: do query is: 'delete from RADONLINE where NASIDENTIFIER='110.5.112.85' and NASPORT=0':
> Tue Apr 10 15:48:32 2012: DEBUG: Handling with Radius::AuthSQL:
> Tue Apr 10 15:48:32 2012: DEBUG: Handling with Radius::AuthSQL:
> Tue Apr 10 15:48:32 2012: DEBUG: Query is: 'select PASSWORD, SESSIONTIMEOUT from SUBSCRIBERS where USERNAME='6100510' and SESSIONTIMEOUT > 0':
> Tue Apr 10 15:48:32 2012: DEBUG: Radius::AuthSQL looks for match with 6100510 [6100510]
> Tue Apr 10 15:48:32 2012: DEBUG: Radius::AuthSQL ACCEPT: : 6100510 [6100510]
> Tue Apr 10 15:48:32 2012: DEBUG: AuthBy SQL result: ACCEPT,
> Tue Apr 10 15:48:32 2012: DEBUG: Access accepted for 6100510
> Tue Apr 10 15:48:32 2012: DEBUG: Packet dump:
> *** Sending to 110.5.112.85 port 32817 ....
> Code: Access-Accept
> Identifier: 29
> Authentic: <253>;<226>m<181>{}V<28><250><198><209><179><151><176><224>
> Attributes:
> Session-Timeout = 86400
>
> Tue Apr 10 15:48:32 2012: DEBUG: Packet dump:
> *** Received from 110.5.112.85 port 32817 ....
> Code: Accounting-Request
> Identifier: 30
> Authentic: {<211>=c;_<160><152>Z<132><22><10>RE<253><3>
> Attributes:
> User-Name = "6100510"
> Acct-Status-Type = Start
> Acct-Session-Id = "00:90:0B:05:6B:14:133411720333317"
> Acct-Authentic = Local
> NAS-Identifier = "110.5.112.85"
> NAS-IP-Address = 110.5.112.85
> Calling-Station-Id = "38:59:f9:80:c8:5d"
> Called-Station-Id = "00:90:0B:05:6B:14"
> Framed-IP-Address = 10.17.4.212
> NAS-Port-Type = Wireless-IEEE-802-11
>
> Tue Apr 10 15:48:32 2012: DEBUG: Handling request with Handler '', Identifier ''
> Tue Apr 10 15:48:32 2012: DEBUG: Adding session for 6100510, 110.5.112.85,
> Tue Apr 10 15:48:32 2012: DEBUG: do query is: 'delete from RADONLINE where NASIDENTIFIER='110.5.112.85' and NASPORT=00':
> Tue Apr 10 15:48:32 2012: DEBUG: do query is: 'insert into SUBSCRIBERS (USERNAME, NASIDENTIFIER, NASPORT, ACCTSESSIONID, TIME_STAMP, FRAMEDIPADDRESS,
> NASPORTTYPE, SERVICETYPE, DNIS)':
> Tue Apr 10 15:48:32 2012: ERR: do failed for 'insert into SUBSCRIBERS (USERNAME, NASIDENTIFIER, NASPORT, ACCTSESSIONID, TIME_STAMP, FRAMEDIPADDRESS,
> NASPORTTYPE, SERVICETYPE, DNIS)': You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right
> syntax to use near '' at line 1
> Tue Apr 10 15:48:32 2012: ERR: do failed for 'insert into SUBSCRIBERS (USERNAME, NASIDENTIFIER, NASPORT, ACCTSESSIONID, TIME_STAMP, FRAMEDIPADDRESS,
> NASPORTTYPE, SERVICETYPE, DNIS)': You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right
> syntax to use near '' at line 1
> Tue Apr 10 15:48:32 2012: DEBUG: Handling with Radius::AuthSQL:
> Tue Apr 10 15:48:32 2012: DEBUG: Handling accounting with Radius::AuthSQL
> Tue Apr 10 15:48:32 2012: DEBUG: AuthBy SQL result: ACCEPT,
> Tue Apr 10 15:48:32 2012: DEBUG: Accounting accepted
> Tue Apr 10 15:48:32 2012: DEBUG: Packet dump:
> *** Sending to 110.5.112.85 port 32817 ....
> Code: Accounting-Response
> Identifier: 30
> Authentic: <159><4>g<161><5>4,Pq<29><169>L<159><251><160><242>
> Attributes:
>
>
> Tue Apr 10 16:14:18 2012: DEBUG: Packet dump:
> *** Received from 110.5.112.85 port 32817 ....
> Code: Accounting-Request
> Identifier: 29
> Authentic: <153>3B}f<158><172>Pb<30><192><139><246><23>h<148>
> Attributes:
> User-Name = "6100510"
> Acct-Status-Type = Stop
> Acct-Session-Id = "00:90:0B:05:6B:14:133411720333317"
> Acct-Authentic = RADIUS
> Acct-Session-Time = 1547
> NAS-Identifier = "110.5.112.85"
> NAS-IP-Address = 110.5.112.85
> Acct-Input-Octets = 1069902
> Acct-Output-Octets = 93367
> Acct-Input-Packets = 1017
> Acct-Output-Packets = 1087
> Calling-Station-Id = "38:59:f9:80:c8:5d"
> Called-Station-Id = "00:90:0B:05:6B:14"
> Framed-IP-Address = 10.17.4.212
> NAS-Port-Type = Wireless-IEEE-802-11
> Class = " "
> Acct-Terminate-Cause = User-Request
>
> Tue Apr 10 16:14:18 2012: DEBUG: Handling request with Handler '', Identifier ''
> Tue Apr 10 16:14:18 2012: DEBUG: Deleting session for 6100510, 110.5.112.85,
> Tue Apr 10 16:14:18 2012: DEBUG: do query is: 'delete from RADONLINE where NASIDENTIFIER='110.5.112.85' and NASPORT=0':
> Tue Apr 10 16:14:18 2012: DEBUG: Handling with Radius::AuthSQL:
> Tue Apr 10 16:14:18 2012: DEBUG: Handling accounting with Radius::AuthSQL
> Tue Apr 10 16:14:18 2012: DEBUG: do query is: 'update SUBSCRIBERS set SESSIONTIMEOUT="until Time" where USERNAME='6100510'':
> Tue Apr 10 16:14:18 2012: DEBUG: AuthBy SQL result: ACCEPT,
> Tue Apr 10 16:14:18 2012: DEBUG: Accounting accepted
> Tue Apr 10 16:14:18 2012: DEBUG: Packet dump:
> *** Sending to 110.5.112.85 port 32817 ....
> Code: Accounting-Response
> Identifier: 29
> Authentic: <203>W~n<225><215>'<26><206><28><183>}<161><181><240><6>
> Attributes:
>
> _______________________________________________
> radiator mailing list
> radiator at open.com.au
> http://www.open.com.au/mailman/listinfo/radiator
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://www.open.com.au/pipermail/radiator/attachments/20120413/d022dd6e/attachment.html
More information about the radiator
mailing list