rad_recv: Access-Request packet from host 10.117.80.168 port 61874, id=21, length=93
Thu Sep 17 15:08:21 2009 : Debug: server dynamic_client_server {
Thu Sep 17 15:08:21 2009 : Info: radius_xlat: Calling decode_attribute with {User-Name}
Thu Sep 17 15:08:21 2009 : Info: decode_attribute: {User-Name}
Thu Sep 17 15:08:21 2009 : Info: radius_xlat: Back from decode_attribute with
Thu Sep 17 15:08:21 2009 : Debug: rlm_sql (sqlaugs): Ignoring unconnected handle 4..
Thu Sep 17 15:08:21 2009 : Debug: rlm_sql (sqlaugs): Ignoring unconnected handle 3..
Thu Sep 17 15:08:21 2009 : Debug: rlm_sql (sqlaugs): Ignoring unconnected handle 2..
Thu Sep 17 15:08:21 2009 : Debug: rlm_sql (sqlaugs): Ignoring unconnected handle 1..
Thu Sep 17 15:08:21 2009 : Debug: rlm_sql (sqlaugs): Ignoring unconnected handle 0..
Thu Sep 17 15:08:21 2009 : Info: rlm_sql (sqlaugs): There are no DB handles to use! skipped 5, tried to connect 0
Thu Sep 17 15:08:21 2009 : Error: rlm_sql: Error getting sql socket - returning FAIL
Thu Sep 17 15:08:21 2009 : Info: radius_xlat: Calling decode_attribute with {User-Name}
Thu Sep 17 15:08:21 2009 : Info: decode_attribute: {User-Name}
Thu Sep 17 15:08:21 2009 : Info: radius_xlat: Back from decode_attribute with
Thu Sep 17 15:08:21 2009 : Debug: rlm_sql (sqlroch): Reserving sql socket id: 4
Thu Sep 17 15:08:21 2009 : Info: rlm_sql: Xlating authorize_check_query...
Thu Sep 17 15:08:21 2009 : Info: radius_xlat: Calling decode_attribute with {SQL-User-Name}',NULL,NULL,NULL
Thu Sep 17 15:08:21 2009 : Info: decode_attribute: {SQL-User-Name}',NULL,NULL,NULL
Thu Sep 17 15:08:21 2009 : Info: radius_xlat: Back from decode_attribute with
Thu Sep 17 15:08:21 2009 : Debug: query:  SELECT 1,'',NULL,NULL,NULL
Thu Sep 17 15:08:21 2009 : Info: rlm_sql: Calling rlm_sql_process_groups...
Thu Sep 17 15:08:21 2009 : Info: rlm_sql: Found 0 groups
Thu Sep 17 15:08:21 2009 : Debug: rlm_sql (sqlroch): Released sql socket id: 4
Thu Sep 17 15:08:21 2009 : Info: rlm_sql: Finished rlm_sql_authorize
Thu Sep 17 15:08:21 2009 : Info: radius_evaluate_condition: ("%{sqlha: SELECT nasname FROM nas WHERE nasname = '%{Packet-Src-IP-Address}'}")
Thu Sep 17 15:08:21 2009 : Info: >>> RECURSING WITH ... "%{sqlha: SELECT nasname FROM nas WHERE nasname = '%{Packet-Src-IP-Address}'}")
Thu Sep 17 15:08:21 2009 : Info: radius_evaluate_condition: "%{sqlha: SELECT nasname FROM nas WHERE nasname = '%{Packet-Src-IP-Address}'}")
Thu Sep 17 15:08:21 2009 : Info: >>> LOOKING AT "%{sqlha: SELECT nasname FROM nas WHERE nasname = '%{Packet-Src-IP-Address}'}")
Thu Sep 17 15:08:21 2009 : Info: expand_string: Calling radius_xlat with %{sqlha: SELECT nasname FROM nas WHERE nasname = '%{Packet-Src-IP-Address}'}
Thu Sep 17 15:08:21 2009 : Info: radius_xlat: Calling decode_attribute with {sqlha: SELECT nasname FROM nas WHERE nasname = '%{Packet-Src-IP-Address}'}
Thu Sep 17 15:08:21 2009 : Info: decode_attribute: {sqlha: SELECT nasname FROM nas WHERE nasname = '%{Packet-Src-IP-Address}'}
Thu Sep 17 15:08:21 2009 : Info: radius_xlat: Back from decode_attribute with
Thu Sep 17 15:08:21 2009 : Info: >>> Comparison returned 0
Thu Sep 17 15:08:21 2009 : Info: >>> GOT result 0
Thu Sep 17 15:08:21 2009 : Info: >>> AT EOL -> 0
Thu Sep 17 15:08:21 2009 : Info: >>> AFTER RECURSION ... )
Thu Sep 17 15:08:21 2009 : Info: >>> AT EOL -> 0
Thu Sep 17 15:08:21 2009 : Debug: } # server dynamic_client_server
Thu Sep 17 15:08:21 2009 : Debug: - Cannot add client 10.117.80.168: Required attribute "FreeRADIUS-Client-Secret" is missing.
Thu Sep 17 15:08:21 2009 : Error: Ignoring request to authentication address * port 1812 from unknown client 10.117.80.168 port 61874
Thu Sep 17 15:08:21 2009 : Debug: Ready to process requests.