radius server stopped receiving request

[Khapare Joshi]

Hi,

I have two freeradius server. Both of them were working well until. But one
the server stopped receiving auth request.

I stopped the radius server and run in debug mode, radiusd -X then all I
get is  below:

Request are coming but looks it is expanding the detail file then sends the
Sending Accounting-Response of id 26 to 192.168.1.1 port 32770 and nothing
else.

How do I fix this so my second server start processing users request and
authenticate them :?


# Executing section preacct from file /etc/raddb/sites-enabled/default
+- entering group preacct {...}
++[preprocess] returns ok
expand: %{Acct-Session-Time} -> 8
expand: %{Acct-Delay-Time} -> 0
expand:  %l - %{%{Acct-Session-Time}:-0} - %{%{Acct-Delay-Time}:-0} ->
 1413805547 - 8 - 0
expand: %{expr: %l - %{%{Acct-Session-Time}:-0} - %{%{Acct-Delay-Time}:-0}}
-> 1413805539
++[request] returns ok
[acct_unique] Hashing 'NAS-Port = 1,Client-IP-Address =
192.168.1.1,NAS-IP-Address = 192.168.1.1,Acct-Session-Id =
"5444f5e2/dc:86:d8:23:03:0d/290270",User-Name = "carpæ"'
[acct_unique] Acct-Unique-Session-ID = "f1f7ece2e5e57147".
++[acct_unique] returns ok
[suffix] No '@' in User-Name = "carlol", looking up realm NULL
[suffix] Found realm "NULL"
[suffix] Adding Stripped-User-Name = "carol"
[suffix] Adding Realm = "NULL"
[suffix] Accounting realm is LOCAL.
++[suffix] returns ok
[files] acct_users: Matched entry DEFAULT at line 22
[files] WARNING: Deprecated conditional expansion ":-".  See "man unlang"
for details
[files] expand: %{Framed-IP-Address:-%{NAS-IP-Address}} -> 192.168.5.2
++[files] returns ok
# Executing section accounting from file /etc/raddb/sites-enabled/default
+- entering group accounting {...}
[detail] expand: %{Packet-Src-IP-Address} -> 192.168.1.1
[detail] expand:
/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d
-> /var/log/radius/radacct/192.168.1.1/detail-20141020
[detail]
/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d
expands to /var/log/radius/radacct/192.168.1.1/detail-20141020
[detail] expand: %t -> Mon Oct 20 11:45:47 2014
++[detail] returns ok
[attr_filter.accounting_response] expand: %{User-Name} -> carol
attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
Sending Accounting-Response of id 26 to 192.168.1.1 port 32770
Finished request 62.
Cleaning up request 62 ID 26 with timestamp +5
Going to the next request
Ready to process requests.
rad_recv: Accounting-Request packet from host 192.168.1.1 port 32770,
id=27, length=264
User-Name = "jebish"
NAS-Port = 1
NAS-IP-Address = 192.168.1.1
Framed-IP-Address = 192.168.5.9
NAS-Identifier = "hinet-wlc00-hbio"
Airespace-Wlan-Id = 300
Acct-Session-Id = "5444f5ca/88:1f:a1:1b:f8:44/290236"
NAS-Port-Type = Wireless-802.11
Cisco-AVPair = "audit-session-id=82d0a5f800051d5a5444f5c7"
Acct-Authentic = RADIUS
Event-Timestamp = "Oct 20 2014 11:45:47 GMT"
Acct-Status-Type = Interim-Update
Acct-Input-Octets = 41102
Acct-Input-Gigawords = 0
Acct-Output-Octets = 55255
Acct-Output-Gigawords = 0
Acct-Input-Packets = 213
Acct-Output-Packets = 191
Acct-Session-Time = 32
Acct-Delay-Time = 0
Calling-Station-Id = "192.168.2.71"
Called-Station-Id = "192.168.1.1"
# Executing section preacct from file /etc/raddb/sites-enabled/default
+- entering group preacct {...}
++[preprocess] returns ok
expand: %{Acct-Session-Time} -> 32
expand: %{Acct-Delay-Time} -> 0
expand:  %l - %{%{Acct-Session-Time}:-0} - %{%{Acct-Delay-Time}:-0} ->
 1413805547 - 32 - 0
expand: %{expr: %l - %{%{Acct-Session-Time}:-0} - %{%{Acct-Delay-Time}:-0}}
-> 1413805515
++[request] returns ok
[acct_unique] Hashing 'NAS-Port = 1,Client-IP-Address =
192.168.1.1,NAS-IP-Address = 192.168.1.1,Acct-Session-Id =
"5444f5ca/88:1f:a1:1b:f8:44/290236",User-Name = "jebish"'
[acct_unique] Acct-Unique-Session-ID = "ae16dee06152c99c".
++[acct_unique] returns ok
[suffix] No '@' in User-Name = "jebish", looking up realm NULL
[suffix] Found realm "NULL"
[suffix] Adding Stripped-User-Name = "jebish"
[suffix] Adding Realm = "NULL"
[suffix] Accounting realm is LOCAL.
++[suffix] returns ok
[files] acct_users: Matched entry DEFAULT at line 22
[files] WARNING: Deprecated conditional expansion ":-".  See "man unlang"
for details
[files] expand: %{Framed-IP-Address:-%{NAS-IP-Address}} -> 192.168.3.74
++[files] returns ok
# Executing section accounting from file /etc/raddb/sites-enabled/default
+- entering group accounting {...}
[detail] expand: %{Packet-Src-IP-Address} -> 192.168.1.1
[detail] expand:
/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d
-> /var/log/radius/radacct/192.168.1.1/detail-20141020
[detail]
/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d
expands to /var/log/radius/radacct/192.168.1.1/detail-20141020
[detail] expand: %t -> Mon Oct 20 11:45:47 2014
++[detail] returns ok
[attr_filter.accounting_response] expand: %{User-Name} -> jenny
attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
Sending Accounting-Response of id 27 to 192.168.1.1 port 32770
Finished request 63.
Cleaning up request 63 ID 27 with timestamp +5
Going to the next request
Ready to process requests
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freeradius.org/pipermail/freeradius-users/attachments/20141020/f24a05e6/attachment.html>