I'm running FreeBSD 4.9 and 5.2.1 with freeradius version 0.9.3
Radius server is running on freeBSD ver. 4.9 and another server acted as
a NAS gear (with freebsd ver. 5.2.1)
I set the acct-interim-interval in access-reply as acct-interim-interval
= 300
Here's the debug with radiusd -X
--------------------------------
rad_recv: Access-Request packet from host 10.150.15.134:49386, id=116,
length=84
User-Name = "test"
Service-Type = Framed-User
Framed-Protocol = PPP
User-Password = "test"
NAS-Identifier = "nas1.eb.com.my"
NAS-Port-Type = Ethernet
NAS-Port = 49
modcall: entering group authorize for request 194
Invalid operator for item Suffix: reverting to '=='
Invalid operator for item Suffix: reverting to '=='
Invalid operator for item Suffix: reverting to '=='
modcall[authorize]: module "preprocess" returns ok for request 194
rlm_realm: No '@' in User-Name = "test", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix" returns noop for request 194
radius_xlat: 'test'
rlm_sql (sql): sql_set_user escaped user --> 'test'
radius_xlat: 'SELECT id,UserName,Attribute,Value,op FROM radcheck WHERE
Username = 'test' ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 4
radius_xlat: 'SELECT
radgroupcheck.id,radgroupcheck.GroupName,radgroupcheck.Attribute,radgroupcheck.Value,radgroupcheck.op
FROM radgroupcheck,usergroup WHERE usergroup.Username = 'test' AND
usergroup.GroupName = radgroupcheck.GroupName ORDER BY radgroupcheck.id'
radius_xlat: 'SELECT id,UserName,Attribute,Value,op FROM radreply WHERE
Username = 'test' ORDER BY id'
radius_xlat: 'SELECT
radgroupreply.id,radgroupreply.GroupName,radgroupreply.Attribute,radgroupreply.Value,radgroupreply.op
FROM radgroupreply,usergroup WHERE usergroup.Username = 'test' AND
usergroup.GroupName = radgroupreply.GroupName ORDER BY radgroupreply.id'
rlm_sql (sql): Released sql socket id: 4
modcall[authorize]: module "sql" returns ok for request 194
modcall: group authorize returns ok for request 194
rad_check_password: Found Auth-Type MD5
auth: type "MD5"
modcall: entering group authtype for request 194
rlm_pap: login attempt by "test" with password test
rlm_pap: Using password "098f6bcd4621d373cade4e832627b4f6" for user test
authentication.
rlm_pap: Using MD5 encryption.
rlm_pap: User authenticated succesfully
modcall[authenticate]: module "pap" returns ok for request 194
modcall: group authtype returns ok for request 194
modcall: entering group session for request 194
radius_xlat: 'test'
rlm_sql (sql): sql_set_user escaped user --> 'test'
radius_xlat: 'SELECT COUNT(*) FROM radacct WHERE UserName='test' AND
AcctStopTime = 0'
rlm_sql (sql): Reserving sql socket id: 3
rlm_sql (sql): Released sql socket id: 3
modcall[session]: module "sql" returns ok for request 194
modcall: group session returns ok for request 194
Login OK: [test] (from client pppoe-in4 port 49)
Sending Access-Accept of id 116 to 10.150.15.134:49386
Session-Timeout = 4294967295
Framed-Protocol = PPP
Framed-Compression = Van-Jacobson-TCP-IP
Acct-Interim-Interval = 300
Framed-Pool = "unrestricted"
Finished request 194
Going to the next request
--- Walking the entire request list ---
Waking up in 6 seconds...
rad_recv: Accounting-Request packet from host 10.150.15.134:49387,
id=120, length=114
User-Name = "test"
Service-Type = Framed-User
Framed-Protocol = PPP
Framed-IP-Address = 10.1.4.192
Framed-IP-Netmask = 0.0.0.0
NAS-Identifier = "nas1.eb.com.my"
NAS-Port-Type = Ethernet
NAS-Port = 49
Acct-Status-Type = Start
Acct-Session-Id = "55565-test1087374373"
Acct-Multi-Session-Id = ""
Acct-Delay-Time = 0
modcall: entering group preacct for request 195
modcall[preacct]: module "preprocess" returns noop for request 195
rlm_realm: No '@' in User-Name = "test", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[preacct]: module "suffix" returns noop for request 195
acct_users: Matched DEFAULT at 22
modcall[preacct]: module "files" returns ok for request 195
modcall: group preacct returns ok for request 195
modcall: entering group accounting for request 195
rlm_acct_unique: WARNING: Attribute NAS-Port-Id was not found in
request, unique ID MAY be inconsistent
rlm_acct_unique: Hashing ',Client-IP-Address =
10.150.15.134,NAS-IP-Address = 10.150.15.134,Acct-Session-Id =
"55565-test1087374373",User-Name = "test"'
rlm_acct_unique: Acct-Unique-Session-ID = "b7db01ced9959b7b".
modcall[accounting]: module "acct_unique" returns ok for request 195
radius_xlat: '/var/log/radacct/10.150.15.134/detail-20040616'
rlm_detail: /var/log/radacct/%{Client-IP-Address}/detail-%Y%m%d expands
to /var/log/radacct/10.150.15.134/detail-20040616
modcall[accounting]: module "detail" returns ok for request 195
modcall[accounting]: module "unix" returns ok for request 195
radius_xlat: 'test'
rlm_sql (sql): sql_set_user escaped user --> 'test'
radius_xlat: 'INSERT into radacct (RadAcctId, 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)
values('', '55565-test1087374373', 'b7db01ced9959b7b', 'test', '',
'10.150.15.134', '49', 'Ethernet', '2004-06-16 16:29:38', '0', '0', '',
'', '', '0', '0', '', '', '', 'Framed-User', 'PPP', '10.1.4.192', '0',
'0')'
rlm_sql (sql): Reserving sql socket id: 2
rlm_sql (sql): Released sql socket id: 2
modcall[accounting]: module "sql" returns ok for request 195
radius_xlat: '/var/log/radutmp'
radius_xlat: 'test'
modcall[accounting]: module "radutmp" returns ok for request 195
modcall: group accounting returns ok for request 195
radius_xlat: 'nothing'
Exec-Program: nothing
Sending Accounting-Response of id 120 to 10.150.15.134:49387
Finished request 195
Going to the next request
Waking up in 6 seconds...
--- Walking the entire request list ---
Cleaning up request 194 ID 116 with timestamp 40d004f2
Cleaning up request 195 ID 120 with timestamp 40d004f2
Nothing to do. Sleeping until we see a request.
rad_recv: Accounting-Request packet from host 10.150.15.134:49416,
id=52, length=144
User-Name = "test"
Service-Type = Framed-User
Framed-Protocol = PPP
Framed-IP-Address = 10.1.4.192
Framed-IP-Netmask = 0.0.0.0
NAS-Identifier = "nas1.eb.com.my"
NAS-Port-Type = Ethernet
NAS-Port = 49
Acct-Status-Type = Stop
Acct-Session-Id = "55565-test1087374373"
Acct-Multi-Session-Id = ""
Acct-Delay-Time = 0
Acct-Input-Octets = 1710500
Acct-Input-Packets = 28487
Acct-Output-Octets = 1411872
Acct-Output-Packets = 25212
Acct-Session-Time = 1399
modcall: entering group preacct for request 196
modcall[preacct]: module "preprocess" returns noop for request 196
rlm_realm: No '@' in User-Name = "test", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[preacct]: module "suffix" returns noop for request 196
modcall[preacct]: module "files" returns noop for request 196
modcall: group preacct returns noop for request 196
modcall: entering group accounting for request 196
rlm_acct_unique: WARNING: Attribute NAS-Port-Id was not found in
request, unique ID MAY be inconsistent
rlm_acct_unique: Hashing ',Client-IP-Address =
10.150.15.134,NAS-IP-Address = 10.150.15.134,Acct-Session-Id =
"55565-test1087374373",User-Name = "test"'
rlm_acct_unique: Acct-Unique-Session-ID = "b7db01ced9959b7b".
modcall[accounting]: module "acct_unique" returns ok for request 196
radius_xlat: '/var/log/radacct/10.150.15.134/detail-20040616'
rlm_detail: /var/log/radacct/%{Client-IP-Address}/detail-%Y%m%d expands
to /var/log/radacct/10.150.15.134/detail-20040616
modcall[accounting]: module "detail" returns ok for request 196
modcall[accounting]: module "unix" returns ok for request 196
radius_xlat: 'test'
rlm_sql (sql): sql_set_user escaped user --> 'test'
radius_xlat: 'UPDATE radacct SET AcctStopTime = '2004-06-16 16:52:57',
AcctSessionTime = '1399', AcctInputOctets = '1710500', AcctOutputOctets
= '1411872', AcctTerminateCause = '', AcctStopDelay = '0',
ConnectInfo_stop = '' WHERE AcctSessionId = '55565-test1087374373' AND
UserName = 'test' AND NASIPAddress = '10.150.15.134' AND AcctStopTime =
0'
rlm_sql (sql): Reserving sql socket id: 1
rlm_sql (sql): Released sql socket id: 1
modcall[accounting]: module "sql" returns ok for request 196
radius_xlat: '/var/log/radutmp'
radius_xlat: 'test'
modcall[accounting]: module "radutmp" returns ok for request 196
modcall: group accounting returns ok for request 196
Sending Accounting-Response of id 52 to 10.150.15.134:49416
Finished request 196
Going to the next request
--- Walking the entire request list ---
Waking up in 6 seconds...
--- Walking the entire request list ---
Cleaning up request 196 ID 52 with timestamp 40d00a69
Nothing to do. Sleeping until we see a request.
Here's the radacct in the /var/log/radacct/..
-------------------------------------------------
Wed Jun 16 16:29:38 2004
User-Name = "test"
Service-Type = Framed-User
Framed-Protocol = PPP
Framed-IP-Address = 10.1.4.192
Framed-IP-Netmask = 0.0.0.0
NAS-Identifier = "nas1.eb.com.my"
NAS-Port-Type = Ethernet
NAS-Port = 49
Acct-Status-Type = Start
Acct-Session-Id = "55565-test1087374373"
Acct-Multi-Session-Id = ""
Acct-Delay-Time = 0
NAS-IP-Address = 10.150.15.134
Client-IP-Address = 10.150.15.134
Acct-Unique-Session-Id = "b7db01ced9959b7b"
Timestamp = 1087374578
Wed Jun 16 16:52:57 2004
User-Name = "test"
Service-Type = Framed-User
Framed-Protocol = PPP
Framed-IP-Address = 10.1.4.192
Framed-IP-Netmask = 0.0.0.0
NAS-Identifier = "nas1.eb.com.my"
NAS-Port-Type = Ethernet
NAS-Port = 49
Acct-Status-Type = Stop
Acct-Session-Id = "55565-test1087374373"
Acct-Multi-Session-Id = ""
Acct-Delay-Time = 0
Acct-Input-Octets = 1710500
Acct-Input-Packets = 28487
Acct-Output-Octets = 1411872
Acct-Output-Packets = 25212
Acct-Session-Time = 1399
NAS-IP-Address = 10.150.15.134
Client-IP-Address = 10.150.15.134
Acct-Unique-Session-Id = "b7db01ced9959b7b"
Timestamp = 1087375977
I'm expecting a reply with Acct-Status-Type = Interim-Update. Is there
anything i missed out? Thanks in advance
-
List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
