trying to get an ACK for a computone intelliserver
Script started on Tue Jun 23 12:12:11 2020 You have mail. root@doctor:~ # /usr/freerad3/si[Kbin/radiusd -X -s[Kx Tue Jun 23 12:12:40 2020 : Debug: Server was built with: Tue Jun 23 12:12:40 2020 : Debug: accounting : yes Tue Jun 23 12:12:40 2020 : Debug: authentication : yes Tue Jun 23 12:12:40 2020 : Debug: ascend-binary-attributes : yes Tue Jun 23 12:12:40 2020 : Debug: coa : yes Tue Jun 23 12:12:40 2020 : Debug: control-socket : yes Tue Jun 23 12:12:40 2020 : Debug: detail : yes Tue Jun 23 12:12:40 2020 : Debug: dhcp : yes Tue Jun 23 12:12:40 2020 : Debug: dynamic-clients : yes Tue Jun 23 12:12:40 2020 : Debug: osfc2 : no Tue Jun 23 12:12:40 2020 : Debug: proxy : yes Tue Jun 23 12:12:40 2020 : Debug: regex-pcre : yes Tue Jun 23 12:12:40 2020 : Debug: regex-posix : no Tue Jun 23 12:12:40 2020 : Debug: regex-posix-extended : no Tue Jun 23 12:12:40 2020 : Debug: session-management : yes Tue Jun 23 12:12:40 2020 : Debug: stats : yes Tue Jun 23 12:12:40 2020 : Debug: systemd : no Tue Jun 23 12:12:40 2020 : Debug: tcp : yes Tue Jun 23 12:12:40 2020 : Debug: threads : yes Tue Jun 23 12:12:40 2020 : Debug: tls : yes Tue Jun 23 12:12:40 2020 : Debug: unlang : yes Tue Jun 23 12:12:40 2020 : Debug: vmps : yes Tue Jun 23 12:12:40 2020 : Debug: developer : no Tue Jun 23 12:12:40 2020 : Debug: Server core libs: Tue Jun 23 12:12:40 2020 : Debug: freeradius-server : 3.0.21 Tue Jun 23 12:12:40 2020 : Debug: talloc : 2.3.* Tue Jun 23 12:12:40 2020 : Debug: ssl : 1.1.1h dev Tue Jun 23 12:12:40 2020 : Debug: pcre : 8.44 2020-02-12 Tue Jun 23 12:12:40 2020 : Debug: Endianness: Tue Jun 23 12:12:40 2020 : Debug: little Tue Jun 23 12:12:40 2020 : Debug: Compilation flags: Tue Jun 23 12:12:40 2020 : Debug: cppflags : -isystem /usr/include/ Tue Jun 23 12:12:40 2020 : 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 -Wno-date-time -g -O2 -Wall -std=c99 -D_GNU_SOURCE -D_REENTRANT -D_POSIX_PTHREAD_SEMANTICS -DOPENSSL_NO_KRB5 -DNDEBUG -DIS_MODULE=1 Tue Jun 23 12:12:40 2020 : Debug: ldflags : -L/usr/lib -Wl,-rpath,/usr/lib Tue Jun 23 12:12:40 2020 : Debug: libs : -lcrypto -lssl -ltalloc -latomic -lpcre -lexecinfo -ldl -lpthread -lreadline Tue Jun 23 12:12:40 2020 : Debug: Tue Jun 23 12:12:40 2020 : Info: FreeRADIUS Version 3.0.21 Tue Jun 23 12:12:40 2020 : Info: Copyright (C) 1999-2019 The FreeRADIUS server project and contributors Tue Jun 23 12:12:40 2020 : Info: There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A Tue Jun 23 12:12:40 2020 : Info: PARTICULAR PURPOSE Tue Jun 23 12:12:40 2020 : Info: You may redistribute copies of FreeRADIUS under the terms of the Tue Jun 23 12:12:40 2020 : Info: GNU General Public License Tue Jun 23 12:12:40 2020 : Info: For more information about these matters, see the file named COPYRIGHT Tue Jun 23 12:12:40 2020 : Info: Starting - reading configuration files ... Tue Jun 23 12:12:40 2020 : Debug: including dictionary file /usr/freerad3/share/freeradius/dictionary Tue Jun 23 12:12:40 2020 : Debug: including dictionary file /usr/freerad3/share/freeradius/dictionary.dhcp Tue Jun 23 12:12:40 2020 : Debug: including dictionary file /usr/freerad3/share/freeradius/dictionary.vqp Tue Jun 23 12:12:40 2020 : Debug: including dictionary file /usr/freerad3/etc/raddb/dictionary Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/radiusd.conf Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/proxy.conf Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/clients.conf Tue Jun 23 12:12:40 2020 : Debug: including files in directory /usr/freerad3/etc/raddb/mods-enabled/ Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/attr_filter Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/cache_eap Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/chap Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/date Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/detail Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/detail.log Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/digest Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/dynamic_clients Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/eap Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/echo Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/exec Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/expiration Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/expr Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/files Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/linelog Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/logintime Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/mschap Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/ntlm_auth Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/pap Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/passwd Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/preprocess Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/radutmp Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/realm Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/replicate Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/soh Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/sradutmp Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/unix Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/unpack Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/mods-enabled/utf8 Tue Jun 23 12:12:40 2020 : Debug: including files in directory /usr/freerad3/etc/raddb/policy.d/ Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/policy.d/abfab-tr Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/policy.d/accounting Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/policy.d/canonicalization Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/policy.d/control Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/policy.d/cui Tue Jun 23 12:12:40 2020 : Debug: OPTIMIZING (${policy.cui_require_operator_name} == yes) --> FALSE Tue Jun 23 12:12:40 2020 : Debug: OPTIMIZING (no == yes) --> FALSE Tue Jun 23 12:12:40 2020 : Debug: OPTIMIZING (${policy.cui_require_operator_name} == yes) --> FALSE Tue Jun 23 12:12:40 2020 : Debug: OPTIMIZING (no == yes) --> FALSE Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/policy.d/debug Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/policy.d/dhcp Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/policy.d/eap Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/policy.d/filter Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/policy.d/moonshot-targeted-ids Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/policy.d/operator-name Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/policy.d/rfc7542 Tue Jun 23 12:12:40 2020 : Debug: including files in directory /usr/freerad3/etc/raddb/sites-enabled/ Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/sites-enabled/default Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/sites-enabled/inner-tunnel Tue Jun 23 12:12:40 2020 : Debug: including configuration file /usr/freerad3/etc/raddb/sites-enabled/status Tue Jun 23 12:12:40 2020 : Debug: main { Tue Jun 23 12:12:40 2020 : Debug: security { Tue Jun 23 12:12:40 2020 : Debug: allow_core_dumps = no Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[527]: The item 'max_attributes' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[545]: The item 'reject_delay' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[565]: The item 'status_server' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[575]: The item 'allow_vulnerable_openssl' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: name = "radiusd" Tue Jun 23 12:12:40 2020 : Debug: prefix = "/usr/freerad3" Tue Jun 23 12:12:40 2020 : Debug: localstatedir = "/var" Tue Jun 23 12:12:40 2020 : Debug: logdir = "/var/log/radius" Tue Jun 23 12:12:40 2020 : Debug: run_dir = "/var/run/radiusd" Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[97]: The item 'confdir' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[104]: The item 'db_dir' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[138]: The item 'libdir' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[149]: The item 'pidfile' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[170]: The item 'correct_escapes' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[224]: The item 'max_request_time' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[243]: The item 'cleanup_delay' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[280]: The item 'hostname_lookups' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[383]: The item 'checkrad' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[594]: The item 'proxy_requests' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: main { Tue Jun 23 12:12:40 2020 : Debug: name = "radiusd" Tue Jun 23 12:12:40 2020 : Debug: prefix = "/usr/freerad3" Tue Jun 23 12:12:40 2020 : Debug: localstatedir = "/var" Tue Jun 23 12:12:40 2020 : Debug: sbindir = "/usr/freerad3/sbin" Tue Jun 23 12:12:40 2020 : Debug: logdir = "/var/log/radius" Tue Jun 23 12:12:40 2020 : Debug: run_dir = "/var/run/radiusd" Tue Jun 23 12:12:40 2020 : Debug: libdir = "/usr/freerad3/lib" Tue Jun 23 12:12:40 2020 : Debug: radacctdir = "/var/log/radius/radacct" Tue Jun 23 12:12:40 2020 : Debug: hostname_lookups = no Tue Jun 23 12:12:40 2020 : Debug: max_request_time = 30 Tue Jun 23 12:12:40 2020 : Debug: cleanup_delay = 5 Tue Jun 23 12:12:40 2020 : Debug: max_requests = 16384 Tue Jun 23 12:12:40 2020 : Debug: pidfile = "/var/run/radiusd/radiusd.pid" Tue Jun 23 12:12:40 2020 : Debug: checkrad = "/usr/freerad3/sbin/checkrad" Tue Jun 23 12:12:40 2020 : Debug: debug_level = 0 Tue Jun 23 12:12:40 2020 : Debug: proxy_requests = yes Tue Jun 23 12:12:40 2020 : Debug: log { Tue Jun 23 12:12:40 2020 : Debug: stripped_names = no Tue Jun 23 12:12:40 2020 : Debug: auth = no Tue Jun 23 12:12:40 2020 : Debug: auth_badpass = no Tue Jun 23 12:12:40 2020 : Debug: auth_goodpass = no Tue Jun 23 12:12:40 2020 : Debug: colourise = yes Tue Jun 23 12:12:40 2020 : Debug: msg_denied = "You are already logged in - access denied" Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[298]: The item 'destination' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[315]: The item 'file' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[323]: The item 'syslog_facility' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: resources { Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: security { Tue Jun 23 12:12:40 2020 : Debug: max_attributes = 200 Tue Jun 23 12:12:40 2020 : Debug: reject_delay = 1.000000 Tue Jun 23 12:12:40 2020 : Debug: status_server = yes Tue Jun 23 12:12:40 2020 : Debug: allow_vulnerable_openssl = "no" Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[97]: The item 'confdir' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[104]: The item 'db_dir' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/radiusd.conf[170]: The item 'correct_escapes' is defined, but is unused by the configuration Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: radiusd: #### Loading Realms and Home Servers #### Tue Jun 23 12:12:40 2020 : Debug: proxy server { Tue Jun 23 12:12:40 2020 : Debug: retry_delay = 5 Tue Jun 23 12:12:40 2020 : Debug: retry_count = 3 Tue Jun 23 12:12:40 2020 : Debug: default_fallback = no Tue Jun 23 12:12:40 2020 : Debug: dead_time = 120 Tue Jun 23 12:12:40 2020 : Debug: wake_all_if_all_dead = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: home_server localhost { Tue Jun 23 12:12:40 2020 : Debug: ipaddr = 192.168.81.1 Tue Jun 23 12:12:40 2020 : Debug: port = 1645 Tue Jun 23 12:12:40 2020 : Debug: type = "auth+acct" Tue Jun 23 12:12:40 2020 : Debug: secret = "Tone" Tue Jun 23 12:12:40 2020 : Debug: response_window = 20.000000 Tue Jun 23 12:12:40 2020 : Debug: response_timeouts = 1 Tue Jun 23 12:12:40 2020 : Debug: max_outstanding = 65536 Tue Jun 23 12:12:40 2020 : Debug: zombie_period = 40 Tue Jun 23 12:12:40 2020 : Debug: status_check = "status-server" Tue Jun 23 12:12:40 2020 : Debug: ping_interval = 30 Tue Jun 23 12:12:40 2020 : Debug: check_interval = 30 Tue Jun 23 12:12:40 2020 : Debug: check_timeout = 4 Tue Jun 23 12:12:40 2020 : Debug: num_answers_to_alive = 3 Tue Jun 23 12:12:40 2020 : Debug: revive_interval = 120 Tue Jun 23 12:12:40 2020 : Debug: limit { Tue Jun 23 12:12:40 2020 : Debug: max_connections = 16 Tue Jun 23 12:12:40 2020 : Debug: max_requests = 0 Tue Jun 23 12:12:40 2020 : Debug: lifetime = 0 Tue Jun 23 12:12:40 2020 : Debug: idle_timeout = 0 Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: coa { Tue Jun 23 12:12:40 2020 : Debug: irt = 2 Tue Jun 23 12:12:40 2020 : Debug: mrt = 16 Tue Jun 23 12:12:40 2020 : Debug: mrc = 5 Tue Jun 23 12:12:40 2020 : Debug: mrd = 30 Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: home_server_pool my_auth_failover { Tue Jun 23 12:12:40 2020 : Debug: type = fail-over Tue Jun 23 12:12:40 2020 : Debug: home_server = localhost Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: realm example.com { Tue Jun 23 12:12:40 2020 : Debug: auth_pool = my_auth_failover Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: realm LOCAL { Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: realm NULL { Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: radiusd: #### Loading Clients #### Tue Jun 23 12:12:40 2020 : Debug: client localhost { Tue Jun 23 12:12:40 2020 : Debug: ipaddr = 127.0.0.1 Tue Jun 23 12:12:40 2020 : Debug: require_message_authenticator = no Tue Jun 23 12:12:40 2020 : Debug: secret = "Tone" Tue Jun 23 12:12:40 2020 : Debug: nas_type = "other" Tue Jun 23 12:12:40 2020 : Debug: proto = "*" Tue Jun 23 12:12:40 2020 : Debug: limit { Tue Jun 23 12:12:40 2020 : Debug: max_connections = 16 Tue Jun 23 12:12:40 2020 : Debug: lifetime = 0 Tue Jun 23 12:12:40 2020 : Debug: idle_timeout = 30 Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Adding client 127.0.0.1/32 (127.0.0.1) to prefix tree 32 Tue Jun 23 12:12:40 2020 : Debug: client localhost_ipv6 { Tue Jun 23 12:12:40 2020 : Debug: ipv6addr = ::1 Tue Jun 23 12:12:40 2020 : Debug: require_message_authenticator = no Tue Jun 23 12:12:40 2020 : Debug: secret = "testing123" Tue Jun 23 12:12:40 2020 : Debug: limit { Tue Jun 23 12:12:40 2020 : Debug: max_connections = 16 Tue Jun 23 12:12:40 2020 : Debug: lifetime = 0 Tue Jun 23 12:12:40 2020 : Debug: idle_timeout = 30 Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Adding client ::1/128 (::1) to prefix tree 128 Tue Jun 23 12:12:40 2020 : Debug: client panopticon.nl2k.ab.ca { Tue Jun 23 12:12:40 2020 : Debug: ipaddr = 192.168.81.4 Tue Jun 23 12:12:40 2020 : Debug: require_message_authenticator = no Tue Jun 23 12:12:40 2020 : Debug: secret = "Tone" Tue Jun 23 12:12:40 2020 : Debug: nas_type = "other" Tue Jun 23 12:12:40 2020 : Debug: limit { Tue Jun 23 12:12:40 2020 : Debug: max_connections = 16 Tue Jun 23 12:12:40 2020 : Debug: lifetime = 0 Tue Jun 23 12:12:40 2020 : Debug: idle_timeout = 30 Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Adding client 192.168.81.4/32 (192.168.81.4) to prefix tree 32 [1mTue Jun 23 12:12:40 2020 : Info: Debugger not attached[0m Tue Jun 23 12:12:40 2020 : Debug: # Creating Auth-Type = mschap Tue Jun 23 12:12:40 2020 : Debug: # Creating Auth-Type = digest Tue Jun 23 12:12:40 2020 : Debug: # Creating Auth-Type = eap Tue Jun 23 12:12:40 2020 : Debug: # Creating Auth-Type = PAP Tue Jun 23 12:12:40 2020 : Debug: # Creating Auth-Type = CHAP Tue Jun 23 12:12:40 2020 : Debug: # Creating Auth-Type = MS-CHAP Tue Jun 23 12:12:40 2020 : Debug: # Creating Autz-Type = Status-Server Tue Jun 23 12:12:40 2020 : Debug: radiusd: #### Instantiating modules #### Tue Jun 23 12:12:40 2020 : Debug: modules { Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_always, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_always Tue Jun 23 12:12:40 2020 : Debug: # Loading module "reject" from file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: always reject { Tue Jun 23 12:12:40 2020 : Debug: rcode = "reject" Tue Jun 23 12:12:40 2020 : Debug: simulcount = 0 Tue Jun 23 12:12:40 2020 : Debug: mpp = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "fail" from file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: always fail { Tue Jun 23 12:12:40 2020 : Debug: rcode = "fail" Tue Jun 23 12:12:40 2020 : Debug: simulcount = 0 Tue Jun 23 12:12:40 2020 : Debug: mpp = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "ok" from file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: always ok { Tue Jun 23 12:12:40 2020 : Debug: rcode = "ok" Tue Jun 23 12:12:40 2020 : Debug: simulcount = 0 Tue Jun 23 12:12:40 2020 : Debug: mpp = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "handled" from file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: always handled { Tue Jun 23 12:12:40 2020 : Debug: rcode = "handled" Tue Jun 23 12:12:40 2020 : Debug: simulcount = 0 Tue Jun 23 12:12:40 2020 : Debug: mpp = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "invalid" from file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: always invalid { Tue Jun 23 12:12:40 2020 : Debug: rcode = "invalid" Tue Jun 23 12:12:40 2020 : Debug: simulcount = 0 Tue Jun 23 12:12:40 2020 : Debug: mpp = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "userlock" from file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: always userlock { Tue Jun 23 12:12:40 2020 : Debug: rcode = "userlock" Tue Jun 23 12:12:40 2020 : Debug: simulcount = 0 Tue Jun 23 12:12:40 2020 : Debug: mpp = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "notfound" from file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: always notfound { Tue Jun 23 12:12:40 2020 : Debug: rcode = "notfound" Tue Jun 23 12:12:40 2020 : Debug: simulcount = 0 Tue Jun 23 12:12:40 2020 : Debug: mpp = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "noop" from file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: always noop { Tue Jun 23 12:12:40 2020 : Debug: rcode = "noop" Tue Jun 23 12:12:40 2020 : Debug: simulcount = 0 Tue Jun 23 12:12:40 2020 : Debug: mpp = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "updated" from file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: always updated { Tue Jun 23 12:12:40 2020 : Debug: rcode = "updated" Tue Jun 23 12:12:40 2020 : Debug: simulcount = 0 Tue Jun 23 12:12:40 2020 : Debug: mpp = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_attr_filter, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_attr_filter Tue Jun 23 12:12:40 2020 : Debug: # Loading module "attr_filter.post-proxy" from file /usr/freerad3/etc/raddb/mods-enabled/attr_filter Tue Jun 23 12:12:40 2020 : Debug: attr_filter attr_filter.post-proxy { Tue Jun 23 12:12:40 2020 : Debug: filename = "/usr/freerad3/etc/raddb/mods-config/attr_filter/post-proxy" Tue Jun 23 12:12:40 2020 : Debug: key = "%{Realm}" Tue Jun 23 12:12:40 2020 : Debug: relaxed = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "attr_filter.pre-proxy" from file /usr/freerad3/etc/raddb/mods-enabled/attr_filter Tue Jun 23 12:12:40 2020 : Debug: attr_filter attr_filter.pre-proxy { Tue Jun 23 12:12:40 2020 : Debug: filename = "/usr/freerad3/etc/raddb/mods-config/attr_filter/pre-proxy" Tue Jun 23 12:12:40 2020 : Debug: key = "%{Realm}" Tue Jun 23 12:12:40 2020 : Debug: relaxed = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "attr_filter.access_reject" from file /usr/freerad3/etc/raddb/mods-enabled/attr_filter Tue Jun 23 12:12:40 2020 : Debug: attr_filter attr_filter.access_reject { Tue Jun 23 12:12:40 2020 : Debug: filename = "/usr/freerad3/etc/raddb/mods-config/attr_filter/access_reject" Tue Jun 23 12:12:40 2020 : Debug: key = "%{User-Name}" Tue Jun 23 12:12:40 2020 : Debug: relaxed = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "attr_filter.access_challenge" from file /usr/freerad3/etc/raddb/mods-enabled/attr_filter Tue Jun 23 12:12:40 2020 : Debug: attr_filter attr_filter.access_challenge { Tue Jun 23 12:12:40 2020 : Debug: filename = "/usr/freerad3/etc/raddb/mods-config/attr_filter/access_challenge" Tue Jun 23 12:12:40 2020 : Debug: key = "%{User-Name}" Tue Jun 23 12:12:40 2020 : Debug: relaxed = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "attr_filter.accounting_response" from file /usr/freerad3/etc/raddb/mods-enabled/attr_filter Tue Jun 23 12:12:40 2020 : Debug: attr_filter attr_filter.accounting_response { Tue Jun 23 12:12:40 2020 : Debug: filename = "/usr/freerad3/etc/raddb/mods-config/attr_filter/accounting_response" Tue Jun 23 12:12:40 2020 : Debug: key = "%{User-Name}" Tue Jun 23 12:12:40 2020 : Debug: relaxed = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_cache, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_cache Tue Jun 23 12:12:40 2020 : Debug: # Loading module "cache_eap" from file /usr/freerad3/etc/raddb/mods-enabled/cache_eap Tue Jun 23 12:12:40 2020 : Debug: cache cache_eap { Tue Jun 23 12:12:40 2020 : Debug: driver = "rlm_cache_rbtree" Tue Jun 23 12:12:40 2020 : Debug: key = "%{%{control:State}:-%{%{reply:State}:-%{State}}}" Tue Jun 23 12:12:40 2020 : Debug: ttl = 15 Tue Jun 23 12:12:40 2020 : Debug: max_entries = 0 Tue Jun 23 12:12:40 2020 : Debug: epoch = 0 Tue Jun 23 12:12:40 2020 : Debug: add_stats = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_chap, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_chap Tue Jun 23 12:12:40 2020 : Debug: # Loading module "chap" from file /usr/freerad3/etc/raddb/mods-enabled/chap Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_date, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_date Tue Jun 23 12:12:40 2020 : Debug: # Loading module "date" from file /usr/freerad3/etc/raddb/mods-enabled/date Tue Jun 23 12:12:40 2020 : Debug: date { Tue Jun 23 12:12:40 2020 : Debug: format = "%b %e %Y %H:%M:%S %Z" Tue Jun 23 12:12:40 2020 : Debug: utc = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "wispr2date" from file /usr/freerad3/etc/raddb/mods-enabled/date Tue Jun 23 12:12:40 2020 : Debug: date wispr2date { Tue Jun 23 12:12:40 2020 : Debug: format = "%Y-%m-%dT%H:%M:%S" Tue Jun 23 12:12:40 2020 : Debug: utc = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_detail, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_detail Tue Jun 23 12:12:40 2020 : Debug: # Loading module "detail" from file /usr/freerad3/etc/raddb/mods-enabled/detail Tue Jun 23 12:12:40 2020 : Debug: detail { Tue Jun 23 12:12:40 2020 : Debug: filename = "/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d" Tue Jun 23 12:12:40 2020 : Debug: header = "%t" Tue Jun 23 12:12:40 2020 : Debug: permissions = 384 Tue Jun 23 12:12:40 2020 : Debug: locking = no Tue Jun 23 12:12:40 2020 : Debug: escape_filenames = no Tue Jun 23 12:12:40 2020 : Debug: log_packet_header = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "auth_log" from file /usr/freerad3/etc/raddb/mods-enabled/detail.log Tue Jun 23 12:12:40 2020 : Debug: detail auth_log { Tue Jun 23 12:12:40 2020 : Debug: filename = "/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d" Tue Jun 23 12:12:40 2020 : Debug: header = "%t" Tue Jun 23 12:12:40 2020 : Debug: permissions = 384 Tue Jun 23 12:12:40 2020 : Debug: locking = no Tue Jun 23 12:12:40 2020 : Debug: escape_filenames = no Tue Jun 23 12:12:40 2020 : Debug: log_packet_header = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "reply_log" from file /usr/freerad3/etc/raddb/mods-enabled/detail.log Tue Jun 23 12:12:40 2020 : Debug: detail reply_log { Tue Jun 23 12:12:40 2020 : Debug: filename = "/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/reply-detail-%Y%m%d" Tue Jun 23 12:12:40 2020 : Debug: header = "%t" Tue Jun 23 12:12:40 2020 : Debug: permissions = 384 Tue Jun 23 12:12:40 2020 : Debug: locking = no Tue Jun 23 12:12:40 2020 : Debug: escape_filenames = no Tue Jun 23 12:12:40 2020 : Debug: log_packet_header = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "pre_proxy_log" from file /usr/freerad3/etc/raddb/mods-enabled/detail.log Tue Jun 23 12:12:40 2020 : Debug: detail pre_proxy_log { Tue Jun 23 12:12:40 2020 : Debug: filename = "/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/pre-proxy-detail-%Y%m%d" Tue Jun 23 12:12:40 2020 : Debug: header = "%t" Tue Jun 23 12:12:40 2020 : Debug: permissions = 384 Tue Jun 23 12:12:40 2020 : Debug: locking = no Tue Jun 23 12:12:40 2020 : Debug: escape_filenames = no Tue Jun 23 12:12:40 2020 : Debug: log_packet_header = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "post_proxy_log" from file /usr/freerad3/etc/raddb/mods-enabled/detail.log Tue Jun 23 12:12:40 2020 : Debug: detail post_proxy_log { Tue Jun 23 12:12:40 2020 : Debug: filename = "/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/post-proxy-detail-%Y%m%d" Tue Jun 23 12:12:40 2020 : Debug: header = "%t" Tue Jun 23 12:12:40 2020 : Debug: permissions = 384 Tue Jun 23 12:12:40 2020 : Debug: locking = no Tue Jun 23 12:12:40 2020 : Debug: escape_filenames = no Tue Jun 23 12:12:40 2020 : Debug: log_packet_header = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_digest, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_digest Tue Jun 23 12:12:40 2020 : Debug: # Loading module "digest" from file /usr/freerad3/etc/raddb/mods-enabled/digest Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_dynamic_clients, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_dynamic_clients Tue Jun 23 12:12:40 2020 : Debug: # Loading module "dynamic_clients" from file /usr/freerad3/etc/raddb/mods-enabled/dynamic_clients Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_eap, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_eap Tue Jun 23 12:12:40 2020 : Debug: # Loading module "eap" from file /usr/freerad3/etc/raddb/mods-enabled/eap Tue Jun 23 12:12:40 2020 : Debug: eap { Tue Jun 23 12:12:40 2020 : Debug: default_eap_type = "md5" Tue Jun 23 12:12:40 2020 : Debug: timer_expire = 60 Tue Jun 23 12:12:40 2020 : Debug: ignore_unknown_eap_types = no Tue Jun 23 12:12:40 2020 : Debug: cisco_accounting_username_bug = no Tue Jun 23 12:12:40 2020 : Debug: max_sessions = 16384 Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_exec, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_exec Tue Jun 23 12:12:40 2020 : Debug: # Loading module "echo" from file /usr/freerad3/etc/raddb/mods-enabled/echo Tue Jun 23 12:12:40 2020 : Debug: exec echo { Tue Jun 23 12:12:40 2020 : Debug: wait = yes Tue Jun 23 12:12:40 2020 : Debug: program = "/bin/echo %{User-Name}" Tue Jun 23 12:12:40 2020 : Debug: input_pairs = "request" Tue Jun 23 12:12:40 2020 : Debug: output_pairs = "reply" Tue Jun 23 12:12:40 2020 : Debug: shell_escape = yes Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "exec" from file /usr/freerad3/etc/raddb/mods-enabled/exec Tue Jun 23 12:12:40 2020 : Debug: exec { Tue Jun 23 12:12:40 2020 : Debug: wait = no Tue Jun 23 12:12:40 2020 : Debug: input_pairs = "request" Tue Jun 23 12:12:40 2020 : Debug: shell_escape = yes Tue Jun 23 12:12:40 2020 : Debug: timeout = 10 Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_expiration, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_expiration Tue Jun 23 12:12:40 2020 : Debug: # Loading module "expiration" from file /usr/freerad3/etc/raddb/mods-enabled/expiration Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_expr, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_expr Tue Jun 23 12:12:40 2020 : Debug: # Loading module "expr" from file /usr/freerad3/etc/raddb/mods-enabled/expr Tue Jun 23 12:12:40 2020 : Debug: expr { Tue Jun 23 12:12:40 2020 : Debug: safe_characters = "@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /äéöüàâæçèéêëîïôœùûüaÿÄÉÖÜßÀÂÆÇÈÉÊËÎÏÔŒÙÛÜŸ" Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_files, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_files Tue Jun 23 12:12:40 2020 : Debug: # Loading module "files" from file /usr/freerad3/etc/raddb/mods-enabled/files Tue Jun 23 12:12:40 2020 : Debug: files { Tue Jun 23 12:12:40 2020 : Debug: filename = "/usr/freerad3/etc/raddb/mods-config/files/authorize" Tue Jun 23 12:12:40 2020 : Debug: acctusersfile = "/usr/freerad3/etc/raddb/mods-config/files/accounting" Tue Jun 23 12:12:40 2020 : Debug: preproxy_usersfile = "/usr/freerad3/etc/raddb/mods-config/files/pre-proxy" Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_linelog, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_linelog Tue Jun 23 12:12:40 2020 : Debug: # Loading module "linelog" from file /usr/freerad3/etc/raddb/mods-enabled/linelog Tue Jun 23 12:12:40 2020 : Debug: linelog { Tue Jun 23 12:12:40 2020 : Debug: filename = "/var/log/radius/linelog" Tue Jun 23 12:12:40 2020 : Debug: escape_filenames = no Tue Jun 23 12:12:40 2020 : Debug: syslog_severity = "info" Tue Jun 23 12:12:40 2020 : Debug: permissions = 384 Tue Jun 23 12:12:40 2020 : Debug: format = "This is a log message for %{User-Name}" Tue Jun 23 12:12:40 2020 : Debug: reference = "messages.%{%{reply:Packet-Type}:-default}" Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "log_accounting" from file /usr/freerad3/etc/raddb/mods-enabled/linelog Tue Jun 23 12:12:40 2020 : Debug: linelog log_accounting { Tue Jun 23 12:12:40 2020 : Debug: filename = "/var/log/radius/linelog-accounting" Tue Jun 23 12:12:40 2020 : Debug: escape_filenames = no Tue Jun 23 12:12:40 2020 : Debug: syslog_severity = "info" Tue Jun 23 12:12:40 2020 : Debug: permissions = 384 Tue Jun 23 12:12:40 2020 : Debug: format = "" Tue Jun 23 12:12:40 2020 : Debug: reference = "Accounting-Request.%{%{Acct-Status-Type}:-unknown}" Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_logintime, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_logintime Tue Jun 23 12:12:40 2020 : Debug: # Loading module "logintime" from file /usr/freerad3/etc/raddb/mods-enabled/logintime Tue Jun 23 12:12:40 2020 : Debug: logintime { Tue Jun 23 12:12:40 2020 : Debug: minimum_timeout = 60 Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_mschap, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_mschap Tue Jun 23 12:12:40 2020 : Debug: # Loading module "mschap" from file /usr/freerad3/etc/raddb/mods-enabled/mschap Tue Jun 23 12:12:40 2020 : Debug: mschap { Tue Jun 23 12:12:40 2020 : Debug: use_mppe = yes Tue Jun 23 12:12:40 2020 : Debug: require_encryption = no Tue Jun 23 12:12:40 2020 : Debug: require_strong = no Tue Jun 23 12:12:40 2020 : Debug: with_ntdomain_hack = yes Tue Jun 23 12:12:40 2020 : Debug: passchange { Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: allow_retry = yes Tue Jun 23 12:12:40 2020 : Debug: winbind_retry_with_normalised_username = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "ntlm_auth" from file /usr/freerad3/etc/raddb/mods-enabled/ntlm_auth Tue Jun 23 12:12:40 2020 : Debug: exec ntlm_auth { Tue Jun 23 12:12:40 2020 : Debug: wait = yes Tue Jun 23 12:12:40 2020 : Debug: program = "/path/to/ntlm_auth --request-nt-key --domain=MYDOMAIN --username=%{mschap:User-Name} --password=%{User-Password}" Tue Jun 23 12:12:40 2020 : Debug: shell_escape = yes Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_pap, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_pap Tue Jun 23 12:12:40 2020 : Debug: # Loading module "pap" from file /usr/freerad3/etc/raddb/mods-enabled/pap Tue Jun 23 12:12:40 2020 : Debug: pap { Tue Jun 23 12:12:40 2020 : Debug: normalise = yes Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_passwd, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_passwd Tue Jun 23 12:12:40 2020 : Debug: # Loading module "etc_passwd" from file /usr/freerad3/etc/raddb/mods-enabled/passwd Tue Jun 23 12:12:40 2020 : Debug: passwd etc_passwd { Tue Jun 23 12:12:40 2020 : Debug: filename = "/etc/master.passwd" Tue Jun 23 12:12:40 2020 : Debug: format = "*User-Name:Crypt-Password:" Tue Jun 23 12:12:40 2020 : Debug: delimiter = ":" Tue Jun 23 12:12:40 2020 : Debug: ignore_nislike = no Tue Jun 23 12:12:40 2020 : Debug: ignore_empty = yes Tue Jun 23 12:12:40 2020 : Debug: allow_multiple_keys = no Tue Jun 23 12:12:40 2020 : Debug: hash_size = 100 Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_preprocess, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_preprocess Tue Jun 23 12:12:40 2020 : Debug: # Loading module "preprocess" from file /usr/freerad3/etc/raddb/mods-enabled/preprocess Tue Jun 23 12:12:40 2020 : Debug: preprocess { Tue Jun 23 12:12:40 2020 : Debug: huntgroups = "/usr/freerad3/etc/raddb/mods-config/preprocess/huntgroups" Tue Jun 23 12:12:40 2020 : Debug: hints = "/usr/freerad3/etc/raddb/mods-config/preprocess/hints" Tue Jun 23 12:12:40 2020 : Debug: with_ascend_hack = no Tue Jun 23 12:12:40 2020 : Debug: ascend_channels_per_line = 23 Tue Jun 23 12:12:40 2020 : Debug: with_ntdomain_hack = no Tue Jun 23 12:12:40 2020 : Debug: with_specialix_jetstream_hack = no Tue Jun 23 12:12:40 2020 : Debug: with_cisco_vsa_hack = no Tue Jun 23 12:12:40 2020 : Debug: with_alvarion_vsa_hack = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_radutmp, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_radutmp Tue Jun 23 12:12:40 2020 : Debug: # Loading module "radutmp" from file /usr/freerad3/etc/raddb/mods-enabled/radutmp Tue Jun 23 12:12:40 2020 : Debug: radutmp { Tue Jun 23 12:12:40 2020 : Debug: filename = "/var/log/radius/radutmp" Tue Jun 23 12:12:40 2020 : Debug: username = "%{User-Name}" Tue Jun 23 12:12:40 2020 : Debug: case_sensitive = yes Tue Jun 23 12:12:40 2020 : Debug: check_with_nas = yes Tue Jun 23 12:12:40 2020 : Debug: permissions = 384 Tue Jun 23 12:12:40 2020 : Debug: caller_id = yes Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_realm, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_realm Tue Jun 23 12:12:40 2020 : Debug: # Loading module "IPASS" from file /usr/freerad3/etc/raddb/mods-enabled/realm Tue Jun 23 12:12:40 2020 : Debug: realm IPASS { Tue Jun 23 12:12:40 2020 : Debug: format = "prefix" Tue Jun 23 12:12:40 2020 : Debug: delimiter = "/" Tue Jun 23 12:12:40 2020 : Debug: ignore_default = no Tue Jun 23 12:12:40 2020 : Debug: ignore_null = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "suffix" from file /usr/freerad3/etc/raddb/mods-enabled/realm Tue Jun 23 12:12:40 2020 : Debug: realm suffix { Tue Jun 23 12:12:40 2020 : Debug: format = "suffix" Tue Jun 23 12:12:40 2020 : Debug: delimiter = "@" Tue Jun 23 12:12:40 2020 : Debug: ignore_default = no Tue Jun 23 12:12:40 2020 : Debug: ignore_null = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "bangpath" from file /usr/freerad3/etc/raddb/mods-enabled/realm Tue Jun 23 12:12:40 2020 : Debug: realm bangpath { Tue Jun 23 12:12:40 2020 : Debug: format = "prefix" Tue Jun 23 12:12:40 2020 : Debug: delimiter = "!" Tue Jun 23 12:12:40 2020 : Debug: ignore_default = no Tue Jun 23 12:12:40 2020 : Debug: ignore_null = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "realmpercent" from file /usr/freerad3/etc/raddb/mods-enabled/realm Tue Jun 23 12:12:40 2020 : Debug: realm realmpercent { Tue Jun 23 12:12:40 2020 : Debug: format = "suffix" Tue Jun 23 12:12:40 2020 : Debug: delimiter = "%" Tue Jun 23 12:12:40 2020 : Debug: ignore_default = no Tue Jun 23 12:12:40 2020 : Debug: ignore_null = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "ntdomain" from file /usr/freerad3/etc/raddb/mods-enabled/realm Tue Jun 23 12:12:40 2020 : Debug: realm ntdomain { Tue Jun 23 12:12:40 2020 : Debug: format = "prefix" Tue Jun 23 12:12:40 2020 : Debug: delimiter = "\\" Tue Jun 23 12:12:40 2020 : Debug: ignore_default = no Tue Jun 23 12:12:40 2020 : Debug: ignore_null = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_replicate, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_replicate Tue Jun 23 12:12:40 2020 : Debug: # Loading module "replicate" from file /usr/freerad3/etc/raddb/mods-enabled/replicate Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_soh, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_soh Tue Jun 23 12:12:40 2020 : Debug: # Loading module "soh" from file /usr/freerad3/etc/raddb/mods-enabled/soh Tue Jun 23 12:12:40 2020 : Debug: soh { Tue Jun 23 12:12:40 2020 : Debug: dhcp = yes Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Loading module "sradutmp" from file /usr/freerad3/etc/raddb/mods-enabled/sradutmp Tue Jun 23 12:12:40 2020 : Debug: radutmp sradutmp { Tue Jun 23 12:12:40 2020 : Debug: filename = "/var/log/radius/sradutmp" Tue Jun 23 12:12:40 2020 : Debug: username = "%{User-Name}" Tue Jun 23 12:12:40 2020 : Debug: case_sensitive = yes Tue Jun 23 12:12:40 2020 : Debug: check_with_nas = yes Tue Jun 23 12:12:40 2020 : Debug: permissions = 420 Tue Jun 23 12:12:40 2020 : Debug: caller_id = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_unix, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_unix Tue Jun 23 12:12:40 2020 : Debug: # Loading module "unix" from file /usr/freerad3/etc/raddb/mods-enabled/unix Tue Jun 23 12:12:40 2020 : Debug: unix { Tue Jun 23 12:12:40 2020 : Debug: radwtmp = "/var/log/radius/radwtmp" Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Creating attribute Unix-Group Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_unpack, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_unpack Tue Jun 23 12:12:40 2020 : Debug: # Loading module "unpack" from file /usr/freerad3/etc/raddb/mods-enabled/unpack Tue Jun 23 12:12:40 2020 : Debug: Loaded rlm_utf8, checking if it's valid Tue Jun 23 12:12:40 2020 : Debug: # Loaded module rlm_utf8 Tue Jun 23 12:12:40 2020 : Debug: # Loading module "utf8" from file /usr/freerad3/etc/raddb/mods-enabled/utf8 Tue Jun 23 12:12:40 2020 : Debug: instantiate { Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "reject" from file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "fail" from file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "ok" from file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "handled" from file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "invalid" from file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "userlock" from file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "notfound" from file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "noop" from file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "updated" from file /usr/freerad3/etc/raddb/mods-enabled/always Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "attr_filter.post-proxy" from file /usr/freerad3/etc/raddb/mods-enabled/attr_filter Tue Jun 23 12:12:40 2020 : Debug: reading pairlist file /usr/freerad3/etc/raddb/mods-config/attr_filter/post-proxy Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "attr_filter.pre-proxy" from file /usr/freerad3/etc/raddb/mods-enabled/attr_filter Tue Jun 23 12:12:40 2020 : Debug: reading pairlist file /usr/freerad3/etc/raddb/mods-config/attr_filter/pre-proxy Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "attr_filter.access_reject" from file /usr/freerad3/etc/raddb/mods-enabled/attr_filter Tue Jun 23 12:12:40 2020 : Debug: reading pairlist file /usr/freerad3/etc/raddb/mods-config/attr_filter/access_reject Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "attr_filter.access_challenge" from file /usr/freerad3/etc/raddb/mods-enabled/attr_filter Tue Jun 23 12:12:40 2020 : Debug: reading pairlist file /usr/freerad3/etc/raddb/mods-config/attr_filter/access_challenge Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "attr_filter.accounting_response" from file /usr/freerad3/etc/raddb/mods-enabled/attr_filter Tue Jun 23 12:12:40 2020 : Debug: reading pairlist file /usr/freerad3/etc/raddb/mods-config/attr_filter/accounting_response Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "cache_eap" from file /usr/freerad3/etc/raddb/mods-enabled/cache_eap Tue Jun 23 12:12:40 2020 : Debug: rlm_cache (cache_eap): Driver rlm_cache_rbtree (module rlm_cache_rbtree) loaded and linked Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "detail" from file /usr/freerad3/etc/raddb/mods-enabled/detail Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "auth_log" from file /usr/freerad3/etc/raddb/mods-enabled/detail.log Tue Jun 23 12:12:40 2020 : Debug: rlm_detail (auth_log): 'User-Password' suppressed, will not appear in detail output Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "reply_log" from file /usr/freerad3/etc/raddb/mods-enabled/detail.log Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "pre_proxy_log" from file /usr/freerad3/etc/raddb/mods-enabled/detail.log Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "post_proxy_log" from file /usr/freerad3/etc/raddb/mods-enabled/detail.log Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "eap" from file /usr/freerad3/etc/raddb/mods-enabled/eap Tue Jun 23 12:12:40 2020 : Debug: # Linked to sub-module rlm_eap_md5 Tue Jun 23 12:12:40 2020 : Debug: # Linked to sub-module rlm_eap_leap Tue Jun 23 12:12:40 2020 : Debug: # Linked to sub-module rlm_eap_gtc Tue Jun 23 12:12:40 2020 : Debug: gtc { Tue Jun 23 12:12:40 2020 : Debug: challenge = "Password: " Tue Jun 23 12:12:40 2020 : Debug: auth_type = "PAP" Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Linked to sub-module rlm_eap_tls Tue Jun 23 12:12:40 2020 : Debug: tls { Tue Jun 23 12:12:40 2020 : Debug: tls = "tls-common" Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: tls-config tls-common { Tue Jun 23 12:12:40 2020 : Debug: verify_depth = 0 Tue Jun 23 12:12:40 2020 : Debug: ca_path = "/usr/freerad3/etc/raddb/certs" Tue Jun 23 12:12:40 2020 : Debug: pem_file_type = yes Tue Jun 23 12:12:40 2020 : Debug: private_key_file = "/usr/freerad3/etc/raddb/certs/server.pem" Tue Jun 23 12:12:40 2020 : Debug: certificate_file = "/usr/freerad3/etc/raddb/certs/server.pem" Tue Jun 23 12:12:40 2020 : Debug: ca_file = "/usr/freerad3/etc/raddb/certs/ca.pem" Tue Jun 23 12:12:40 2020 : Debug: private_key_password = "whatever" Tue Jun 23 12:12:40 2020 : Debug: dh_file = "/usr/freerad3/etc/raddb/certs/dh" Tue Jun 23 12:12:40 2020 : Debug: fragment_size = 1024 Tue Jun 23 12:12:40 2020 : Debug: include_length = yes Tue Jun 23 12:12:40 2020 : Debug: auto_chain = yes Tue Jun 23 12:12:40 2020 : Debug: check_crl = no Tue Jun 23 12:12:40 2020 : Debug: check_all_crl = no Tue Jun 23 12:12:40 2020 : Debug: cipher_list = "DEFAULT" Tue Jun 23 12:12:40 2020 : Debug: cipher_server_preference = no Tue Jun 23 12:12:40 2020 : Debug: ecdh_curve = "prime256v1" Tue Jun 23 12:12:40 2020 : Debug: disable_tlsv1 = yes Tue Jun 23 12:12:40 2020 : Debug: disable_tlsv1_1 = yes Tue Jun 23 12:12:40 2020 : Debug: tls_max_version = "1.2" Tue Jun 23 12:12:40 2020 : Debug: tls_min_version = "1.2" Tue Jun 23 12:12:40 2020 : Debug: cache { Tue Jun 23 12:12:40 2020 : Debug: enable = no Tue Jun 23 12:12:40 2020 : Debug: lifetime = 24 Tue Jun 23 12:12:40 2020 : Debug: max_entries = 255 Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: verify { Tue Jun 23 12:12:40 2020 : Debug: skip_if_ocsp_ok = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: ocsp { Tue Jun 23 12:12:40 2020 : Debug: enable = no Tue Jun 23 12:12:40 2020 : Debug: override_cert_url = yes Tue Jun 23 12:12:40 2020 : Debug: url = "http://127.0.0.1/ocsp/" Tue Jun 23 12:12:40 2020 : Debug: use_nonce = yes Tue Jun 23 12:12:40 2020 : Debug: timeout = 0 Tue Jun 23 12:12:40 2020 : Debug: softfail = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } [1m[33mTue Jun 23 12:12:40 2020 : Warning: Please use tls_min_version and tls_max_version instead of disable_tlsv1[0m [1m[33mTue Jun 23 12:12:40 2020 : Warning: Please use tls_min_version and tls_max_version instead of disable_tlsv1_2[0m Tue Jun 23 12:12:40 2020 : Debug: # Linked to sub-module rlm_eap_ttls Tue Jun 23 12:12:40 2020 : Debug: ttls { Tue Jun 23 12:12:40 2020 : Debug: tls = "tls-common" Tue Jun 23 12:12:40 2020 : Debug: default_eap_type = "md5" Tue Jun 23 12:12:40 2020 : Debug: copy_request_to_tunnel = no Tue Jun 23 12:12:40 2020 : Debug: use_tunneled_reply = no Tue Jun 23 12:12:40 2020 : Debug: virtual_server = "inner-tunnel" Tue Jun 23 12:12:40 2020 : Debug: include_length = yes Tue Jun 23 12:12:40 2020 : Debug: require_client_cert = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: tls: Using cached TLS configuration from previous invocation Tue Jun 23 12:12:40 2020 : Debug: # Linked to sub-module rlm_eap_peap Tue Jun 23 12:12:40 2020 : Debug: peap { Tue Jun 23 12:12:40 2020 : Debug: tls = "tls-common" Tue Jun 23 12:12:40 2020 : Debug: default_eap_type = "mschapv2" Tue Jun 23 12:12:40 2020 : Debug: copy_request_to_tunnel = no Tue Jun 23 12:12:40 2020 : Debug: use_tunneled_reply = no Tue Jun 23 12:12:40 2020 : Debug: proxy_tunneled_request_as_eap = yes Tue Jun 23 12:12:40 2020 : Debug: virtual_server = "inner-tunnel" Tue Jun 23 12:12:40 2020 : Debug: soh = no Tue Jun 23 12:12:40 2020 : Debug: require_client_cert = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: tls: Using cached TLS configuration from previous invocation Tue Jun 23 12:12:40 2020 : Debug: # Linked to sub-module rlm_eap_mschapv2 Tue Jun 23 12:12:40 2020 : Debug: mschapv2 { Tue Jun 23 12:12:40 2020 : Debug: with_ntdomain_hack = no Tue Jun 23 12:12:40 2020 : Debug: send_error = no Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "expiration" from file /usr/freerad3/etc/raddb/mods-enabled/expiration Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "files" from file /usr/freerad3/etc/raddb/mods-enabled/files Tue Jun 23 12:12:40 2020 : Debug: reading pairlist file /usr/freerad3/etc/raddb/mods-config/files/authorize Tue Jun 23 12:12:40 2020 : Debug: reading pairlist file /usr/freerad3/etc/raddb/mods-config/files/accounting Tue Jun 23 12:12:40 2020 : Debug: reading pairlist file /usr/freerad3/etc/raddb/mods-config/files/pre-proxy Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "linelog" from file /usr/freerad3/etc/raddb/mods-enabled/linelog Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "log_accounting" from file /usr/freerad3/etc/raddb/mods-enabled/linelog Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "logintime" from file /usr/freerad3/etc/raddb/mods-enabled/logintime Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "mschap" from file /usr/freerad3/etc/raddb/mods-enabled/mschap Tue Jun 23 12:12:40 2020 : Debug: rlm_mschap (mschap): using internal authentication Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "pap" from file /usr/freerad3/etc/raddb/mods-enabled/pap Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "etc_passwd" from file /usr/freerad3/etc/raddb/mods-enabled/passwd Tue Jun 23 12:12:40 2020 : Debug: rlm_passwd: nfields: 3 keyfield 0(User-Name) listable: no Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "preprocess" from file /usr/freerad3/etc/raddb/mods-enabled/preprocess Tue Jun 23 12:12:40 2020 : Debug: reading pairlist file /usr/freerad3/etc/raddb/mods-config/preprocess/huntgroups Tue Jun 23 12:12:40 2020 : Debug: reading pairlist file /usr/freerad3/etc/raddb/mods-config/preprocess/hints Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "IPASS" from file /usr/freerad3/etc/raddb/mods-enabled/realm Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "suffix" from file /usr/freerad3/etc/raddb/mods-enabled/realm Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "bangpath" from file /usr/freerad3/etc/raddb/mods-enabled/realm Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "realmpercent" from file /usr/freerad3/etc/raddb/mods-enabled/realm Tue Jun 23 12:12:40 2020 : Debug: # Instantiating module "ntdomain" from file /usr/freerad3/etc/raddb/mods-enabled/realm Tue Jun 23 12:12:40 2020 : Debug: } # modules Tue Jun 23 12:12:40 2020 : Debug: radiusd: #### Loading Virtual Servers #### Tue Jun 23 12:12:40 2020 : Debug: server { # from file /usr/freerad3/etc/raddb/radiusd.conf Tue Jun 23 12:12:40 2020 : Debug: } # server Tue Jun 23 12:12:40 2020 : Debug: server default { # from file /usr/freerad3/etc/raddb/sites-enabled/default Tue Jun 23 12:12:40 2020 : Debug: # Loading authenticate {...} Tue Jun 23 12:12:40 2020 : Debug: mschap Tue Jun 23 12:12:40 2020 : Debug: digest Tue Jun 23 12:12:40 2020 : Debug: eap Tue Jun 23 12:12:40 2020 : Debug: # Loading authorize {...} Tue Jun 23 12:12:40 2020 : Debug: policy filter_username { Tue Jun 23 12:12:40 2020 : Debug: if (&User-Name) { Tue Jun 23 12:12:40 2020 : Debug: if (&User-Name =~ / /) { Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &Module-Failure-Message += 'Rejected: User-Name contains whitespace' Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: reject Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: if (&User-Name =~ /@[^@]*@/) { Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &Module-Failure-Message += 'Rejected: Multiple @ in User-Name' Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: reject Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: if (&User-Name =~ /\.\./) { Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &Module-Failure-Message += 'Rejected: User-Name contains multiple ..s' Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: reject Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: if (&User-Name =~ /@/ && !&User-Name =~ /@(.+)\.(.+)$/) { Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &Module-Failure-Message += 'Rejected: Realm does not have at least one dot separator' Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: reject Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: if (&User-Name =~ /\.$/) { Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &Module-Failure-Message += 'Rejected: Realm ends with a dot' Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: reject Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: if (&User-Name =~ /@\./) { Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &Module-Failure-Message += 'Rejected: Realm begins with a dot' Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: reject Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: preprocess Tue Jun 23 12:12:40 2020 : Debug: chap Tue Jun 23 12:12:40 2020 : Debug: mschap Tue Jun 23 12:12:40 2020 : Debug: digest Tue Jun 23 12:12:40 2020 : Debug: suffix Tue Jun 23 12:12:40 2020 : Debug: eap Tue Jun 23 12:12:40 2020 : Debug: unix Tue Jun 23 12:12:40 2020 : Debug: files [1m[33mTue Jun 23 12:12:40 2020 : Warning: Ignoring "sql" (see raddb/mods-available/README.rst)[0m [1m[33mTue Jun 23 12:12:40 2020 : Warning: Ignoring "ldap" (see raddb/mods-available/README.rst)[0m Tue Jun 23 12:12:40 2020 : Debug: expiration Tue Jun 23 12:12:40 2020 : Debug: logintime Tue Jun 23 12:12:40 2020 : Debug: pap Tue Jun 23 12:12:40 2020 : Debug: # Loading preacct {...} Tue Jun 23 12:12:40 2020 : Debug: preprocess Tue Jun 23 12:12:40 2020 : Debug: policy acct_unique { Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &Tmp-String-9 := "ai:" Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: if ("%{hex:&Class}" =~ /^%{hex:&Tmp-String-9}/ && "%{string:&Class}" =~ /^ai:([0-9a-f]{32})/) { Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &Acct-Unique-Session-Id := "%{md5:%{1},%{Acct-Session-ID}}" Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: else { Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &Acct-Unique-Session-Id := "%{md5:%{User-Name},%{Acct-Session-ID},%{%{NAS-IPv6-Address}:-%{NAS-IP-Address}},%{NAS-Identifier},%{NAS-Port-ID},%{NAS-Port}}" Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: suffix Tue Jun 23 12:12:40 2020 : Debug: files Tue Jun 23 12:12:40 2020 : Debug: # Loading accounting {...} Tue Jun 23 12:12:40 2020 : Debug: detail Tue Jun 23 12:12:40 2020 : Debug: unix Tue Jun 23 12:12:40 2020 : Debug: exec Tue Jun 23 12:12:40 2020 : Debug: attr_filter.accounting_response Tue Jun 23 12:12:40 2020 : Debug: # Loading post-proxy {...} Tue Jun 23 12:12:40 2020 : Debug: post_proxy_log Tue Jun 23 12:12:40 2020 : Debug: attr_filter.post-proxy Tue Jun 23 12:12:40 2020 : Debug: eap Tue Jun 23 12:12:40 2020 : Debug: # Loading post-auth {...} [1m[33mTue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/sites-enabled/default[704]: Please change attribute reference to '&State := ...'[0m Tue Jun 23 12:12:40 2020 : Debug: if (!&reply:State) { Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &reply:State := "0x%{randstr:16h}" Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: reply_log Tue Jun 23 12:12:40 2020 : Debug: exec [1m[33mTue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/sites-enabled/default[806]: Please change attribute reference to '&Reply-Message += ...'[0m [1m[33mTue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/sites-enabled/default[807]: Please change attribute reference to '&Reply-Message += ...'[0m [1m[33mTue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/sites-enabled/default[808]: Please change attribute reference to '&Reply-Message += ...'[0m [1m[33mTue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/sites-enabled/default[809]: Please change attribute reference to '&Reply-Message += ...'[0m [1m[33mTue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/sites-enabled/default[810]: Please change attribute reference to '&Reply-Message += ...'[0m [1m[33mTue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/sites-enabled/default[811]: Please change attribute reference to '&Reply-Message += ...'[0m [1m[33mTue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/sites-enabled/default[813]: Please change attribute reference to '&Reply-Message += ...'[0m [1m[33mTue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/sites-enabled/default[814]: Please change attribute reference to '&Reply-Message += ...'[0m [1m[33mTue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/sites-enabled/default[815]: Please change attribute reference to '&Reply-Message += ...'[0m [1m[33mTue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/sites-enabled/default[816]: Please change attribute reference to '&Reply-Message += ...'[0m [1m[33mTue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/sites-enabled/default[817]: Please change attribute reference to '&Reply-Message += ...'[0m [1m[33mTue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/sites-enabled/default[818]: Please change attribute reference to '&Reply-Message += ...'[0m Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Cert-Serial}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Cert-Expiration}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Cert-Subject}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Cert-Issuer}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Cert-Common-Name}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Cert-Subject-Alt-Name-Email}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Client-Cert-Serial}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Client-Cert-Expiration}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Client-Cert-Subject}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Client-Cert-Issuer}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Client-Cert-Common-Name}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Client-Cert-Subject-Alt-Name-Email}" Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: policy insert_acct_class { Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &reply:Class = "ai:%{md5:%t,%I,%{Packet-Src-Port},%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}},%{NAS-IP-Address},%{Calling-Station-ID},%{User-Name}}" Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } [1m[33mTue Jun 23 12:12:40 2020 : Warning: /usr/freerad3/etc/raddb/sites-enabled/default[838]: Please change attribute reference to '&EAP-Key-Name := ...'[0m Tue Jun 23 12:12:40 2020 : Debug: if (&reply:EAP-Session-Id) { Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &reply:EAP-Key-Name := &reply:EAP-Session-Id Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } # server default Tue Jun 23 12:12:40 2020 : Debug: server inner-tunnel { # from file /usr/freerad3/etc/raddb/sites-enabled/inner-tunnel Tue Jun 23 12:12:40 2020 : Debug: # Loading authenticate {...} Tue Jun 23 12:12:40 2020 : Debug: mschap Tue Jun 23 12:12:40 2020 : Debug: eap Tue Jun 23 12:12:40 2020 : Debug: # Loading authorize {...} Tue Jun 23 12:12:40 2020 : Debug: policy filter_username { Tue Jun 23 12:12:40 2020 : Debug: if (&User-Name) { Tue Jun 23 12:12:40 2020 : Debug: if (&User-Name =~ / /) { Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &Module-Failure-Message += 'Rejected: User-Name contains whitespace' Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: reject Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: if (&User-Name =~ /@[^@]*@/) { Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &Module-Failure-Message += 'Rejected: Multiple @ in User-Name' Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: reject Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: if (&User-Name =~ /\.\./) { Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &Module-Failure-Message += 'Rejected: User-Name contains multiple ..s' Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: reject Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: if (&User-Name =~ /@/ && !&User-Name =~ /@(.+)\.(.+)$/) { Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &Module-Failure-Message += 'Rejected: Realm does not have at least one dot separator' Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: reject Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: if (&User-Name =~ /\.$/) { Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &Module-Failure-Message += 'Rejected: Realm ends with a dot' Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: reject Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: if (&User-Name =~ /@\./) { Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &Module-Failure-Message += 'Rejected: Realm begins with a dot' Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: reject Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: chap Tue Jun 23 12:12:40 2020 : Debug: mschap Tue Jun 23 12:12:40 2020 : Debug: unix Tue Jun 23 12:12:40 2020 : Debug: suffix Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &control:Proxy-To-Realm := LOCAL Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: eap Tue Jun 23 12:12:40 2020 : Debug: files Tue Jun 23 12:12:40 2020 : Debug: expiration Tue Jun 23 12:12:40 2020 : Debug: logintime Tue Jun 23 12:12:40 2020 : Debug: pap Tue Jun 23 12:12:40 2020 : Debug: # Loading session {...} Tue Jun 23 12:12:40 2020 : Debug: radutmp Tue Jun 23 12:12:40 2020 : Debug: # Loading post-proxy {...} Tue Jun 23 12:12:40 2020 : Debug: eap Tue Jun 23 12:12:40 2020 : Debug: # Loading post-auth {...} [1mTue Jun 23 12:12:40 2020 : Info: # Skipping contents of 'if' as it is always 'false' -- /usr/freerad3/etc/raddb/sites-enabled/inner-tunnel:336[0m Tue Jun 23 12:12:40 2020 : Debug: if (false) { Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } # server inner-tunnel Tue Jun 23 12:12:40 2020 : Debug: server status { # from file /usr/freerad3/etc/raddb/sites-enabled/status Tue Jun 23 12:12:40 2020 : Debug: # Loading authorize {...} Tue Jun 23 12:12:40 2020 : Debug: ok Tue Jun 23 12:12:40 2020 : Debug: } # server status Tue Jun 23 12:12:40 2020 : Debug: radiusd: #### Opening IP addresses and Ports #### Tue Jun 23 12:12:40 2020 : Debug: listen { Tue Jun 23 12:12:40 2020 : Debug: type = "auth" Tue Jun 23 12:12:40 2020 : Debug: ipaddr = * Tue Jun 23 12:12:40 2020 : Debug: port = 0 Tue Jun 23 12:12:40 2020 : Debug: limit { Tue Jun 23 12:12:40 2020 : Debug: max_connections = 16 Tue Jun 23 12:12:40 2020 : Debug: lifetime = 0 Tue Jun 23 12:12:40 2020 : Debug: idle_timeout = 30 Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: listen { Tue Jun 23 12:12:40 2020 : Debug: type = "acct" Tue Jun 23 12:12:40 2020 : Debug: ipaddr = * Tue Jun 23 12:12:40 2020 : Debug: port = 0 Tue Jun 23 12:12:40 2020 : Debug: limit { Tue Jun 23 12:12:40 2020 : Debug: max_connections = 16 Tue Jun 23 12:12:40 2020 : Debug: lifetime = 0 Tue Jun 23 12:12:40 2020 : Debug: idle_timeout = 30 Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: listen { Tue Jun 23 12:12:40 2020 : Debug: type = "auth" Tue Jun 23 12:12:40 2020 : Debug: ipv6addr = :: Tue Jun 23 12:12:40 2020 : Debug: port = 0 Tue Jun 23 12:12:40 2020 : Debug: limit { Tue Jun 23 12:12:40 2020 : Debug: max_connections = 16 Tue Jun 23 12:12:40 2020 : Debug: lifetime = 0 Tue Jun 23 12:12:40 2020 : Debug: idle_timeout = 30 Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: listen { Tue Jun 23 12:12:40 2020 : Debug: type = "acct" Tue Jun 23 12:12:40 2020 : Debug: ipv6addr = :: Tue Jun 23 12:12:40 2020 : Debug: port = 0 Tue Jun 23 12:12:40 2020 : Debug: limit { Tue Jun 23 12:12:40 2020 : Debug: max_connections = 16 Tue Jun 23 12:12:40 2020 : Debug: lifetime = 0 Tue Jun 23 12:12:40 2020 : Debug: idle_timeout = 30 Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: listen { Tue Jun 23 12:12:40 2020 : Debug: type = "auth" Tue Jun 23 12:12:40 2020 : Debug: ipaddr = 127.0.0.1 Tue Jun 23 12:12:40 2020 : Debug: port = 18120 Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: listen { Tue Jun 23 12:12:40 2020 : Debug: type = "status" Tue Jun 23 12:12:40 2020 : Debug: ipaddr = 127.0.0.1 Tue Jun 23 12:12:40 2020 : Debug: port = 18121 Tue Jun 23 12:12:40 2020 : Debug: client admin { Tue Jun 23 12:12:40 2020 : Debug: ipaddr = 127.0.0.1 Tue Jun 23 12:12:40 2020 : Debug: require_message_authenticator = no Tue Jun 23 12:12:40 2020 : Debug: secret = "adminsecret" Tue Jun 23 12:12:40 2020 : Debug: limit { Tue Jun 23 12:12:40 2020 : Debug: max_connections = 16 Tue Jun 23 12:12:40 2020 : Debug: lifetime = 0 Tue Jun 23 12:12:40 2020 : Debug: idle_timeout = 30 Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Adding client 127.0.0.1/32 (127.0.0.1) to prefix tree 32 Tue Jun 23 12:12:40 2020 : Debug: } Tue Jun 23 12:12:40 2020 : Debug: Listening on auth address * port 1645 bound to server default Tue Jun 23 12:12:40 2020 : Debug: Listening on acct address * port 1646 bound to server default Tue Jun 23 12:12:40 2020 : Debug: Listening on auth address :: port 1645 bound to server default Tue Jun 23 12:12:40 2020 : Debug: Listening on acct address :: port 1646 bound to server default Tue Jun 23 12:12:40 2020 : Debug: Listening on auth address 127.0.0.1 port 18120 bound to server inner-tunnel Tue Jun 23 12:12:40 2020 : Debug: Listening on status address 127.0.0.1 port 18121 bound to server status Tue Jun 23 12:12:40 2020 : Debug: Opened new proxy socket 'proxy address * port 26735' Tue Jun 23 12:12:40 2020 : Debug: Listening on proxy address * port 26735 Tue Jun 23 12:12:40 2020 : Debug: Opened new proxy socket 'proxy address :: port 10084' Tue Jun 23 12:12:40 2020 : Debug: Listening on proxy address :: port 10084 [1mTue Jun 23 12:12:40 2020 : Info: Ready to process requests[0m Tue Jun 23 12:13:41 2020 : Debug: (0) Received Access-Request Id 1 from 192.168.81.4:1882 to 192.168.81.1:1645 length 56 Tue Jun 23 12:13:41 2020 : Debug: (0) User-Name = "Puser" Tue Jun 23 12:13:41 2020 : Debug: (0) User-Password = "passwd" Tue Jun 23 12:13:41 2020 : Debug: (0) NAS-IP-Address = 192.168.81.4 Tue Jun 23 12:13:41 2020 : Debug: (0) NAS-Port = 20 Tue Jun 23 12:13:41 2020 : Debug: (0) session-state: No State attribute Tue Jun 23 12:13:41 2020 : Debug: (0) # Executing section authorize from file /usr/freerad3/etc/raddb/sites-enabled/default Tue Jun 23 12:13:41 2020 : Debug: (0) authorize { Tue Jun 23 12:13:41 2020 : Debug: (0) policy filter_username { Tue Jun 23 12:13:41 2020 : Debug: (0) if (&User-Name) { Tue Jun 23 12:13:41 2020 : Debug: (0) if (&User-Name) -> TRUE Tue Jun 23 12:13:41 2020 : Debug: (0) if (&User-Name) { Tue Jun 23 12:13:41 2020 : Debug: (0) if (&User-Name =~ / /) { Tue Jun 23 12:13:41 2020 : Debug: (0) if (&User-Name =~ / /) -> FALSE Tue Jun 23 12:13:41 2020 : Debug: (0) if (&User-Name =~ /@[^@]*@/ ) { Tue Jun 23 12:13:41 2020 : Debug: (0) if (&User-Name =~ /@[^@]*@/ ) -> FALSE Tue Jun 23 12:13:41 2020 : Debug: (0) if (&User-Name =~ /\.\./ ) { Tue Jun 23 12:13:41 2020 : Debug: (0) if (&User-Name =~ /\.\./ ) -> FALSE Tue Jun 23 12:13:41 2020 : Debug: (0) if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/)) { Tue Jun 23 12:13:41 2020 : Debug: (0) if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/)) -> FALSE Tue Jun 23 12:13:41 2020 : Debug: (0) if (&User-Name =~ /\.$/) { Tue Jun 23 12:13:41 2020 : Debug: (0) if (&User-Name =~ /\.$/) -> FALSE Tue Jun 23 12:13:41 2020 : Debug: (0) if (&User-Name =~ /@\./) { Tue Jun 23 12:13:41 2020 : Debug: (0) if (&User-Name =~ /@\./) -> FALSE Tue Jun 23 12:13:41 2020 : Debug: (0) } # if (&User-Name) = notfound Tue Jun 23 12:13:41 2020 : Debug: (0) } # policy filter_username = notfound Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: calling preprocess (rlm_preprocess) Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: returned from preprocess (rlm_preprocess) Tue Jun 23 12:13:41 2020 : Debug: (0) [preprocess] = ok Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: calling chap (rlm_chap) Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: returned from chap (rlm_chap) Tue Jun 23 12:13:41 2020 : Debug: (0) [chap] = noop Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: calling mschap (rlm_mschap) Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: returned from mschap (rlm_mschap) Tue Jun 23 12:13:41 2020 : Debug: (0) [mschap] = noop Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: calling digest (rlm_digest) Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: returned from digest (rlm_digest) Tue Jun 23 12:13:41 2020 : Debug: (0) [digest] = noop Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: calling suffix (rlm_realm) Tue Jun 23 12:13:41 2020 : Debug: (0) suffix: Checking for suffix after "@" Tue Jun 23 12:13:41 2020 : Debug: (0) suffix: No '@' in User-Name = "Puser", looking up realm NULL Tue Jun 23 12:13:41 2020 : Debug: (0) suffix: Found realm "NULL" Tue Jun 23 12:13:41 2020 : Debug: (0) suffix: Adding Stripped-User-Name = "Puser" Tue Jun 23 12:13:41 2020 : Debug: (0) suffix: Adding Realm = "NULL" Tue Jun 23 12:13:41 2020 : Debug: (0) suffix: Authentication realm is LOCAL Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: returned from suffix (rlm_realm) Tue Jun 23 12:13:41 2020 : Debug: (0) [suffix] = ok Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: calling eap (rlm_eap) Tue Jun 23 12:13:41 2020 : Debug: (0) eap: No EAP-Message, not doing EAP Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: returned from eap (rlm_eap) Tue Jun 23 12:13:41 2020 : Debug: (0) [eap] = noop Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: calling unix (rlm_unix) Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: returned from unix (rlm_unix) Tue Jun 23 12:13:41 2020 : Debug: (0) [unix] = updated Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: calling files (rlm_files) Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: returned from files (rlm_files) Tue Jun 23 12:13:41 2020 : Debug: (0) [files] = noop Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: calling expiration (rlm_expiration) Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: returned from expiration (rlm_expiration) Tue Jun 23 12:13:41 2020 : Debug: (0) [expiration] = noop Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: calling logintime (rlm_logintime) Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: returned from logintime (rlm_logintime) Tue Jun 23 12:13:41 2020 : Debug: (0) [logintime] = noop Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: calling pap (rlm_pap) Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authorize]: returned from pap (rlm_pap) Tue Jun 23 12:13:41 2020 : Debug: (0) [pap] = updated Tue Jun 23 12:13:41 2020 : Debug: (0) } # authorize = updated Tue Jun 23 12:13:41 2020 : Debug: (0) Found Auth-Type = PAP Tue Jun 23 12:13:41 2020 : Debug: (0) # Executing group from file /usr/freerad3/etc/raddb/sites-enabled/default Tue Jun 23 12:13:41 2020 : Debug: (0) Auth-Type PAP { Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authenticate]: calling pap (rlm_pap) Tue Jun 23 12:13:41 2020 : Debug: (0) pap: Login attempt with password "passwd" (7) Tue Jun 23 12:13:41 2020 : Debug: (0) pap: Comparing with "known good" Crypt-Password "AsqCrii8QwjG6" Tue Jun 23 12:13:41 2020 : Debug: (0) pap: User authenticated successfully Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[authenticate]: returned from pap (rlm_pap) Tue Jun 23 12:13:41 2020 : Debug: (0) [pap] = ok Tue Jun 23 12:13:41 2020 : Debug: (0) } # Auth-Type PAP = ok Tue Jun 23 12:13:41 2020 : Debug: (0) # Executing section post-auth from file /usr/freerad3/etc/raddb/sites-enabled/default Tue Jun 23 12:13:41 2020 : Debug: (0) post-auth { Tue Jun 23 12:13:41 2020 : Debug: (0) if (!&reply:State) { Tue Jun 23 12:13:41 2020 : Debug: (0) if (!&reply:State) -> TRUE Tue Jun 23 12:13:41 2020 : Debug: (0) if (!&reply:State) { Tue Jun 23 12:13:41 2020 : Debug: (0) update reply { Tue Jun 23 12:13:41 2020 : Debug: (0) EXPAND 0x%{randstr:16h} Tue Jun 23 12:13:41 2020 : Debug: (0) --> 0x4040256939aa0f5d64a1f2a8c133ae6b4a Tue Jun 23 12:13:41 2020 : Debug: (0) State := 0x4040256939aa0f5d64a1f2a8c133ae6b4a Tue Jun 23 12:13:41 2020 : Debug: (0) } # update reply = noop Tue Jun 23 12:13:41 2020 : Debug: (0) } # if (!&reply:State) = noop Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[post-auth]: calling reply_log (rlm_detail) Tue Jun 23 12:13:41 2020 : Debug: /var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/reply-detail-%Y%m%d Tue Jun 23 12:13:41 2020 : Debug: Parsed xlat tree: Tue Jun 23 12:13:41 2020 : Debug: literal --> /var/log/radius/radacct/ Tue Jun 23 12:13:41 2020 : Debug: XLAT-IF { Tue Jun 23 12:13:41 2020 : Debug: attribute --> Packet-Src-IP-Address Tue Jun 23 12:13:41 2020 : Debug: } Tue Jun 23 12:13:41 2020 : Debug: XLAT-ELSE { Tue Jun 23 12:13:41 2020 : Debug: attribute --> Packet-Src-IPv6-Address Tue Jun 23 12:13:41 2020 : Debug: } Tue Jun 23 12:13:41 2020 : Debug: literal --> /reply-detail- Tue Jun 23 12:13:41 2020 : Debug: percent --> Y Tue Jun 23 12:13:41 2020 : Debug: percent --> m Tue Jun 23 12:13:41 2020 : Debug: percent --> d Tue Jun 23 12:13:41 2020 : Debug: (0) reply_log: EXPAND /var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/reply-detail-%Y%m%d Tue Jun 23 12:13:41 2020 : Debug: (0) reply_log: --> /var/log/radius/radacct/192.168.81.4/reply-detail-20200623 Tue Jun 23 12:13:41 2020 : Debug: (0) reply_log: /var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/reply-detail-%Y%m%d expands to /var/log/radius/radacct/192.168.81.4/reply-detail-20200623 Tue Jun 23 12:13:41 2020 : Debug: %t Tue Jun 23 12:13:41 2020 : Debug: Parsed xlat tree: Tue Jun 23 12:13:41 2020 : Debug: percent --> t Tue Jun 23 12:13:41 2020 : Debug: (0) reply_log: EXPAND %t Tue Jun 23 12:13:41 2020 : Debug: (0) reply_log: --> Tue Jun 23 12:13:41 2020 Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[post-auth]: returned from reply_log (rlm_detail) Tue Jun 23 12:13:41 2020 : Debug: (0) [reply_log] = ok Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[post-auth]: calling exec (rlm_exec) Tue Jun 23 12:13:41 2020 : Debug: (0) modsingle[post-auth]: returned from exec (rlm_exec) Tue Jun 23 12:13:41 2020 : Debug: (0) [exec] = noop Tue Jun 23 12:13:41 2020 : Debug: (0) update reply { Tue Jun 23 12:13:41 2020 : Debug: (0) EXPAND %{TLS-Cert-Serial} Tue Jun 23 12:13:41 2020 : Debug: (0) --> Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += Tue Jun 23 12:13:41 2020 : Debug: (0) EXPAND %{TLS-Cert-Expiration} Tue Jun 23 12:13:41 2020 : Debug: (0) --> Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += Tue Jun 23 12:13:41 2020 : Debug: (0) EXPAND %{TLS-Cert-Subject} Tue Jun 23 12:13:41 2020 : Debug: (0) --> Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += Tue Jun 23 12:13:41 2020 : Debug: (0) EXPAND %{TLS-Cert-Issuer} Tue Jun 23 12:13:41 2020 : Debug: (0) --> Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += Tue Jun 23 12:13:41 2020 : Debug: (0) EXPAND %{TLS-Cert-Common-Name} Tue Jun 23 12:13:41 2020 : Debug: (0) --> Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += Tue Jun 23 12:13:41 2020 : Debug: (0) EXPAND %{TLS-Cert-Subject-Alt-Name-Email} Tue Jun 23 12:13:41 2020 : Debug: (0) --> Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += Tue Jun 23 12:13:41 2020 : Debug: (0) EXPAND %{TLS-Client-Cert-Serial} Tue Jun 23 12:13:41 2020 : Debug: (0) --> Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += Tue Jun 23 12:13:41 2020 : Debug: (0) EXPAND %{TLS-Client-Cert-Expiration} Tue Jun 23 12:13:41 2020 : Debug: (0) --> Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += Tue Jun 23 12:13:41 2020 : Debug: (0) EXPAND %{TLS-Client-Cert-Subject} Tue Jun 23 12:13:41 2020 : Debug: (0) --> Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += Tue Jun 23 12:13:41 2020 : Debug: (0) EXPAND %{TLS-Client-Cert-Issuer} Tue Jun 23 12:13:41 2020 : Debug: (0) --> Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += Tue Jun 23 12:13:41 2020 : Debug: (0) EXPAND %{TLS-Client-Cert-Common-Name} Tue Jun 23 12:13:41 2020 : Debug: (0) --> Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += Tue Jun 23 12:13:41 2020 : Debug: (0) EXPAND %{TLS-Client-Cert-Subject-Alt-Name-Email} Tue Jun 23 12:13:41 2020 : Debug: (0) --> Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += Tue Jun 23 12:13:41 2020 : Debug: (0) } # update reply = noop Tue Jun 23 12:13:41 2020 : Debug: (0) policy insert_acct_class { Tue Jun 23 12:13:41 2020 : Debug: (0) update reply { Tue Jun 23 12:13:41 2020 : Debug: (0) EXPAND ai:%{md5:%t,%I,%{Packet-Src-Port},%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}},%{NAS-IP-Address},%{Calling-Station-ID},%{User-Name}} Tue Jun 23 12:13:41 2020 : Debug: (0) --> ai:cbbb1c3bd6d20795e043a79c9a72eaea Tue Jun 23 12:13:41 2020 : Debug: (0) &Class = 0x61693a6362626231633362643664323037393565303433613739633961373265616561 Tue Jun 23 12:13:41 2020 : Debug: (0) } # update reply = noop Tue Jun 23 12:13:41 2020 : Debug: (0) } # policy insert_acct_class = noop Tue Jun 23 12:13:41 2020 : Debug: (0) if (&reply:EAP-Session-Id) { Tue Jun 23 12:13:41 2020 : Debug: (0) if (&reply:EAP-Session-Id) -> FALSE Tue Jun 23 12:13:41 2020 : Debug: (0) } # post-auth = ok Tue Jun 23 12:13:41 2020 : Debug: (0) Sent Access-Accept Id 1 from 192.168.81.1:1645 to 192.168.81.4:1882 length 0 Tue Jun 23 12:13:41 2020 : Debug: (0) State := 0x4040256939aa0f5d64a1f2a8c133ae6b4a Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Class = 0x61693a6362626231633362643664323037393565303433613739633961373265616561 Tue Jun 23 12:13:41 2020 : Debug: (0) Finished request Tue Jun 23 12:13:41 2020 : Debug: Waking up in 4.9 seconds. Tue Jun 23 12:13:46 2020 : Debug: (0) Cleaning up request packet ID 1 with timestamp +61 [1mTue Jun 23 12:13:46 2020 : Info: Ready to process requests[0m ^Croot@doctor:~ # /[Kexit exit Script done on Tue Jun 23 12:14:09 2020 Even if the usrname/password is correct, the NAS is not getting clearance to continue. What bridge needs to built? -- Member - Liberal International This is doctor@@nl2k.ab.ca Ici doctor@@nl2k.ab.ca Yahweh, Queen & country!Never Satan President Republic!Beware AntiChrist rising! nk.ca started 1 June 1995 . https://www.empire.kred/ROOTNK?t=94a1f39b They are not born of the Lord, who alter His Word to please fools. -unknown
Well, Looks that you're replying correctly to the Access-Accept, therefore you should check if the NAS expects extra attributes like vlan, frame-type or anything else.
Tue Jun 23 12:13:41 2020 : Debug: (0) Sent Access-Accept Id 1 from 192.168.81.1:1645 to 192.168.81.4:1882 length 0 Tue Jun 23 12:13:41 2020 : Debug: (0) State := 0x4040256939aa0f5d64a1f2a8c133ae6b4a Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Reply-Message += "" Tue Jun 23 12:13:41 2020 : Debug: (0) Class = 0x61693a6362626231633362643664323037393565303433613739633961373265616561 Tue Jun 23 12:13:41 2020 : Debug: (0) Finished request Tue Jun 23 12:13:41 2020 : Debug: Waking up in 4.9 seconds. Tue Jun 23 12:13:46 2020 : Debug: (0) Cleaning up request packet ID 1 with timestamp +61 [1mTue Jun 23 12:13:46 2020 : Info: Ready to process requests[0m ^Croot@doctor:~ # /[Kexit
exit
Script done on Tue Jun 23 12:14:09 2020
Even if the usrname/password is correct, the NAS is not getting clearance to continue. What bridge needs to built?
- List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
Pn Jun 23, 2020, at 2:25 PM, The Doctor via Freeradius-Users <freeradius-users@lists.freeradius.org> wrote:
Script started on Tue Jun 23 12:12:11 2020 You have mail. root@doctor:~ # /usr/freerad3/sbin/radiusd -X -sx
So... you're not following the documentation, despite REPEATED instructions to do so.
Tue Jun 23 12:12:40 2020 : Debug: update { Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Cert-Serial}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Cert-Expiration}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Cert-Subject}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Cert-Issuer}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Cert-Common-Name}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Cert-Subject-Alt-Name-Email}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Client-Cert-Serial}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Client-Cert-Expiration}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Client-Cert-Subject}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Client-Cert-Issuer}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Client-Cert-Common-Name}" Tue Jun 23 12:12:40 2020 : Debug: &reply:Reply-Message += "%{TLS-Client-Cert-Subject-Alt-Name-Email}" Tue Jun 23 12:12:40 2020 : Debug: }
I have no idea why you uncommented that. It doesn't do anything useful.
Even if the usrname/password is correct, the NAS is not getting clearance to continue. What bridge needs to built?
You're making random changes to the config, and failing to follow the documentation. Can you explain why you think it's important for you to not follow the documentation? Can you explain why you ask for help, and then ignore our advice? Alan DeKok.
participants (3)
-
Alan DeKok -
Jorge Pereira -
The Doctor