[RADIATOR] Idle timeout issue

Fri Apr 13 09:04:01 CDT 2012

Hi Mike,

This never used to happen before. Prior to this issue, all sessions would
run continuously for the entire provisioned time (which was what we
wanted). Eg. A 2 hour pass would have a 2 hour continuous session until it
ran out, regardless of whether the laptop went into sleep
mode/shutdown/rebooted…etc.

This issue just cropped up recently. I read somewhere that the mysql
database might be sending a kill switch but I’m not sure as I’m a mysql
novice. I have however checked my database and can’t see anything regarding
idle timeout or session timeout, but then again, I could be looking in the
wrong place. L

Really appreciate your help and response as this is an issue I would love
to solve before we launch our hotspot service.

Best regards,

Jay

*From:* Mike Puchol [mailto:puchol at me.com]
*Sent:* Saturday, 14 April 2012 2:42 AM
*To:* Jennings Tuala
*Cc:* radiator at open.com.au
*Subject:* Re: [RADIATOR] Idle timeout issue

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/20120414/b181f13c/attachment-0001.html 