Empty packet, socket not ready message

Alex Sharaz alex.sharaz at york.ac.uk
Thu Jan 14 14:50:59 CET 2016


Hi,
We've just upgraded one of our outward facing freeradius servers to Ubuntu
14.04.

After the upgrade I rebuilt FR 2.2.9 and while tings are working, I'm now
getting a lot of  Error: Empty packey: socket not ready messages

debug trace shows

Thu Jan 14 13:45:54 2016 : Info: [attr_filter.access_reject]    expand:
%{User-Name} -> as1129 at york
.ac.uk
Thu Jan 14 13:45:54 2016 : Debug: attr_filter: Matched entry DEFAULT at
line 11
Thu Jan 14 13:45:54 2016 : Info: ++[attr_filter.access_reject] = updated
Thu Jan 14 13:45:54 2016 : Info: +} # group REJECT = updated
Thu Jan 14 13:45:54 2016 : Info: Delaying reject of request 67 for 1 seconds
Thu Jan 14 13:45:54 2016 : Debug: Going to the next request
Thu Jan 14 13:45:54 2016 : Debug: Thread 3 waiting to be assigned a request
Thu Jan 14 13:45:55 2016 : Info: Sending delayed reject for request 23
Thu Jan 14 13:45:55 2016 : Info: Sending delayed reject for request 24
Thu Jan 14 13:45:55 2016 : Info: Sending duplicate proxied request to home
server 194.83.56.233 por
t 1812 - ID: 120
Thu Jan 14 13:45:55 2016 : Error: Empty packet: Socket is not ready.
Thu Jan 14 13:45:55 2016 : Info: Sending duplicate proxied request to home
server 194.83.56.233 por
t 1812 - ID: 7
Thu Jan 14 13:45:55 2016 : Error: Empty packet: Socket is not ready.
Thu Jan 14 13:45:55 2016 : Info: Sending delayed reject for request 39
Thu Jan 14 13:45:55 2016 : Info: Sending delayed reject for request 41
Sending Access-Accept of id 237 to 194.82.174.185 port 29981


or


Thu Jan 14 13:45:51 2016 : Info: } # server eduroam
Thu Jan 14 13:45:51 2016 : Info: Finished request 9.
Thu Jan 14 13:45:51 2016 : Debug: Going to the next request
Thu Jan 14 13:45:51 2016 : Debug: Thread 1 waiting to be assigned a request
Thu Jan 14 13:45:51 2016 : Error: Empty packet: Socket is not ready.
Thu Jan 14 13:45:51 2016 : Debug: Waking up in 0.3 seconds.
Thu Jan 14 13:45:51 2016 : Debug: Waking up in 0.2 seconds.

or

 Jan 14 13:45:50 2016 : Debug: rlm_perl: Added pair Proxy-State = 0x3236
Thu Jan 14 13:45:50 2016 : Debug: rlm_perl: Added pair State =
0x382b03450000013700010200c253f60c00
000000000000000000000000000004162412a2
Thu Jan 14 13:45:50 2016 : Debug: rlm_perl: Added pair NAS-Identifier =
aruba0
Thu Jan 14 13:45:50 2016 : Debug: rlm_perl: Added pair NAS-IP-Address =
144.32.64.18
Thu Jan 14 13:45:50 2016 : Debug: rlm_perl: Added pair User-Name =
hyuai at hyms.ac.uk
Thu Jan 14 13:45:50 2016 : Debug: rlm_perl: Added pair
Message-Authenticator = 0x82e775ee97b58da33b
734bd29026433c
Thu Jan 14 13:45:50 2016 : Debug: rlm_perl: Added pair Calling-Station-Id =
CC-3A-61-B4-4C-5D
Thu Jan 14 13:45:50 2016 : Info: ++[perl] = ok
Thu Jan 14 13:45:50 2016 : Info: +} # group pre-proxy = updated
Thu Jan 14 13:45:50 2016 : Info: Proxying request 4 to home server
194.83.56.233 port 1812
Thu Jan 14 13:45:50 2016 : Debug: Going to the next request
Thu Jan 14 13:45:50 2016 : Debug: Thread 1 waiting to be assigned a request
Thu Jan 14 13:45:50 2016 : Error: Empty packet: Socket is not ready.
Thu Jan 14 13:45:50 2016 : Debug: Waking up in 0.2 seconds.

Any other info reuired to find out why this is now happening?

Rgds
Alex


More information about the Freeradius-Users mailing list