[RADIATOR] Expiration Date Bug ?

Thu Sep 19 23:49:16 CDT 2013

Hello,

I am using RADIATOR 4.10 on x86 linux with MySQL DB.

I'm using Expiration attribute with format "October 20 2013 00:00:00" but
suprisingly the Radiator converted it to "1358611200" instead of the
correct value which is "1382198400".

mysql> select from_unixtime(1358611200);
+---------------------------+
| from_unixtime(1358611200) |
+---------------------------+
| 2013-01-20 00:00:00       |
+---------------------------+

mysql> select from_unixtime(1382198400);
+---------------------------+
| from_unixtime(1382198400) |
+---------------------------+
| 2013-10-20 00:00:00       |
+---------------------------+

here's the level 4 debug log,

Fri Sep 20 11:46:00 2013: DEBUG: Packet dump:
*** Received from xx.xx.xx.xx port 47400 ....
Code:       Access-Request
Identifier: 90
Authentic:  <187>n<195><204><165>}@<151>"<163><167><207><229><27><24><198>
Attributes:
        NAS-Identifier = "localdomain"
        NAS-IP-Address = xx.xx.xx.xx
        Message-Authenticator =
><159>j$+<133>K<212><210><250><9><169><154><240>S<11>
        Acct-Session-Id = "9649291-em1-2"
        NAS-Port = 2
        NAS-Port-Type = Ethernet
        Service-Type = Framed-User
        Framed-Protocol = PPP
        Calling-Station-Id = "f0bf975c58b2"
        Called-Station-Id = ""
        NAS-Port-Id = "em1"
        mpd-link = "em1-2"
        mpd-peer-ident = "MSRASV5.20 MSRAS-0-AGNES-VAIO
<166>Sr<140>JB<225>B<182><170><163>@<232>h<164><150>"
        Tunnel-Medium-Type = 0:802
        Tunnel-Client-Endpoint = f0:bf:97:5c:58:b2
        User-Name = "test1 at xxx.net"
        MS-CHAP-Challenge = <187><30>h.:<239><133><3><151><6><173>]<150>
<176><206>
        MS-CHAP2-Response =
<1><0>E<134>u<238><187><172><234><30><172><253><141><205>x_<131>+<0><0><0><0><0><0><0><0><186><174><147><30>s<25>&JY<25><196>-<191><220><139><179>r<196>o{<233><193><167>l
Fri Sep 20 11:46:00 2013: DEBUG: Handling request with Handler
'Realm=DEFAULT', Identifier ''
Fri Sep 20 11:46:00 2013: DEBUG:  Deleting session for test1 at xxx.net,
xx.xx.xx.xx, 2
Fri Sep 20 11:46:00 2013: DEBUG: do query to
'dbi:mysql:radius:xx.xx.xx.xx:3306': 'delete from ActiveSessions where
nasportid='2' and nasname='xx.xx.xx.xx'':
Fri Sep 20 11:46:00 2013: DEBUG: Handling with Radius::AuthFREERADIUSSQL:
Fri Sep 20 11:46:00 2013: DEBUG: Handling with Radius::AuthFREERADIUSSQL:
Fri Sep 20 11:46:00 2013: DEBUG: Query to
'dbi:mysql:radius:xx.xx.xx.xx:3306': 'SELECT id, UserName, Attribute,
Value, op FROM  radcheck WHERE Username=? ORDER BY id': test1 at xxx.net
Fri Sep 20 11:46:00 2013: DEBUG: Got user check row: 7323
test1 at xxx.netPassword xxxxxx ==
Fri Sep 20 11:46:00 2013: DEBUG: Got user check row: 7325
test1 at xxx.netExpiration October 20 2013 00:00:00 :=
Fri Sep 20 11:46:00 2013: DEBUG: Query to
'dbi:mysql:radius:xx.xx.xx.xx:3306': 'SELECT id, UserName, Attribute,
Value, op FROM radreply WHERE Username = ? ORDER BY id': test1 at xxx.net
Fri Sep 20 11:46:00 2013: DEBUG: Got user reply row: 5237
test1 at xx.netFramed-IP-Address 10.32.0.2 :=
Fri Sep 20 11:46:00 2013: DEBUG: Query to
'dbi:mysql:radius:xx.xx.xx.xx:3306': 'SELECT
radgroupcheck.id,radgroupcheck.GroupName,radgroupcheck.Attribute,radgroupcheck.Value,radgroupcheck.op
FROM radgroupcheck,radusergroup WHERE radusergroup.Username = ? AND
radusergroup.GroupName = radgroupcheck.GroupName ORDER BY radgroupcheck.id':
test1 at xxx.net
Fri Sep 20 11:46:00 2013: DEBUG: Query to
'dbi:mysql:radius:xx.xx.xx.xx:3306': 'SELECT
radgroupreply.id,radgroupreply.GroupName,radgroupreply.Attribute,radgroupreply.Value,radgroupreply.op
FROM radgroupreply,radusergroup WHERE radusergroup.Username = ? AND
radusergroup.GroupName = radgroupreply.GroupName ORDER BY radgroupreply.id':
test1 at xxx.net
Fri Sep 20 11:46:00 2013: DEBUG: Got group reply row: 19 Prepaid Standard
Acct-Interim-Interval 60 =
Fri Sep 20 11:46:00 2013: DEBUG: Got group reply row: 21 Prepaid Standard
mpd-limit in#1=all shape 384000 +=
Fri Sep 20 11:46:00 2013: DEBUG: Got group reply row: 22 Prepaid Standard
mpd-limit out#1=all shape 1024000 +=
Fri Sep 20 11:46:00 2013: DEBUG: Radius::AuthFREERADIUSSQL looks for match
with test1 at xxx.net [test1 at xxx.net]
Fri Sep 20 11:46:00 2013: DEBUG: Expiration date converted to: 1358611200
Fri Sep 20 11:46:00 2013: DEBUG: Radius::AuthFREERADIUSSQL REJECT:
Expiration date has passed: test1 at xxx.net [test1 at xxx.net]
Fri Sep 20 11:46:00 2013: DEBUG: Query to
'dbi:mysql:radius:xx.xx.xx.xx:3306': 'SELECT id, UserName, Attribute,
Value, op FROM  radcheck WHERE Username=? ORDER BY id': DEFAULT
Fri Sep 20 11:46:00 2013: DEBUG: AuthBy FREERADIUSSQL result: REJECT,
Expiration date has passed
Fri Sep 20 11:46:00 2013: INFO: Access rejected for test1 at xxx.net:
Expiration date has passed
Fri Sep 20 11:46:00 2013: DEBUG: do query to 'dbi:mysql:radius:
192.168.66.66:3306': 'insert into radpostauth (username,reply) values('
test1 at xxx.net','Access-Reject')':
Fri Sep 20 11:46:00 2013: DEBUG: Packet dump:
*** Sending to 210.195.155.242 port 47400 ....
Code:       Access-Reject
Identifier: 90
Authentic:  h<253><25><171><164><248>|4<138>~<200><222><156><20>`<154>
Attributes:
        MS-CHAP2-Success = "<1>S=DD72975DA9372B033A52115BCD039F1445C5B613"
        Reply-Message = "Request Denied"

username/password/ip addresses have been amended to protect client privacy.

Best Regards,

steve
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://www.open.com.au/pipermail/radiator/attachments/20130920/04959718/attachment.html 