<br><br><div class="gmail_quote"><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div class="im">
>><br>
>> > examples from <a href="http://wiki.freeradius.org/Rlm_sqlcounter" target="_blank">http://wiki.freeradius.org/Rlm_sqlcounter</a>, but my counter<br>
>> > doesn't still increase.? What am I missing please.<br>
>><br>
>><br>
>> Do you have accounting records for that user in radacct?<br>
>><br>
><br>
> mmm. no Fajar, I have checked from mysql and do not have any record for<br>
> service type= Framed-User. I only have record for service type=NAS-Prompt-User.<br>
> What could be the reason why accounting records are not captured in radacct<br>
> for a Framed-User?<br>
<br>
</div>Does your NAS send accounting packets?<br></blockquote><br><div><br>Yes i found some accounting packets, but only for telnet logins users. My NAS is a Cisco 2960 switch.<br><br><br>Feb 28 15:16:07.111: AAA/ACCT/EXEC(00000118): Queueing record is UPDATE<br>
Feb 28 15:16:07.111: AAA/ACCT(00000118): Sending periodic record type=EXEC user=pam<br>Feb 28 15:16:07.111: AAA/ACCT(00000118): Accounting method=radius (RADIUS)<br>Feb 28 15:16:07.111: RADIUS/ENCODE(00000118):Orig. component type = EXEC<br>
Feb 28 15:16:07.111: RADIUS(00000118): Config NAS IP: 0.0.0.0<br>Feb 28 15:16:07.111: RADIUS/ENCODE: Best Local IP-Address 10.1.5.4 for Radius-Server 10.1.7.202<br>Feb 28 15:16:07.111: RADIUS(00000118): Send Accounting-Request to <a href="http://10.1.7.202:1813">10.1.7.202:1813</a> id 1646/45, len 132<br>
Feb 28 15:16:07.111: RADIUS(00000118): Started 5 sec timeout<br>Feb 28 15:16:07.111: RADIUS: Received from id 1646/45 <a href="http://10.1.7.202:1813">10.1.7.202:1813</a>, Accounting-response, len 20<br>Feb 28 15:16:07.111: AAA/ACCT/EXEC(00000118): UPDATE protocol reply PASS<br>
Feb 28 15:16:07.111: AAA/ACCT(00000118): Resetting Periodic timer<br>Feb 28 15:16:35.532: AAA/ACCT/HC(00000125): Update DOT1X/03194F10<br>Feb 28 15:16:35.532: AAA/ACCT/HC(00000125): DOT1X/03194F10 [pre-sess] (rx/tx) base 149953679/386909991 pre 150010061/386953971 c<br>
Feb 28 15:16:35.532: AAA/ACCT/HC(00000125): DOT1X/03194F10 [pre-sess] (rx/tx) adjusted, pre 56382/43980 call 0/0<br>Feb 28 15:16:35.532: AAA/ACCT/HC(00000125): Update DOT1X/03194F10<br>Feb 28 15:16:35.532: AAA/ACCT/HC(00000125): DOT1X/03194F10 [sess] (rx/tx) base 149953679/386909991 pre 150010061/386953971 call<br>
Feb 28 15:16:35.532: AAA/ACCT/HC(00000125): DOT1X/03194F10 [sess] (rx/tx) adjusted, pre 56382/43980 call 0/0<br>Feb 28 15:16:35.532: AAA/ACCT/HC(00000125): Deregister DOT1X/03194F10<br>Feb 28 15:16:35.540: AAA/ACCT/EVENT/(00000125): CALL STOP<br>
Feb 28 15:16:35.540: AAA/ACCT/CALL STOP(00000125): Sending stop requests<br>Feb 28 15:16:35.540: AAA/ACCT(00000125): Send all stops<br>Feb 28 15:16:35.540: AAA/ACCT/NET(00000125): STOP<br>Feb 28 15:16:35.540: AAA/ACCT/NET(00000125): Method list not found<br>
Feb 28 15:16:35.540: AAA/ACCT(00000125): del node, session 293<br>Feb 28 15:16:35.540: AAA/ACCT/NET(00000125): free_rec, count 0<br>Feb 28 15:16:35.540: AAA/ACCT/NET(00000125) reccnt 0, csr TRUE, osr 0<br>Feb 28 15:16:35.540: AAA/ACCT/NET(00000125): Last rec in db, intf not enqueued<br>
Feb 28 15:16:36.530: %LINEPROTO-5-UPDOWN: Line protocol on Interface FastEthernet0/9, changed state to down<br>Feb 28 15:16:37.218: AAA/ACCT/HC(00000126): Register DOT1X/03194F10 0bit/s, assuming 100Mbit/s, poll every 5m 0s<br>
Feb 28 15:16:37.218: AAA/ACCT/HC(00000126): Update DOT1X/03194F10<br>Feb 28 15:16:37.218: AAA/ACCT/HC(00000126): DOT1X/03194F10 [init-sess] (rx/tx) base 150010061/386953971 pre 150010061/386953971<br>Feb 28 15:16:37.218: AAA/ACCT/HC(00000126): DOT1X/03194F10 [init-sess] (rx/tx) adjusted, pre 0/0 call 0/0<br>
Feb 28 15:16:37.218: AAA/ACCT/EVENT/(00000126): CALL START<br>Feb 28 15:16:37.218: Getting session id for NET(00000126) : db=3567CE4<br>Feb 28 15:16:37.218: AAA/ACCT(00000000): add node, session 294<br>Feb 28 15:16:37.218: AAA/ACCT/NET(00000126): add, count 1<br>
Feb 28 15:16:37.218: Getting session id for DOT1X(00000126) : db=3567CE4<br>Feb 28 15:16:37.411: %AUTHMGR-5-START: Starting 'dot1x' for client (001e.33d5.7a68) on Interface Fa0/9 AuditSessionID 0A01050400<br>Feb 28 15:16:38.535: %LINEPROTO-5-UPDOWN: Line protocol on Interface FastEthernet0/9, changed state to up<br>
Feb 28 15:16:45.523: RADIUS/ENCODE(00000126):Orig. component type = DOT1X<br>Feb 28 15:16:45.523: RADIUS(00000126): Config NAS IP: 0.0.0.0<br>Feb 28 15:16:45.523: Getting session id for DOT1X(00000126) : db=3567CE4<br>Feb 28 15:16:45.523: RADIUS/ENCODE: Best Local IP-Address 10.1.5.4 for Radius-Server 10.1.7.202<br>
Feb 28 15:16:45.523: RADIUS(00000126): Send Access-Request to <a href="http://10.1.7.202:1812">10.1.7.202:1812</a> id 1645/179, len 193<br>Feb 28 15:16:45.523: RADIUS(00000126): Started 5 sec timeout<br>Feb 28 15:16:45.531: RADIUS: Received from id 1645/179 <a href="http://10.1.7.202:1812">10.1.7.202:1812</a>, Access-Challenge, len 76<br>
Feb 28 15:16:45.531: RADIUS/DECODE: EAP-Message fragments, 6, total 6 bytes<br>Feb 28 15:16:45.531: RADIUS/ENCODE(00000126):Orig. component type = DOT1X<br>Feb 28 15:16:45.531: RADIUS(00000126): Config NAS IP: 0.0.0.0<br>
Feb 28 15:16:45.531: Getting session id for DOT1X(00000126) : db=3567CE4<br>Feb 28 15:16:45.531: RADIUS/ENCODE: Best Local IP-Address 10.1.5.4 for Radius-Server 10.1.7.202<br>Feb 28 15:16:45.540: RADIUS(00000126): Send Access-Request to <a href="http://10.1.7.202:1812">10.1.7.202:1812</a> id 1645/180, len 352<br>
Feb 28 15:16:45.540: RADIUS(00000126): Started 5 sec timeout<br>Feb 28 15:16:45.607: RADIUS: Received from id 1645/180 <a href="http://10.1.7.202:1812">10.1.7.202:1812</a>, Access-Challenge, len 1112<br>Feb 28 15:16:45.607: RADIUS/DECODE: EAP-Message fragments, 253+253+253+253+22, total 1034 bytes<br>
Feb 28 15:16:45.615: RADIUS/ENCODE(00000126):Orig. component type = DOT1X<br>Feb 28 15:16:45.615: RADIUS(00000126): Config NAS IP: 0.0.0.0<br>Feb 28 15:16:45.615: Getting session id for DOT1X(00000126) : db=3567CE4<br>Feb 28 15:16:45.615: RADIUS/ENCODE: Best Local IP-Address 10.1.5.4 for Radius-Server 10.1.7.202<br>
Feb 28 15:16:45.615: RADIUS(00000126): Send Access-Request to <a href="http://10.1.7.202:1812">10.1.7.202:1812</a> id 1645/181, len 207<br>Feb 28 15:16:45.615: RADIUS(00000126): Started 5 sec timeout<br>Feb 28 15:16:45.623: RADIUS: Received from id 1645/181 <a href="http://10.1.7.202:1812">10.1.7.202:1812</a>, Access-Challenge, len 842<br>
Feb 28 15:16:45.623: RADIUS/DECODE: EAP-Message fragments, 253+253+253+7, total 766 bytes<br>Feb 28 15:16:45.632: RADIUS/ENCODE(00000126):Orig. component type = DOT1X<br>Feb 28 15:16:45.632: RADIUS(00000126): Config NAS IP: 0.0.0.0<br>
Feb 28 15:16:45.632: Getting session id for DOT1X(00000126) : db=3567CE4<br>Feb 28 15:16:45.632: RADIUS/ENCODE: Best Local IP-Address 10.1.5.4 for Radius-Server 10.1.7.202<br>Feb 28 15:16:45.632: RADIUS(00000126): Send Access-Request to <a href="http://10.1.7.202:1812">10.1.7.202:1812</a> id 1645/182, len 409<br>
Feb 28 15:16:45.632: RADIUS(00000126): Started 5 sec timeout<br>Feb 28 15:16:45.732: RADIUS: Received from id 1645/182 <a href="http://10.1.7.202:1812">10.1.7.202:1812</a>, Access-Challenge, len 135<br>Feb 28 15:16:45.732: RADIUS/DECODE: EAP-Message fragments, 65, total 65 bytes<br>
Feb 28 15:16:45.741: RADIUS/ENCODE(00000126):Orig. component type = DOT1X<br>Feb 28 15:16:45.741: RADIUS(00000126): Config NAS IP: 0.0.0.0<br>Feb 28 15:16:45.741: Getting session id for DOT1X(00000126) : db=3567CE4<br>Feb 28 15:16:45.741: RADIUS/ENCODE: Best Local IP-Address 10.1.5.4 for Radius-Server 10.1.7.202<br>
Feb 28 15:16:45.741: RADIUS(00000126): Send Access-Request to <a href="http://10.1.7.202:1812">10.1.7.202:1812</a> id 1645/183, len 207<br>Feb 28 15:16:45.741: RADIUS(00000126): Started 5 sec timeout<br>Feb 28 15:16:45.749: RADIUS: Received from id 1645/183 <a href="http://10.1.7.202:1812">10.1.7.202:1812</a>, Access-Challenge, len 113<br>
Feb 28 15:16:45.749: RADIUS/DECODE: EAP-Message fragments, 43, total 43 bytes<br>Feb 28 15:16:45.758: RADIUS/ENCODE(00000126):Orig. component type = DOT1X<br>Feb 28 15:16:45.758: RADIUS(00000126): Config NAS IP: 0.0.0.0<br>
Feb 28 15:16:45.758: Getting session id for DOT1X(00000126) : db=3567CE4<br>Feb 28 15:16:45.758: RADIUS/ENCODE: Best Local IP-Address 10.1.5.4 for Radius-Server 10.1.7.202<br>Feb 28 15:16:45.758: RADIUS(00000126): Send Access-Request to <a href="http://10.1.7.202:1812">10.1.7.202:1812</a> id 1645/184, len 244<br>
Feb 28 15:16:45.758: RADIUS(00000126): Started 5 sec timeout<br>Feb 28 15:16:45.850: RADIUS: Received from id 1645/184 <a href="http://10.1.7.202:1812">10.1.7.202:1812</a>, Access-Challenge, len 129<br>Feb 28 15:16:45.850: RADIUS/DECODE: EAP-Message fragments, 59, total 59 bytes<br>
Feb 28 15:16:45.867: RADIUS/ENCODE(00000126):Orig. component type = DOT1X<br>Feb 28 15:16:45.867: RADIUS(00000126): Config NAS IP: 0.0.0.0<br>Feb 28 15:16:45.867: Getting session id for DOT1X(00000126) : db=3567CE4<br>Feb 28 15:16:45.867: RADIUS/ENCODE: Best Local IP-Address 10.1.5.4 for Radius-Server 10.1.7.202<br>
Feb 28 15:16:45.867: RADIUS(00000126): Send Access-Request to <a href="http://10.1.7.202:1812">10.1.7.202:1812</a> id 1645/185, len 308<br>Feb 28 15:16:45.867: RADIUS(00000126): Started 5 sec timeout<br>Feb 28 15:16:46.060: RADIUS: Received from id 1645/185 <a href="http://10.1.7.202:1812">10.1.7.202:1812</a>, Access-Challenge, len 161<br>
Feb 28 15:16:46.060: RADIUS/DECODE: EAP-Message fragments, 91, total 91 bytes<br>Feb 28 15:16:46.068: RADIUS/ENCODE(00000126):Orig. component type = DOT1X<br>Feb 28 15:16:46.068: RADIUS(00000126): Config NAS IP: 0.0.0.0<br>
Feb 28 15:16:46.068: Getting session id for DOT1X(00000126) : db=3567CE4<br>Feb 28 15:16:46.068: RADIUS/ENCODE: Best Local IP-Address 10.1.5.4 for Radius-Server 10.1.7.202<br>Feb 28 15:16:46.068: RADIUS(00000126): Send Access-Request to <a href="http://10.1.7.202:1812">10.1.7.202:1812</a> id 1645/186, len 244<br>
Feb 28 15:16:46.068: RADIUS(00000126): Started 5 sec timeout<br>Feb 28 15:16:46.076: RADIUS: Received from id 1645/186 <a href="http://10.1.7.202:1812">10.1.7.202:1812</a>, Access-Challenge, len 113<br>Feb 28 15:16:46.076: RADIUS/DECODE: EAP-Message fragments, 43, total 43 bytes<br>
Feb 28 15:16:46.085: RADIUS/ENCODE(00000126):Orig. component type = DOT1X<br>Feb 28 15:16:46.085: RADIUS(00000126): Config NAS IP: 0.0.0.0<br>Feb 28 15:16:46.085: Getting session id for DOT1X(00000126) : db=3567CE4<br>Feb 28 15:16:46.085: RADIUS/ENCODE: Best Local IP-Address 10.1.5.4 for Radius-Server 10.1.7.202<br>
Feb 28 15:16:46.085: RADIUS(00000126): Send Access-Request to <a href="http://10.1.7.202:1812">10.1.7.202:1812</a> id 1645/187, len 244<br>Feb 28 15:16:46.085: RADIUS(00000126): Started 5 sec timeout<br>Feb 28 15:16:46.177: RADIUS: Received from id 1645/187 <a href="http://10.1.7.202:1812">10.1.7.202:1812</a>, Access-Accept, len 180<br>
Feb 28 15:16:46.177: RADIUS/DECODE: EAP-Message fragments, 4, total 4 bytes<br>Feb 28 15:16:46.177: %DOT1X-5-SUCCESS: Authentication successful for client (001e.33d5.7a68) on Interface Fa0/9 AuditSessionID<br>Feb 28 15:16:46.177: %AUTHMGR-7-RESULT: Authentication result 'success' from 'dot1x' for client (001e.33d5.7a68) on Interface Fa<br>
Feb 28 15:16:47.217: %AUTHMGR-5-SUCCESS: Authorization succeeded for client (001e.33d5.7a68) on Interface Fa0/9 AuditSessionID 0<br>Feb 28 15:17:07.619: AAA/ACCT/EXEC(00000118): Queueing record is UPDATE<br>Feb 28 15:17:07.619: AAA/ACCT(00000118): Sending periodic record type=EXEC user=pam<br>
Feb 28 15:17:07.619: AAA/ACCT(00000118): Accounting method=radius (RADIUS)<br>Feb 28 15:17:07.619: RADIUS/ENCODE(00000118):Orig. component type = EXEC<br></div><blockquote class="gmail_quote" style="margin:0pt 0pt 0pt 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex">
<br>
What does FR debug log shows like when it's receiving accounting packets?<br></blockquote><div><br><br>Debug log from FR looks like below. I am not sure if it recieves accounting packets.<br><br>Going to the next request<br>
Waking up in 6 seconds...<br>rad_recv: Access-Request packet from host <a href="http://10.1.5.4:1645">10.1.5.4:1645</a>, id=186, length=244<br> User-Name = "clare"<br> Service-Type = Framed-User<br>
Framed-MTU = 1500<br> Called-Station-Id = "EC-30-91-1D-69-89"<br> Calling-Station-Id = "00-1E-33-D5-7A-68"<br> EAP-Message = 0x0208002b19001703010020d06936f660c0fb15857fffd011a62ae19dfcc280b9bf19b74398bda313b99706<br>
Message-Authenticator = 0x0064b79e63b6b1ad0add9398f8ba9751<br> Cisco-AVPair = "audit-session-id=0A0105040000011F08185C13"<br> NAS-Port-Type = Ethernet<br> NAS-Port = 50009<br> NAS-Port-Id = "FastEthernet0/9"<br>
State = 0x00f91a7e57066443fa1652468504ce18<br> NAS-IP-Address = 10.1.5.4<br> Processing the authorize section of radiusd.conf<br>modcall: entering group authorize for request 100<br> modcall[authorize]: module "preprocess" returns ok for request 100<br>
modcall[authorize]: module "mschap" returns noop for request 100<br> rlm_realm: No '@' in User-Name = "clare", looking up realm NULL<br> rlm_realm: No such realm "NULL"<br> modcall[authorize]: module "suffix" returns noop for request 100<br>
rlm_eap: EAP packet type response id 8 length 43<br> rlm_eap: No EAP Start, assuming it's an on-going EAP conversation<br> modcall[authorize]: module "eap" returns updated for request 100<br> users: Matched entry clare at line 97<br>
modcall[authorize]: module "files" returns ok for request 100<br>radius_xlat: 'clare'<br>rlm_sql (sql): sql_set_user escaped user --> 'clare'<br>radius_xlat: 'SELECT id, UserName, Attribute, Value, op FROM radcheck WHERE Username = 'clare' ORDER BY id'<br>
rlm_sql (sql): Reserving sql socket id: 0<br>rlm_sql (sql): User clare not found in radcheck<br>radius_xlat: 'SELECT <a href="http://radgroupcheck.id">radgroupcheck.id</a>,radgroupcheck.GroupName,radgroupcheck.Attribute,radgroupcheck.Value,radgroupcheck.op FROM radgroupcheck,usergroup WHERE usergroup.Username = 'clare' AND usergroup.GroupName = radgroupcheck.GroupName ORDER BY <a href="http://radgroupcheck.id">radgroupcheck.id</a>'<br>
radius_xlat: 'SELECT <a href="http://radgroupreply.id">radgroupreply.id</a>,radgroupreply.GroupName,radgroupreply.Attribute,radgroupreply.Value,radgroupreply.op FROM radgroupreply,usergroup WHERE usergroup.Username = 'clare' AND usergroup.GroupName = radgroupreply.GroupName ORDER BY <a href="http://radgroupreply.id">radgroupreply.id</a>'<br>
rlm_sql (sql): User clare not found in radgroupcheck<br>rlm_sql (sql): Released sql socket id: 0<br>rlm_sql (sql): User not found<br> modcall[authorize]: module "sql" returns notfound for request 100<br>rlm_sqlcounter: Entering module authorize code<br>
rlm_sqlcounter: Could not find Check item value pair<br> modcall[authorize]: module "noresetcounter" returns noop for request 100<br>rlm_sqlcounter: Entering module authorize code<br>sqlcounter_expand: 'SELECT SUM(AcctSessionTime - GREATEST((1330387200 - UNIX_TIMESTAMP(AcctStartTime)), 0)) FROM radacct WHERE UserName='%{User-Name}' AND UNIX_TIMESTAMP(AcctStartTime) + AcctSessionTime > '1330387200''<br>
radius_xlat: 'SELECT SUM(AcctSessionTime - GREATEST((1330387200 - UNIX_TIMESTAMP(AcctStartTime)), 0)) FROM radacct WHERE UserName='clare' AND UNIX_TIMESTAMP(AcctStartTime) + AcctSessionTime > '1330387200''<br>
sqlcounter_expand: '%{sqlcca3:SELECT SUM(AcctSessionTime - GREATEST((1330387200 - UNIX_TIMESTAMP(AcctStartTime)), 0)) FROM radacct WHERE UserName='clare' AND UNIX_TIMESTAMP(AcctStartTime) + AcctSessionTime > '1330387200'}'<br>
WARNING: Attempt to use unknown xlat function, or non-existent attribute in string %{sqlcca3:SELECT SUM(AcctSessionTime - GREATEST((1330387200 - UNIX_TIMESTAMP(AcctStartTime)), 0)) FROM radacct WHERE UserName='clare' AND UNIX_TIMESTAMP(AcctStartTime) + AcctSessionTime > '1330387200'}<br>
radius_xlat: ''<br>rlm_sqlcounter: (Check item - counter) is greater than zero<br>rlm_sqlcounter: Authorized user clare, check_item=120, counter=0<br>rlm_sqlcounter: Sent Reply-Item for user clare, Type=Session-Timeout, value=120<br>
modcall[authorize]: module "dailycounter" returns ok for request 100<br>modcall: leaving group authorize (returns updated) for request 100<br> rad_check_password: Found Auth-Type EAP<br>auth: type "EAP"<br>
Processing the authenticate section of radiusd.conf<br>modcall: entering group authenticate for request 100<br> rlm_eap: Request found, released from the list<br> rlm_eap: EAP/peap<br> rlm_eap: processing type peap<br>
rlm_eap_peap: Authenticate<br> rlm_eap_tls: processing TLS<br> eaptls_verify returned 7 <br> rlm_eap_tls: Done initial handshake<br> eaptls_process returned 7 <br> rlm_eap_peap: EAPTLS_OK<br> rlm_eap_peap: Session established. Decoding tunneled attributes.<br>
rlm_eap_peap: EAP type mschapv2<br> rlm_eap_peap: Tunneled data is valid.<br> PEAP: Got tunneled EAP-Message<br> EAP-Message = 0x020800061a03<br> PEAP: Setting User-Name to clare<br> PEAP: Adding old state with 3b b3<br>
PEAP: Sending tunneled request<br> EAP-Message = 0x020800061a03<br> FreeRADIUS-Proxied-To = 127.0.0.1<br> User-Name = "clare"<br> State = 0x3bb38a486193de91b9a05ac2529e2912<br> Processing the authorize section of radiusd.conf<br>
modcall: entering group authorize for request 100<br> modcall[authorize]: module "preprocess" returns ok for request 100<br> modcall[authorize]: module "mschap" returns noop for request 100<br> rlm_realm: No '@' in User-Name = "clare", looking up realm NULL<br>
rlm_realm: No such realm "NULL"<br> modcall[authorize]: module "suffix" returns noop for request 100<br> rlm_eap: EAP packet type response id 8 length 6<br> rlm_eap: No EAP Start, assuming it's an on-going EAP conversation<br>
modcall[authorize]: module "eap" returns updated for request 100<br> users: Matched entry clare at line 97<br> modcall[authorize]: module "files" returns ok for request 100<br>radius_xlat: 'clare'<br>
rlm_sql (sql): sql_set_user escaped user --> 'clare'<br>radius_xlat: 'SELECT id, UserName, Attribute, Value, op FROM radcheck WHERE Username = 'clare' ORDER BY id'<br>
rlm_sql (sql): Reserving sql socket id: 4<br>rlm_sql (sql): User clare not found in radcheck<br>radius_xlat: 'SELECT <a href="http://radgroupcheck.id">radgroupcheck.id</a>,radgroupcheck.GroupName,radgroupcheck.Attribute,radgroupcheck.Value,radgroupcheck.op FROM radgroupcheck,usergroup WHERE usergroup.Username = 'clare' AND usergroup.GroupName = radgroupcheck.GroupName ORDER BY <a href="http://radgroupcheck.id">radgroupcheck.id</a>'<br>
radius_xlat: 'SELECT <a href="http://radgroupreply.id">radgroupreply.id</a>,radgroupreply.GroupName,radgroupreply.Attribute,radgroupreply.Value,radgroupreply.op FROM radgroupreply,usergroup WHERE usergroup.Username = 'clare' AND usergroup.GroupName = radgroupreply.GroupName ORDER BY <a href="http://radgroupreply.id">radgroupreply.id</a>'<br>
rlm_sql (sql): User clare not found in radgroupcheck<br>rlm_sql (sql): Released sql socket id: 4<br>rlm_sql (sql): User not found<br> modcall[authorize]: module "sql" returns notfound for request 100<br>rlm_sqlcounter: Entering module authorize code<br>
rlm_sqlcounter: Could not find Check item value pair<br> modcall[authorize]: module "noresetcounter" returns noop for request 100<br>rlm_sqlcounter: Entering module authorize code<br>sqlcounter_expand: 'SELECT SUM(AcctSessionTime - GREATEST((1330387200 - UNIX_TIMESTAMP(AcctStartTime)), 0)) FROM radacct WHERE UserName='%{User-Name}' AND UNIX_TIMESTAMP(AcctStartTime) + AcctSessionTime > '1330387200''<br>
radius_xlat: 'SELECT SUM(AcctSessionTime - GREATEST((1330387200 - UNIX_TIMESTAMP(AcctStartTime)), 0)) FROM radacct WHERE UserName='clare' AND UNIX_TIMESTAMP(AcctStartTime) + AcctSessionTime > '1330387200''<br>
sqlcounter_expand: '%{sqlcca3:SELECT SUM(AcctSessionTime - GREATEST((1330387200 - UNIX_TIMESTAMP(AcctStartTime)), 0)) FROM radacct WHERE UserName='clare' AND UNIX_TIMESTAMP(AcctStartTime) + AcctSessionTime > '1330387200'}'<br>
WARNING: Attempt to use unknown xlat function, or non-existent attribute in string %{sqlcca3:SELECT SUM(AcctSessionTime - GREATEST((1330387200 - UNIX_TIMESTAMP(AcctStartTime)), 0)) FROM radacct WHERE UserName='clare' AND UNIX_TIMESTAMP(AcctStartTime) + AcctSessionTime > '1330387200'}<br>
radius_xlat: ''<br>rlm_sqlcounter: (Check item - counter) is greater than zero<br>rlm_sqlcounter: Authorized user clare, check_item=120, counter=0<br>rlm_sqlcounter: Sent Reply-Item for user clare, Type=Session-Timeout, value=120<br>
modcall[authorize]: module "dailycounter" returns ok for request 100<br>modcall: leaving group authorize (returns updated) for request 100<br> rad_check_password: Found Auth-Type EAP<br>auth: type "EAP"<br>
Processing the authenticate section of radiusd.conf<br>modcall: entering group authenticate for request 100<br> rlm_eap: Request found, released from the list<br> rlm_eap: EAP/mschapv2<br> rlm_eap: processing type mschapv2<br>
rlm_eap: Freeing handler<br> modcall[authenticate]: module "eap" returns ok for request 100<br>modcall: leaving group authenticate (returns ok) for request 100<br>Login OK: [clare] (from client localhost port 0)<br>
PEAP: Got tunneled reply RADIUS code 2<br> Service-Type = Framed-User<br> Session-Timeout = 120<br> EAP-Message = 0x03080004<br> Message-Authenticator = 0x00000000000000000000000000000000<br>
User-Name = "clare"<br> PEAP: Processing from tunneled session code 0x9cec000 2<br> Service-Type = Framed-User<br> Session-Timeout = 120<br> EAP-Message = 0x03080004<br> Message-Authenticator = 0x00000000000000000000000000000000<br>
User-Name = "clare"<br> PEAP: Tunneled authentication was successful.<br> rlm_eap_peap: SUCCESS<br> modcall[authenticate]: module "eap" returns handled for request 100<br>modcall: leaving group authenticate (returns handled) for request 100<br>
Sending Access-Challenge of id 186 to 10.1.5.4 port 1645<br> Service-Type = Framed-User<br> Session-Timeout = 120<br> EAP-Message = 0x0109002b19001703010020894b0248edb8c12721f80e332c6365548e99e020c086b8db7ed0a3d462d6dc37<br>
Message-Authenticator = 0x00000000000000000000000000000000<br> State = 0xa91be12e67268f7d04712d947281391b<br>Finished request 100 <br></div><blockquote class="gmail_quote" style="margin:0pt 0pt 0pt 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex">
<span class="HOEnZb"><font color="#888888"><br>
</font></span></blockquote></div><br>