Skip to content

Instantly share code, notes, and snippets.

@dzinevich

dzinevich/freeradius debug log

Created June 28, 2016 09:03
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save dzinevich/56b3259246b62a36d2ed62836b181fac to your computer and use it in GitHub Desktop.
Save dzinevich/56b3259246b62a36d2ed62836b181fac to your computer and use it in GitHub Desktop.
Download ZIP
Raw
freeradius debug log
Polling for detail file /var/log/freeradius/radacct/detail.example.com/detail-*:*
Detail listener /var/log/freeradius/radacct/detail.example.com/detail-*:* state unopened waiting 0.986246 sec
Received Accounting-Request Id 219 from 192.168.1.10:55683 to 192.168.1.10:1813 length 147
Acct-Session-Id = '57722C84726B00'
User-Name = 'user-paid'
Acct-Status-Type = Interim-Update
Service-Type = Framed-User
Framed-Protocol = PPP
Acct-Authentic = RADIUS
Acct-Session-Time = 180
Acct-Output-Octets = 3960
Acct-Input-Octets = 4644
Acct-Output-Packets = 16
Acct-Input-Packets = 50
Calling-Station-Id = '46.164.150.50'
NAS-Port-Type = Async
Framed-IP-Address = 10.20.20.2
NAS-Identifier = 'boxpn'
NAS-Port = 0
Acct-Delay-Time = 0
(4) Received Accounting-Request packet from host 192.168.1.10 port 55683, id=219, length=147
(4) Acct-Session-Id = '57722C84726B00'
(4) User-Name = 'user-paid'
(4) Acct-Status-Type = Interim-Update
(4) Service-Type = Framed-User
(4) Framed-Protocol = PPP
(4) Acct-Authentic = RADIUS
(4) Acct-Session-Time = 180
(4) Acct-Output-Octets = 3960
(4) Acct-Input-Octets = 4644
(4) Acct-Output-Packets = 16
(4) Acct-Input-Packets = 50
(4) Calling-Station-Id = '46.164.150.50'
(4) NAS-Port-Type = Async
(4) Framed-IP-Address = 10.20.20.2
(4) NAS-Identifier = 'boxpn'
(4) NAS-Port = 0
(4) Acct-Delay-Time = 0
(4) # Executing section preacct from file /etc/freeradius/sites-enabled/default
(4) preacct {
(4) [preprocess] = ok
(4) acct_unique acct_unique {
(4) if ("%{string:Class}" =~ /ai:([0-9a-f]{32})/i)
(4) EXPAND %{string:Class}
(4) -->
(4) if ("%{string:Class}" =~ /ai:([0-9a-f]{32})/i) -> FALSE
(4) else else {
(4) update request {
(4) EXPAND %{md5:%{User-Name},%{Acct-Session-ID},%{%{NAS-IPv6-Address}:-%{NAS-IP-Address}},%{NAS-Identifier},%{NAS-Port-ID},%{NAS-Port}}
(4) --> f64fc3243057e5a36e04e8430edb3ab4
(4) Acct-Unique-Session-Id := "f64fc3243057e5a36e04e8430edb3ab4"
(4) } # update request = noop
(4) } # else else = noop
(4) } # acct_unique acct_unique = noop
(4) suffix : Checking for suffix after "@"
(4) suffix : No '@' in User-Name = "user-paid", looking up realm NULL
(4) suffix : No such realm "NULL"
(4) [suffix] = noop
(4) [files] = noop
(4) } # preacct = ok
(4) # Executing section accounting from file /etc/freeradius/sites-enabled/default
(4) accounting {
(4) [unix] = noop
(4) update control {
(4) Proxy-To-Realm := 'acct_realm.example.com'
(4) ERROR: 'wait' must be enabled to use exec xlat
(4) EXPAND %{exec:/etc/freeradius/disconnect.sh}
(4) -->
(4) Tmp-String-0 := ""
(4) } # update control = noop
(4) attr_filter.accounting_response : EXPAND %{User-Name}
(4) attr_filter.accounting_response : --> user-paid
(4) attr_filter.accounting_response : Matched entry DEFAULT at line 12
(4) [attr_filter.accounting_response] = updated
(4) } # accounting = updated
(4) server home.example.com {
(4) }
(4) Proxying request to home server 192.168.1.1 port 1813 timeout 6.000000
(4) Sending Accounting-Request packet to host 192.168.1.1 port 1813, id=77, length=0
(4) Acct-Session-Id = '57722C84726B00'
(4) User-Name = 'user-paid'
(4) Acct-Status-Type = Interim-Update
(4) Service-Type = Framed-User
(4) Framed-Protocol = PPP
(4) Acct-Authentic = RADIUS
(4) Acct-Session-Time = 180
(4) Acct-Output-Octets = 3960
(4) Acct-Input-Octets = 4644
(4) Acct-Output-Packets = 16
(4) Acct-Input-Packets = 50
(4) Calling-Station-Id = '46.164.150.50'
(4) NAS-Port-Type = Async
(4) Framed-IP-Address = 10.20.20.2
(4) NAS-Identifier = 'boxpn'
(4) NAS-Port = 0
(4) Acct-Delay-Time = 0
(4) NAS-IP-Address = 192.168.1.10
(4) Event-Timestamp = 'Jun 28 2016 07:54:32 UTC'
(4) Acct-Unique-Session-Id := 'f64fc3243057e5a36e04e8430edb3ab4'
(4) Module-Failure-Message += ''wait' must be enabled to use exec xlat'
(4) Proxy-State = 0x323139
Sending Accounting-Request Id 77 from 0.0.0.0:21298 to 192.168.1.1:1813
Acct-Session-Id = '57722C84726B00'
User-Name = 'user-paid'
Acct-Status-Type = Interim-Update
Service-Type = Framed-User
Framed-Protocol = PPP
Acct-Authentic = RADIUS
Acct-Session-Time = 180
Acct-Output-Octets = 3960
Acct-Input-Octets = 4644
Acct-Output-Packets = 16
Acct-Input-Packets = 50
Calling-Station-Id = '46.164.150.50'
NAS-Port-Type = Async
Framed-IP-Address = 10.20.20.2
NAS-Identifier = 'boxpn'
NAS-Port = 0
Acct-Delay-Time = 0
NAS-IP-Address = 192.168.1.10
Event-Timestamp = 'Jun 28 2016 07:54:32 UTC'
Proxy-State = 0x323139
Waking up in 0.3 seconds.
Waking up in 0.1 seconds.
(4) Expecting proxy response no later than 5.499687 seconds from now
Waking up in 5.4 seconds.
Polling for detail file /var/log/freeradius/radacct/detail.example.com/detail-*:*
Detail listener /var/log/freeradius/radacct/detail.example.com/detail-*:* state unopened waiting 0.966468 sec
Polling for detail file /var/log/freeradius/radacct/detail.example.com/detail-*:*
Detail listener /var/log/freeradius/radacct/detail.example.com/detail-*:* state unopened waiting 0.997877 sec
Polling for detail file /var/log/freeradius/radacct/detail.example.com/detail-*:*
Detail listener /var/log/freeradius/radacct/detail.example.com/detail-*:* state unopened waiting 1.050359 sec
Polling for detail file /var/log/freeradius/radacct/detail.example.com/detail-*:*
Detail listener /var/log/freeradius/radacct/detail.example.com/detail-*:* state unopened waiting 0.880268 sec
Polling for detail file /var/log/freeradius/radacct/detail.example.com/detail-*:*
Detail listener /var/log/freeradius/radacct/detail.example.com/detail-*:* state unopened waiting 0.845904 sec
Polling for detail file /var/log/freeradius/radacct/detail.example.com/detail-*:*
Detail listener /var/log/freeradius/radacct/detail.example.com/detail-*:* state unopened waiting 0.939339 sec
(4) No proxy response, giving up on request and marking it done
Marking home server 192.168.1.1 port 1813 as zombie (it has not responded in 6.000000 seconds).
PING: Waiting 4 seconds for response to ping
Sending Status-Server Id 238 from 0.0.0.0:21298 to 192.168.1.1:1813
Message-Authenticator := 0x00
NAS-Identifier := 'Status Check 0. Are you alive?'
PING: Next status packet in 30 seconds
(4) ERROR: Failing proxied request, due to lack of any response from home server 192.168.1.1 port 1813
(4) Found Post-Proxy-Type Fail
(4) server home.example.com {
(4) # Executing group from file /etc/freeradius/sites-enabled/robust-proxy-accounting
(4) Post-Proxy-Type Fail {
(4) detail.example.com : EXPAND /var/log/freeradius/radacct/detail.example.com/detail-%Y%m%d:%H:%G
(4) detail.example.com : --> /var/log/freeradius/radacct/detail.example.com/detail-20160628:07:54
(4) detail.example.com : /var/log/freeradius/radacct/detail.example.com/detail-%Y%m%d:%H:%G expands to /var/log/freeradius/radacct/detail.example.com/detail-20160628:07:54
(4) detail.example.com : EXPAND %t
(4) detail.example.com : --> Tue Jun 28 07:54:32 2016
(4) [detail.example.com] = ok
(4) } # Post-Proxy-Type Fail = ok
(4) }
(4) Sending Accounting-Response packet to host 192.168.1.10 port 55683, id=219, length=0
Sending Accounting-Response Id 219 from 192.168.1.10:1813 to 192.168.1.10:55683
(4) Finished request
(4) Cleaning up request packet ID 219 with timestamp +213
Waking up in 3.9 seconds.
Polling for detail file /var/log/freeradius/radacct/detail.example.com/detail-*:*
Detail - Renaming /var/log/freeradius/radacct/detail.example.com/detail-20160628:07:54 -> /var/log/freeradius/radacct/detail.example.com/detail.work
detail_recv: Read packet from /var/log/freeradius/radacct/detail.example.com/detail.work
Acct-Session-Id = '57722C84726B00'
User-Name = 'user-paid'
Acct-Status-Type = Interim-Update
Service-Type = Framed-User
Framed-Protocol = PPP
Acct-Authentic = RADIUS
Acct-Session-Time = 180
Acct-Output-Octets = 3960
Acct-Input-Octets = 4644
Acct-Output-Packets = 16
Acct-Input-Packets = 50
Calling-Station-Id = '46.164.150.50'
NAS-Port-Type = Async
Framed-IP-Address = 10.20.20.2
NAS-Identifier = 'boxpn'
NAS-Port = 0
Acct-Delay-Time = 7
NAS-IP-Address = 192.168.1.10
Event-Timestamp = 'Jun 28 2016 07:54:32 UTC'
Acct-Unique-Session-Id = 'f64fc3243057e5a36e04e8430edb3ab4'
Realm = 'acct_realm.example.com'
Module-Failure-Message = 'Failing proxied request, due to lack of any response from home server 192.168.1.1 port 1813'
FreeRADIUS-Proxied-To = 192.168.1.1
Packet-Original-Timestamp = 'Jun 28 2016 07:54:32 UTC'
Packet-Transmit-Counter = 1
(6) # Executing section accounting from file /etc/freeradius/sites-enabled/robust-proxy-accounting
(6) accounting {
(6) update control {
(6) Proxy-To-Realm := 'acct_realm.example.com'
(6) } # update control = noop
(6) } # accounting = noop
(6) server home.example.com {
(6) }
Opening new proxy socket 'proxy address * port 0'
Listening on proxy address * port 59679
(6) Proxying request to home server 192.0.2.20 port 1813 timeout 6.000000
(6) Sending Accounting-Request packet to host 192.0.2.20 port 1813, id=30, length=0
(6) Acct-Session-Id = '57722C84726B00'
(6) User-Name = 'user-paid'
(6) Acct-Status-Type = Interim-Update
(6) Service-Type = Framed-User
(6) Framed-Protocol = PPP
(6) Acct-Authentic = RADIUS
(6) Acct-Session-Time = 180
(6) Acct-Output-Octets = 3960
(6) Acct-Input-Octets = 4644
(6) Acct-Output-Packets = 16
(6) Acct-Input-Packets = 50
(6) Calling-Station-Id = '46.164.150.50'
(6) NAS-Port-Type = Async
(6) Framed-IP-Address = 10.20.20.2
(6) NAS-Identifier = 'boxpn'
(6) NAS-Port = 0
(6) Acct-Delay-Time = 7
(6) NAS-IP-Address = 192.168.1.10
(6) Event-Timestamp = 'Jun 28 2016 07:54:32 UTC'
(6) Acct-Unique-Session-Id = 'f64fc3243057e5a36e04e8430edb3ab4'
(6) Realm = 'acct_realm.example.com'
(6) Module-Failure-Message = 'Failing proxied request, due to lack of any response from home server 192.168.1.1 port 1813'
(6) FreeRADIUS-Proxied-To = 192.168.1.1
(6) Packet-Original-Timestamp = 'Jun 28 2016 07:54:32 UTC'
(6) Packet-Transmit-Counter = 1
(6) Proxy-State = 0x30
Sending Accounting-Request Id 30 from 0.0.0.0:59679 to 192.0.2.20:1813
Acct-Session-Id = '57722C84726B00'
User-Name = 'user-paid'
Acct-Status-Type = Interim-Update
Service-Type = Framed-User
Framed-Protocol = PPP
Acct-Authentic = RADIUS
Acct-Session-Time = 180
Acct-Output-Octets = 3960
Acct-Input-Octets = 4644
Acct-Output-Packets = 16
Acct-Input-Packets = 50
Calling-Station-Id = '46.164.150.50'
NAS-Port-Type = Async
Framed-IP-Address = 10.20.20.2
NAS-Identifier = 'boxpn'
NAS-Port = 0
Acct-Delay-Time = 7
NAS-IP-Address = 192.168.1.10
Event-Timestamp = 'Jun 28 2016 07:54:32 UTC'
FreeRADIUS-Proxied-To = 192.168.1.1
Proxy-State = 0x30
Waking up in 0.3 seconds.
Waking up in 0.1 seconds.
(6) Expecting proxy response no later than 5.499700 seconds from now
Waking up in 3.0 seconds.
No response to status check 5 for home server 192.168.1.1 port 1813
Waking up in 2.4 seconds.
(6) No proxy response, giving up on request and marking it done
Marking home server 192.0.2.20 port 1813 as zombie (it has not responded in 6.000000 seconds).
PING: Waiting 4 seconds for response to ping
Sending Status-Server Id 91 from 0.0.0.0:59679 to 192.0.2.20:1813
Message-Authenticator := 0x00
NAS-Identifier := 'Status Check 0. Are you alive?'
PING: Next status packet in 30 seconds
(6) ERROR: Failing proxied request, due to lack of any response from home server 192.0.2.20 port 1813
(6) Found Post-Proxy-Type Fail
(6) server home.example.com {
(6) # Executing group from file /etc/freeradius/sites-enabled/robust-proxy-accounting
(6) Post-Proxy-Type Fail {
(6) detail.example.com : EXPAND /var/log/freeradius/radacct/detail.example.com/detail-%Y%m%d:%H:%G
(6) detail.example.com : --> /var/log/freeradius/radacct/detail.example.com/detail-20160628:07:54
(6) detail.example.com : /var/log/freeradius/radacct/detail.example.com/detail-%Y%m%d:%H:%G expands to /var/log/freeradius/radacct/detail.example.com/detail-20160628:07:54
(6) WARNING: detail.example.com : Suppressing infinite loop
(6) [detail.example.com] = noop
(6) } # Post-Proxy-Type Fail = noop
(6) }
(6) Not sending reply
(6) Finished request
(6) Cleaning up request packet ID 0 with timestamp +220
Waking up in 3.9 seconds.
No response to status check 7 for home server 192.0.2.20 port 1813
Waking up in 17.5 seconds.
PING: Waiting 4 seconds for response to ping
Sending Status-Server Id 65 from 0.0.0.0:21298 to 192.168.1.1:1813
Message-Authenticator := 0x00
NAS-Identifier := 'Status Check 1. Are you alive?'
PING: Next status packet in 30 seconds
Waking up in 3.9 seconds.
No response to status check 8 for home server 192.168.1.1 port 1813
Waking up in 4.9 seconds.
PING: Zombie period is over for home server home2.example.com
Marking home server 192.0.2.20 port 1813 as dead.
PING: Already pinging home server home2.example.com
PING: Waiting 4 seconds for response to ping
Sending Status-Server Id 84 from 0.0.0.0:59679 to 192.0.2.20:1813
Message-Authenticator := 0x00
NAS-Identifier := 'Status Check 1. Are you alive?'
PING: Next status packet in 30 seconds
Waking up in 3.9 seconds.
No response to status check 9 for home server 192.0.2.20 port 1813
Waking up in 18.6 seconds.
PING: Zombie period is over for home server home1.example.com
Marking home server 192.168.1.1 port 1813 as dead.
PING: Already pinging home server home1.example.com
PING: Waiting 4 seconds for response to ping
Sending Status-Server Id 229 from 0.0.0.0:21298 to 192.168.1.1:1813
Message-Authenticator := 0x00
NAS-Identifier := 'Status Check 2. Are you alive?'
PING: Next status packet in 30 seconds
Waking up in 3.9 seconds.
Received Accounting-Request Id 220 from 192.168.1.10:21412 to 192.168.1.10:1813 length 147
Acct-Session-Id = '57722C84726B00'
User-Name = 'user-paid'
Acct-Status-Type = Interim-Update
Service-Type = Framed-User
Framed-Protocol = PPP
Acct-Authentic = RADIUS
Acct-Session-Time = 246
Acct-Output-Octets = 3960
Acct-Input-Octets = 4942
Acct-Output-Packets = 16
Acct-Input-Packets = 52
Calling-Station-Id = '46.164.150.50'
NAS-Port-Type = Async
Framed-IP-Address = 10.20.20.2
NAS-Identifier = 'boxpn'
NAS-Port = 0
Acct-Delay-Time = 0
(11) Received Accounting-Request packet from host 192.168.1.10 port 21412, id=220, length=147
(11) Acct-Session-Id = '57722C84726B00'
(11) User-Name = 'user-paid'
(11) Acct-Status-Type = Interim-Update
(11) Service-Type = Framed-User
(11) Framed-Protocol = PPP
(11) Acct-Authentic = RADIUS
(11) Acct-Session-Time = 246
(11) Acct-Output-Octets = 3960
(11) Acct-Input-Octets = 4942
(11) Acct-Output-Packets = 16
(11) Acct-Input-Packets = 52
(11) Calling-Station-Id = '46.164.150.50'
(11) NAS-Port-Type = Async
(11) Framed-IP-Address = 10.20.20.2
(11) NAS-Identifier = 'boxpn'
(11) NAS-Port = 0
(11) Acct-Delay-Time = 0
(11) # Executing section preacct from file /etc/freeradius/sites-enabled/default
(11) preacct {
(11) [preprocess] = ok
(11) acct_unique acct_unique {
(11) if ("%{string:Class}" =~ /ai:([0-9a-f]{32})/i)
(11) EXPAND %{string:Class}
(11) -->
(11) if ("%{string:Class}" =~ /ai:([0-9a-f]{32})/i) -> FALSE
(11) else else {
(11) update request {
(11) EXPAND %{md5:%{User-Name},%{Acct-Session-ID},%{%{NAS-IPv6-Address}:-%{NAS-IP-Address}},%{NAS-Identifier},%{NAS-Port-ID},%{NAS-Port}}
(11) --> f64fc3243057e5a36e04e8430edb3ab4
(11) Acct-Unique-Session-Id := "f64fc3243057e5a36e04e8430edb3ab4"
(11) } # update request = noop
(11) } # else else = noop
(11) } # acct_unique acct_unique = noop
(11) suffix : Checking for suffix after "@"
(11) suffix : No '@' in User-Name = "user-paid", looking up realm NULL
(11) suffix : No such realm "NULL"
(11) [suffix] = noop
(11) [files] = noop
(11) } # preacct = ok
(11) # Executing section accounting from file /etc/freeradius/sites-enabled/default
(11) accounting {
(11) [unix] = noop
(11) update control {
(11) Proxy-To-Realm := 'acct_realm.example.com'
(11) ERROR: 'wait' must be enabled to use exec xlat
(11) EXPAND %{exec:/etc/freeradius/disconnect.sh}
(11) -->
(11) Tmp-String-0 := ""
(11) } # update control = noop
(11) attr_filter.accounting_response : EXPAND %{User-Name}
(11) attr_filter.accounting_response : --> user-paid
(11) attr_filter.accounting_response : Matched entry DEFAULT at line 12
(11) [attr_filter.accounting_response] = updated
(11) } # accounting = updated
Home server pool acct_pool.example.com failing over to fallback acct_detail.example.com
(11) server home.example.com {
(11) }
Proxying to virtual server acct_detail.example.com
(11) # Executing section accounting from file /etc/freeradius/sites-enabled/robust-proxy-accounting
(11) accounting {
(11) detail.example.com : EXPAND /var/log/freeradius/radacct/detail.example.com/detail-%Y%m%d:%H:%G
(11) detail.example.com : --> /var/log/freeradius/radacct/detail.example.com/detail-20160628:07:55
(11) detail.example.com : /var/log/freeradius/radacct/detail.example.com/detail-%Y%m%d:%H:%G expands to /var/log/freeradius/radacct/detail.example.com/detail-20160628:07:55
(11) detail.example.com : EXPAND %t
(11) detail.example.com : --> Tue Jun 28 07:55:38 2016
(11) [detail.example.com] = ok
(11) } # accounting = ok
(11) server home.example.com {
(11) # Executing section post-proxy from file /etc/freeradius/sites-enabled/robust-proxy-accounting
(11) }
(11) Sending Accounting-Response packet to host 192.168.1.10 port 21412, id=220, length=0
Sending Accounting-Response Id 220 from 192.168.1.10:1813 to 192.168.1.10:21412
(11) Finished request
Waking up in 0.2 seconds.
(11) Cleaning up request packet ID 220 with timestamp +279
Waking up in 3.3 seconds.
No response to status check 10 for home server 192.168.1.1 port 1813
Waking up in 3.8 seconds.
PING: Waiting 4 seconds for response to ping
Sending Status-Server Id 163 from 0.0.0.0:59679 to 192.0.2.20:1813
Message-Authenticator := 0x00
NAS-Identifier := 'Status Check 2. Are you alive?'
PING: Next status packet in 30 seconds
Waking up in 3.9 seconds.
No response to status check 12 for home server 192.0.2.20 port 1813
Waking up in 17.4 seconds.
PING: Waiting 4 seconds for response to ping
Sending Status-Server Id 206 from 0.0.0.0:21298 to 192.168.1.1:1813
Message-Authenticator := 0x00
NAS-Identifier := 'Status Check 3. Are you alive?'
PING: Next status packet in 30 seconds
Waking up in 3.9 seconds.
No response to status check 13 for home server 192.168.1.1 port 1813
Waking up in 4.7 seconds.
PING: Waiting 4 seconds for response to ping
Sending Status-Server Id 6 from 0.0.0.0:59679 to 192.0.2.20:1813
Message-Authenticator := 0x00
NAS-Identifier := 'Status Check 3. Are you alive?'
PING: Next status packet in 30 seconds
Waking up in 3.9 seconds.
No response to status check 14 for home server 192.0.2.20 port 1813
Waking up in 18.0 seconds.
Received Accounting-Request Id 221 from 192.168.1.10:51091 to 192.168.1.10:1813 length 153
Acct-Session-Id = '57722C84726B00'
User-Name = 'user-paid'
Acct-Status-Type = Stop
Service-Type = Framed-User
Framed-Protocol = PPP
Acct-Authentic = RADIUS
Acct-Session-Time = 297
Acct-Output-Octets = 3960
Acct-Input-Octets = 4942
Acct-Output-Packets = 16
Acct-Input-Packets = 52
Calling-Station-Id = '46.164.150.50'
NAS-Port-Type = Async
Acct-Terminate-Cause = Lost-Carrier
Framed-IP-Address = 10.20.20.2
NAS-Identifier = 'boxpn'
NAS-Port = 0
Acct-Delay-Time = 0
(15) Received Accounting-Request packet from host 192.168.1.10 port 51091, id=221, length=153
(15) Acct-Session-Id = '57722C84726B00'
(15) User-Name = 'user-paid'
(15) Acct-Status-Type = Stop
(15) Service-Type = Framed-User
(15) Framed-Protocol = PPP
(15) Acct-Authentic = RADIUS
(15) Acct-Session-Time = 297
(15) Acct-Output-Octets = 3960
(15) Acct-Input-Octets = 4942
(15) Acct-Output-Packets = 16
(15) Acct-Input-Packets = 52
(15) Calling-Station-Id = '46.164.150.50'
(15) NAS-Port-Type = Async
(15) Acct-Terminate-Cause = Lost-Carrier
(15) Framed-IP-Address = 10.20.20.2
(15) NAS-Identifier = 'boxpn'
(15) NAS-Port = 0
(15) Acct-Delay-Time = 0
(15) # Executing section preacct from file /etc/freeradius/sites-enabled/default
(15) preacct {
(15) [preprocess] = ok
(15) acct_unique acct_unique {
(15) if ("%{string:Class}" =~ /ai:([0-9a-f]{32})/i)
(15) EXPAND %{string:Class}
(15) -->
(15) if ("%{string:Class}" =~ /ai:([0-9a-f]{32})/i) -> FALSE
(15) else else {
(15) update request {
(15) EXPAND %{md5:%{User-Name},%{Acct-Session-ID},%{%{NAS-IPv6-Address}:-%{NAS-IP-Address}},%{NAS-Identifier},%{NAS-Port-ID},%{NAS-Port}}
(15) --> f64fc3243057e5a36e04e8430edb3ab4
(15) Acct-Unique-Session-Id := "f64fc3243057e5a36e04e8430edb3ab4"
(15) } # update request = noop
(15) } # else else = noop
(15) } # acct_unique acct_unique = noop
(15) suffix : Checking for suffix after "@"
(15) suffix : No '@' in User-Name = "user-paid", looking up realm NULL
(15) suffix : No such realm "NULL"
(15) [suffix] = noop
(15) [files] = noop
(15) } # preacct = ok
(15) # Executing section accounting from file /etc/freeradius/sites-enabled/default
(15) accounting {
(15) [unix] = ok
(15) update control {
(15) Proxy-To-Realm := 'acct_realm.example.com'
(15) ERROR: 'wait' must be enabled to use exec xlat
(15) EXPAND %{exec:/etc/freeradius/disconnect.sh}
(15) -->
(15) Tmp-String-0 := ""
(15) } # update control = noop
(15) attr_filter.accounting_response : EXPAND %{User-Name}
(15) attr_filter.accounting_response : --> user-paid
(15) attr_filter.accounting_response : Matched entry DEFAULT at line 12
(15) [attr_filter.accounting_response] = updated
(15) } # accounting = updated
Home server pool acct_pool.example.com failing over to fallback acct_detail.example.com
(15) server home.example.com {
(15) }
Proxying to virtual server acct_detail.example.com
(15) # Executing section accounting from file /etc/freeradius/sites-enabled/robust-proxy-accounting
(15) accounting {
(15) detail.example.com : EXPAND /var/log/freeradius/radacct/detail.example.com/detail-%Y%m%d:%H:%G
(15) detail.example.com : --> /var/log/freeradius/radacct/detail.example.com/detail-20160628:07:56
(15) detail.example.com : /var/log/freeradius/radacct/detail.example.com/detail-%Y%m%d:%H:%G expands to /var/log/freeradius/radacct/detail.example.com/detail-20160628:07:56
(15) detail.example.com : EXPAND %t
(15) detail.example.com : --> Tue Jun 28 07:56:29 2016
(15) [detail.example.com] = ok
(15) } # accounting = ok
(15) server home.example.com {
(15) # Executing section post-proxy from file /etc/freeradius/sites-enabled/robust-proxy-accounting
(15) }
(15) Sending Accounting-Response packet to host 192.168.1.10 port 51091, id=221, length=0
Sending Accounting-Response Id 221 from 192.168.1.10:1813 to 192.168.1.10:51091
(15) Finished request
Waking up in 0.3 seconds.
(15) Cleaning up request packet ID 221 with timestamp +330
Waking up in 8.9 seconds.
PING: Waiting 4 seconds for response to ping
Sending Status-Server Id 157 from 0.0.0.0:21298 to 192.168.1.1:1813
Message-Authenticator := 0x00
NAS-Identifier := 'Status Check 4. Are you alive?'
PING: Next status packet in 30 seconds
Waking up in 3.9 seconds.
Received Accounting-Response Id 157 from 192.168.1.1:1813 to 192.168.1.10:21298 length 20
(16) Received response to status check 16 (1 in current sequence)
Waking up in 8.1 seconds.
PING: Waiting 4 seconds for response to ping
Sending Status-Server Id 145 from 0.0.0.0:59679 to 192.0.2.20:1813
Message-Authenticator := 0x00
NAS-Identifier := 'Status Check 4. Are you alive?'
PING: Next status packet in 30 seconds
Waking up in 3.9 seconds.
No response to status check 17 for home server 192.0.2.20 port 1813
Waking up in 19.6 seconds.
PING: Waiting 4 seconds for response to ping
Sending Status-Server Id 219 from 0.0.0.0:21298 to 192.168.1.1:1813
Message-Authenticator := 0x00
NAS-Identifier := 'Status Check 5. Are you alive?'
PING: Next status packet in 30 seconds
Waking up in 3.9 seconds.
Received Accounting-Response Id 219 from 192.168.1.1:1813 to 192.168.1.10:21298 length 20
(18) Received response to status check 18 (2 in current sequence)
Waking up in 7.5 seconds.
PING: Waiting 4 seconds for response to ping
Sending Status-Server Id 254 from 0.0.0.0:59679 to 192.0.2.20:1813
Message-Authenticator := 0x00
NAS-Identifier := 'Status Check 5. Are you alive?'
PING: Next status packet in 30 seconds
Waking up in 3.9 seconds.
No response to status check 19 for home server 192.0.2.20 port 1813
Waking up in 18.8 seconds.
PING: Waiting 4 seconds for response to ping
Sending Status-Server Id 248 from 0.0.0.0:21298 to 192.168.1.1:1813
Message-Authenticator := 0x00
NAS-Identifier := 'Status Check 6. Are you alive?'
PING: Next status packet in 30 seconds
Waking up in 3.9 seconds.
Received Accounting-Response Id 248 from 192.168.1.1:1813 to 192.168.1.10:21298 length 20
(20) Received response to status check 20 (3 in current sequence)
(20) Marking home server 192.168.1.1 port 1813 alive
Waking up in 8.2 seconds.
PING: Waiting 4 seconds for response to ping
Sending Status-Server Id 58 from 0.0.0.0:59679 to 192.0.2.20:1813
Message-Authenticator := 0x00
NAS-Identifier := 'Status Check 6. Are you alive?'
PING: Next status packet in 30 seconds
Waking up in 3.9 seconds.
No response to status check 21 for home server 192.0.2.20 port 1813
Waking up in 25.1 seconds.
PING: Waiting 4 seconds for response to ping
Sending Status-Server Id 210 from 0.0.0.0:59679 to 192.0.2.20:1813
Message-Authenticator := 0x00
NAS-Identifier := 'Status Check 7. Are you alive?'
PING: Next status packet in 30 seconds
Waking up in 3.9 seconds.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment