Wed Dec 13 16:11:19 2017 : Debug: Server was built with: Wed Dec 13 16:11:19 2017 : Debug: accounting : yes Wed Dec 13 16:11:19 2017 : Debug: authentication : yes Wed Dec 13 16:11:19 2017 : Debug: ascend-binary-attributes : yes Wed Dec 13 16:11:19 2017 : Debug: coa : yes Wed Dec 13 16:11:19 2017 : Debug: control-socket : yes Wed Dec 13 16:11:19 2017 : Debug: detail : yes Wed Dec 13 16:11:19 2017 : Debug: dhcp : yes Wed Dec 13 16:11:19 2017 : Debug: dynamic-clients : yes Wed Dec 13 16:11:19 2017 : Debug: osfc2 : no Wed Dec 13 16:11:19 2017 : Debug: proxy : yes Wed Dec 13 16:11:19 2017 : Debug: regex-pcre : yes Wed Dec 13 16:11:19 2017 : Debug: regex-posix : no Wed Dec 13 16:11:19 2017 : Debug: regex-posix-extended : no Wed Dec 13 16:11:19 2017 : Debug: session-management : yes Wed Dec 13 16:11:19 2017 : Debug: stats : yes Wed Dec 13 16:11:19 2017 : Debug: tcp : yes Wed Dec 13 16:11:19 2017 : Debug: threads : yes Wed Dec 13 16:11:19 2017 : Debug: tls : yes Wed Dec 13 16:11:19 2017 : Debug: unlang : yes Wed Dec 13 16:11:19 2017 : Debug: vmps : yes Wed Dec 13 16:11:19 2017 : Debug: developer : no Wed Dec 13 16:11:19 2017 : Debug: Server core libs: Wed Dec 13 16:11:19 2017 : Debug: freeradius-server : 3.0.13 Wed Dec 13 16:11:19 2017 : Debug: talloc : 2.1.* Wed Dec 13 16:11:19 2017 : Debug: ssl : 1.0.2k release Wed Dec 13 16:11:19 2017 : Debug: pcre : 8.32 2012-11-30 Wed Dec 13 16:11:19 2017 : Debug: Endianness: Wed Dec 13 16:11:19 2017 : Debug: little Wed Dec 13 16:11:19 2017 : Debug: Compilation flags: Wed Dec 13 16:11:19 2017 : Debug: cppflags : Wed Dec 13 16:11:19 2017 : Debug: cflags : -I. -Isrc -include src/freeradius-devel/autoconf.h -include src/freeradius-devel/build.h -include src/freeradius-devel/features.h -include src/freeradius-devel/radpaths.h -fno-strict-aliasing -O2 -g -pipe -Wall -Wp,-D_FORTIFY_SOURCE=2 -fexceptions -fstack-protector-strong --param=ssp-buffer-size=4 -grecord-gcc-switches -specs=/usr/lib/rpm/redhat/redhat-hardened-cc1 -m64 -mtune=generic -Wall -std=c99 -D_GNU_SOURCE -D_REENTRANT -D_POSIX_PTHREAD_SEMANTICS -DOPENSSL_NO_KRB5 -DNDEBUG -DIS_MODULE=1 Wed Dec 13 16:11:19 2017 : Debug: ldflags : -Wl,-z,relro -specs=/usr/lib/rpm/redhat/redhat-hardened-ld Wed Dec 13 16:11:19 2017 : Debug: libs : -lcrypto -lssl -ltalloc -lpcre -lnsl -lresolv -ldl -lpthread -lreadline Wed Dec 13 16:11:19 2017 : Debug: Wed Dec 13 16:11:19 2017 : Info: FreeRADIUS Version 3.0.13 Wed Dec 13 16:11:19 2017 : Info: Copyright (C) 1999-2017 The FreeRADIUS server project and contributors Wed Dec 13 16:11:19 2017 : Info: There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A Wed Dec 13 16:11:19 2017 : Info: PARTICULAR PURPOSE Wed Dec 13 16:11:19 2017 : Info: You may redistribute copies of FreeRADIUS under the terms of the Wed Dec 13 16:11:19 2017 : Info: GNU General Public License Wed Dec 13 16:11:19 2017 : Info: For more information about these matters, see the file named COPYRIGHT Wed Dec 13 16:11:19 2017 : Info: Starting - reading configuration files ... Wed Dec 13 16:11:19 2017 : Debug: including dictionary file /usr/share/freeradius/dictionary Wed Dec 13 16:11:19 2017 : Debug: including dictionary file /usr/share/freeradius/dictionary.dhcp Wed Dec 13 16:11:19 2017 : Debug: including dictionary file /usr/share/freeradius/dictionary.vqp Wed Dec 13 16:11:19 2017 : Debug: including dictionary file /etc/raddb/dictionary Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/radiusd.conf Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/proxy.conf Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/clients.conf Wed Dec 13 16:11:19 2017 : Debug: including files in directory /etc/raddb/mods-enabled/ Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/attr_filter Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/cache_eap Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/chap Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/date Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/detail Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/detail.log Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/dhcp Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/digest Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/dynamic_clients Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/eap Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/echo Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/exec Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/expiration Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/expr Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/files Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/linelog Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/logintime Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/mschap Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/ntlm_auth Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/pap Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/passwd Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/preprocess Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/radutmp Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/realm Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/replicate Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/soh Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/sradutmp Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/unix Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/unpack Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/utf8 Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/mods-enabled/ldap Wed Dec 13 16:11:19 2017 : Debug: including files in directory /etc/raddb/policy.d/ Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/policy.d/accounting Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/policy.d/canonicalization Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/policy.d/control Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/policy.d/cui Wed Dec 13 16:11:19 2017 : Debug: OPTIMIZING (${policy.cui_require_operator_name} == yes) --> FALSE Wed Dec 13 16:11:19 2017 : Debug: OPTIMIZING (no == yes) --> FALSE Wed Dec 13 16:11:19 2017 : Debug: OPTIMIZING (${policy.cui_require_operator_name} == yes) --> FALSE Wed Dec 13 16:11:19 2017 : Debug: OPTIMIZING (no == yes) --> FALSE Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/policy.d/debug Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/policy.d/dhcp Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/policy.d/eap Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/policy.d/filter Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/policy.d/operator-name Wed Dec 13 16:11:19 2017 : Debug: including files in directory /etc/raddb/sites-enabled/ Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/sites-enabled/default Wed Dec 13 16:11:19 2017 : Debug: including configuration file /etc/raddb/sites-enabled/inner-tunnel Wed Dec 13 16:11:19 2017 : Debug: main { Wed Dec 13 16:11:19 2017 : Debug: security { Wed Dec 13 16:11:19 2017 : Debug: user = "radiusd" Wed Dec 13 16:11:19 2017 : Debug: group = "radiusd" Wed Dec 13 16:11:19 2017 : Debug: allow_core_dumps = no Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[461]: The item 'max_attributes' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[479]: The item 'reject_delay' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[499]: The item 'status_server' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: name = "radiusd" Wed Dec 13 16:11:19 2017 : Debug: prefix = "/usr" Wed Dec 13 16:11:19 2017 : Debug: localstatedir = "/var" Wed Dec 13 16:11:19 2017 : Debug: logdir = "/var/log/radius" Wed Dec 13 16:11:19 2017 : Debug: run_dir = "/var/run/radiusd" Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[54]: The item 'exec_prefix' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[67]: The item 'confdir' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[73]: The item 'db_dir' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[107]: The item 'libdir' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[118]: The item 'pidfile' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[139]: The item 'correct_escapes' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[193]: The item 'max_request_time' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[212]: The item 'cleanup_delay' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[249]: The item 'hostname_lookups' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[371]: The item 'checkrad' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[520]: The item 'proxy_requests' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: main { Wed Dec 13 16:11:19 2017 : Debug: name = "radiusd" Wed Dec 13 16:11:19 2017 : Debug: prefix = "/usr" Wed Dec 13 16:11:19 2017 : Debug: localstatedir = "/var" Wed Dec 13 16:11:19 2017 : Debug: sbindir = "/usr/sbin" Wed Dec 13 16:11:19 2017 : Debug: logdir = "/var/log/radius" Wed Dec 13 16:11:19 2017 : Debug: run_dir = "/var/run/radiusd" Wed Dec 13 16:11:19 2017 : Debug: libdir = "/usr/lib64/freeradius" Wed Dec 13 16:11:19 2017 : Debug: radacctdir = "/var/log/radius/radacct" Wed Dec 13 16:11:19 2017 : Debug: hostname_lookups = no Wed Dec 13 16:11:19 2017 : Debug: max_request_time = 30 Wed Dec 13 16:11:19 2017 : Debug: cleanup_delay = 5 Wed Dec 13 16:11:19 2017 : Debug: max_requests = 16384 Wed Dec 13 16:11:19 2017 : Debug: pidfile = "/var/run/radiusd/radiusd.pid" Wed Dec 13 16:11:19 2017 : Debug: checkrad = "/usr/sbin/checkrad" Wed Dec 13 16:11:19 2017 : Debug: debug_level = 0 Wed Dec 13 16:11:19 2017 : Debug: proxy_requests = yes Wed Dec 13 16:11:19 2017 : Debug: log { Wed Dec 13 16:11:19 2017 : Debug: stripped_names = no Wed Dec 13 16:11:19 2017 : Debug: auth = no Wed Dec 13 16:11:19 2017 : Debug: auth_badpass = no Wed Dec 13 16:11:19 2017 : Debug: auth_goodpass = no Wed Dec 13 16:11:19 2017 : Debug: colourise = yes Wed Dec 13 16:11:19 2017 : Debug: msg_denied = "You are already logged in - access denied" Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[267]: The item 'destination' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[284]: The item 'file' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[330]: The item 'syslog_facility' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: resources { Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: security { Wed Dec 13 16:11:19 2017 : Debug: max_attributes = 200 Wed Dec 13 16:11:19 2017 : Debug: reject_delay = 1.000000 Wed Dec 13 16:11:19 2017 : Debug: status_server = yes Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[54]: The item 'exec_prefix' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[67]: The item 'confdir' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[73]: The item 'db_dir' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/radiusd.conf[139]: The item 'correct_escapes' is defined, but is unused by the configuration Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: radiusd: #### Loading Realms and Home Servers #### Wed Dec 13 16:11:19 2017 : Debug: proxy server { Wed Dec 13 16:11:19 2017 : Debug: retry_delay = 5 Wed Dec 13 16:11:19 2017 : Debug: retry_count = 3 Wed Dec 13 16:11:19 2017 : Debug: default_fallback = no Wed Dec 13 16:11:19 2017 : Debug: dead_time = 120 Wed Dec 13 16:11:19 2017 : Debug: wake_all_if_all_dead = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: home_server localhost { Wed Dec 13 16:11:19 2017 : Debug: ipaddr = 127.0.0.1 Wed Dec 13 16:11:19 2017 : Debug: port = 1812 Wed Dec 13 16:11:19 2017 : Debug: type = "auth" Wed Dec 13 16:11:19 2017 : Debug: secret = "testing123" Wed Dec 13 16:11:19 2017 : Debug: response_window = 20.000000 Wed Dec 13 16:11:19 2017 : Debug: response_timeouts = 1 Wed Dec 13 16:11:19 2017 : Debug: max_outstanding = 65536 Wed Dec 13 16:11:19 2017 : Debug: zombie_period = 40 Wed Dec 13 16:11:19 2017 : Debug: status_check = "status-server" Wed Dec 13 16:11:19 2017 : Debug: ping_interval = 30 Wed Dec 13 16:11:19 2017 : Debug: check_interval = 30 Wed Dec 13 16:11:19 2017 : Debug: check_timeout = 4 Wed Dec 13 16:11:19 2017 : Debug: num_answers_to_alive = 3 Wed Dec 13 16:11:19 2017 : Debug: revive_interval = 120 Wed Dec 13 16:11:19 2017 : Debug: limit { Wed Dec 13 16:11:19 2017 : Debug: max_connections = 16 Wed Dec 13 16:11:19 2017 : Debug: max_requests = 0 Wed Dec 13 16:11:19 2017 : Debug: lifetime = 0 Wed Dec 13 16:11:19 2017 : Debug: idle_timeout = 0 Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: coa { Wed Dec 13 16:11:19 2017 : Debug: irt = 2 Wed Dec 13 16:11:19 2017 : Debug: mrt = 16 Wed Dec 13 16:11:19 2017 : Debug: mrc = 5 Wed Dec 13 16:11:19 2017 : Debug: mrd = 30 Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: home_server_pool my_auth_failover { Wed Dec 13 16:11:19 2017 : Debug: type = fail-over Wed Dec 13 16:11:19 2017 : Debug: home_server = localhost Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: realm example.com { Wed Dec 13 16:11:19 2017 : Debug: auth_pool = my_auth_failover Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: realm LOCAL { Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: radiusd: #### Loading Clients #### Wed Dec 13 16:11:19 2017 : Debug: client localhost { Wed Dec 13 16:11:19 2017 : Debug: ipaddr = 127.0.0.1 Wed Dec 13 16:11:19 2017 : Debug: require_message_authenticator = no Wed Dec 13 16:11:19 2017 : Debug: secret = "testing123" Wed Dec 13 16:11:19 2017 : Debug: nas_type = "other" Wed Dec 13 16:11:19 2017 : Debug: proto = "*" Wed Dec 13 16:11:19 2017 : Debug: limit { Wed Dec 13 16:11:19 2017 : Debug: max_connections = 16 Wed Dec 13 16:11:19 2017 : Debug: lifetime = 0 Wed Dec 13 16:11:19 2017 : Debug: idle_timeout = 30 Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Adding client 127.0.0.1/32 (127.0.0.1) to prefix tree 32 Wed Dec 13 16:11:19 2017 : Debug: client 172.0.0.0 { Wed Dec 13 16:11:19 2017 : Debug: ipaddr = 172.0.0.0/8 Wed Dec 13 16:11:19 2017 : Debug: require_message_authenticator = no Wed Dec 13 16:11:19 2017 : Debug: secret = "testing123" Wed Dec 13 16:11:19 2017 : Debug: nas_type = "other" Wed Dec 13 16:11:19 2017 : Debug: limit { Wed Dec 13 16:11:19 2017 : Debug: max_connections = 16 Wed Dec 13 16:11:19 2017 : Debug: lifetime = 0 Wed Dec 13 16:11:19 2017 : Debug: idle_timeout = 30 Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Adding client 172.0.0.0/8 (172.0.0.0) to prefix tree 8 Wed Dec 13 16:11:19 2017 : Debug: client 192.168.0.0 { Wed Dec 13 16:11:19 2017 : Debug: ipaddr = 192.168.0.0/16 Wed Dec 13 16:11:19 2017 : Debug: require_message_authenticator = no Wed Dec 13 16:11:19 2017 : Debug: secret = "testing123" Wed Dec 13 16:11:19 2017 : Debug: nas_type = "misvirtuales" Wed Dec 13 16:11:19 2017 : Debug: limit { Wed Dec 13 16:11:19 2017 : Debug: max_connections = 16 Wed Dec 13 16:11:19 2017 : Debug: lifetime = 0 Wed Dec 13 16:11:19 2017 : Debug: idle_timeout = 30 Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Adding client 192.168.0.0/16 (192.168.0.0) to prefix tree 16 Wed Dec 13 16:11:19 2017 : Debug: client localhost_ipv6 { Wed Dec 13 16:11:19 2017 : Debug: ipv6addr = ::1 Wed Dec 13 16:11:19 2017 : Debug: require_message_authenticator = no Wed Dec 13 16:11:19 2017 : Debug: secret = "testing123" Wed Dec 13 16:11:19 2017 : Debug: limit { Wed Dec 13 16:11:19 2017 : Debug: max_connections = 16 Wed Dec 13 16:11:19 2017 : Debug: lifetime = 0 Wed Dec 13 16:11:19 2017 : Debug: idle_timeout = 30 Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Adding client ::1/128 (::1) to prefix tree 128 Wed Dec 13 16:11:19 2017 : Info: Debugger not attached Wed Dec 13 16:11:19 2017 : Debug: # Creating Auth-Type = mschap Wed Dec 13 16:11:19 2017 : Debug: # Creating Auth-Type = digest Wed Dec 13 16:11:19 2017 : Debug: # Creating Auth-Type = eap Wed Dec 13 16:11:19 2017 : Debug: # Creating Auth-Type = PAP Wed Dec 13 16:11:19 2017 : Debug: # Creating Auth-Type = CHAP Wed Dec 13 16:11:19 2017 : Debug: # Creating Auth-Type = MS-CHAP Wed Dec 13 16:11:19 2017 : Debug: # Creating Auth-Type = LDAP Wed Dec 13 16:11:19 2017 : Debug: radiusd: #### Instantiating modules #### Wed Dec 13 16:11:19 2017 : Debug: modules { Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_always with path: /usr/lib64/freeradius/rlm_always.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_always, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_always Wed Dec 13 16:11:19 2017 : Debug: # Loading module "reject" from file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: always reject { Wed Dec 13 16:11:19 2017 : Debug: rcode = "reject" Wed Dec 13 16:11:19 2017 : Debug: simulcount = 0 Wed Dec 13 16:11:19 2017 : Debug: mpp = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "fail" from file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: always fail { Wed Dec 13 16:11:19 2017 : Debug: rcode = "fail" Wed Dec 13 16:11:19 2017 : Debug: simulcount = 0 Wed Dec 13 16:11:19 2017 : Debug: mpp = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "ok" from file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: always ok { Wed Dec 13 16:11:19 2017 : Debug: rcode = "ok" Wed Dec 13 16:11:19 2017 : Debug: simulcount = 0 Wed Dec 13 16:11:19 2017 : Debug: mpp = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "handled" from file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: always handled { Wed Dec 13 16:11:19 2017 : Debug: rcode = "handled" Wed Dec 13 16:11:19 2017 : Debug: simulcount = 0 Wed Dec 13 16:11:19 2017 : Debug: mpp = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "invalid" from file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: always invalid { Wed Dec 13 16:11:19 2017 : Debug: rcode = "invalid" Wed Dec 13 16:11:19 2017 : Debug: simulcount = 0 Wed Dec 13 16:11:19 2017 : Debug: mpp = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "userlock" from file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: always userlock { Wed Dec 13 16:11:19 2017 : Debug: rcode = "userlock" Wed Dec 13 16:11:19 2017 : Debug: simulcount = 0 Wed Dec 13 16:11:19 2017 : Debug: mpp = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "notfound" from file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: always notfound { Wed Dec 13 16:11:19 2017 : Debug: rcode = "notfound" Wed Dec 13 16:11:19 2017 : Debug: simulcount = 0 Wed Dec 13 16:11:19 2017 : Debug: mpp = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "noop" from file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: always noop { Wed Dec 13 16:11:19 2017 : Debug: rcode = "noop" Wed Dec 13 16:11:19 2017 : Debug: simulcount = 0 Wed Dec 13 16:11:19 2017 : Debug: mpp = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "updated" from file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: always updated { Wed Dec 13 16:11:19 2017 : Debug: rcode = "updated" Wed Dec 13 16:11:19 2017 : Debug: simulcount = 0 Wed Dec 13 16:11:19 2017 : Debug: mpp = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_attr_filter with path: /usr/lib64/freeradius/rlm_attr_filter.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_attr_filter, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_attr_filter Wed Dec 13 16:11:19 2017 : Debug: # Loading module "attr_filter.post-proxy" from file /etc/raddb/mods-enabled/attr_filter Wed Dec 13 16:11:19 2017 : Debug: attr_filter attr_filter.post-proxy { Wed Dec 13 16:11:19 2017 : Debug: filename = "/etc/raddb/mods-config/attr_filter/post-proxy" Wed Dec 13 16:11:19 2017 : Debug: key = "%{Realm}" Wed Dec 13 16:11:19 2017 : Debug: relaxed = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "attr_filter.pre-proxy" from file /etc/raddb/mods-enabled/attr_filter Wed Dec 13 16:11:19 2017 : Debug: attr_filter attr_filter.pre-proxy { Wed Dec 13 16:11:19 2017 : Debug: filename = "/etc/raddb/mods-config/attr_filter/pre-proxy" Wed Dec 13 16:11:19 2017 : Debug: key = "%{Realm}" Wed Dec 13 16:11:19 2017 : Debug: relaxed = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "attr_filter.access_reject" from file /etc/raddb/mods-enabled/attr_filter Wed Dec 13 16:11:19 2017 : Debug: attr_filter attr_filter.access_reject { Wed Dec 13 16:11:19 2017 : Debug: filename = "/etc/raddb/mods-config/attr_filter/access_reject" Wed Dec 13 16:11:19 2017 : Debug: key = "%{User-Name}" Wed Dec 13 16:11:19 2017 : Debug: relaxed = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "attr_filter.access_challenge" from file /etc/raddb/mods-enabled/attr_filter Wed Dec 13 16:11:19 2017 : Debug: attr_filter attr_filter.access_challenge { Wed Dec 13 16:11:19 2017 : Debug: filename = "/etc/raddb/mods-config/attr_filter/access_challenge" Wed Dec 13 16:11:19 2017 : Debug: key = "%{User-Name}" Wed Dec 13 16:11:19 2017 : Debug: relaxed = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "attr_filter.accounting_response" from file /etc/raddb/mods-enabled/attr_filter Wed Dec 13 16:11:19 2017 : Debug: attr_filter attr_filter.accounting_response { Wed Dec 13 16:11:19 2017 : Debug: filename = "/etc/raddb/mods-config/attr_filter/accounting_response" Wed Dec 13 16:11:19 2017 : Debug: key = "%{User-Name}" Wed Dec 13 16:11:19 2017 : Debug: relaxed = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_cache with path: /usr/lib64/freeradius/rlm_cache.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_cache, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_cache Wed Dec 13 16:11:19 2017 : Debug: # Loading module "cache_eap" from file /etc/raddb/mods-enabled/cache_eap Wed Dec 13 16:11:19 2017 : Debug: cache cache_eap { Wed Dec 13 16:11:19 2017 : Debug: driver = "rlm_cache_rbtree" Wed Dec 13 16:11:19 2017 : Debug: key = "%{%{control:State}:-%{%{reply:State}:-%{State}}}" Wed Dec 13 16:11:19 2017 : Debug: ttl = 15 Wed Dec 13 16:11:19 2017 : Debug: max_entries = 0 Wed Dec 13 16:11:19 2017 : Debug: epoch = 0 Wed Dec 13 16:11:19 2017 : Debug: add_stats = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_chap with path: /usr/lib64/freeradius/rlm_chap.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_chap, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_chap Wed Dec 13 16:11:19 2017 : Debug: # Loading module "chap" from file /etc/raddb/mods-enabled/chap Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_date with path: /usr/lib64/freeradius/rlm_date.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_date, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_date Wed Dec 13 16:11:19 2017 : Debug: # Loading module "date" from file /etc/raddb/mods-enabled/date Wed Dec 13 16:11:19 2017 : Debug: date { Wed Dec 13 16:11:19 2017 : Debug: format = "%b %e %Y %H:%M:%S %Z" Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_detail with path: /usr/lib64/freeradius/rlm_detail.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_detail, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_detail Wed Dec 13 16:11:19 2017 : Debug: # Loading module "detail" from file /etc/raddb/mods-enabled/detail Wed Dec 13 16:11:19 2017 : Debug: detail { Wed Dec 13 16:11:19 2017 : Debug: filename = "/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d" Wed Dec 13 16:11:19 2017 : Debug: header = "%t" Wed Dec 13 16:11:19 2017 : Debug: permissions = 384 Wed Dec 13 16:11:19 2017 : Debug: locking = no Wed Dec 13 16:11:19 2017 : Debug: escape_filenames = no Wed Dec 13 16:11:19 2017 : Debug: log_packet_header = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "auth_log" from file /etc/raddb/mods-enabled/detail.log Wed Dec 13 16:11:19 2017 : Debug: detail auth_log { Wed Dec 13 16:11:19 2017 : Debug: filename = "/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d" Wed Dec 13 16:11:19 2017 : Debug: header = "%t" Wed Dec 13 16:11:19 2017 : Debug: permissions = 384 Wed Dec 13 16:11:19 2017 : Debug: locking = no Wed Dec 13 16:11:19 2017 : Debug: escape_filenames = no Wed Dec 13 16:11:19 2017 : Debug: log_packet_header = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "reply_log" from file /etc/raddb/mods-enabled/detail.log Wed Dec 13 16:11:19 2017 : Debug: detail reply_log { Wed Dec 13 16:11:19 2017 : Debug: filename = "/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/reply-detail-%Y%m%d" Wed Dec 13 16:11:19 2017 : Debug: header = "%t" Wed Dec 13 16:11:19 2017 : Debug: permissions = 384 Wed Dec 13 16:11:19 2017 : Debug: locking = no Wed Dec 13 16:11:19 2017 : Debug: escape_filenames = no Wed Dec 13 16:11:19 2017 : Debug: log_packet_header = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "pre_proxy_log" from file /etc/raddb/mods-enabled/detail.log Wed Dec 13 16:11:19 2017 : Debug: detail pre_proxy_log { Wed Dec 13 16:11:19 2017 : Debug: filename = "/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/pre-proxy-detail-%Y%m%d" Wed Dec 13 16:11:19 2017 : Debug: header = "%t" Wed Dec 13 16:11:19 2017 : Debug: permissions = 384 Wed Dec 13 16:11:19 2017 : Debug: locking = no Wed Dec 13 16:11:19 2017 : Debug: escape_filenames = no Wed Dec 13 16:11:19 2017 : Debug: log_packet_header = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "post_proxy_log" from file /etc/raddb/mods-enabled/detail.log Wed Dec 13 16:11:19 2017 : Debug: detail post_proxy_log { Wed Dec 13 16:11:19 2017 : Debug: filename = "/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/post-proxy-detail-%Y%m%d" Wed Dec 13 16:11:19 2017 : Debug: header = "%t" Wed Dec 13 16:11:19 2017 : Debug: permissions = 384 Wed Dec 13 16:11:19 2017 : Debug: locking = no Wed Dec 13 16:11:19 2017 : Debug: escape_filenames = no Wed Dec 13 16:11:19 2017 : Debug: log_packet_header = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_dhcp with path: /usr/lib64/freeradius/rlm_dhcp.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_dhcp, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_dhcp Wed Dec 13 16:11:19 2017 : Debug: # Loading module "dhcp" from file /etc/raddb/mods-enabled/dhcp Wed Dec 13 16:11:19 2017 : Debug: Adding values for DHCP-Parameter-Request-List Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 1 DHCP-Subnet-Mask Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 2 DHCP-Time-Offset Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 3 DHCP-Router-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 4 DHCP-Time-Server Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 5 DHCP-IEN-116-Name-Server Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 6 DHCP-Domain-Name-Server Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 7 DHCP-Log-Server Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 8 DHCP-Quotes-Server Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 9 DHCP-LPR-Server Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 10 DHCP-Impress-Server Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 11 DHCP-RLP-Server Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 12 DHCP-Hostname Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 13 DHCP-Boot-File-Size Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 14 DHCP-Merit-Dump-File Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 15 DHCP-Domain-Name Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 16 DHCP-Swap-Server Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 17 DHCP-Root-Path Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 18 DHCP-Bootp-Extensions-Path Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 19 DHCP-IP-Forward-Enable Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 20 DHCP-Source-Route-Enable Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 21 DHCP-Policy-Filter Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 22 DHCP-Max-Datagram-Reassembly-Size Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 23 DHCP-Default-IP-TTL Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 24 DHCP-Path-MTU-Aging-Timeout Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 25 DHCP-Path-MTU-Plateau-Table Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 26 DHCP-Interface-MTU-Size Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 27 DHCP-All-Subnets-Are-Local Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 28 DHCP-Broadcast-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 29 DHCP-Perform-Mask-Discovery Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 30 DHCP-Provide-Mask-To-Others Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 31 DHCP-Perform-Router-Discovery Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 32 DHCP-Router-Solicitation-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 33 DHCP-Static-Routes Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 34 DHCP-Trailer-Encapsulation Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 35 DHCP-ARP-Cache-Timeout Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 36 DHCP-Ethernet-Encapsulation Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 37 DHCP-Default-TCP-TTL Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 38 DHCP-Keep-Alive-Interval Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 39 DHCP-Keep-Alive-Garbage Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 40 DHCP-NIS-Domain-Name Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 41 DHCP-NIS-Servers Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 42 DHCP-NTP-Servers Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 43 DHCP-Vendor Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 44 DHCP-NETBIOS-Name-Servers Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 45 DHCP-NETBIOS-Dgm-Dist-Servers Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 46 DHCP-NETBIOS-Node-Type Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 47 DHCP-NETBIOS Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 48 DHCP-X-Window-Font-Server Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 49 DHCP-X-Window-Display-Mgr Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 50 DHCP-Requested-IP-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 51 DHCP-IP-Address-Lease-Time Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 52 DHCP-Overload Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 53 DHCP-Message-Type Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 54 DHCP-DHCP-Server-Identifier Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 55 DHCP-Parameter-Request-List Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 56 DHCP-DHCP-Error-Message Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 57 DHCP-DHCP-Maximum-Msg-Size Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 58 DHCP-Renewal-Time Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 59 DHCP-Rebinding-Time Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 60 DHCP-Vendor-Class-Identifier Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 61 DHCP-Client-Identifier Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 62 DHCP-Netware-Domain-Name Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 63 DHCP-Netware-Sub-Options Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 64 DHCP-NIS-Client-Domain-Name Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 65 DHCP-NIS-Server-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 66 DHCP-TFTP-Server-Name Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 67 DHCP-Boot-File-Name Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 68 DHCP-Home-Agent-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 69 DHCP-SMTP-Server-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 70 DHCP-POP3-Server-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 71 DHCP-NNTP-Server-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 72 DHCP-WWW-Server-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 73 DHCP-Finger-Server-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 74 DHCP-IRC-Server-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 75 DHCP-StreetTalk-Server-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 76 DHCP-STDA-Server-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 77 DHCP-User-Class Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 78 DHCP-Directory-Agent Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 79 DHCP-Service-Scope Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 80 DHCP-Rapid-Commit Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 81 DHCP-Client-FQDN Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 82 DHCP-Relay-Agent-Information Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 83 DHCP-iSNS Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 84 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 85 DHCP-NDS-Servers Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 86 DHCP-NDS-Tree-Name Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 87 DHCP-NDS-Context Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 88 DHCP-BCMS-Server-IPv4-FQDN Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 89 DHCP-BCMS-Server-IPv4-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 90 DHCP-Authentication Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 91 DHCP-Client-Last-Txn-Time Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 92 DHCP-associated-ip Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 93 DHCP-Client-System Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 94 DHCP-Client-NDI Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 95 DHCP-LDAP Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 96 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 97 DHCP-UUID/GUID Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 98 DHCP-User-Auth Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 99 DHCP-GeoConf-Civic Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 100 DHCP-Timezone-Posix Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 101 DHCP-Timezone-Database Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 102 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 103 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 104 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 105 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 106 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 107 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 108 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 109 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 110 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 111 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 112 DHCP-Netinfo-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 113 DHCP-Netinfo-Tag Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 114 DHCP-URL Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 115 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 116 DHCP-Auto-Config Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 117 DHCP-Name-Service-Search Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 118 DHCP-Subnet-Selection-Option Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 119 DHCP-Domain-Search Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 120 DHCP-SIP-Servers-DHCP-Option Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 121 DHCP-Classless-Static-Route Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 122 DHCP-CCC Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 123 DHCP-GeoConf-Option Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 124 DHCP-V-I-Vendor-Class Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 125 DHCP-V-I-Vendor-Specific Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 126 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 127 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 128 DHCP-TFTP-Server-IP-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 129 DHCP-Call-Server-IP-address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 130 DHCP-Vendor-Discrimination-Str Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 131 DHCP-Remote-Stats-Svr-IP-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 132 DHCP-IEEE-802.1P-VLAN-ID Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 133 DHCP-IEEE-802.1Q-L2-Priority Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 134 DHCP-Diffserv-Code-Point Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 135 DHCP-HTTP-Proxy Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 136 DHCP-PANA-Agent Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 137 DHCP-LoST-Server Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 138 DHCP-CAPWAP-AC-IPv4-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 139 DHCP-MoS-IPv4-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 140 DHCP-MoS-IPv4-FQDN Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 141 DHCP-SIP-UA-Configuration-Service-Domains Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 142 DHCP-ANDSF-IPv4-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 143 DHCP-ANDSF-IPv6-Address Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 144 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 145 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 146 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 147 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 148 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 149 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 150 DHCP-TFTP-Server-IPv4-Address Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 151 DHCP-Query-Status-Code Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 152 DHCP-Query-Server-Base-Time Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 153 DHCP-Query-Start-Time-Of-State Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 154 DHCP-Query-Start-Time Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 155 DHCP-Query-End-Time Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 156 DHCP-State Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 157 DHCP-Data-Source Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 158 DHCP-PCP-IPv4-Server-Address Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 159 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 160 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 161 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 162 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 163 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 164 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 165 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 166 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 167 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 168 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 169 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 170 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 171 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 172 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 173 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 174 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 175 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 176 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 177 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 178 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 179 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 180 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 181 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 182 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 183 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 184 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 185 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 186 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 187 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 188 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 189 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 190 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 191 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 192 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 193 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 194 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 195 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 196 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 197 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 198 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 199 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 200 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 201 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 202 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 203 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 204 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 205 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 206 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 207 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 208 DHCP-PXELINUX-Magic Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 209 DHCP-Packet-Format Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 210 DHCP-Path-Prefix Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 211 DHCP-Reboot-Time Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 212 DHCP-6RD Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 213 DHCP-Access-Network-Domain-Name Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 214 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 215 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 216 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 217 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 218 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 219 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 220 DHCP-Virtual-Subnet-Allocation Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 221 DHCP-Virtual-Subnet-Selection Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 222 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 223 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 224 DHCP-Site-specific-0 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 225 DHCP-Site-specific-1 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 226 DHCP-Site-specific-2 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 227 DHCP-Site-specific-3 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 228 DHCP-Site-specific-4 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 229 DHCP-Site-specific-5 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 230 DHCP-Site-specific-6 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 231 DHCP-Site-specific-7 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 232 DHCP-Site-specific-8 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 233 DHCP-Site-specific-9 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 234 DHCP-Site-specific-10 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 235 DHCP-Site-specific-11 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 236 DHCP-Site-specific-12 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 237 DHCP-Site-specific-13 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 238 DHCP-Site-specific-14 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 239 DHCP-Site-specific-15 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 240 DHCP-Site-specific-16 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 241 DHCP-Site-specific-17 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 242 DHCP-Site-specific-18 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 243 DHCP-Site-specific-19 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 244 DHCP-Site-specific-20 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 245 DHCP-Site-specific-21 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 246 DHCP-Site-specific-22 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 247 DHCP-Site-specific-23 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 248 DHCP-Site-specific-24 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 249 DHCP-Site-specific-25 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 250 DHCP-Site-specific-26 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 251 DHCP-Site-specific-27 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 252 DHCP-Site-specific-28 Wed Dec 13 16:11:19 2017 : Debug: Adding DHCP-Parameter-Request-List value 253 DHCP-Site-specific-30 Wed Dec 13 16:11:19 2017 : Debug: No DHCP RFC space attribute at 254 Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_digest with path: /usr/lib64/freeradius/rlm_digest.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_digest, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_digest Wed Dec 13 16:11:19 2017 : Debug: # Loading module "digest" from file /etc/raddb/mods-enabled/digest Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_dynamic_clients with path: /usr/lib64/freeradius/rlm_dynamic_clients.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_dynamic_clients, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_dynamic_clients Wed Dec 13 16:11:19 2017 : Debug: # Loading module "dynamic_clients" from file /etc/raddb/mods-enabled/dynamic_clients Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_eap with path: /usr/lib64/freeradius/rlm_eap.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_eap, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_eap Wed Dec 13 16:11:19 2017 : Debug: # Loading module "eap" from file /etc/raddb/mods-enabled/eap Wed Dec 13 16:11:19 2017 : Debug: eap { Wed Dec 13 16:11:19 2017 : Debug: default_eap_type = "md5" Wed Dec 13 16:11:19 2017 : Debug: timer_expire = 60 Wed Dec 13 16:11:19 2017 : Debug: ignore_unknown_eap_types = no Wed Dec 13 16:11:19 2017 : Debug: cisco_accounting_username_bug = no Wed Dec 13 16:11:19 2017 : Debug: max_sessions = 16384 Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_exec with path: /usr/lib64/freeradius/rlm_exec.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_exec, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_exec Wed Dec 13 16:11:19 2017 : Debug: # Loading module "echo" from file /etc/raddb/mods-enabled/echo Wed Dec 13 16:11:19 2017 : Debug: exec echo { Wed Dec 13 16:11:19 2017 : Debug: wait = yes Wed Dec 13 16:11:19 2017 : Debug: program = "/bin/echo %{User-Name}" Wed Dec 13 16:11:19 2017 : Debug: input_pairs = "request" Wed Dec 13 16:11:19 2017 : Debug: output_pairs = "reply" Wed Dec 13 16:11:19 2017 : Debug: shell_escape = yes Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "exec" from file /etc/raddb/mods-enabled/exec Wed Dec 13 16:11:19 2017 : Debug: exec { Wed Dec 13 16:11:19 2017 : Debug: wait = no Wed Dec 13 16:11:19 2017 : Debug: input_pairs = "request" Wed Dec 13 16:11:19 2017 : Debug: shell_escape = yes Wed Dec 13 16:11:19 2017 : Debug: timeout = 10 Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_expiration with path: /usr/lib64/freeradius/rlm_expiration.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_expiration, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_expiration Wed Dec 13 16:11:19 2017 : Debug: # Loading module "expiration" from file /etc/raddb/mods-enabled/expiration Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_expr with path: /usr/lib64/freeradius/rlm_expr.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_expr, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_expr Wed Dec 13 16:11:19 2017 : Debug: # Loading module "expr" from file /etc/raddb/mods-enabled/expr Wed Dec 13 16:11:19 2017 : Debug: expr { Wed Dec 13 16:11:19 2017 : Debug: safe_characters = "@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /äéöüàâæçèéêëîïôœùûüaÿÄÉÖÜßÀÂÆÇÈÉÊËÎÏÔŒÙÛÜŸ" Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_files with path: /usr/lib64/freeradius/rlm_files.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_files, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_files Wed Dec 13 16:11:19 2017 : Debug: # Loading module "files" from file /etc/raddb/mods-enabled/files Wed Dec 13 16:11:19 2017 : Debug: files { Wed Dec 13 16:11:19 2017 : Debug: filename = "/etc/raddb/mods-config/files/authorize" Wed Dec 13 16:11:19 2017 : Debug: acctusersfile = "/etc/raddb/mods-config/files/accounting" Wed Dec 13 16:11:19 2017 : Debug: preproxy_usersfile = "/etc/raddb/mods-config/files/pre-proxy" Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_linelog with path: /usr/lib64/freeradius/rlm_linelog.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_linelog, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_linelog Wed Dec 13 16:11:19 2017 : Debug: # Loading module "linelog" from file /etc/raddb/mods-enabled/linelog Wed Dec 13 16:11:19 2017 : Debug: linelog { Wed Dec 13 16:11:19 2017 : Debug: filename = "/var/log/radius/linelog" Wed Dec 13 16:11:19 2017 : Debug: escape_filenames = no Wed Dec 13 16:11:19 2017 : Debug: syslog_severity = "info" Wed Dec 13 16:11:19 2017 : Debug: permissions = 384 Wed Dec 13 16:11:19 2017 : Debug: format = "This is a log message for %{User-Name}" Wed Dec 13 16:11:19 2017 : Debug: reference = "messages.%{%{reply:Packet-Type}:-default}" Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "log_accounting" from file /etc/raddb/mods-enabled/linelog Wed Dec 13 16:11:19 2017 : Debug: linelog log_accounting { Wed Dec 13 16:11:19 2017 : Debug: filename = "/var/log/radius/linelog-accounting" Wed Dec 13 16:11:19 2017 : Debug: escape_filenames = no Wed Dec 13 16:11:19 2017 : Debug: syslog_severity = "info" Wed Dec 13 16:11:19 2017 : Debug: permissions = 384 Wed Dec 13 16:11:19 2017 : Debug: format = "" Wed Dec 13 16:11:19 2017 : Debug: reference = "Accounting-Request.%{%{Acct-Status-Type}:-unknown}" Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_logintime with path: /usr/lib64/freeradius/rlm_logintime.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_logintime, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_logintime Wed Dec 13 16:11:19 2017 : Debug: # Loading module "logintime" from file /etc/raddb/mods-enabled/logintime Wed Dec 13 16:11:19 2017 : Debug: logintime { Wed Dec 13 16:11:19 2017 : Debug: minimum_timeout = 60 Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_mschap with path: /usr/lib64/freeradius/rlm_mschap.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_mschap, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_mschap Wed Dec 13 16:11:19 2017 : Debug: # Loading module "mschap" from file /etc/raddb/mods-enabled/mschap Wed Dec 13 16:11:19 2017 : Debug: mschap { Wed Dec 13 16:11:19 2017 : Debug: use_mppe = yes Wed Dec 13 16:11:19 2017 : Debug: require_encryption = no Wed Dec 13 16:11:19 2017 : Debug: require_strong = no Wed Dec 13 16:11:19 2017 : Debug: with_ntdomain_hack = yes Wed Dec 13 16:11:19 2017 : Debug: passchange { Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: allow_retry = yes Wed Dec 13 16:11:19 2017 : Debug: winbind_retry_with_normalised_username = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "ntlm_auth" from file /etc/raddb/mods-enabled/ntlm_auth Wed Dec 13 16:11:19 2017 : Debug: exec ntlm_auth { Wed Dec 13 16:11:19 2017 : Debug: wait = yes Wed Dec 13 16:11:19 2017 : Debug: program = "/path/to/ntlm_auth --request-nt-key --domain=MYDOMAIN --username=%{mschap:User-Name} --password=%{User-Password}" Wed Dec 13 16:11:19 2017 : Debug: shell_escape = yes Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_pap with path: /usr/lib64/freeradius/rlm_pap.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_pap, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_pap Wed Dec 13 16:11:19 2017 : Debug: # Loading module "pap" from file /etc/raddb/mods-enabled/pap Wed Dec 13 16:11:19 2017 : Debug: pap { Wed Dec 13 16:11:19 2017 : Debug: normalise = yes Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_passwd with path: /usr/lib64/freeradius/rlm_passwd.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_passwd, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_passwd Wed Dec 13 16:11:19 2017 : Debug: # Loading module "etc_passwd" from file /etc/raddb/mods-enabled/passwd Wed Dec 13 16:11:19 2017 : Debug: passwd etc_passwd { Wed Dec 13 16:11:19 2017 : Debug: filename = "/etc/passwd" Wed Dec 13 16:11:19 2017 : Debug: format = "*User-Name:Crypt-Password:" Wed Dec 13 16:11:19 2017 : Debug: delimiter = ":" Wed Dec 13 16:11:19 2017 : Debug: ignore_nislike = no Wed Dec 13 16:11:19 2017 : Debug: ignore_empty = yes Wed Dec 13 16:11:19 2017 : Debug: allow_multiple_keys = no Wed Dec 13 16:11:19 2017 : Debug: hash_size = 100 Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_preprocess with path: /usr/lib64/freeradius/rlm_preprocess.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_preprocess, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_preprocess Wed Dec 13 16:11:19 2017 : Debug: # Loading module "preprocess" from file /etc/raddb/mods-enabled/preprocess Wed Dec 13 16:11:19 2017 : Debug: preprocess { Wed Dec 13 16:11:19 2017 : Debug: huntgroups = "/etc/raddb/mods-config/preprocess/huntgroups" Wed Dec 13 16:11:19 2017 : Debug: hints = "/etc/raddb/mods-config/preprocess/hints" Wed Dec 13 16:11:19 2017 : Debug: with_ascend_hack = no Wed Dec 13 16:11:19 2017 : Debug: ascend_channels_per_line = 23 Wed Dec 13 16:11:19 2017 : Debug: with_ntdomain_hack = no Wed Dec 13 16:11:19 2017 : Debug: with_specialix_jetstream_hack = no Wed Dec 13 16:11:19 2017 : Debug: with_cisco_vsa_hack = no Wed Dec 13 16:11:19 2017 : Debug: with_alvarion_vsa_hack = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_radutmp with path: /usr/lib64/freeradius/rlm_radutmp.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_radutmp, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_radutmp Wed Dec 13 16:11:19 2017 : Debug: # Loading module "radutmp" from file /etc/raddb/mods-enabled/radutmp Wed Dec 13 16:11:19 2017 : Debug: radutmp { Wed Dec 13 16:11:19 2017 : Debug: filename = "/var/log/radius/radutmp" Wed Dec 13 16:11:19 2017 : Debug: username = "%{User-Name}" Wed Dec 13 16:11:19 2017 : Debug: case_sensitive = yes Wed Dec 13 16:11:19 2017 : Debug: check_with_nas = yes Wed Dec 13 16:11:19 2017 : Debug: permissions = 384 Wed Dec 13 16:11:19 2017 : Debug: caller_id = yes Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_realm with path: /usr/lib64/freeradius/rlm_realm.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_realm, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_realm Wed Dec 13 16:11:19 2017 : Debug: # Loading module "IPASS" from file /etc/raddb/mods-enabled/realm Wed Dec 13 16:11:19 2017 : Debug: realm IPASS { Wed Dec 13 16:11:19 2017 : Debug: format = "prefix" Wed Dec 13 16:11:19 2017 : Debug: delimiter = "/" Wed Dec 13 16:11:19 2017 : Debug: ignore_default = no Wed Dec 13 16:11:19 2017 : Debug: ignore_null = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "suffix" from file /etc/raddb/mods-enabled/realm Wed Dec 13 16:11:19 2017 : Debug: realm suffix { Wed Dec 13 16:11:19 2017 : Debug: format = "suffix" Wed Dec 13 16:11:19 2017 : Debug: delimiter = "@" Wed Dec 13 16:11:19 2017 : Debug: ignore_default = no Wed Dec 13 16:11:19 2017 : Debug: ignore_null = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "realmpercent" from file /etc/raddb/mods-enabled/realm Wed Dec 13 16:11:19 2017 : Debug: realm realmpercent { Wed Dec 13 16:11:19 2017 : Debug: format = "suffix" Wed Dec 13 16:11:19 2017 : Debug: delimiter = "%" Wed Dec 13 16:11:19 2017 : Debug: ignore_default = no Wed Dec 13 16:11:19 2017 : Debug: ignore_null = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "ntdomain" from file /etc/raddb/mods-enabled/realm Wed Dec 13 16:11:19 2017 : Debug: realm ntdomain { Wed Dec 13 16:11:19 2017 : Debug: format = "prefix" Wed Dec 13 16:11:19 2017 : Debug: delimiter = "\\" Wed Dec 13 16:11:19 2017 : Debug: ignore_default = no Wed Dec 13 16:11:19 2017 : Debug: ignore_null = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_replicate with path: /usr/lib64/freeradius/rlm_replicate.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_replicate, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_replicate Wed Dec 13 16:11:19 2017 : Debug: # Loading module "replicate" from file /etc/raddb/mods-enabled/replicate Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_soh with path: /usr/lib64/freeradius/rlm_soh.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_soh, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_soh Wed Dec 13 16:11:19 2017 : Debug: # Loading module "soh" from file /etc/raddb/mods-enabled/soh Wed Dec 13 16:11:19 2017 : Debug: soh { Wed Dec 13 16:11:19 2017 : Debug: dhcp = yes Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Loading module "sradutmp" from file /etc/raddb/mods-enabled/sradutmp Wed Dec 13 16:11:19 2017 : Debug: radutmp sradutmp { Wed Dec 13 16:11:19 2017 : Debug: filename = "/var/log/radius/sradutmp" Wed Dec 13 16:11:19 2017 : Debug: username = "%{User-Name}" Wed Dec 13 16:11:19 2017 : Debug: case_sensitive = yes Wed Dec 13 16:11:19 2017 : Debug: check_with_nas = yes Wed Dec 13 16:11:19 2017 : Debug: permissions = 420 Wed Dec 13 16:11:19 2017 : Debug: caller_id = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_unix with path: /usr/lib64/freeradius/rlm_unix.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_unix, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_unix Wed Dec 13 16:11:19 2017 : Debug: # Loading module "unix" from file /etc/raddb/mods-enabled/unix Wed Dec 13 16:11:19 2017 : Debug: unix { Wed Dec 13 16:11:19 2017 : Debug: radwtmp = "/var/log/radius/radwtmp" Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Creating attribute Unix-Group Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_unpack with path: /usr/lib64/freeradius/rlm_unpack.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_unpack, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_unpack Wed Dec 13 16:11:19 2017 : Debug: # Loading module "unpack" from file /etc/raddb/mods-enabled/unpack Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_utf8 with path: /usr/lib64/freeradius/rlm_utf8.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_utf8, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_utf8 Wed Dec 13 16:11:19 2017 : Debug: # Loading module "utf8" from file /etc/raddb/mods-enabled/utf8 Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_ldap with path: /usr/lib64/freeradius/rlm_ldap.so Wed Dec 13 16:11:19 2017 : Debug: Loaded rlm_ldap, checking if it's valid Wed Dec 13 16:11:19 2017 : Debug: # Loaded module rlm_ldap Wed Dec 13 16:11:19 2017 : Debug: # Loading module "ldap" from file /etc/raddb/mods-enabled/ldap Wed Dec 13 16:11:19 2017 : Debug: ldap { Wed Dec 13 16:11:19 2017 : Debug: server = "172.18.98.110" Wed Dec 13 16:11:19 2017 : Debug: sasl { Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: user { Wed Dec 13 16:11:19 2017 : Debug: scope = "sub" Wed Dec 13 16:11:19 2017 : Debug: access_positive = yes Wed Dec 13 16:11:19 2017 : Debug: sasl { Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: group { Wed Dec 13 16:11:19 2017 : Debug: filter = "(objectClass=posixGroup)" Wed Dec 13 16:11:19 2017 : Debug: scope = "sub" Wed Dec 13 16:11:19 2017 : Debug: name_attribute = "cn" Wed Dec 13 16:11:19 2017 : Debug: membership_attribute = "memberOf" Wed Dec 13 16:11:19 2017 : Debug: cacheable_name = no Wed Dec 13 16:11:19 2017 : Debug: cacheable_dn = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: client { Wed Dec 13 16:11:19 2017 : Debug: filter = "(objectClass=radiusClient)" Wed Dec 13 16:11:19 2017 : Debug: scope = "sub" Wed Dec 13 16:11:19 2017 : Debug: base_dn = "cn=Users,dc=*****,dc=net" Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: profile { Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: options { Wed Dec 13 16:11:19 2017 : Debug: ldap_debug = 40 Wed Dec 13 16:11:19 2017 : Debug: chase_referrals = yes Wed Dec 13 16:11:19 2017 : Debug: rebind = yes Wed Dec 13 16:11:19 2017 : Debug: net_timeout = 1 Wed Dec 13 16:11:19 2017 : Debug: res_timeout = 10 Wed Dec 13 16:11:19 2017 : Debug: srv_timelimit = 3 Wed Dec 13 16:11:19 2017 : Debug: idle = 60 Wed Dec 13 16:11:19 2017 : Debug: probes = 3 Wed Dec 13 16:11:19 2017 : Debug: interval = 3 Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: tls { Wed Dec 13 16:11:19 2017 : Debug: start_tls = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Creating attribute LDAP-Group Wed Dec 13 16:11:19 2017 : Debug: instantiate { Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "reject" from file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "fail" from file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "ok" from file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "handled" from file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "invalid" from file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "userlock" from file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "notfound" from file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "noop" from file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "updated" from file /etc/raddb/mods-enabled/always Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "attr_filter.post-proxy" from file /etc/raddb/mods-enabled/attr_filter Wed Dec 13 16:11:19 2017 : Debug: reading pairlist file /etc/raddb/mods-config/attr_filter/post-proxy Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "attr_filter.pre-proxy" from file /etc/raddb/mods-enabled/attr_filter Wed Dec 13 16:11:19 2017 : Debug: reading pairlist file /etc/raddb/mods-config/attr_filter/pre-proxy Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "attr_filter.access_reject" from file /etc/raddb/mods-enabled/attr_filter Wed Dec 13 16:11:19 2017 : Debug: reading pairlist file /etc/raddb/mods-config/attr_filter/access_reject Wed Dec 13 16:11:19 2017 : Warning: [/etc/raddb/mods-config/attr_filter/access_reject]:11 Check item "FreeRADIUS-Response-Delay" found in filter list for realm "DEFAULT". Wed Dec 13 16:11:19 2017 : Warning: [/etc/raddb/mods-config/attr_filter/access_reject]:11 Check item "FreeRADIUS-Response-Delay-USec" found in filter list for realm "DEFAULT". Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "attr_filter.access_challenge" from file /etc/raddb/mods-enabled/attr_filter Wed Dec 13 16:11:19 2017 : Debug: reading pairlist file /etc/raddb/mods-config/attr_filter/access_challenge Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "attr_filter.accounting_response" from file /etc/raddb/mods-enabled/attr_filter Wed Dec 13 16:11:19 2017 : Debug: reading pairlist file /etc/raddb/mods-config/attr_filter/accounting_response Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "cache_eap" from file /etc/raddb/mods-enabled/cache_eap Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_cache_rbtree with path: /usr/lib64/freeradius/rlm_cache_rbtree.so Wed Dec 13 16:11:19 2017 : Debug: rlm_cache (cache_eap): Driver rlm_cache_rbtree (module rlm_cache_rbtree) loaded and linked Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "detail" from file /etc/raddb/mods-enabled/detail Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "auth_log" from file /etc/raddb/mods-enabled/detail.log Wed Dec 13 16:11:19 2017 : Debug: rlm_detail (auth_log): 'User-Password' suppressed, will not appear in detail output Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "reply_log" from file /etc/raddb/mods-enabled/detail.log Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "pre_proxy_log" from file /etc/raddb/mods-enabled/detail.log Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "post_proxy_log" from file /etc/raddb/mods-enabled/detail.log Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "eap" from file /etc/raddb/mods-enabled/eap Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_eap_md5 with path: /usr/lib64/freeradius/rlm_eap_md5.so Wed Dec 13 16:11:19 2017 : Debug: # Linked to sub-module rlm_eap_md5 Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_eap_leap with path: /usr/lib64/freeradius/rlm_eap_leap.so Wed Dec 13 16:11:19 2017 : Debug: # Linked to sub-module rlm_eap_leap Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_eap_gtc with path: /usr/lib64/freeradius/rlm_eap_gtc.so Wed Dec 13 16:11:19 2017 : Debug: # Linked to sub-module rlm_eap_gtc Wed Dec 13 16:11:19 2017 : Debug: gtc { Wed Dec 13 16:11:19 2017 : Debug: challenge = "Password: " Wed Dec 13 16:11:19 2017 : Debug: auth_type = "PAP" Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_eap_tls with path: /usr/lib64/freeradius/rlm_eap_tls.so Wed Dec 13 16:11:19 2017 : Debug: # Linked to sub-module rlm_eap_tls Wed Dec 13 16:11:19 2017 : Debug: tls { Wed Dec 13 16:11:19 2017 : Debug: tls = "tls-common" Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: tls-config tls-common { Wed Dec 13 16:11:19 2017 : Debug: verify_depth = 0 Wed Dec 13 16:11:19 2017 : Debug: ca_path = "/etc/raddb/certs" Wed Dec 13 16:11:19 2017 : Debug: pem_file_type = yes Wed Dec 13 16:11:19 2017 : Debug: private_key_file = "/etc/raddb/certs/server.pem" Wed Dec 13 16:11:19 2017 : Debug: certificate_file = "/etc/raddb/certs/server.pem" Wed Dec 13 16:11:19 2017 : Debug: ca_file = "/etc/raddb/certs/ca.pem" Wed Dec 13 16:11:19 2017 : Debug: private_key_password = "whatever" Wed Dec 13 16:11:19 2017 : Debug: dh_file = "/etc/raddb/certs/dh" Wed Dec 13 16:11:19 2017 : Debug: fragment_size = 1024 Wed Dec 13 16:11:19 2017 : Debug: include_length = yes Wed Dec 13 16:11:19 2017 : Debug: auto_chain = yes Wed Dec 13 16:11:19 2017 : Debug: check_crl = no Wed Dec 13 16:11:19 2017 : Debug: check_all_crl = no Wed Dec 13 16:11:19 2017 : Debug: cipher_list = "DEFAULT" Wed Dec 13 16:11:19 2017 : Debug: cipher_server_preference = no Wed Dec 13 16:11:19 2017 : Debug: ecdh_curve = "prime256v1" Wed Dec 13 16:11:19 2017 : Debug: cache { Wed Dec 13 16:11:19 2017 : Debug: enable = no Wed Dec 13 16:11:19 2017 : Debug: lifetime = 24 Wed Dec 13 16:11:19 2017 : Debug: max_entries = 255 Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: verify { Wed Dec 13 16:11:19 2017 : Debug: skip_if_ocsp_ok = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: ocsp { Wed Dec 13 16:11:19 2017 : Debug: enable = no Wed Dec 13 16:11:19 2017 : Debug: override_cert_url = yes Wed Dec 13 16:11:19 2017 : Debug: url = "http://127.0.0.1/ocsp/" Wed Dec 13 16:11:19 2017 : Debug: use_nonce = yes Wed Dec 13 16:11:19 2017 : Debug: timeout = 0 Wed Dec 13 16:11:19 2017 : Debug: softfail = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_eap_ttls with path: /usr/lib64/freeradius/rlm_eap_ttls.so Wed Dec 13 16:11:19 2017 : Debug: # Linked to sub-module rlm_eap_ttls Wed Dec 13 16:11:19 2017 : Debug: ttls { Wed Dec 13 16:11:19 2017 : Debug: tls = "tls-common" Wed Dec 13 16:11:19 2017 : Debug: default_eap_type = "md5" Wed Dec 13 16:11:19 2017 : Debug: copy_request_to_tunnel = no Wed Dec 13 16:11:19 2017 : Debug: use_tunneled_reply = no Wed Dec 13 16:11:19 2017 : Debug: virtual_server = "inner-tunnel" Wed Dec 13 16:11:19 2017 : Debug: include_length = yes Wed Dec 13 16:11:19 2017 : Debug: require_client_cert = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: tls: Using cached TLS configuration from previous invocation Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_eap_peap with path: /usr/lib64/freeradius/rlm_eap_peap.so Wed Dec 13 16:11:19 2017 : Debug: # Linked to sub-module rlm_eap_peap Wed Dec 13 16:11:19 2017 : Debug: peap { Wed Dec 13 16:11:19 2017 : Debug: tls = "tls-common" Wed Dec 13 16:11:19 2017 : Debug: default_eap_type = "mschapv2" Wed Dec 13 16:11:19 2017 : Debug: copy_request_to_tunnel = no Wed Dec 13 16:11:19 2017 : Debug: use_tunneled_reply = no Wed Dec 13 16:11:19 2017 : Debug: proxy_tunneled_request_as_eap = yes Wed Dec 13 16:11:19 2017 : Debug: virtual_server = "inner-tunnel" Wed Dec 13 16:11:19 2017 : Debug: soh = no Wed Dec 13 16:11:19 2017 : Debug: require_client_cert = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: tls: Using cached TLS configuration from previous invocation Wed Dec 13 16:11:19 2017 : Debug: Loading rlm_eap_mschapv2 with path: /usr/lib64/freeradius/rlm_eap_mschapv2.so Wed Dec 13 16:11:19 2017 : Debug: # Linked to sub-module rlm_eap_mschapv2 Wed Dec 13 16:11:19 2017 : Debug: mschapv2 { Wed Dec 13 16:11:19 2017 : Debug: with_ntdomain_hack = no Wed Dec 13 16:11:19 2017 : Debug: send_error = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "expiration" from file /etc/raddb/mods-enabled/expiration Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "files" from file /etc/raddb/mods-enabled/files Wed Dec 13 16:11:19 2017 : Debug: reading pairlist file /etc/raddb/mods-config/files/authorize Wed Dec 13 16:11:19 2017 : Debug: reading pairlist file /etc/raddb/mods-config/files/accounting Wed Dec 13 16:11:19 2017 : Debug: reading pairlist file /etc/raddb/mods-config/files/pre-proxy Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "linelog" from file /etc/raddb/mods-enabled/linelog Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "log_accounting" from file /etc/raddb/mods-enabled/linelog Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "logintime" from file /etc/raddb/mods-enabled/logintime Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "mschap" from file /etc/raddb/mods-enabled/mschap Wed Dec 13 16:11:19 2017 : Debug: rlm_mschap (mschap): using internal authentication Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "pap" from file /etc/raddb/mods-enabled/pap Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "etc_passwd" from file /etc/raddb/mods-enabled/passwd Wed Dec 13 16:11:19 2017 : Debug: rlm_passwd: nfields: 3 keyfield 0(User-Name) listable: no Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "preprocess" from file /etc/raddb/mods-enabled/preprocess Wed Dec 13 16:11:19 2017 : Debug: reading pairlist file /etc/raddb/mods-config/preprocess/huntgroups Wed Dec 13 16:11:19 2017 : Debug: reading pairlist file /etc/raddb/mods-config/preprocess/hints Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "IPASS" from file /etc/raddb/mods-enabled/realm Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "suffix" from file /etc/raddb/mods-enabled/realm Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "realmpercent" from file /etc/raddb/mods-enabled/realm Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "ntdomain" from file /etc/raddb/mods-enabled/realm Wed Dec 13 16:11:19 2017 : Debug: # Instantiating module "ldap" from file /etc/raddb/mods-enabled/ldap Wed Dec 13 16:11:19 2017 : Info: rlm_ldap: libldap vendor: OpenLDAP, version: 20444 Wed Dec 13 16:11:19 2017 : Debug: accounting { Wed Dec 13 16:11:19 2017 : Debug: reference = "%{tolower:type.%{Acct-Status-Type}}" Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: post-auth { Wed Dec 13 16:11:19 2017 : Debug: reference = "." Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: LDAP server string: ldap://172.18.98.110:389 Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): Using local pool section Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): No pool reference found for config item "ldap.pool" Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): Initialising connection pool Wed Dec 13 16:11:19 2017 : Debug: pool { Wed Dec 13 16:11:19 2017 : Debug: start = 5 Wed Dec 13 16:11:19 2017 : Debug: min = 3 Wed Dec 13 16:11:19 2017 : Debug: max = 32 Wed Dec 13 16:11:19 2017 : Debug: spare = 10 Wed Dec 13 16:11:19 2017 : Debug: uses = 0 Wed Dec 13 16:11:19 2017 : Debug: lifetime = 0 Wed Dec 13 16:11:19 2017 : Debug: cleanup_interval = 30 Wed Dec 13 16:11:19 2017 : Debug: idle_timeout = 60 Wed Dec 13 16:11:19 2017 : Debug: retry_delay = 30 Wed Dec 13 16:11:19 2017 : Debug: spread = no Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Info: rlm_ldap (ldap): Opening additional connection (0), 1 of 32 pending slots used Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): Connecting to ldap://172.18.98.110:389 Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): New libldap handle 0x55c19315b1f0 Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): Waiting for bind result... Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): Bind successful Wed Dec 13 16:11:19 2017 : Info: rlm_ldap (ldap): Opening additional connection (1), 1 of 31 pending slots used Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): Connecting to ldap://172.18.98.110:389 Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): New libldap handle 0x55c19319cd40 Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): Waiting for bind result... Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): Bind successful Wed Dec 13 16:11:19 2017 : Info: rlm_ldap (ldap): Opening additional connection (2), 1 of 30 pending slots used Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): Connecting to ldap://172.18.98.110:389 Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): New libldap handle 0x55c19319d630 Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): Waiting for bind result... Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): Bind successful Wed Dec 13 16:11:19 2017 : Info: rlm_ldap (ldap): Opening additional connection (3), 1 of 29 pending slots used Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): Connecting to ldap://172.18.98.110:389 Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): New libldap handle 0x55c1931ae090 Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): Waiting for bind result... Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): Bind successful Wed Dec 13 16:11:19 2017 : Info: rlm_ldap (ldap): Opening additional connection (4), 1 of 28 pending slots used Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): Connecting to ldap://172.18.98.110:389 Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): New libldap handle 0x55c1931ae980 Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): Waiting for bind result... Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): Bind successful Wed Dec 13 16:11:19 2017 : Debug: rlm_ldap (ldap): Adding pool reference 0x55c19318de40 to config item "ldap.pool" Wed Dec 13 16:11:19 2017 : Debug: } # modules Wed Dec 13 16:11:19 2017 : Debug: radiusd: #### Loading Virtual Servers #### Wed Dec 13 16:11:19 2017 : Debug: server { # from file /etc/raddb/radiusd.conf Wed Dec 13 16:11:19 2017 : Debug: } # server Wed Dec 13 16:11:19 2017 : Debug: server default { # from file /etc/raddb/sites-enabled/default Wed Dec 13 16:11:19 2017 : Debug: authenticate { Wed Dec 13 16:11:19 2017 : Debug: group { Wed Dec 13 16:11:19 2017 : Debug: pap Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: group { Wed Dec 13 16:11:19 2017 : Debug: chap Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: group { Wed Dec 13 16:11:19 2017 : Debug: mschap Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: mschap Wed Dec 13 16:11:19 2017 : Debug: digest Wed Dec 13 16:11:19 2017 : Debug: group { Wed Dec 13 16:11:19 2017 : Debug: ldap Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: eap Wed Dec 13 16:11:19 2017 : Debug: } # authenticate Wed Dec 13 16:11:19 2017 : Debug: authorize { Wed Dec 13 16:11:19 2017 : Debug: policy filter_username { Wed Dec 13 16:11:19 2017 : Debug: if (&User-Name) { Wed Dec 13 16:11:19 2017 : Debug: if (&User-Name =~ / /) { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &Module-Failure-Message += 'Rejected: User-Name contains whitespace' Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: reject Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: if (&User-Name =~ /@[^@]*@/) { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &Module-Failure-Message += 'Rejected: Multiple @ in User-Name' Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: reject Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: if (&User-Name =~ /\.\./) { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &Module-Failure-Message += 'Rejected: User-Name contains multiple ..s' Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: reject Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: if (&User-Name =~ /@/ && !&User-Name =~ /@(.+)\.(.+)$/) { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &Module-Failure-Message += 'Rejected: Realm does not have at least one dot separator' Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: reject Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: if (&User-Name =~ /\.$/) { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &Module-Failure-Message += 'Rejected: Realm ends with a dot' Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: reject Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: if (&User-Name =~ /@\./) { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &Module-Failure-Message += 'Rejected: Realm begins with a dot' Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: reject Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: preprocess Wed Dec 13 16:11:19 2017 : Debug: chap Wed Dec 13 16:11:19 2017 : Debug: mschap Wed Dec 13 16:11:19 2017 : Debug: digest Wed Dec 13 16:11:19 2017 : Debug: suffix Wed Dec 13 16:11:19 2017 : Debug: eap Wed Dec 13 16:11:19 2017 : Debug: files Wed Dec 13 16:11:19 2017 : Warning: Ignoring "sql" (see raddb/mods-available/README.rst) Wed Dec 13 16:11:19 2017 : Debug: ldap Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/sites-enabled/default[418]: Please change attribute reference to '&control:Auth-Type := ...' Wed Dec 13 16:11:19 2017 : Debug: if ((ok || updated) && &User-Password) { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &control:Auth-Type := LDAP Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: expiration Wed Dec 13 16:11:19 2017 : Debug: logintime Wed Dec 13 16:11:19 2017 : Debug: pap Wed Dec 13 16:11:19 2017 : Debug: } # authorize Wed Dec 13 16:11:19 2017 : Debug: preacct { Wed Dec 13 16:11:19 2017 : Debug: preprocess Wed Dec 13 16:11:19 2017 : Debug: policy acct_unique { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &Tmp-String-9 := "ai:" Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: if ("%{hex:&Class}" =~ /^%{hex:&Tmp-String-9}/ && "%{string:&Class}" =~ /^ai:([0-9a-f]{32})/) { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &Acct-Unique-Session-Id := "%{md5:%{1},%{Acct-Session-ID}}" Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: else { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &Acct-Unique-Session-Id := "%{md5:%{User-Name},%{Acct-Session-ID},%{%{NAS-IPv6-Address}:-%{NAS-IP-Address}},%{NAS-Identifier},%{NAS-Port-ID},%{NAS-Port}}" Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: suffix Wed Dec 13 16:11:19 2017 : Debug: files Wed Dec 13 16:11:19 2017 : Debug: } # preacct Wed Dec 13 16:11:19 2017 : Debug: accounting { Wed Dec 13 16:11:19 2017 : Debug: detail Wed Dec 13 16:11:19 2017 : Debug: unix Wed Dec 13 16:11:19 2017 : Debug: exec Wed Dec 13 16:11:19 2017 : Debug: attr_filter.accounting_response Wed Dec 13 16:11:19 2017 : Debug: } # accounting Wed Dec 13 16:11:19 2017 : Debug: post-proxy { Wed Dec 13 16:11:19 2017 : Debug: eap Wed Dec 13 16:11:19 2017 : Debug: } # post-proxy Wed Dec 13 16:11:19 2017 : Debug: post-auth { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &reply[*] += &session-state[*] Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: ldap Wed Dec 13 16:11:19 2017 : Debug: exec Wed Dec 13 16:11:19 2017 : Debug: policy remove_reply_message_if_eap { Wed Dec 13 16:11:19 2017 : Debug: if (&reply:EAP-Message && &reply:Reply-Message) { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &reply:Reply-Message !* ANY Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: else { Wed Dec 13 16:11:19 2017 : Debug: noop Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: group { Wed Dec 13 16:11:19 2017 : Debug: attr_filter.access_reject Wed Dec 13 16:11:19 2017 : Debug: eap Wed Dec 13 16:11:19 2017 : Debug: policy remove_reply_message_if_eap { Wed Dec 13 16:11:19 2017 : Debug: if (&reply:EAP-Message && &reply:Reply-Message) { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &reply:Reply-Message !* ANY Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: else { Wed Dec 13 16:11:19 2017 : Debug: noop Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: group { Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } # post-auth Wed Dec 13 16:11:19 2017 : Debug: } # server default Wed Dec 13 16:11:19 2017 : Debug: server inner-tunnel { # from file /etc/raddb/sites-enabled/inner-tunnel Wed Dec 13 16:11:19 2017 : Debug: authenticate { Wed Dec 13 16:11:19 2017 : Debug: group { Wed Dec 13 16:11:19 2017 : Debug: pap Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: group { Wed Dec 13 16:11:19 2017 : Debug: chap Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: group { Wed Dec 13 16:11:19 2017 : Debug: mschap Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: mschap Wed Dec 13 16:11:19 2017 : Debug: group { Wed Dec 13 16:11:19 2017 : Debug: ldap Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: eap Wed Dec 13 16:11:19 2017 : Debug: } # authenticate Wed Dec 13 16:11:19 2017 : Debug: authorize { Wed Dec 13 16:11:19 2017 : Debug: policy filter_username { Wed Dec 13 16:11:19 2017 : Debug: if (&User-Name) { Wed Dec 13 16:11:19 2017 : Debug: if (&User-Name =~ / /) { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &Module-Failure-Message += 'Rejected: User-Name contains whitespace' Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: reject Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: if (&User-Name =~ /@[^@]*@/) { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &Module-Failure-Message += 'Rejected: Multiple @ in User-Name' Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: reject Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: if (&User-Name =~ /\.\./) { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &Module-Failure-Message += 'Rejected: User-Name contains multiple ..s' Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: reject Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: if (&User-Name =~ /@/ && !&User-Name =~ /@(.+)\.(.+)$/) { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &Module-Failure-Message += 'Rejected: Realm does not have at least one dot separator' Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: reject Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: if (&User-Name =~ /\.$/) { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &Module-Failure-Message += 'Rejected: Realm ends with a dot' Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: reject Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: if (&User-Name =~ /@\./) { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &Module-Failure-Message += 'Rejected: Realm begins with a dot' Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: reject Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: chap Wed Dec 13 16:11:19 2017 : Debug: mschap Wed Dec 13 16:11:19 2017 : Debug: suffix Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &control:Proxy-To-Realm := LOCAL Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: eap Wed Dec 13 16:11:19 2017 : Debug: files Wed Dec 13 16:11:19 2017 : Debug: ldap Wed Dec 13 16:11:19 2017 : Warning: /etc/raddb/sites-enabled/inner-tunnel[160]: Please change attribute reference to '&control:Auth-Type := ...' Wed Dec 13 16:11:19 2017 : Debug: if ((ok || updated) && &User-Password) { Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &control:Auth-Type := LDAP Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: expiration Wed Dec 13 16:11:19 2017 : Debug: logintime Wed Dec 13 16:11:19 2017 : Debug: pap Wed Dec 13 16:11:19 2017 : Debug: } # authorize Wed Dec 13 16:11:19 2017 : Debug: session { Wed Dec 13 16:11:19 2017 : Debug: radutmp Wed Dec 13 16:11:19 2017 : Debug: } # session Wed Dec 13 16:11:19 2017 : Debug: post-proxy { Wed Dec 13 16:11:19 2017 : Debug: eap Wed Dec 13 16:11:19 2017 : Debug: } # post-proxy Wed Dec 13 16:11:19 2017 : Debug: post-auth { Wed Dec 13 16:11:19 2017 : Debug: ldap Wed Dec 13 16:11:19 2017 : Info: # Skipping contents of 'if' as it is always 'false' -- /etc/raddb/sites-enabled/inner-tunnel:334 Wed Dec 13 16:11:19 2017 : Debug: if (false) { Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: group { Wed Dec 13 16:11:19 2017 : Debug: attr_filter.access_reject Wed Dec 13 16:11:19 2017 : Debug: update { Wed Dec 13 16:11:19 2017 : Debug: &outer.session-state:Module-Failure-Message := &Module-Failure-Message Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } # post-auth Wed Dec 13 16:11:19 2017 : Debug: } # server inner-tunnel Wed Dec 13 16:11:19 2017 : Debug: Created signal pipe. Read end FD 10, write end FD 11 Wed Dec 13 16:11:19 2017 : Debug: radiusd: #### Opening IP addresses and Ports #### Wed Dec 13 16:11:19 2017 : Debug: Loading proto_auth with path: /usr/lib64/freeradius/proto_auth.so Wed Dec 13 16:11:19 2017 : Debug: Loading proto_auth failed: /usr/lib64/freeradius/proto_auth.so: cannot open shared object file: No such file or directory - No such file or directory Wed Dec 13 16:11:19 2017 : Debug: Loading library using linker search path(s) Wed Dec 13 16:11:19 2017 : Debug: Defaults : /lib:/usr/lib Wed Dec 13 16:11:19 2017 : Debug: Failed with error: proto_auth.so: cannot open shared object file: No such file or directory Wed Dec 13 16:11:19 2017 : Debug: listen { Wed Dec 13 16:11:19 2017 : Debug: type = "auth" Wed Dec 13 16:11:19 2017 : Debug: ipaddr = * Wed Dec 13 16:11:19 2017 : Debug: port = 0 Wed Dec 13 16:11:19 2017 : Debug: limit { Wed Dec 13 16:11:19 2017 : Debug: max_connections = 16 Wed Dec 13 16:11:19 2017 : Debug: lifetime = 0 Wed Dec 13 16:11:19 2017 : Debug: idle_timeout = 30 Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading proto_acct with path: /usr/lib64/freeradius/proto_acct.so Wed Dec 13 16:11:19 2017 : Debug: Loading proto_acct failed: /usr/lib64/freeradius/proto_acct.so: cannot open shared object file: No such file or directory - No such file or directory Wed Dec 13 16:11:19 2017 : Debug: Loading library using linker search path(s) Wed Dec 13 16:11:19 2017 : Debug: Defaults : /lib:/usr/lib Wed Dec 13 16:11:19 2017 : Debug: Failed with error: proto_acct.so: cannot open shared object file: No such file or directory Wed Dec 13 16:11:19 2017 : Debug: listen { Wed Dec 13 16:11:19 2017 : Debug: type = "acct" Wed Dec 13 16:11:19 2017 : Debug: ipaddr = * Wed Dec 13 16:11:19 2017 : Debug: port = 0 Wed Dec 13 16:11:19 2017 : Debug: limit { Wed Dec 13 16:11:19 2017 : Debug: max_connections = 16 Wed Dec 13 16:11:19 2017 : Debug: lifetime = 0 Wed Dec 13 16:11:19 2017 : Debug: idle_timeout = 30 Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading proto_auth with path: /usr/lib64/freeradius/proto_auth.so Wed Dec 13 16:11:19 2017 : Debug: Loading proto_auth failed: /usr/lib64/freeradius/proto_auth.so: cannot open shared object file: No such file or directory - No such file or directory Wed Dec 13 16:11:19 2017 : Debug: Loading library using linker search path(s) Wed Dec 13 16:11:19 2017 : Debug: Defaults : /lib:/usr/lib Wed Dec 13 16:11:19 2017 : Debug: Failed with error: proto_auth.so: cannot open shared object file: No such file or directory Wed Dec 13 16:11:19 2017 : Debug: listen { Wed Dec 13 16:11:19 2017 : Debug: type = "auth" Wed Dec 13 16:11:19 2017 : Debug: ipv6addr = :: Wed Dec 13 16:11:19 2017 : Debug: port = 0 Wed Dec 13 16:11:19 2017 : Debug: limit { Wed Dec 13 16:11:19 2017 : Debug: max_connections = 16 Wed Dec 13 16:11:19 2017 : Debug: lifetime = 0 Wed Dec 13 16:11:19 2017 : Debug: idle_timeout = 30 Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading proto_acct with path: /usr/lib64/freeradius/proto_acct.so Wed Dec 13 16:11:19 2017 : Debug: Loading proto_acct failed: /usr/lib64/freeradius/proto_acct.so: cannot open shared object file: No such file or directory - No such file or directory Wed Dec 13 16:11:19 2017 : Debug: Loading library using linker search path(s) Wed Dec 13 16:11:19 2017 : Debug: Defaults : /lib:/usr/lib Wed Dec 13 16:11:19 2017 : Debug: Failed with error: proto_acct.so: cannot open shared object file: No such file or directory Wed Dec 13 16:11:19 2017 : Debug: listen { Wed Dec 13 16:11:19 2017 : Debug: type = "acct" Wed Dec 13 16:11:19 2017 : Debug: ipv6addr = :: Wed Dec 13 16:11:19 2017 : Debug: port = 0 Wed Dec 13 16:11:19 2017 : Debug: limit { Wed Dec 13 16:11:19 2017 : Debug: max_connections = 16 Wed Dec 13 16:11:19 2017 : Debug: lifetime = 0 Wed Dec 13 16:11:19 2017 : Debug: idle_timeout = 30 Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Loading proto_auth with path: /usr/lib64/freeradius/proto_auth.so Wed Dec 13 16:11:19 2017 : Debug: Loading proto_auth failed: /usr/lib64/freeradius/proto_auth.so: cannot open shared object file: No such file or directory - No such file or directory Wed Dec 13 16:11:19 2017 : Debug: Loading library using linker search path(s) Wed Dec 13 16:11:19 2017 : Debug: Defaults : /lib:/usr/lib Wed Dec 13 16:11:19 2017 : Debug: Failed with error: proto_auth.so: cannot open shared object file: No such file or directory Wed Dec 13 16:11:19 2017 : Debug: listen { Wed Dec 13 16:11:19 2017 : Debug: type = "auth" Wed Dec 13 16:11:19 2017 : Debug: ipaddr = 127.0.0.1 Wed Dec 13 16:11:19 2017 : Debug: port = 18120 Wed Dec 13 16:11:19 2017 : Debug: } Wed Dec 13 16:11:19 2017 : Debug: Listening on auth address * port 1812 bound to server default Wed Dec 13 16:11:19 2017 : Debug: Listening on acct address * port 1813 bound to server default Wed Dec 13 16:11:19 2017 : Debug: Listening on auth address :: port 1812 bound to server default Wed Dec 13 16:11:19 2017 : Debug: Listening on acct address :: port 1813 bound to server default Wed Dec 13 16:11:19 2017 : Debug: Listening on auth address 127.0.0.1 port 18120 bound to server inner-tunnel Wed Dec 13 16:11:19 2017 : Debug: Opened new proxy socket 'proxy address * port 36266' Wed Dec 13 16:11:19 2017 : Debug: Listening on proxy address * port 36266 Wed Dec 13 16:11:19 2017 : Debug: Opened new proxy socket 'proxy address :: port 42975' Wed Dec 13 16:11:19 2017 : Debug: Listening on proxy address :: port 42975 Wed Dec 13 16:11:19 2017 : Info: Ready to process requests Wed Dec 13 16:11:27 2017 : Debug: (0) Received Access-Request Id 36 from 127.0.0.1:39761 to 127.0.0.1:1812 length 82 Wed Dec 13 16:11:27 2017 : Debug: (0) User-Name = "administraor" Wed Dec 13 16:11:27 2017 : Debug: (0) User-Password = "H23dMclc" Wed Dec 13 16:11:27 2017 : Debug: (0) NAS-IP-Address = 172.18.98.201 Wed Dec 13 16:11:27 2017 : Debug: (0) NAS-Port = 2 Wed Dec 13 16:11:27 2017 : Debug: (0) Message-Authenticator = 0xd074a73f8ce97eb316e0a2d5f1194ec2 Wed Dec 13 16:11:27 2017 : Debug: (0) session-state: No State attribute Wed Dec 13 16:11:27 2017 : Debug: (0) # Executing section authorize from file /etc/raddb/sites-enabled/default Wed Dec 13 16:11:27 2017 : Debug: (0) authorize { Wed Dec 13 16:11:27 2017 : Debug: (0) policy filter_username { Wed Dec 13 16:11:27 2017 : Debug: (0) if (&User-Name) { Wed Dec 13 16:11:27 2017 : Debug: (0) if (&User-Name) -> TRUE Wed Dec 13 16:11:27 2017 : Debug: (0) if (&User-Name) { Wed Dec 13 16:11:27 2017 : Debug: (0) if (&User-Name =~ / /) { Wed Dec 13 16:11:27 2017 : Debug: No matches Wed Dec 13 16:11:27 2017 : Debug: (0) if (&User-Name =~ / /) -> FALSE Wed Dec 13 16:11:27 2017 : Debug: (0) if (&User-Name =~ /@[^@]*@/ ) { Wed Dec 13 16:11:27 2017 : Debug: No matches Wed Dec 13 16:11:27 2017 : Debug: (0) if (&User-Name =~ /@[^@]*@/ ) -> FALSE Wed Dec 13 16:11:27 2017 : Debug: (0) if (&User-Name =~ /\.\./ ) { Wed Dec 13 16:11:27 2017 : Debug: No matches Wed Dec 13 16:11:27 2017 : Debug: (0) if (&User-Name =~ /\.\./ ) -> FALSE Wed Dec 13 16:11:27 2017 : Debug: (0) if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/)) { Wed Dec 13 16:11:27 2017 : Debug: No matches Wed Dec 13 16:11:27 2017 : Debug: (0) if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/)) -> FALSE Wed Dec 13 16:11:27 2017 : Debug: (0) if (&User-Name =~ /\.$/) { Wed Dec 13 16:11:27 2017 : Debug: No matches Wed Dec 13 16:11:27 2017 : Debug: (0) if (&User-Name =~ /\.$/) -> FALSE Wed Dec 13 16:11:27 2017 : Debug: (0) if (&User-Name =~ /@\./) { Wed Dec 13 16:11:27 2017 : Debug: No matches Wed Dec 13 16:11:27 2017 : Debug: (0) if (&User-Name =~ /@\./) -> FALSE Wed Dec 13 16:11:27 2017 : Debug: (0) } # if (&User-Name) = notfound Wed Dec 13 16:11:27 2017 : Debug: (0) } # policy filter_username = notfound Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[authorize]: calling preprocess (rlm_preprocess) Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[authorize]: returned from preprocess (rlm_preprocess) Wed Dec 13 16:11:27 2017 : Debug: (0) [preprocess] = ok Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[authorize]: calling chap (rlm_chap) Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[authorize]: returned from chap (rlm_chap) Wed Dec 13 16:11:27 2017 : Debug: (0) [chap] = noop Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[authorize]: calling mschap (rlm_mschap) Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[authorize]: returned from mschap (rlm_mschap) Wed Dec 13 16:11:27 2017 : Debug: (0) [mschap] = noop Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[authorize]: calling digest (rlm_digest) Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[authorize]: returned from digest (rlm_digest) Wed Dec 13 16:11:27 2017 : Debug: (0) [digest] = noop Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[authorize]: calling suffix (rlm_realm) Wed Dec 13 16:11:27 2017 : Debug: (0) suffix: Checking for suffix after "@" Wed Dec 13 16:11:27 2017 : Debug: (0) suffix: No '@' in User-Name = "administraor", looking up realm NULL Wed Dec 13 16:11:27 2017 : Debug: (0) suffix: No such realm "NULL" Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[authorize]: returned from suffix (rlm_realm) Wed Dec 13 16:11:27 2017 : Debug: (0) [suffix] = noop Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[authorize]: calling eap (rlm_eap) Wed Dec 13 16:11:27 2017 : Debug: (0) eap: No EAP-Message, not doing EAP Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[authorize]: returned from eap (rlm_eap) Wed Dec 13 16:11:27 2017 : Debug: (0) [eap] = noop Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[authorize]: calling files (rlm_files) Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[authorize]: returned from files (rlm_files) Wed Dec 13 16:11:27 2017 : Debug: (0) [files] = noop Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[authorize]: calling ldap (rlm_ldap) Wed Dec 13 16:11:27 2017 : Debug: (0) ldap: EXPAND TMPL LITERAL Wed Dec 13 16:11:27 2017 : Debug: (0) ldap: EXPAND TMPL LITERAL Wed Dec 13 16:11:27 2017 : Debug: (0) ldap: EXPAND TMPL LITERAL Wed Dec 13 16:11:27 2017 : Debug: (0) ldap: EXPAND TMPL LITERAL Wed Dec 13 16:11:27 2017 : Debug: rlm_ldap (ldap): Reserved connection (0) Wed Dec 13 16:11:27 2017 : Debug: (0) ldap: EXPAND TMPL XLAT Wed Dec 13 16:11:27 2017 : Debug: (uid=%{%{Stripped-User-Name}:-%{User-Name}}) Wed Dec 13 16:11:27 2017 : Debug: Parsed xlat tree: Wed Dec 13 16:11:27 2017 : Debug: literal --> (uid= Wed Dec 13 16:11:27 2017 : Debug: XLAT-IF { Wed Dec 13 16:11:27 2017 : Debug: attribute --> Stripped-User-Name Wed Dec 13 16:11:27 2017 : Debug: } Wed Dec 13 16:11:27 2017 : Debug: XLAT-ELSE { Wed Dec 13 16:11:27 2017 : Debug: attribute --> User-Name Wed Dec 13 16:11:27 2017 : Debug: } Wed Dec 13 16:11:27 2017 : Debug: literal --> ) Wed Dec 13 16:11:27 2017 : Debug: (0) ldap: EXPAND (uid=%{%{Stripped-User-Name}:-%{User-Name}}) Wed Dec 13 16:11:27 2017 : Debug: (0) ldap: --> (uid=administraor) Wed Dec 13 16:11:27 2017 : Debug: (0) ldap: EXPAND TMPL LITERAL Wed Dec 13 16:11:27 2017 : Debug: (0) ldap: Performing search in "cn=Users,dc=****,dc=net" with filter "(uid=administraor)", scope "sub" Wed Dec 13 16:11:27 2017 : Debug: (0) ldap: Waiting for search result... Wed Dec 13 16:11:27 2017 : ERROR: (0) ldap: Failed performing search: Please set 'chase_referrals=yes' and 'rebind=yes'. See the ldap module configuration for details. Wed Dec 13 16:11:27 2017 : ERROR: (0) ldap: Server said: 00002020: Operation unavailable without authentication. Wed Dec 13 16:11:27 2017 : Debug: rlm_ldap (ldap): Released connection (0) Wed Dec 13 16:11:27 2017 : Info: Need 5 more connections to reach 10 spares Wed Dec 13 16:11:27 2017 : Info: rlm_ldap (ldap): Opening additional connection (5), 1 of 27 pending slots used Wed Dec 13 16:11:27 2017 : Debug: rlm_ldap (ldap): Connecting to ldap://172.18.98.110:389 Wed Dec 13 16:11:27 2017 : Debug: rlm_ldap (ldap): New libldap handle 0x55c1931e24d0 Wed Dec 13 16:11:27 2017 : Debug: rlm_ldap (ldap): Waiting for bind result... Wed Dec 13 16:11:27 2017 : Debug: rlm_ldap (ldap): Bind successful Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[authorize]: returned from ldap (rlm_ldap) Wed Dec 13 16:11:27 2017 : Debug: (0) [ldap] = fail Wed Dec 13 16:11:27 2017 : Debug: (0) } # authorize = fail Wed Dec 13 16:11:27 2017 : Debug: (0) Using Post-Auth-Type Reject Wed Dec 13 16:11:27 2017 : Debug: (0) # Executing group from file /etc/raddb/sites-enabled/default Wed Dec 13 16:11:27 2017 : Debug: (0) Post-Auth-Type REJECT { Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[post-auth]: calling attr_filter.access_reject (rlm_attr_filter) Wed Dec 13 16:11:27 2017 : Debug: %{User-Name} Wed Dec 13 16:11:27 2017 : Debug: Parsed xlat tree: Wed Dec 13 16:11:27 2017 : Debug: attribute --> User-Name Wed Dec 13 16:11:27 2017 : Debug: (0) attr_filter.access_reject: EXPAND %{User-Name} Wed Dec 13 16:11:27 2017 : Debug: (0) attr_filter.access_reject: --> administraor Wed Dec 13 16:11:27 2017 : Debug: (0) attr_filter.access_reject: Matched entry DEFAULT at line 11 Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[post-auth]: returned from attr_filter.access_reject (rlm_attr_filter) Wed Dec 13 16:11:27 2017 : Debug: (0) [attr_filter.access_reject] = updated Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[post-auth]: calling eap (rlm_eap) Wed Dec 13 16:11:27 2017 : Debug: (0) eap: Request didn't contain an EAP-Message, not inserting EAP-Failure Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[post-auth]: returned from eap (rlm_eap) Wed Dec 13 16:11:27 2017 : Debug: (0) [eap] = noop Wed Dec 13 16:11:27 2017 : Debug: (0) policy remove_reply_message_if_eap { Wed Dec 13 16:11:27 2017 : Debug: (0) if (&reply:EAP-Message && &reply:Reply-Message) { Wed Dec 13 16:11:27 2017 : Debug: (0) if (&reply:EAP-Message && &reply:Reply-Message) -> FALSE Wed Dec 13 16:11:27 2017 : Debug: (0) else { Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[post-auth]: calling noop (rlm_always) Wed Dec 13 16:11:27 2017 : Debug: (0) modsingle[post-auth]: returned from noop (rlm_always) Wed Dec 13 16:11:27 2017 : Debug: (0) [noop] = noop Wed Dec 13 16:11:27 2017 : Debug: (0) } # else = noop Wed Dec 13 16:11:27 2017 : Debug: (0) } # policy remove_reply_message_if_eap = noop Wed Dec 13 16:11:27 2017 : Debug: (0) } # Post-Auth-Type REJECT = updated Wed Dec 13 16:11:27 2017 : Debug: (0) Delaying response for 1.000000 seconds Wed Dec 13 16:11:27 2017 : Debug: Waking up in 0.3 seconds. Wed Dec 13 16:11:27 2017 : Debug: Waking up in 0.6 seconds. Wed Dec 13 16:11:28 2017 : Debug: (0) Sending delayed response Wed Dec 13 16:11:28 2017 : Debug: (0) Sent Access-Reject Id 36 from 127.0.0.1:1812 to 127.0.0.1:39761 length 20 Wed Dec 13 16:11:28 2017 : Debug: Waking up in 3.9 seconds. Wed Dec 13 16:11:32 2017 : Debug: (0) Cleaning up request packet ID 36 with timestamp +8 Wed Dec 13 16:11:32 2017 : Info: Ready to process requests