trying to get an ACK for a computone intelliserver
The Doctor
doctor at doctor.nl2k.ab.ca
Tue Jun 23 20:25:21 CEST 2020
Script started on Tue Jun 23 12:12:11 2020
You have mail.
root at 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 at 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
More information about the Freeradius-Users
mailing list