[root@kdc ~]# valgrind radiusd -Xxxx ==26224== Memcheck, a memory error detector ==26224== Copyright (C) 2002-2013, and GNU GPL'd, by Julian Seward et al. ==26224== Using Valgrind-3.10.1 and LibVEX; rerun with -h for copyright info ==26224== Command: radiusd -Xxxx ==26224== Sun Feb 4 18:43:47 2018 : Debug: Server was built with: Sun Feb 4 18:43:47 2018 : Debug: accounting : yes Sun Feb 4 18:43:47 2018 : Debug: authentication : yes Sun Feb 4 18:43:47 2018 : Debug: ascend-binary-attributes : yes Sun Feb 4 18:43:47 2018 : Debug: coa : yes Sun Feb 4 18:43:47 2018 : Debug: control-socket : yes Sun Feb 4 18:43:47 2018 : Debug: detail : yes Sun Feb 4 18:43:47 2018 : Debug: dhcp : yes Sun Feb 4 18:43:47 2018 : Debug: dynamic-clients : yes Sun Feb 4 18:43:47 2018 : Debug: osfc2 : no Sun Feb 4 18:43:47 2018 : Debug: proxy : yes Sun Feb 4 18:43:47 2018 : Debug: regex-pcre : yes Sun Feb 4 18:43:47 2018 : Debug: regex-posix : no Sun Feb 4 18:43:47 2018 : Debug: regex-posix-extended : no Sun Feb 4 18:43:47 2018 : Debug: session-management : yes Sun Feb 4 18:43:47 2018 : Debug: stats : yes Sun Feb 4 18:43:47 2018 : Debug: tcp : yes Sun Feb 4 18:43:47 2018 : Debug: threads : yes Sun Feb 4 18:43:47 2018 : Debug: tls : yes Sun Feb 4 18:43:47 2018 : Debug: unlang : yes Sun Feb 4 18:43:47 2018 : Debug: vmps : yes Sun Feb 4 18:43:47 2018 : Debug: developer : yes Sun Feb 4 18:43:47 2018 : Debug: Server core libs: Sun Feb 4 18:43:47 2018 : Debug: freeradius-server : 3.0.17 Sun Feb 4 18:43:47 2018 : Debug: talloc : 2.0.* Sun Feb 4 18:43:47 2018 : Debug: ssl : 1.1.1 dev Sun Feb 4 18:43:47 2018 : Debug: pcre : 8.35 2014-04-04 Sun Feb 4 18:43:47 2018 : Debug: Endianness: Sun Feb 4 18:43:47 2018 : Debug: little Sun Feb 4 18:43:47 2018 : Debug: Compilation flags: Sun Feb 4 18:43:47 2018 : Debug: cppflags : -isystem /usr/local/include/ Sun Feb 4 18:43:47 2018 : 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 -g3 -Wall -std=c99 -D_GNU_SOURCE -D_REENTRANT -D_POSIX_PTHREAD_SEMANTICS -DOPENSSL_NO_KRB5 -Wshadow -Wpointer-arith -Wcast-qual -Wcast-align -Wwrite-strings -Wstrict-prototypes -Wmissing-prototypes -Wmissing-declarations -Wnested-externs -W -Wredundant-decls -Wundef -Wformat-y2k -Wno-format-extra-args -Wno-format-zero-length -Wno-cast-align -Wformat-nonliteral -Wformat-security -Wformat=2 -DWITH_VERIFY_PTR=1 -DIS_MODULE=1 Sun Feb 4 18:43:47 2018 : Debug: ldflags : -L/usr/local/lib64 -Wl,-rpath,/usr/local/lib64 Sun Feb 4 18:43:47 2018 : Debug: libs : -lcrypto -lssl -ltalloc -lpcre -lnsl -lresolv -ldl -lpthread -lreadline Sun Feb 4 18:43:47 2018 : Debug: Sun Feb 4 18:43:47 2018 : Info: FreeRADIUS Version 3.0.17 Sun Feb 4 18:43:47 2018 : Info: Copyright (C) 1999-2017 The FreeRADIUS server project and contributors Sun Feb 4 18:43:47 2018 : Info: There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A Sun Feb 4 18:43:47 2018 : Info: PARTICULAR PURPOSE Sun Feb 4 18:43:47 2018 : Info: You may redistribute copies of FreeRADIUS under the terms of the Sun Feb 4 18:43:47 2018 : Info: GNU General Public License Sun Feb 4 18:43:47 2018 : Info: For more information about these matters, see the file named COPYRIGHT ==26226== ==26226== HEAP SUMMARY: ==26226== in use at exit: 314 bytes in 3 blocks ==26226== total heap usage: 113 allocs, 110 frees, 14,789 bytes allocated ==26226== ==26226== LEAK SUMMARY: ==26226== definitely lost: 0 bytes in 0 blocks ==26226== indirectly lost: 0 bytes in 0 blocks ==26226== possibly lost: 314 bytes in 3 blocks ==26226== still reachable: 0 bytes in 0 blocks ==26226== suppressed: 0 bytes in 0 blocks ==26226== Rerun with --leak-check=full to see details of leaked memory ==26226== ==26226== For counts of detected and suppressed errors, rerun with: -v ==26226== ERROR SUMMARY: 0 errors from 0 contexts (suppressed: 0 from 0) Sun Feb 4 18:43:48 2018 : Info: Starting - reading configuration files ... Sun Feb 4 18:43:48 2018 : Debug: including dictionary file /usr/local/share/freeradius/dictionary Sun Feb 4 18:43:48 2018 : Debug: including dictionary file /usr/local/share/freeradius/dictionary.dhcp Sun Feb 4 18:43:48 2018 : Debug: including dictionary file /usr/local/share/freeradius/dictionary.vqp Sun Feb 4 18:43:48 2018 : Debug: including dictionary file /usr/local/etc/raddb/dictionary Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/radiusd.conf Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/proxy.conf Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/clients.conf Sun Feb 4 18:43:48 2018 : Debug: including files in directory /usr/local/etc/raddb/mods-enabled/ Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/utf8 Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/dynamic_clients Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/expiration Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/replicate Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/linelog Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/realm Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/preprocess Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/soh Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/detail Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/sradutmp Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/eap Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/passwd Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/logintime Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/mschap Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/chap Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/date Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/pap Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/detail.log Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/unix Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/ntlm_auth Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/unpack Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/attr_filter Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/expr Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/exec Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/echo Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/digest Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/files Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/radutmp Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/mods-enabled/cache_eap Sun Feb 4 18:43:48 2018 : Debug: including files in directory /usr/local/etc/raddb/policy.d/ Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/policy.d/moonshot-targeted-ids Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/policy.d/control Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/policy.d/abfab-tr Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/policy.d/eap Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/policy.d/accounting Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/policy.d/filter Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/policy.d/dhcp Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/policy.d/cui Sun Feb 4 18:43:48 2018 : Debug: OPTIMIZING (${policy.cui_require_operator_name} == yes) --> FALSE Sun Feb 4 18:43:48 2018 : Debug: OPTIMIZING (no == yes) --> FALSE Sun Feb 4 18:43:48 2018 : Debug: OPTIMIZING (${policy.cui_require_operator_name} == yes) --> FALSE Sun Feb 4 18:43:48 2018 : Debug: OPTIMIZING (no == yes) --> FALSE Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/policy.d/debug Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/policy.d/operator-name Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/policy.d/canonicalization Sun Feb 4 18:43:48 2018 : Debug: including files in directory /usr/local/etc/raddb/sites-enabled/ Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/sites-enabled/inner-tunnel Sun Feb 4 18:43:48 2018 : Debug: including configuration file /usr/local/etc/raddb/sites-enabled/default Sun Feb 4 18:43:48 2018 : Debug: main { Sun Feb 4 18:43:48 2018 : Debug: security { Sun Feb 4 18:43:48 2018 : Debug: allow_core_dumps = no Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[424]: The item 'max_attributes' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[442]: The item 'reject_delay' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[462]: The item 'status_server' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[472]: The item 'allow_vulnerable_openssl' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Debug: } Sun Feb 4 18:43:48 2018 : Debug: name = "radiusd" Sun Feb 4 18:43:48 2018 : Debug: prefix = "/usr/local" Sun Feb 4 18:43:48 2018 : Debug: localstatedir = "/usr/local/var" Sun Feb 4 18:43:48 2018 : Debug: logdir = "/usr/local/var/log/radius" Sun Feb 4 18:43:48 2018 : Debug: run_dir = "/usr/local/var/run/radiusd" Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[67]: The item 'confdir' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[74]: The item 'db_dir' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[108]: The item 'libdir' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[119]: The item 'pidfile' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[140]: The item 'correct_escapes' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[194]: The item 'max_request_time' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[213]: The item 'cleanup_delay' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[250]: The item 'hostname_lookups' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[334]: The item 'checkrad' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[491]: The item 'proxy_requests' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Debug: } Sun Feb 4 18:43:48 2018 : Debug: main { Sun Feb 4 18:43:48 2018 : Debug: name = "radiusd" Sun Feb 4 18:43:48 2018 : Debug: prefix = "/usr/local" Sun Feb 4 18:43:48 2018 : Debug: localstatedir = "/usr/local/var" Sun Feb 4 18:43:48 2018 : Debug: sbindir = "/usr/local/sbin" Sun Feb 4 18:43:48 2018 : Debug: logdir = "/usr/local/var/log/radius" Sun Feb 4 18:43:48 2018 : Debug: run_dir = "/usr/local/var/run/radiusd" Sun Feb 4 18:43:48 2018 : Debug: libdir = "/usr/local/lib" Sun Feb 4 18:43:48 2018 : Debug: radacctdir = "/usr/local/var/log/radius/radacct" Sun Feb 4 18:43:48 2018 : Debug: hostname_lookups = no Sun Feb 4 18:43:48 2018 : Debug: max_request_time = 30 Sun Feb 4 18:43:48 2018 : Debug: cleanup_delay = 5 Sun Feb 4 18:43:48 2018 : Debug: max_requests = 16384 Sun Feb 4 18:43:48 2018 : Debug: pidfile = "/usr/local/var/run/radiusd/radiusd.pid" Sun Feb 4 18:43:48 2018 : Debug: checkrad = "/usr/local/sbin/checkrad" Sun Feb 4 18:43:48 2018 : Debug: debug_level = 0 Sun Feb 4 18:43:48 2018 : Debug: proxy_requests = yes Sun Feb 4 18:43:48 2018 : Debug: log { Sun Feb 4 18:43:48 2018 : Debug: stripped_names = no Sun Feb 4 18:43:48 2018 : Debug: auth = no Sun Feb 4 18:43:48 2018 : Debug: auth_badpass = no Sun Feb 4 18:43:48 2018 : Debug: auth_goodpass = no Sun Feb 4 18:43:48 2018 : Debug: colourise = yes Sun Feb 4 18:43:48 2018 : Debug: msg_denied = "You are already logged in - access denied" Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[268]: The item 'destination' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[285]: The item 'file' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[293]: The item 'syslog_facility' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Debug: } Sun Feb 4 18:43:48 2018 : Debug: resources { Sun Feb 4 18:43:48 2018 : Debug: } Sun Feb 4 18:43:48 2018 : Debug: security { Sun Feb 4 18:43:48 2018 : Debug: max_attributes = 200 Sun Feb 4 18:43:48 2018 : Debug: reject_delay = 1.000000 Sun Feb 4 18:43:48 2018 : Debug: status_server = yes Sun Feb 4 18:43:48 2018 : Debug: allow_vulnerable_openssl = "yes" Sun Feb 4 18:43:48 2018 : Debug: } Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[67]: The item 'confdir' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[74]: The item 'db_dir' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Warning: /usr/local/etc/raddb/radiusd.conf[140]: The item 'correct_escapes' is defined, but is unused by the configuration Sun Feb 4 18:43:48 2018 : Debug: } Sun Feb 4 18:43:48 2018 : Debug: radiusd: #### Loading Realms and Home Servers #### Sun Feb 4 18:43:48 2018 : Debug: proxy server { Sun Feb 4 18:43:48 2018 : Debug: retry_delay = 5 Sun Feb 4 18:43:48 2018 : Debug: retry_count = 3 Sun Feb 4 18:43:48 2018 : Debug: default_fallback = no Sun Feb 4 18:43:48 2018 : Debug: dead_time = 120 Sun Feb 4 18:43:48 2018 : Debug: wake_all_if_all_dead = no Sun Feb 4 18:43:48 2018 : Debug: } Sun Feb 4 18:43:48 2018 : Debug: home_server localhost { Sun Feb 4 18:43:48 2018 : Debug: ipaddr = 127.0.0.1 Sun Feb 4 18:43:48 2018 : Debug: port = 1812 Sun Feb 4 18:43:48 2018 : Debug: type = "auth" Sun Feb 4 18:43:48 2018 : Debug: secret = "testing123" Sun Feb 4 18:43:48 2018 : Debug: response_window = 20.000000 Sun Feb 4 18:43:48 2018 : Debug: response_timeouts = 1 Sun Feb 4 18:43:48 2018 : Debug: max_outstanding = 65536 Sun Feb 4 18:43:48 2018 : Debug: zombie_period = 40 Sun Feb 4 18:43:48 2018 : Debug: status_check = "status-server" Sun Feb 4 18:43:48 2018 : Debug: ping_interval = 30 Sun Feb 4 18:43:48 2018 : Debug: check_interval = 30 Sun Feb 4 18:43:48 2018 : Debug: check_timeout = 4 Sun Feb 4 18:43:48 2018 : Debug: num_answers_to_alive = 3 Sun Feb 4 18:43:48 2018 : Debug: revive_interval = 120 Sun Feb 4 18:43:48 2018 : Debug: limit { Sun Feb 4 18:43:48 2018 : Debug: max_connections = 16 Sun Feb 4 18:43:48 2018 : Debug: max_requests = 0 Sun Feb 4 18:43:48 2018 : Debug: lifetime = 0 Sun Feb 4 18:43:48 2018 : Debug: idle_timeout = 0 Sun Feb 4 18:43:48 2018 : Debug: } Sun Feb 4 18:43:48 2018 : Debug: coa { Sun Feb 4 18:43:48 2018 : Debug: irt = 2 Sun Feb 4 18:43:48 2018 : Debug: mrt = 16 Sun Feb 4 18:43:48 2018 : Debug: mrc = 5 Sun Feb 4 18:43:48 2018 : Debug: mrd = 30 Sun Feb 4 18:43:48 2018 : Debug: } Sun Feb 4 18:43:48 2018 : Debug: } Sun Feb 4 18:43:48 2018 : Debug: home_server_pool my_auth_failover { Sun Feb 4 18:43:48 2018 : Debug: type = fail-over Sun Feb 4 18:43:48 2018 : Debug: home_server = localhost Sun Feb 4 18:43:48 2018 : Debug: } Sun Feb 4 18:43:48 2018 : Debug: realm example.com { Sun Feb 4 18:43:48 2018 : Debug: auth_pool = my_auth_failover Sun Feb 4 18:43:48 2018 : Debug: } Sun Feb 4 18:43:48 2018 : Debug: realm LOCAL { Sun Feb 4 18:43:48 2018 : Debug: } Sun Feb 4 18:43:48 2018 : Debug: radiusd: #### Loading Clients #### Sun Feb 4 18:43:48 2018 : Debug: client localhost { Sun Feb 4 18:43:48 2018 : Debug: ipaddr = 127.0.0.1 Sun Feb 4 18:43:48 2018 : Debug: require_message_authenticator = no Sun Feb 4 18:43:48 2018 : Debug: secret = "testing123" Sun Feb 4 18:43:48 2018 : Debug: nas_type = "other" Sun Feb 4 18:43:48 2018 : Debug: proto = "*" Sun Feb 4 18:43:48 2018 : Debug: limit { Sun Feb 4 18:43:48 2018 : Debug: max_connections = 16 Sun Feb 4 18:43:48 2018 : Debug: lifetime = 0 Sun Feb 4 18:43:48 2018 : Debug: idle_timeout = 30 Sun Feb 4 18:43:48 2018 : Debug: } Sun Feb 4 18:43:48 2018 : Debug: } Sun Feb 4 18:43:48 2018 : Debug: Adding client 127.0.0.1/32 (127.0.0.1) to prefix tree 32 Sun Feb 4 18:43:49 2018 : Info: Debugger not attached Sun Feb 4 18:43:49 2018 : Debug: # Creating Auth-Type = mschap Sun Feb 4 18:43:49 2018 : Debug: # Creating Auth-Type = eap Sun Feb 4 18:43:49 2018 : Debug: # Creating Auth-Type = PAP Sun Feb 4 18:43:49 2018 : Debug: # Creating Auth-Type = CHAP Sun Feb 4 18:43:49 2018 : Debug: # Creating Auth-Type = MS-CHAP Sun Feb 4 18:43:49 2018 : Debug: # Creating Auth-Type = digest Sun Feb 4 18:43:49 2018 : Debug: radiusd: #### Instantiating modules #### Sun Feb 4 18:43:49 2018 : Debug: modules { Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_utf8 with path: /usr/local/lib/rlm_utf8.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_utf8, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_utf8 Sun Feb 4 18:43:49 2018 : Debug: # Loading module "utf8" from file /usr/local/etc/raddb/mods-enabled/utf8 Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_dynamic_clients with path: /usr/local/lib/rlm_dynamic_clients.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_dynamic_clients, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_dynamic_clients Sun Feb 4 18:43:49 2018 : Debug: # Loading module "dynamic_clients" from file /usr/local/etc/raddb/mods-enabled/dynamic_clients Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_expiration with path: /usr/local/lib/rlm_expiration.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_expiration, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_expiration Sun Feb 4 18:43:49 2018 : Debug: # Loading module "expiration" from file /usr/local/etc/raddb/mods-enabled/expiration Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_replicate with path: /usr/local/lib/rlm_replicate.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_replicate, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_replicate Sun Feb 4 18:43:49 2018 : Debug: # Loading module "replicate" from file /usr/local/etc/raddb/mods-enabled/replicate Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_linelog with path: /usr/local/lib/rlm_linelog.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_linelog, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_linelog Sun Feb 4 18:43:49 2018 : Debug: # Loading module "linelog" from file /usr/local/etc/raddb/mods-enabled/linelog Sun Feb 4 18:43:49 2018 : Debug: linelog { Sun Feb 4 18:43:49 2018 : Debug: filename = "/usr/local/var/log/radius/linelog" Sun Feb 4 18:43:49 2018 : Debug: escape_filenames = no Sun Feb 4 18:43:49 2018 : Debug: syslog_severity = "info" Sun Feb 4 18:43:49 2018 : Debug: permissions = 384 Sun Feb 4 18:43:49 2018 : Debug: format = "This is a log message for %{User-Name}" Sun Feb 4 18:43:49 2018 : Debug: reference = "messages.%{%{reply:Packet-Type}:-default}" Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "log_accounting" from file /usr/local/etc/raddb/mods-enabled/linelog Sun Feb 4 18:43:49 2018 : Debug: linelog log_accounting { Sun Feb 4 18:43:49 2018 : Debug: filename = "/usr/local/var/log/radius/linelog-accounting" Sun Feb 4 18:43:49 2018 : Debug: escape_filenames = no Sun Feb 4 18:43:49 2018 : Debug: syslog_severity = "info" Sun Feb 4 18:43:49 2018 : Debug: permissions = 384 Sun Feb 4 18:43:49 2018 : Debug: format = "" Sun Feb 4 18:43:49 2018 : Debug: reference = "Accounting-Request.%{%{Acct-Status-Type}:-unknown}" Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_realm with path: /usr/local/lib/rlm_realm.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_realm, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_realm Sun Feb 4 18:43:49 2018 : Debug: # Loading module "IPASS" from file /usr/local/etc/raddb/mods-enabled/realm Sun Feb 4 18:43:49 2018 : Debug: realm IPASS { Sun Feb 4 18:43:49 2018 : Debug: format = "prefix" Sun Feb 4 18:43:49 2018 : Debug: delimiter = "/" Sun Feb 4 18:43:49 2018 : Debug: ignore_default = no Sun Feb 4 18:43:49 2018 : Debug: ignore_null = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "suffix" from file /usr/local/etc/raddb/mods-enabled/realm Sun Feb 4 18:43:49 2018 : Debug: realm suffix { Sun Feb 4 18:43:49 2018 : Debug: format = "suffix" Sun Feb 4 18:43:49 2018 : Debug: delimiter = "@" Sun Feb 4 18:43:49 2018 : Debug: ignore_default = no Sun Feb 4 18:43:49 2018 : Debug: ignore_null = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "realmpercent" from file /usr/local/etc/raddb/mods-enabled/realm Sun Feb 4 18:43:49 2018 : Debug: realm realmpercent { Sun Feb 4 18:43:49 2018 : Debug: format = "suffix" Sun Feb 4 18:43:49 2018 : Debug: delimiter = "%" Sun Feb 4 18:43:49 2018 : Debug: ignore_default = no Sun Feb 4 18:43:49 2018 : Debug: ignore_null = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "ntdomain" from file /usr/local/etc/raddb/mods-enabled/realm Sun Feb 4 18:43:49 2018 : Debug: realm ntdomain { Sun Feb 4 18:43:49 2018 : Debug: format = "prefix" Sun Feb 4 18:43:49 2018 : Debug: delimiter = "\\" Sun Feb 4 18:43:49 2018 : Debug: ignore_default = no Sun Feb 4 18:43:49 2018 : Debug: ignore_null = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_preprocess with path: /usr/local/lib/rlm_preprocess.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_preprocess, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_preprocess Sun Feb 4 18:43:49 2018 : Debug: # Loading module "preprocess" from file /usr/local/etc/raddb/mods-enabled/preprocess Sun Feb 4 18:43:49 2018 : Debug: preprocess { Sun Feb 4 18:43:49 2018 : Debug: huntgroups = "/usr/local/etc/raddb/mods-config/preprocess/huntgroups" Sun Feb 4 18:43:49 2018 : Debug: hints = "/usr/local/etc/raddb/mods-config/preprocess/hints" Sun Feb 4 18:43:49 2018 : Debug: with_ascend_hack = no Sun Feb 4 18:43:49 2018 : Debug: ascend_channels_per_line = 23 Sun Feb 4 18:43:49 2018 : Debug: with_ntdomain_hack = no Sun Feb 4 18:43:49 2018 : Debug: with_specialix_jetstream_hack = no Sun Feb 4 18:43:49 2018 : Debug: with_cisco_vsa_hack = no Sun Feb 4 18:43:49 2018 : Debug: with_alvarion_vsa_hack = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_soh with path: /usr/local/lib/rlm_soh.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_soh, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_soh Sun Feb 4 18:43:49 2018 : Debug: # Loading module "soh" from file /usr/local/etc/raddb/mods-enabled/soh Sun Feb 4 18:43:49 2018 : Debug: soh { Sun Feb 4 18:43:49 2018 : Debug: dhcp = yes Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_detail with path: /usr/local/lib/rlm_detail.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_detail, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_detail Sun Feb 4 18:43:49 2018 : Debug: # Loading module "detail" from file /usr/local/etc/raddb/mods-enabled/detail Sun Feb 4 18:43:49 2018 : Debug: detail { Sun Feb 4 18:43:49 2018 : Debug: filename = "/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d" Sun Feb 4 18:43:49 2018 : Debug: header = "%t" Sun Feb 4 18:43:49 2018 : Debug: permissions = 384 Sun Feb 4 18:43:49 2018 : Debug: locking = no Sun Feb 4 18:43:49 2018 : Debug: escape_filenames = no Sun Feb 4 18:43:49 2018 : Debug: log_packet_header = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_radutmp with path: /usr/local/lib/rlm_radutmp.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_radutmp, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_radutmp Sun Feb 4 18:43:49 2018 : Debug: # Loading module "sradutmp" from file /usr/local/etc/raddb/mods-enabled/sradutmp Sun Feb 4 18:43:49 2018 : Debug: radutmp sradutmp { Sun Feb 4 18:43:49 2018 : Debug: filename = "/usr/local/var/log/radius/sradutmp" Sun Feb 4 18:43:49 2018 : Debug: username = "%{User-Name}" Sun Feb 4 18:43:49 2018 : Debug: case_sensitive = yes Sun Feb 4 18:43:49 2018 : Debug: check_with_nas = yes Sun Feb 4 18:43:49 2018 : Debug: permissions = 420 Sun Feb 4 18:43:49 2018 : Debug: caller_id = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_eap with path: /usr/local/lib/rlm_eap.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_eap, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_eap Sun Feb 4 18:43:49 2018 : Debug: # Loading module "eap" from file /usr/local/etc/raddb/mods-enabled/eap Sun Feb 4 18:43:49 2018 : Debug: eap { Sun Feb 4 18:43:49 2018 : Debug: default_eap_type = "md5" Sun Feb 4 18:43:49 2018 : Debug: timer_expire = 60 Sun Feb 4 18:43:49 2018 : Debug: ignore_unknown_eap_types = no Sun Feb 4 18:43:49 2018 : Debug: cisco_accounting_username_bug = no Sun Feb 4 18:43:49 2018 : Debug: max_sessions = 16384 Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_passwd with path: /usr/local/lib/rlm_passwd.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_passwd, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_passwd Sun Feb 4 18:43:49 2018 : Debug: # Loading module "etc_passwd" from file /usr/local/etc/raddb/mods-enabled/passwd Sun Feb 4 18:43:49 2018 : Debug: passwd etc_passwd { Sun Feb 4 18:43:49 2018 : Debug: filename = "/etc/passwd" Sun Feb 4 18:43:49 2018 : Debug: format = "*User-Name:Crypt-Password:" Sun Feb 4 18:43:49 2018 : Debug: delimiter = ":" Sun Feb 4 18:43:49 2018 : Debug: ignore_nislike = no Sun Feb 4 18:43:49 2018 : Debug: ignore_empty = yes Sun Feb 4 18:43:49 2018 : Debug: allow_multiple_keys = no Sun Feb 4 18:43:49 2018 : Debug: hash_size = 100 Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_logintime with path: /usr/local/lib/rlm_logintime.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_logintime, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_logintime Sun Feb 4 18:43:49 2018 : Debug: # Loading module "logintime" from file /usr/local/etc/raddb/mods-enabled/logintime Sun Feb 4 18:43:49 2018 : Debug: logintime { Sun Feb 4 18:43:49 2018 : Debug: minimum_timeout = 60 Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_mschap with path: /usr/local/lib/rlm_mschap.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_mschap, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_mschap Sun Feb 4 18:43:49 2018 : Debug: # Loading module "mschap" from file /usr/local/etc/raddb/mods-enabled/mschap Sun Feb 4 18:43:49 2018 : Debug: mschap { Sun Feb 4 18:43:49 2018 : Debug: use_mppe = yes Sun Feb 4 18:43:49 2018 : Debug: require_encryption = no Sun Feb 4 18:43:49 2018 : Debug: require_strong = no Sun Feb 4 18:43:49 2018 : Debug: with_ntdomain_hack = yes Sun Feb 4 18:43:49 2018 : Debug: passchange { Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: allow_retry = yes Sun Feb 4 18:43:49 2018 : Debug: winbind_retry_with_normalised_username = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_chap with path: /usr/local/lib/rlm_chap.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_chap, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_chap Sun Feb 4 18:43:49 2018 : Debug: # Loading module "chap" from file /usr/local/etc/raddb/mods-enabled/chap Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_date with path: /usr/local/lib/rlm_date.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_date, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_date Sun Feb 4 18:43:49 2018 : Debug: # Loading module "date" from file /usr/local/etc/raddb/mods-enabled/date Sun Feb 4 18:43:49 2018 : Debug: date { Sun Feb 4 18:43:49 2018 : Debug: format = "%b %e %Y %H:%M:%S %Z" Sun Feb 4 18:43:49 2018 : Debug: utc = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_always with path: /usr/local/lib/rlm_always.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_always, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_always Sun Feb 4 18:43:49 2018 : Debug: # Loading module "reject" from file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:49 2018 : Debug: always reject { Sun Feb 4 18:43:49 2018 : Debug: rcode = "reject" Sun Feb 4 18:43:49 2018 : Debug: simulcount = 0 Sun Feb 4 18:43:49 2018 : Debug: mpp = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "fail" from file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:49 2018 : Debug: always fail { Sun Feb 4 18:43:49 2018 : Debug: rcode = "fail" Sun Feb 4 18:43:49 2018 : Debug: simulcount = 0 Sun Feb 4 18:43:49 2018 : Debug: mpp = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "ok" from file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:49 2018 : Debug: always ok { Sun Feb 4 18:43:49 2018 : Debug: rcode = "ok" Sun Feb 4 18:43:49 2018 : Debug: simulcount = 0 Sun Feb 4 18:43:49 2018 : Debug: mpp = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "handled" from file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:49 2018 : Debug: always handled { Sun Feb 4 18:43:49 2018 : Debug: rcode = "handled" Sun Feb 4 18:43:49 2018 : Debug: simulcount = 0 Sun Feb 4 18:43:49 2018 : Debug: mpp = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "invalid" from file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:49 2018 : Debug: always invalid { Sun Feb 4 18:43:49 2018 : Debug: rcode = "invalid" Sun Feb 4 18:43:49 2018 : Debug: simulcount = 0 Sun Feb 4 18:43:49 2018 : Debug: mpp = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "userlock" from file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:49 2018 : Debug: always userlock { Sun Feb 4 18:43:49 2018 : Debug: rcode = "userlock" Sun Feb 4 18:43:49 2018 : Debug: simulcount = 0 Sun Feb 4 18:43:49 2018 : Debug: mpp = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "notfound" from file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:49 2018 : Debug: always notfound { Sun Feb 4 18:43:49 2018 : Debug: rcode = "notfound" Sun Feb 4 18:43:49 2018 : Debug: simulcount = 0 Sun Feb 4 18:43:49 2018 : Debug: mpp = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "noop" from file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:49 2018 : Debug: always noop { Sun Feb 4 18:43:49 2018 : Debug: rcode = "noop" Sun Feb 4 18:43:49 2018 : Debug: simulcount = 0 Sun Feb 4 18:43:49 2018 : Debug: mpp = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "updated" from file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:49 2018 : Debug: always updated { Sun Feb 4 18:43:49 2018 : Debug: rcode = "updated" Sun Feb 4 18:43:49 2018 : Debug: simulcount = 0 Sun Feb 4 18:43:49 2018 : Debug: mpp = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_pap with path: /usr/local/lib/rlm_pap.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_pap, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_pap Sun Feb 4 18:43:49 2018 : Debug: # Loading module "pap" from file /usr/local/etc/raddb/mods-enabled/pap Sun Feb 4 18:43:49 2018 : Debug: pap { Sun Feb 4 18:43:49 2018 : Debug: normalise = yes Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "auth_log" from file /usr/local/etc/raddb/mods-enabled/detail.log Sun Feb 4 18:43:49 2018 : Debug: detail auth_log { Sun Feb 4 18:43:49 2018 : Debug: filename = "/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d" Sun Feb 4 18:43:49 2018 : Debug: header = "%t" Sun Feb 4 18:43:49 2018 : Debug: permissions = 384 Sun Feb 4 18:43:49 2018 : Debug: locking = no Sun Feb 4 18:43:49 2018 : Debug: escape_filenames = no Sun Feb 4 18:43:49 2018 : Debug: log_packet_header = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "reply_log" from file /usr/local/etc/raddb/mods-enabled/detail.log Sun Feb 4 18:43:49 2018 : Debug: detail reply_log { Sun Feb 4 18:43:49 2018 : Debug: filename = "/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/reply-detail-%Y%m%d" Sun Feb 4 18:43:49 2018 : Debug: header = "%t" Sun Feb 4 18:43:49 2018 : Debug: permissions = 384 Sun Feb 4 18:43:49 2018 : Debug: locking = no Sun Feb 4 18:43:49 2018 : Debug: escape_filenames = no Sun Feb 4 18:43:49 2018 : Debug: log_packet_header = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "pre_proxy_log" from file /usr/local/etc/raddb/mods-enabled/detail.log Sun Feb 4 18:43:49 2018 : Debug: detail pre_proxy_log { Sun Feb 4 18:43:49 2018 : Debug: filename = "/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/pre-proxy-detail-%Y%m%d" Sun Feb 4 18:43:49 2018 : Debug: header = "%t" Sun Feb 4 18:43:49 2018 : Debug: permissions = 384 Sun Feb 4 18:43:49 2018 : Debug: locking = no Sun Feb 4 18:43:49 2018 : Debug: escape_filenames = no Sun Feb 4 18:43:49 2018 : Debug: log_packet_header = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "post_proxy_log" from file /usr/local/etc/raddb/mods-enabled/detail.log Sun Feb 4 18:43:49 2018 : Debug: detail post_proxy_log { Sun Feb 4 18:43:49 2018 : Debug: filename = "/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/post-proxy-detail-%Y%m%d" Sun Feb 4 18:43:49 2018 : Debug: header = "%t" Sun Feb 4 18:43:49 2018 : Debug: permissions = 384 Sun Feb 4 18:43:49 2018 : Debug: locking = no Sun Feb 4 18:43:49 2018 : Debug: escape_filenames = no Sun Feb 4 18:43:49 2018 : Debug: log_packet_header = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_unix with path: /usr/local/lib/rlm_unix.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_unix, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_unix Sun Feb 4 18:43:49 2018 : Debug: # Loading module "unix" from file /usr/local/etc/raddb/mods-enabled/unix Sun Feb 4 18:43:49 2018 : Debug: unix { Sun Feb 4 18:43:49 2018 : Debug: radwtmp = "/usr/local/var/log/radius/radwtmp" Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Creating attribute Unix-Group Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_exec with path: /usr/local/lib/rlm_exec.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_exec, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_exec Sun Feb 4 18:43:49 2018 : Debug: # Loading module "ntlm_auth" from file /usr/local/etc/raddb/mods-enabled/ntlm_auth Sun Feb 4 18:43:49 2018 : Debug: exec ntlm_auth { Sun Feb 4 18:43:49 2018 : Debug: wait = yes Sun Feb 4 18:43:49 2018 : Debug: program = "/path/to/ntlm_auth --request-nt-key --domain=MYDOMAIN --username=%{mschap:User-Name} --password=%{User-Password}" Sun Feb 4 18:43:49 2018 : Debug: shell_escape = yes Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_unpack with path: /usr/local/lib/rlm_unpack.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_unpack, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_unpack Sun Feb 4 18:43:49 2018 : Debug: # Loading module "unpack" from file /usr/local/etc/raddb/mods-enabled/unpack Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_attr_filter with path: /usr/local/lib/rlm_attr_filter.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_attr_filter, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_attr_filter Sun Feb 4 18:43:49 2018 : Debug: # Loading module "attr_filter.post-proxy" from file /usr/local/etc/raddb/mods-enabled/attr_filter Sun Feb 4 18:43:49 2018 : Debug: attr_filter attr_filter.post-proxy { Sun Feb 4 18:43:49 2018 : Debug: filename = "/usr/local/etc/raddb/mods-config/attr_filter/post-proxy" Sun Feb 4 18:43:49 2018 : Debug: key = "%{Realm}" Sun Feb 4 18:43:49 2018 : Debug: relaxed = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "attr_filter.pre-proxy" from file /usr/local/etc/raddb/mods-enabled/attr_filter Sun Feb 4 18:43:49 2018 : Debug: attr_filter attr_filter.pre-proxy { Sun Feb 4 18:43:49 2018 : Debug: filename = "/usr/local/etc/raddb/mods-config/attr_filter/pre-proxy" Sun Feb 4 18:43:49 2018 : Debug: key = "%{Realm}" Sun Feb 4 18:43:49 2018 : Debug: relaxed = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "attr_filter.access_reject" from file /usr/local/etc/raddb/mods-enabled/attr_filter Sun Feb 4 18:43:49 2018 : Debug: attr_filter attr_filter.access_reject { Sun Feb 4 18:43:49 2018 : Debug: filename = "/usr/local/etc/raddb/mods-config/attr_filter/access_reject" Sun Feb 4 18:43:49 2018 : Debug: key = "%{User-Name}" Sun Feb 4 18:43:49 2018 : Debug: relaxed = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "attr_filter.access_challenge" from file /usr/local/etc/raddb/mods-enabled/attr_filter Sun Feb 4 18:43:49 2018 : Debug: attr_filter attr_filter.access_challenge { Sun Feb 4 18:43:49 2018 : Debug: filename = "/usr/local/etc/raddb/mods-config/attr_filter/access_challenge" Sun Feb 4 18:43:49 2018 : Debug: key = "%{User-Name}" Sun Feb 4 18:43:49 2018 : Debug: relaxed = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "attr_filter.accounting_response" from file /usr/local/etc/raddb/mods-enabled/attr_filter Sun Feb 4 18:43:49 2018 : Debug: attr_filter attr_filter.accounting_response { Sun Feb 4 18:43:49 2018 : Debug: filename = "/usr/local/etc/raddb/mods-config/attr_filter/accounting_response" Sun Feb 4 18:43:49 2018 : Debug: key = "%{User-Name}" Sun Feb 4 18:43:49 2018 : Debug: relaxed = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_expr with path: /usr/local/lib/rlm_expr.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_expr, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_expr Sun Feb 4 18:43:49 2018 : Debug: # Loading module "expr" from file /usr/local/etc/raddb/mods-enabled/expr Sun Feb 4 18:43:49 2018 : Debug: expr { Sun Feb 4 18:43:49 2018 : Debug: safe_characters = "@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /äéöüàâæçèéêëîïôœùûüaÿÄÉÖÜßÀÂÆÇÈÉÊËÎÏÔŒÙÛÜŸ" Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "exec" from file /usr/local/etc/raddb/mods-enabled/exec Sun Feb 4 18:43:49 2018 : Debug: exec { Sun Feb 4 18:43:49 2018 : Debug: wait = no Sun Feb 4 18:43:49 2018 : Debug: input_pairs = "request" Sun Feb 4 18:43:49 2018 : Debug: shell_escape = yes Sun Feb 4 18:43:49 2018 : Debug: timeout = 10 Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "echo" from file /usr/local/etc/raddb/mods-enabled/echo Sun Feb 4 18:43:49 2018 : Debug: exec echo { Sun Feb 4 18:43:49 2018 : Debug: wait = yes Sun Feb 4 18:43:49 2018 : Debug: program = "/bin/echo %{User-Name}" Sun Feb 4 18:43:49 2018 : Debug: input_pairs = "request" Sun Feb 4 18:43:49 2018 : Debug: output_pairs = "reply" Sun Feb 4 18:43:49 2018 : Debug: shell_escape = yes Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_digest with path: /usr/local/lib/rlm_digest.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_digest, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_digest Sun Feb 4 18:43:49 2018 : Debug: # Loading module "digest" from file /usr/local/etc/raddb/mods-enabled/digest Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_files with path: /usr/local/lib/rlm_files.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_files, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_files Sun Feb 4 18:43:49 2018 : Debug: # Loading module "files" from file /usr/local/etc/raddb/mods-enabled/files Sun Feb 4 18:43:49 2018 : Debug: files { Sun Feb 4 18:43:49 2018 : Debug: filename = "/usr/local/etc/raddb/mods-config/files/authorize" Sun Feb 4 18:43:49 2018 : Debug: acctusersfile = "/usr/local/etc/raddb/mods-config/files/accounting" Sun Feb 4 18:43:49 2018 : Debug: preproxy_usersfile = "/usr/local/etc/raddb/mods-config/files/pre-proxy" Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Loading module "radutmp" from file /usr/local/etc/raddb/mods-enabled/radutmp Sun Feb 4 18:43:49 2018 : Debug: radutmp { Sun Feb 4 18:43:49 2018 : Debug: filename = "/usr/local/var/log/radius/radutmp" Sun Feb 4 18:43:49 2018 : Debug: username = "%{User-Name}" Sun Feb 4 18:43:49 2018 : Debug: case_sensitive = yes Sun Feb 4 18:43:49 2018 : Debug: check_with_nas = yes Sun Feb 4 18:43:49 2018 : Debug: permissions = 384 Sun Feb 4 18:43:49 2018 : Debug: caller_id = yes Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_cache with path: /usr/local/lib/rlm_cache.so Sun Feb 4 18:43:49 2018 : Debug: Loaded rlm_cache, checking if it's valid Sun Feb 4 18:43:49 2018 : Debug: # Loaded module rlm_cache Sun Feb 4 18:43:49 2018 : Debug: # Loading module "cache_eap" from file /usr/local/etc/raddb/mods-enabled/cache_eap Sun Feb 4 18:43:49 2018 : Debug: cache cache_eap { Sun Feb 4 18:43:49 2018 : Debug: driver = "rlm_cache_rbtree" Sun Feb 4 18:43:49 2018 : Debug: key = "%{%{control:State}:-%{%{reply:State}:-%{State}}}" Sun Feb 4 18:43:49 2018 : Debug: ttl = 15 Sun Feb 4 18:43:49 2018 : Debug: max_entries = 0 Sun Feb 4 18:43:49 2018 : Debug: epoch = 0 Sun Feb 4 18:43:49 2018 : Debug: add_stats = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: instantiate { Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "expiration" from file /usr/local/etc/raddb/mods-enabled/expiration Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "linelog" from file /usr/local/etc/raddb/mods-enabled/linelog Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "log_accounting" from file /usr/local/etc/raddb/mods-enabled/linelog Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "IPASS" from file /usr/local/etc/raddb/mods-enabled/realm Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "suffix" from file /usr/local/etc/raddb/mods-enabled/realm Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "realmpercent" from file /usr/local/etc/raddb/mods-enabled/realm Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "ntdomain" from file /usr/local/etc/raddb/mods-enabled/realm Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "preprocess" from file /usr/local/etc/raddb/mods-enabled/preprocess Sun Feb 4 18:43:49 2018 : Debug: reading pairlist file /usr/local/etc/raddb/mods-config/preprocess/huntgroups Sun Feb 4 18:43:49 2018 : Debug: reading pairlist file /usr/local/etc/raddb/mods-config/preprocess/hints Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "detail" from file /usr/local/etc/raddb/mods-enabled/detail Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "eap" from file /usr/local/etc/raddb/mods-enabled/eap Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_eap_md5 with path: /usr/local/lib/rlm_eap_md5.so Sun Feb 4 18:43:49 2018 : Debug: # Linked to sub-module rlm_eap_md5 Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_eap_leap with path: /usr/local/lib/rlm_eap_leap.so Sun Feb 4 18:43:49 2018 : Debug: # Linked to sub-module rlm_eap_leap Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_eap_gtc with path: /usr/local/lib/rlm_eap_gtc.so Sun Feb 4 18:43:49 2018 : Debug: # Linked to sub-module rlm_eap_gtc Sun Feb 4 18:43:49 2018 : Debug: gtc { Sun Feb 4 18:43:49 2018 : Debug: challenge = "Password: " Sun Feb 4 18:43:49 2018 : Debug: auth_type = "PAP" Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_eap_tls with path: /usr/local/lib/rlm_eap_tls.so Sun Feb 4 18:43:49 2018 : Debug: # Linked to sub-module rlm_eap_tls Sun Feb 4 18:43:49 2018 : Debug: tls { Sun Feb 4 18:43:49 2018 : Debug: tls = "tls-common" Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: tls-config tls-common { Sun Feb 4 18:43:49 2018 : Debug: verify_depth = 0 Sun Feb 4 18:43:49 2018 : Debug: ca_path = "/usr/local/etc/raddb/certs" Sun Feb 4 18:43:49 2018 : Debug: pem_file_type = yes Sun Feb 4 18:43:49 2018 : Debug: private_key_file = "/usr/local/etc/raddb/certs/server.pem" Sun Feb 4 18:43:49 2018 : Debug: certificate_file = "/usr/local/etc/raddb/certs/server.pem" Sun Feb 4 18:43:49 2018 : Debug: ca_file = "/usr/local/etc/raddb/certs/frenche.ca.chain.pem" Sun Feb 4 18:43:49 2018 : Debug: private_key_password = "whatever" Sun Feb 4 18:43:49 2018 : Debug: dh_file = "/usr/local/etc/raddb/certs/dh" Sun Feb 4 18:43:49 2018 : Debug: fragment_size = 1024 Sun Feb 4 18:43:49 2018 : Debug: include_length = yes Sun Feb 4 18:43:49 2018 : Debug: auto_chain = yes Sun Feb 4 18:43:49 2018 : Debug: check_crl = no Sun Feb 4 18:43:49 2018 : Debug: check_all_crl = no Sun Feb 4 18:43:49 2018 : Debug: cipher_list = "DEFAULT" Sun Feb 4 18:43:49 2018 : Debug: cipher_server_preference = no Sun Feb 4 18:43:49 2018 : Debug: ecdh_curve = "prime256v1" Sun Feb 4 18:43:49 2018 : Debug: tls_max_version = "" Sun Feb 4 18:43:49 2018 : Debug: tls_min_version = "1.0" Sun Feb 4 18:43:49 2018 : Debug: cache { Sun Feb 4 18:43:49 2018 : Debug: enable = no Sun Feb 4 18:43:49 2018 : Debug: lifetime = 24 Sun Feb 4 18:43:49 2018 : Debug: max_entries = 255 Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: verify { Sun Feb 4 18:43:49 2018 : Debug: skip_if_ocsp_ok = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: ocsp { Sun Feb 4 18:43:49 2018 : Debug: enable = yes Sun Feb 4 18:43:49 2018 : Debug: override_cert_url = yes Sun Feb 4 18:43:49 2018 : Debug: url = "http://127.0.0.1/ocsp/" Sun Feb 4 18:43:49 2018 : Debug: use_nonce = yes Sun Feb 4 18:43:49 2018 : Debug: timeout = 0 Sun Feb 4 18:43:49 2018 : Debug: softfail = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_eap_ttls with path: /usr/local/lib/rlm_eap_ttls.so Sun Feb 4 18:43:49 2018 : Debug: # Linked to sub-module rlm_eap_ttls Sun Feb 4 18:43:49 2018 : Debug: ttls { Sun Feb 4 18:43:49 2018 : Debug: tls = "tls-common" Sun Feb 4 18:43:49 2018 : Debug: default_eap_type = "md5" Sun Feb 4 18:43:49 2018 : Debug: copy_request_to_tunnel = no Sun Feb 4 18:43:49 2018 : Debug: use_tunneled_reply = no Sun Feb 4 18:43:49 2018 : Debug: virtual_server = "inner-tunnel" Sun Feb 4 18:43:49 2018 : Debug: include_length = yes Sun Feb 4 18:43:49 2018 : Debug: require_client_cert = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: tls: Using cached TLS configuration from previous invocation Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_eap_peap with path: /usr/local/lib/rlm_eap_peap.so Sun Feb 4 18:43:49 2018 : Debug: # Linked to sub-module rlm_eap_peap Sun Feb 4 18:43:49 2018 : Debug: peap { Sun Feb 4 18:43:49 2018 : Debug: tls = "tls-common" Sun Feb 4 18:43:49 2018 : Debug: default_eap_type = "mschapv2" Sun Feb 4 18:43:49 2018 : Debug: copy_request_to_tunnel = no Sun Feb 4 18:43:49 2018 : Debug: use_tunneled_reply = no Sun Feb 4 18:43:49 2018 : Debug: proxy_tunneled_request_as_eap = yes Sun Feb 4 18:43:49 2018 : Debug: virtual_server = "inner-tunnel" Sun Feb 4 18:43:49 2018 : Debug: soh = no Sun Feb 4 18:43:49 2018 : Debug: require_client_cert = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: tls: Using cached TLS configuration from previous invocation Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_eap_mschapv2 with path: /usr/local/lib/rlm_eap_mschapv2.so Sun Feb 4 18:43:49 2018 : Debug: # Linked to sub-module rlm_eap_mschapv2 Sun Feb 4 18:43:49 2018 : Debug: mschapv2 { Sun Feb 4 18:43:49 2018 : Debug: with_ntdomain_hack = no Sun Feb 4 18:43:49 2018 : Debug: send_error = no Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "etc_passwd" from file /usr/local/etc/raddb/mods-enabled/passwd Sun Feb 4 18:43:49 2018 : Debug: rlm_passwd: nfields: 3 keyfield 0(User-Name) listable: no Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "logintime" from file /usr/local/etc/raddb/mods-enabled/logintime Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "mschap" from file /usr/local/etc/raddb/mods-enabled/mschap Sun Feb 4 18:43:49 2018 : Debug: rlm_mschap (mschap): using internal authentication Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "reject" from file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "fail" from file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "ok" from file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "handled" from file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "invalid" from file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "userlock" from file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "notfound" from file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "noop" from file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "updated" from file /usr/local/etc/raddb/mods-enabled/always Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "pap" from file /usr/local/etc/raddb/mods-enabled/pap Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "auth_log" from file /usr/local/etc/raddb/mods-enabled/detail.log Sun Feb 4 18:43:49 2018 : Debug: rlm_detail (auth_log): 'User-Password' suppressed, will not appear in detail output Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "reply_log" from file /usr/local/etc/raddb/mods-enabled/detail.log Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "pre_proxy_log" from file /usr/local/etc/raddb/mods-enabled/detail.log Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "post_proxy_log" from file /usr/local/etc/raddb/mods-enabled/detail.log Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "attr_filter.post-proxy" from file /usr/local/etc/raddb/mods-enabled/attr_filter Sun Feb 4 18:43:49 2018 : Debug: reading pairlist file /usr/local/etc/raddb/mods-config/attr_filter/post-proxy Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "attr_filter.pre-proxy" from file /usr/local/etc/raddb/mods-enabled/attr_filter Sun Feb 4 18:43:49 2018 : Debug: reading pairlist file /usr/local/etc/raddb/mods-config/attr_filter/pre-proxy Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "attr_filter.access_reject" from file /usr/local/etc/raddb/mods-enabled/attr_filter Sun Feb 4 18:43:49 2018 : Debug: reading pairlist file /usr/local/etc/raddb/mods-config/attr_filter/access_reject Sun Feb 4 18:43:49 2018 : Warning: [/usr/local/etc/raddb/mods-config/attr_filter/access_reject]:11 Check item "FreeRADIUS-Response-Delay" found in filter list for realm "DEFAULT". Sun Feb 4 18:43:49 2018 : Warning: [/usr/local/etc/raddb/mods-config/attr_filter/access_reject]:11 Check item "FreeRADIUS-Response-Delay-USec" found in filter list for realm "DEFAULT". Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "attr_filter.access_challenge" from file /usr/local/etc/raddb/mods-enabled/attr_filter Sun Feb 4 18:43:49 2018 : Debug: reading pairlist file /usr/local/etc/raddb/mods-config/attr_filter/access_challenge Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "attr_filter.accounting_response" from file /usr/local/etc/raddb/mods-enabled/attr_filter Sun Feb 4 18:43:49 2018 : Debug: reading pairlist file /usr/local/etc/raddb/mods-config/attr_filter/accounting_response Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "files" from file /usr/local/etc/raddb/mods-enabled/files Sun Feb 4 18:43:49 2018 : Debug: reading pairlist file /usr/local/etc/raddb/mods-config/files/authorize Sun Feb 4 18:43:49 2018 : Debug: reading pairlist file /usr/local/etc/raddb/mods-config/files/accounting Sun Feb 4 18:43:49 2018 : Debug: reading pairlist file /usr/local/etc/raddb/mods-config/files/pre-proxy Sun Feb 4 18:43:49 2018 : Debug: # Instantiating module "cache_eap" from file /usr/local/etc/raddb/mods-enabled/cache_eap Sun Feb 4 18:43:49 2018 : Debug: Loading rlm_cache_rbtree with path: /usr/local/lib/rlm_cache_rbtree.so Sun Feb 4 18:43:49 2018 : Debug: rlm_cache (cache_eap): Driver rlm_cache_rbtree (module rlm_cache_rbtree) loaded and linked Sun Feb 4 18:43:49 2018 : Debug: } # modules Sun Feb 4 18:43:49 2018 : Debug: radiusd: #### Loading Virtual Servers #### Sun Feb 4 18:43:49 2018 : Debug: server { # from file /usr/local/etc/raddb/radiusd.conf Sun Feb 4 18:43:49 2018 : Debug: } # server Sun Feb 4 18:43:49 2018 : Debug: server inner-tunnel { # from file /usr/local/etc/raddb/sites-enabled/inner-tunnel Sun Feb 4 18:43:49 2018 : Debug: authenticate { Sun Feb 4 18:43:49 2018 : Debug: group { Sun Feb 4 18:43:49 2018 : Debug: pap Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: group { Sun Feb 4 18:43:49 2018 : Debug: chap Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: group { Sun Feb 4 18:43:49 2018 : Debug: mschap Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: mschap Sun Feb 4 18:43:49 2018 : Debug: eap Sun Feb 4 18:43:49 2018 : Debug: } # authenticate Sun Feb 4 18:43:49 2018 : Debug: authorize { Sun Feb 4 18:43:49 2018 : Debug: policy filter_username { Sun Feb 4 18:43:49 2018 : Debug: if (&User-Name) { Sun Feb 4 18:43:49 2018 : Debug: if (&User-Name =~ / /) { Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &Module-Failure-Message += 'Rejected: User-Name contains whitespace' Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: reject Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: if (&User-Name =~ /@[^@]*@/) { Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &Module-Failure-Message += 'Rejected: Multiple @ in User-Name' Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: reject Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: if (&User-Name =~ /\.\./) { Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &Module-Failure-Message += 'Rejected: User-Name contains multiple ..s' Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: reject Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: if (&User-Name =~ /@/ && !&User-Name =~ /@(.+)\.(.+)$/) { Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &Module-Failure-Message += 'Rejected: Realm does not have at least one dot separator' Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: reject Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: if (&User-Name =~ /\.$/) { Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &Module-Failure-Message += 'Rejected: Realm ends with a dot' Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: reject Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: if (&User-Name =~ /@\./) { Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &Module-Failure-Message += 'Rejected: Realm begins with a dot' Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: reject Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: chap Sun Feb 4 18:43:49 2018 : Debug: mschap Sun Feb 4 18:43:49 2018 : Debug: suffix Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &control:Proxy-To-Realm := LOCAL Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: eap Sun Feb 4 18:43:49 2018 : Debug: files Sun Feb 4 18:43:49 2018 : Warning: Ignoring "sql" (see raddb/mods-available/README.rst) Sun Feb 4 18:43:49 2018 : Warning: Ignoring "ldap" (see raddb/mods-available/README.rst) Sun Feb 4 18:43:49 2018 : Debug: expiration Sun Feb 4 18:43:49 2018 : Debug: logintime Sun Feb 4 18:43:49 2018 : Debug: pap Sun Feb 4 18:43:49 2018 : Debug: } # authorize Sun Feb 4 18:43:49 2018 : Debug: session { Sun Feb 4 18:43:49 2018 : Debug: radutmp Sun Feb 4 18:43:49 2018 : Debug: } # session Sun Feb 4 18:43:49 2018 : Debug: post-proxy { Sun Feb 4 18:43:49 2018 : Debug: eap Sun Feb 4 18:43:49 2018 : Debug: } # post-proxy Sun Feb 4 18:43:49 2018 : Debug: post-auth { Sun Feb 4 18:43:49 2018 : Info: # Skipping contents of 'if' as it is always 'false' -- /usr/local/etc/raddb/sites-enabled/inner-tunnel:331 Sun Feb 4 18:43:49 2018 : Debug: if (false) { Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: group { Sun Feb 4 18:43:49 2018 : Debug: attr_filter.access_reject Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &outer.session-state:Module-Failure-Message := &Module-Failure-Message Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } # post-auth Sun Feb 4 18:43:49 2018 : Debug: } # server inner-tunnel Sun Feb 4 18:43:49 2018 : Debug: server default { # from file /usr/local/etc/raddb/sites-enabled/default Sun Feb 4 18:43:49 2018 : Debug: authenticate { Sun Feb 4 18:43:49 2018 : Debug: group { Sun Feb 4 18:43:49 2018 : Debug: pap Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: group { Sun Feb 4 18:43:49 2018 : Debug: chap Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: group { Sun Feb 4 18:43:49 2018 : Debug: mschap Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: mschap Sun Feb 4 18:43:49 2018 : Debug: digest Sun Feb 4 18:43:49 2018 : Debug: eap Sun Feb 4 18:43:49 2018 : Debug: } # authenticate Sun Feb 4 18:43:49 2018 : Debug: authorize { Sun Feb 4 18:43:49 2018 : Debug: policy filter_username { Sun Feb 4 18:43:49 2018 : Debug: if (&User-Name) { Sun Feb 4 18:43:49 2018 : Debug: if (&User-Name =~ / /) { Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &Module-Failure-Message += 'Rejected: User-Name contains whitespace' Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: reject Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: if (&User-Name =~ /@[^@]*@/) { Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &Module-Failure-Message += 'Rejected: Multiple @ in User-Name' Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: reject Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: if (&User-Name =~ /\.\./) { Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &Module-Failure-Message += 'Rejected: User-Name contains multiple ..s' Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: reject Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: if (&User-Name =~ /@/ && !&User-Name =~ /@(.+)\.(.+)$/) { Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &Module-Failure-Message += 'Rejected: Realm does not have at least one dot separator' Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: reject Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: if (&User-Name =~ /\.$/) { Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &Module-Failure-Message += 'Rejected: Realm ends with a dot' Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: reject Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: if (&User-Name =~ /@\./) { Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &Module-Failure-Message += 'Rejected: Realm begins with a dot' Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: reject Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: preprocess Sun Feb 4 18:43:49 2018 : Debug: chap Sun Feb 4 18:43:49 2018 : Debug: mschap Sun Feb 4 18:43:49 2018 : Debug: digest Sun Feb 4 18:43:49 2018 : Debug: suffix Sun Feb 4 18:43:49 2018 : Debug: eap Sun Feb 4 18:43:49 2018 : Debug: files Sun Feb 4 18:43:49 2018 : Debug: expiration Sun Feb 4 18:43:49 2018 : Debug: logintime Sun Feb 4 18:43:49 2018 : Debug: pap Sun Feb 4 18:43:49 2018 : Debug: } # authorize Sun Feb 4 18:43:49 2018 : Debug: preacct { Sun Feb 4 18:43:49 2018 : Debug: preprocess Sun Feb 4 18:43:49 2018 : Debug: policy acct_unique { Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &Tmp-String-9 := "ai:" Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: if ("%{hex:&Class}" =~ /^%{hex:&Tmp-String-9}/ && "%{string:&Class}" =~ /^ai:([0-9a-f]{32})/) { Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &Acct-Unique-Session-Id := "%{md5:%{1},%{Acct-Session-ID}}" Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: else { Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &Acct-Unique-Session-Id := "%{md5:%{User-Name},%{Acct-Session-ID},%{%{NAS-IPv6-Address}:-%{NAS-IP-Address}},%{NAS-Identifier},%{NAS-Port-ID},%{NAS-Port}}" Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: suffix Sun Feb 4 18:43:49 2018 : Debug: files Sun Feb 4 18:43:49 2018 : Debug: } # preacct Sun Feb 4 18:43:49 2018 : Debug: accounting { Sun Feb 4 18:43:49 2018 : Debug: detail Sun Feb 4 18:43:49 2018 : Debug: unix Sun Feb 4 18:43:49 2018 : Debug: exec Sun Feb 4 18:43:49 2018 : Debug: attr_filter.accounting_response Sun Feb 4 18:43:49 2018 : Debug: } # accounting Sun Feb 4 18:43:49 2018 : Debug: post-proxy { Sun Feb 4 18:43:49 2018 : Debug: eap Sun Feb 4 18:43:49 2018 : Debug: } # post-proxy Sun Feb 4 18:43:49 2018 : Debug: post-auth { Sun Feb 4 18:43:49 2018 : Debug: policy debug_all { Sun Feb 4 18:43:49 2018 : Debug: policy debug_control { Sun Feb 4 18:43:49 2018 : Debug: if ("%{debug_attr:control:}" == ) { Sun Feb 4 18:43:49 2018 : Debug: noop Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: policy debug_request { Sun Feb 4 18:43:49 2018 : Debug: if ("%{debug_attr:request:}" == ) { Sun Feb 4 18:43:49 2018 : Debug: noop Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: policy debug_coa { Sun Feb 4 18:43:49 2018 : Debug: if ("%{debug_attr:coa:}" == ) { Sun Feb 4 18:43:49 2018 : Debug: noop Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: policy debug_reply { Sun Feb 4 18:43:49 2018 : Debug: if ("%{debug_attr:reply:}" == ) { Sun Feb 4 18:43:49 2018 : Debug: noop Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: policy debug_session_state { Sun Feb 4 18:43:49 2018 : Debug: if ("%{debug_attr:session-state:}" == ) { Sun Feb 4 18:43:49 2018 : Debug: noop Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &reply:[*] += &session-state:[*] Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: exec Sun Feb 4 18:43:49 2018 : Debug: policy remove_reply_message_if_eap { Sun Feb 4 18:43:49 2018 : Debug: if (&reply:EAP-Message && &reply:Reply-Message) { Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &reply:Reply-Message !* ANY Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: else { Sun Feb 4 18:43:49 2018 : Debug: noop Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: group { Sun Feb 4 18:43:49 2018 : Debug: attr_filter.access_reject Sun Feb 4 18:43:49 2018 : Debug: eap Sun Feb 4 18:43:49 2018 : Debug: policy remove_reply_message_if_eap { Sun Feb 4 18:43:49 2018 : Debug: if (&reply:EAP-Message && &reply:Reply-Message) { Sun Feb 4 18:43:49 2018 : Debug: update { Sun Feb 4 18:43:49 2018 : Debug: &reply:Reply-Message !* ANY Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: else { Sun Feb 4 18:43:49 2018 : Debug: noop Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: policy debug_all { Sun Feb 4 18:43:49 2018 : Debug: policy debug_control { Sun Feb 4 18:43:49 2018 : Debug: if ("%{debug_attr:control:}" == ) { Sun Feb 4 18:43:49 2018 : Debug: noop Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: policy debug_request { Sun Feb 4 18:43:49 2018 : Debug: if ("%{debug_attr:request:}" == ) { Sun Feb 4 18:43:49 2018 : Debug: noop Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: policy debug_coa { Sun Feb 4 18:43:49 2018 : Debug: if ("%{debug_attr:coa:}" == ) { Sun Feb 4 18:43:49 2018 : Debug: noop Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: policy debug_reply { Sun Feb 4 18:43:49 2018 : Debug: if ("%{debug_attr:reply:}" == ) { Sun Feb 4 18:43:49 2018 : Debug: noop Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: policy debug_session_state { Sun Feb 4 18:43:49 2018 : Debug: if ("%{debug_attr:session-state:}" == ) { Sun Feb 4 18:43:49 2018 : Debug: noop Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: group { Sun Feb 4 18:43:49 2018 : Debug: policy debug_all { Sun Feb 4 18:43:49 2018 : Debug: policy debug_control { Sun Feb 4 18:43:49 2018 : Debug: if ("%{debug_attr:control:}" == ) { Sun Feb 4 18:43:49 2018 : Debug: noop Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: policy debug_request { Sun Feb 4 18:43:49 2018 : Debug: if ("%{debug_attr:request:}" == ) { Sun Feb 4 18:43:49 2018 : Debug: noop Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: policy debug_coa { Sun Feb 4 18:43:49 2018 : Debug: if ("%{debug_attr:coa:}" == ) { Sun Feb 4 18:43:49 2018 : Debug: noop Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: policy debug_reply { Sun Feb 4 18:43:49 2018 : Debug: if ("%{debug_attr:reply:}" == ) { Sun Feb 4 18:43:49 2018 : Debug: noop Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: policy debug_session_state { Sun Feb 4 18:43:49 2018 : Debug: if ("%{debug_attr:session-state:}" == ) { Sun Feb 4 18:43:49 2018 : Debug: noop Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } Sun Feb 4 18:43:49 2018 : Debug: } # post-auth Sun Feb 4 18:43:49 2018 : Debug: } # server default Sun Feb 4 18:43:50 2018 : Debug: Created signal pipe. Read end FD 5, write end FD 6 Sun Feb 4 18:43:50 2018 : Debug: radiusd: #### Opening IP addresses and Ports #### Sun Feb 4 18:43:50 2018 : Debug: Loading proto_auth with path: /usr/local/lib/proto_auth.so Sun Feb 4 18:43:50 2018 : Debug: Loading proto_auth failed: /usr/local/lib/proto_auth.so: cannot open shared object file: No such file or directory - No such file or directory Sun Feb 4 18:43:50 2018 : Debug: Loading library using linker search path(s) Sun Feb 4 18:43:50 2018 : Debug: LD_LIBRARY_PATH : /usr/local/lib64:/usr/local/lib Sun Feb 4 18:43:50 2018 : Debug: Defaults : /lib:/usr/lib Sun Feb 4 18:43:50 2018 : Debug: Failed with error: proto_auth.so: cannot open shared object file: No such file or directory Sun Feb 4 18:43:50 2018 : Debug: listen { Sun Feb 4 18:43:50 2018 : Debug: type = "auth" Sun Feb 4 18:43:50 2018 : Debug: ipaddr = 127.0.0.1 Sun Feb 4 18:43:50 2018 : Debug: port = 18120 Sun Feb 4 18:43:50 2018 : Debug: } Sun Feb 4 18:43:50 2018 : Debug: Loading proto_auth with path: /usr/local/lib/proto_auth.so Sun Feb 4 18:43:50 2018 : Debug: Loading proto_auth failed: /usr/local/lib/proto_auth.so: cannot open shared object file: No such file or directory - No such file or directory Sun Feb 4 18:43:50 2018 : Debug: Loading library using linker search path(s) Sun Feb 4 18:43:50 2018 : Debug: LD_LIBRARY_PATH : /usr/local/lib64:/usr/local/lib Sun Feb 4 18:43:50 2018 : Debug: Defaults : /lib:/usr/lib Sun Feb 4 18:43:50 2018 : Debug: Failed with error: proto_auth.so: cannot open shared object file: No such file or directory Sun Feb 4 18:43:50 2018 : Debug: listen { Sun Feb 4 18:43:50 2018 : Debug: type = "auth" Sun Feb 4 18:43:50 2018 : Debug: ipaddr = * Sun Feb 4 18:43:50 2018 : Debug: port = 0 Sun Feb 4 18:43:50 2018 : Debug: limit { Sun Feb 4 18:43:50 2018 : Debug: max_connections = 16 Sun Feb 4 18:43:50 2018 : Debug: lifetime = 0 Sun Feb 4 18:43:50 2018 : Debug: idle_timeout = 30 Sun Feb 4 18:43:50 2018 : Debug: } Sun Feb 4 18:43:50 2018 : Debug: } Sun Feb 4 18:43:50 2018 : Debug: Loading proto_acct with path: /usr/local/lib/proto_acct.so Sun Feb 4 18:43:50 2018 : Debug: Loading proto_acct failed: /usr/local/lib/proto_acct.so: cannot open shared object file: No such file or directory - No such file or directory Sun Feb 4 18:43:50 2018 : Debug: Loading library using linker search path(s) Sun Feb 4 18:43:50 2018 : Debug: LD_LIBRARY_PATH : /usr/local/lib64:/usr/local/lib Sun Feb 4 18:43:50 2018 : Debug: Defaults : /lib:/usr/lib Sun Feb 4 18:43:50 2018 : Debug: Failed with error: proto_acct.so: cannot open shared object file: No such file or directory Sun Feb 4 18:43:50 2018 : Debug: listen { Sun Feb 4 18:43:50 2018 : Debug: type = "acct" Sun Feb 4 18:43:50 2018 : Debug: ipaddr = * Sun Feb 4 18:43:50 2018 : Debug: port = 0 Sun Feb 4 18:43:50 2018 : Debug: limit { Sun Feb 4 18:43:50 2018 : Debug: max_connections = 16 Sun Feb 4 18:43:50 2018 : Debug: lifetime = 0 Sun Feb 4 18:43:50 2018 : Debug: idle_timeout = 30 Sun Feb 4 18:43:50 2018 : Debug: } Sun Feb 4 18:43:50 2018 : Debug: } Sun Feb 4 18:43:50 2018 : Debug: Listening on auth address 127.0.0.1 port 18120 bound to server inner-tunnel Sun Feb 4 18:43:50 2018 : Debug: Listening on auth address * port 1812 bound to server default Sun Feb 4 18:43:50 2018 : Debug: Listening on acct address * port 1813 bound to server default Sun Feb 4 18:43:50 2018 : Debug: Opened new proxy socket 'proxy address * port 41635' Sun Feb 4 18:43:50 2018 : Debug: Listening on proxy address * port 41635 Sun Feb 4 18:43:50 2018 : Info: Ready to process requests Socket: 8 Proto: 0 Src IP: 127.0.0.1 port: 35041 Dst IP: 127.0.0.1 port: 1812 Code: (1) Access-Request Id: 186 Length: 129 Vector: ae18170a40fdc5bb76df9c9f7119bf95 Data: 01 05 62 6f 62 04 06 d4 53 9e 02 05 06 00 00 00 00 50 12 88 19 96 3c 0c 38 d9 36 75 5d 37 e0 99 4b e2 00 1a 10 00000137 (311) 0b 0a cf a4 1b 33 fd 53 95 ef 1a 3a 00000137 (311) 01 34 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 44 88 13 52 2c 61 e7 0b 6d 35 90 ea fd 1f 12 66 1e e9 16 db b9 ce 9e 69 Sun Feb 4 18:43:52 2018 : Debug: (0) Received Access-Request Id 186 from 127.0.0.1:35041 to 127.0.0.1:1812 length 129 Sun Feb 4 18:43:52 2018 : Debug: (0) User-Name = "bob" Sun Feb 4 18:43:52 2018 : Debug: (0) NAS-IP-Address = 212.83.158.2 Sun Feb 4 18:43:52 2018 : Debug: (0) NAS-Port = 0 Sun Feb 4 18:43:52 2018 : Debug: (0) Message-Authenticator = 0x8819963c0c38d936755d37e0994be200 Sun Feb 4 18:43:52 2018 : Debug: (0) MS-CHAP-Challenge = 0xcfa41b33fd5395ef Sun Feb 4 18:43:52 2018 : Debug: (0) MS-CHAP-Response = 0x0001000000000000000000000000000000000000000000000000448813522c61e70b6d3590eafd1f12661ee916dbb9ce9e69 Sun Feb 4 18:43:52 2018 : Debug: (0) session-state: No State attribute Sun Feb 4 18:43:52 2018 : Debug: (0) # Executing section authorize from file /usr/local/etc/raddb/sites-enabled/default Sun Feb 4 18:43:52 2018 : Debug: (0) authorize { Sun Feb 4 18:43:52 2018 : Debug: (0) policy filter_username { Sun Feb 4 18:43:52 2018 : Debug: (0) if (&User-Name) { Sun Feb 4 18:43:52 2018 : Debug: (0) if (&User-Name) -> TRUE Sun Feb 4 18:43:52 2018 : Debug: (0) if (&User-Name) { Sun Feb 4 18:43:52 2018 : Debug: (0) if (&User-Name =~ / /) { Sun Feb 4 18:43:52 2018 : Debug: No matches Sun Feb 4 18:43:52 2018 : Debug: (0) if (&User-Name =~ / /) -> FALSE Sun Feb 4 18:43:52 2018 : Debug: (0) if (&User-Name =~ /@[^@]*@/ ) { Sun Feb 4 18:43:52 2018 : Debug: No matches Sun Feb 4 18:43:52 2018 : Debug: (0) if (&User-Name =~ /@[^@]*@/ ) -> FALSE Sun Feb 4 18:43:52 2018 : Debug: (0) if (&User-Name =~ /\.\./ ) { Sun Feb 4 18:43:52 2018 : Debug: No matches Sun Feb 4 18:43:52 2018 : Debug: (0) if (&User-Name =~ /\.\./ ) -> FALSE Sun Feb 4 18:43:52 2018 : Debug: (0) if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/)) { Sun Feb 4 18:43:52 2018 : Debug: No matches Sun Feb 4 18:43:52 2018 : Debug: (0) if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/)) -> FALSE Sun Feb 4 18:43:52 2018 : Debug: (0) if (&User-Name =~ /\.$/) { Sun Feb 4 18:43:52 2018 : Debug: No matches Sun Feb 4 18:43:52 2018 : Debug: (0) if (&User-Name =~ /\.$/) -> FALSE Sun Feb 4 18:43:52 2018 : Debug: (0) if (&User-Name =~ /@\./) { Sun Feb 4 18:43:52 2018 : Debug: No matches Sun Feb 4 18:43:52 2018 : Debug: (0) if (&User-Name =~ /@\./) -> FALSE Sun Feb 4 18:43:52 2018 : Debug: (0) } # if (&User-Name) = notfound Sun Feb 4 18:43:52 2018 : Debug: (0) } # policy filter_username = notfound Sun Feb 4 18:43:52 2018 : Debug: (0) modsingle[authorize]: calling preprocess (rlm_preprocess) Sun Feb 4 18:43:52 2018 : Debug: (0) modsingle[authorize]: returned from preprocess (rlm_preprocess) Sun Feb 4 18:43:52 2018 : Debug: (0) [preprocess] = ok Sun Feb 4 18:43:52 2018 : Debug: (0) modsingle[authorize]: calling chap (rlm_chap) Sun Feb 4 18:43:52 2018 : Debug: (0) modsingle[authorize]: returned from chap (rlm_chap) Sun Feb 4 18:43:52 2018 : Debug: (0) [chap] = noop Sun Feb 4 18:43:52 2018 : Debug: (0) modsingle[authorize]: calling mschap (rlm_mschap) Sun Feb 4 18:43:52 2018 : Debug: (0) mschap: Found MS-CHAP attributes. Setting 'Auth-Type = mschap' Sun Feb 4 18:43:52 2018 : Debug: (0) modsingle[authorize]: returned from mschap (rlm_mschap) Sun Feb 4 18:43:52 2018 : Debug: (0) [mschap] = ok Sun Feb 4 18:43:52 2018 : Debug: (0) modsingle[authorize]: calling digest (rlm_digest) Sun Feb 4 18:43:52 2018 : Debug: (0) modsingle[authorize]: returned from digest (rlm_digest) Sun Feb 4 18:43:52 2018 : Debug: (0) [digest] = noop Sun Feb 4 18:43:52 2018 : Debug: (0) modsingle[authorize]: calling suffix (rlm_realm) Sun Feb 4 18:43:52 2018 : Debug: (0) suffix: Checking for suffix after "@" Sun Feb 4 18:43:52 2018 : Debug: (0) suffix: No '@' in User-Name = "bob", looking up realm NULL Sun Feb 4 18:43:52 2018 : Debug: (0) suffix: No such realm "NULL" Sun Feb 4 18:43:52 2018 : Debug: (0) modsingle[authorize]: returned from suffix (rlm_realm) Sun Feb 4 18:43:52 2018 : Debug: (0) [suffix] = noop Sun Feb 4 18:43:52 2018 : Debug: (0) modsingle[authorize]: calling eap (rlm_eap) Sun Feb 4 18:43:52 2018 : Debug: (0) eap: No EAP-Message, not doing EAP Sun Feb 4 18:43:52 2018 : Debug: (0) modsingle[authorize]: returned from eap (rlm_eap) Sun Feb 4 18:43:52 2018 : Debug: (0) [eap] = noop Sun Feb 4 18:43:52 2018 : Debug: (0) modsingle[authorize]: calling files (rlm_files) Sun Feb 4 18:43:53 2018 : Debug: (0) files: users: Matched entry bob at line 87 Sun Feb 4 18:43:53 2018 : Debug: (0) files: ::: FROM 4 TO 0 MAX 4 Sun Feb 4 18:43:53 2018 : Debug: (0) files: ::: Examining Reply-Message Sun Feb 4 18:43:53 2018 : Debug: Hello2, %{User-Name} Sun Feb 4 18:43:53 2018 : Debug: Parsed xlat tree: Sun Feb 4 18:43:53 2018 : Debug: literal --> Hello2, Sun Feb 4 18:43:53 2018 : Debug: attribute --> User-Name Sun Feb 4 18:43:53 2018 : Debug: (0) files: EXPAND Hello2, %{User-Name} Sun Feb 4 18:43:53 2018 : Debug: (0) files: --> Hello2, bob Sun Feb 4 18:43:53 2018 : Debug: (0) files: ::: APPENDING Reply-Message FROM 0 TO 0 Sun Feb 4 18:43:53 2018 : Debug: (0) files: ::: Examining Reply-Message Sun Feb 4 18:43:53 2018 : Debug: Hello3, %{User-Name} Sun Feb 4 18:43:53 2018 : Debug: Parsed xlat tree: Sun Feb 4 18:43:53 2018 : Debug: literal --> Hello3, Sun Feb 4 18:43:53 2018 : Debug: attribute --> User-Name Sun Feb 4 18:43:53 2018 : Debug: (0) files: EXPAND Hello3, %{User-Name} Sun Feb 4 18:43:53 2018 : Debug: (0) files: --> Hello3, bob Sun Feb 4 18:43:53 2018 : Debug: (0) files: ::: APPENDING Reply-Message FROM 1 TO 0 Sun Feb 4 18:43:53 2018 : Debug: (0) files: ::: Examining Reply-Message Sun Feb 4 18:43:53 2018 : Debug: Hello44, %{User-Name} Sun Feb 4 18:43:53 2018 : Debug: Parsed xlat tree: Sun Feb 4 18:43:53 2018 : Debug: literal --> Hello44, Sun Feb 4 18:43:53 2018 : Debug: attribute --> User-Name Sun Feb 4 18:43:53 2018 : Debug: (0) files: EXPAND Hello44, %{User-Name} Sun Feb 4 18:43:53 2018 : Debug: (0) files: --> Hello44, bob Sun Feb 4 18:43:53 2018 : Debug: (0) files: ::: APPENDING Reply-Message FROM 2 TO 0 Sun Feb 4 18:43:53 2018 : Debug: (0) files: ::: Examining Reply-Message Sun Feb 4 18:43:53 2018 : Debug: Hello2222, %{User-Name} Sun Feb 4 18:43:53 2018 : Debug: Parsed xlat tree: Sun Feb 4 18:43:53 2018 : Debug: literal --> Hello2222, Sun Feb 4 18:43:53 2018 : Debug: attribute --> User-Name Sun Feb 4 18:43:53 2018 : Debug: (0) files: EXPAND Hello2222, %{User-Name} Sun Feb 4 18:43:53 2018 : Debug: (0) files: --> Hello2222, bob Sun Feb 4 18:43:53 2018 : Debug: (0) files: ::: APPENDING Reply-Message FROM 3 TO 0 Sun Feb 4 18:43:53 2018 : Debug: (0) files: ::: TO in 0 out 0 Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[authorize]: returned from files (rlm_files) Sun Feb 4 18:43:53 2018 : Debug: (0) [files] = ok Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[authorize]: calling expiration (rlm_expiration) Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[authorize]: returned from expiration (rlm_expiration) Sun Feb 4 18:43:53 2018 : Debug: (0) [expiration] = noop Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[authorize]: calling logintime (rlm_logintime) Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[authorize]: returned from logintime (rlm_logintime) Sun Feb 4 18:43:53 2018 : Debug: (0) [logintime] = noop Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[authorize]: calling pap (rlm_pap) Sun Feb 4 18:43:53 2018 : WARNING: (0) pap: Auth-Type already set. Not setting to PAP Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[authorize]: returned from pap (rlm_pap) Sun Feb 4 18:43:53 2018 : Debug: (0) [pap] = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # authorize = ok Sun Feb 4 18:43:53 2018 : Debug: (0) Found Auth-Type = mschap Sun Feb 4 18:43:53 2018 : Debug: (0) # Executing group from file /usr/local/etc/raddb/sites-enabled/default Sun Feb 4 18:43:53 2018 : Debug: (0) authenticate { Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[authenticate]: calling mschap (rlm_mschap) Sun Feb 4 18:43:53 2018 : Debug: (0) mschap: Found Cleartext-Password, hashing to create NT-Password Sun Feb 4 18:43:53 2018 : Debug: (0) mschap: Found Cleartext-Password, hashing to create LM-Password Sun Feb 4 18:43:53 2018 : Debug: (0) mschap: Client is using MS-CHAPv1 with NT-Password Sun Feb 4 18:43:53 2018 : Debug: (0) mschap: adding MS-CHAPv1 MPPE keys Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[authenticate]: returned from mschap (rlm_mschap) Sun Feb 4 18:43:53 2018 : Debug: (0) [mschap] = ok Sun Feb 4 18:43:53 2018 : Debug: (0) } # authenticate = ok Sun Feb 4 18:43:53 2018 : Debug: (0) # Executing section post-auth from file /usr/local/etc/raddb/sites-enabled/default Sun Feb 4 18:43:53 2018 : Debug: (0) post-auth { Sun Feb 4 18:43:53 2018 : Debug: (0) policy debug_all { Sun Feb 4 18:43:53 2018 : Debug: (0) policy debug_control { Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:control:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND TMPL XLAT STRUCT Sun Feb 4 18:43:53 2018 : Info: (0) Attributes matching "control:" Sun Feb 4 18:43:53 2018 : Info: (0) &control:Auth-Type = mschap Sun Feb 4 18:43:53 2018 : Info: (0) Type : integer Sun Feb 4 18:43:53 2018 : Info: (0) Length : 4 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : mschap Sun Feb 4 18:43:53 2018 : Debug: (0) as ipaddr : 0.142.156.152 Sun Feb 4 18:43:53 2018 : Debug: (0) as date : Jan 1 1970 00:00:00 UTC Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x008e9c98 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : ::/0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 9346200 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 9346200 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 9346200 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 9346200 Sun Feb 4 18:43:53 2018 : Info: (0) &control:Cleartext-Password := aristo1 Sun Feb 4 18:43:53 2018 : Info: (0) Type : string Sun Feb 4 18:43:53 2018 : Info: (0) Length : 7 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x61726973746f31 Sun Feb 4 18:43:53 2018 : Debug: (0) as byte : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint8 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 0 Sun Feb 4 18:43:53 2018 : Info: (0) &control:NT-Password = 0xefc86e9649eddc50c1c202e3fbed3760 Sun Feb 4 18:43:53 2018 : Info: (0) Type : octets Sun Feb 4 18:43:53 2018 : Info: (0) Length : 16 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 4022890134 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : 0xefc86e9649eddc50c1c202e3fbed3760 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6addr : efc8:6e96:49ed:dc50:c1c2:2e3:fbed:3760 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : 6e96:49ed:dc50:c1c2:2e3:fbed:3760:0/200 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 61384 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : -272077162 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 61384 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 4022890134 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : -272077162 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 17278181562171382864 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 17278181562171382864 Sun Feb 4 18:43:53 2018 : Info: (0) &control:LM-Password = 0xc007e3905f1d5f4aaad3b435b51404ee Sun Feb 4 18:43:53 2018 : Info: (0) Type : octets Sun Feb 4 18:43:53 2018 : Info: (0) Length : 16 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 3221742480 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : 0xc007e3905f1d5f4aaad3b435b51404ee Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6addr : c007:e390:5f1d:5f4a:aad3:b435:b514:4ee Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : e390:5f1d:5f4a:aad3:b435:b514:4ee:0/7 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 49159 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : -1073224816 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 49159 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 3221742480 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : -1073224816 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 13837278589329694538 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 13837278589329694538 Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND %{debug_attr:control:} Sun Feb 4 18:43:53 2018 : Debug: (0) --> Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:control:}" == '') -> TRUE Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:control:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: calling noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: returned from noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) [noop] = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # if ("%{debug_attr:control:}" == '') = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # policy debug_control = noop Sun Feb 4 18:43:53 2018 : Debug: (0) policy debug_request { Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:request:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND TMPL XLAT STRUCT Sun Feb 4 18:43:53 2018 : Info: (0) Attributes matching "request:" Sun Feb 4 18:43:53 2018 : Info: (0) &request:User-Name = bob Sun Feb 4 18:43:53 2018 : Info: (0) Type : string Sun Feb 4 18:43:53 2018 : Info: (0) Length : 3 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x626f62 Sun Feb 4 18:43:53 2018 : Debug: (0) as byte : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint8 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 0 Sun Feb 4 18:43:53 2018 : Info: (0) &request:NAS-IP-Address = 212.83.158.2 Sun Feb 4 18:43:53 2018 : Info: (0) Type : ipaddr Sun Feb 4 18:43:53 2018 : Info: (0) Length : 4 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : 212.83.158.2 Sun Feb 4 18:43:53 2018 : Debug: (0) as date : Nov 18 2082 17:48:50 UTC Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0xd4539e02 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6addr : ::ffff:212.83.158.2 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : ::ffff:212.83.158.2/128 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv4prefix : 212.83.158.2/32 Sun Feb 4 18:43:53 2018 : Debug: (0) as cidr : 212.83.158.2/32 Sun Feb 4 18:43:53 2018 : Info: (0) &request:NAS-Port = 0 Sun Feb 4 18:43:53 2018 : Info: (0) Type : integer Sun Feb 4 18:43:53 2018 : Info: (0) Length : 4 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipaddr : 0.0.0.0 Sun Feb 4 18:43:53 2018 : Debug: (0) as date : Jan 1 1970 00:00:00 UTC Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x00000000 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : ::/0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 0 Sun Feb 4 18:43:53 2018 : Info: (0) &request:Message-Authenticator = 0x8819963c0c38d936755d37e0994be200 Sun Feb 4 18:43:53 2018 : Info: (0) Type : octets Sun Feb 4 18:43:53 2018 : Info: (0) Length : 16 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 2283378236 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : 0x8819963c0c38d936755d37e0994be200 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6addr : 8819:963c:c38:d936:755d:37e0:994b:e200 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : 963c:c38:d936:755d:37e0:994b:e200:0/25 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 34841 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : -2011589060 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 34841 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 2283378236 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : -2011589060 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 9807034848223222070 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 9807034848223222070 Sun Feb 4 18:43:53 2018 : Info: (0) &request:MS-CHAP-Challenge = 0xcfa41b33fd5395ef Sun Feb 4 18:43:53 2018 : Info: (0) Vendor : 311 (Microsoft) Sun Feb 4 18:43:53 2018 : Info: (0) Type : octets Sun Feb 4 18:43:53 2018 : Info: (0) Length : 8 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 3483638579 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : 0xcfa41b33fd5395ef Sun Feb 4 18:43:53 2018 : Debug: (0) as ifid : cfa4:1b33:fd53:95ef Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : 1b33:fd53:95ef::/164 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 53156 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : -811328717 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 53156 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 3483638579 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : -811328717 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 14962113772139025903 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 14962113772139025903 Sun Feb 4 18:43:53 2018 : Info: (0) &request:MS-CHAP-Response = 0x0001000000000000000000000000000000000000000000000000448813522c61e70b6d3590eafd1f12661ee916dbb9ce9e69 Sun Feb 4 18:43:53 2018 : Info: (0) Vendor : 311 (Microsoft) Sun Feb 4 18:43:53 2018 : Info: (0) Type : octets Sun Feb 4 18:43:53 2018 : Info: (0) Length : 50 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 65536 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : 0x0001000000000000000000000000000000000000000000000000448813522c61e70b6d3590eafd1f12661ee916dbb9ce9e69 ==26224== Invalid write of size 8 ==26224== at 0x4C2E3A3: memcpy@@GLIBC_2.14 (in /usr/lib64/valgrind/vgpreload_memcheck-amd64-linux.so) ==26224== by 0x50A21A3: value_data_hton (value.c:1001) ==26224== by 0x50A2C85: value_data_cast (value.c:1384) ==26224== by 0x4E5D8FF: xlat_debug_attr (xlat.c:473) ==26224== by 0x4E61E4C: xlat_aprint (xlat.c:2306) ==26224== by 0x4E620E1: xlat_process (xlat.c:2398) ==26224== by 0x4E623F0: xlat_expand_struct (xlat.c:2470) ==26224== by 0x4E628F5: radius_axlat_struct (xlat.c:2611) ==26224== by 0x4E556D8: tmpl_aexpand (tmpl.c:1514) ==26224== by 0x4E47387: radius_evaluate_map (evaluate.c:684) ==26224== by 0x4E474E9: radius_evaluate_cond (evaluate.c:746) ==26224== by 0x42938F: modcall_recurse (modcall.c:483) ==26224== Address 0x7c5fb60 is 0 bytes after a block of size 128 alloc'd ==26224== at 0x4C29C4F: malloc (in /usr/lib64/valgrind/vgpreload_memcheck-amd64-linux.so) ==26224== by 0x5C22C80: _talloc_zero (in /usr/lib64/libtalloc.so.2.1.2) ==26224== by 0x4E5D8AF: xlat_debug_attr (xlat.c:472) ==26224== by 0x4E61E4C: xlat_aprint (xlat.c:2306) ==26224== by 0x4E620E1: xlat_process (xlat.c:2398) ==26224== by 0x4E623F0: xlat_expand_struct (xlat.c:2470) ==26224== by 0x4E628F5: radius_axlat_struct (xlat.c:2611) ==26224== by 0x4E556D8: tmpl_aexpand (tmpl.c:1514) ==26224== by 0x4E47387: radius_evaluate_map (evaluate.c:684) ==26224== by 0x4E474E9: radius_evaluate_cond (evaluate.c:746) ==26224== by 0x42938F: modcall_recurse (modcall.c:483) ==26224== by 0x429145: modcall_child (modcall.c:408) ==26224== ==26224== Invalid write of size 2 ==26224== at 0x4C2E3EB: memcpy@@GLIBC_2.14 (in /usr/lib64/valgrind/vgpreload_memcheck-amd64-linux.so) ==26224== by 0x50A21A3: value_data_hton (value.c:1001) ==26224== by 0x50A2C85: value_data_cast (value.c:1384) ==26224== by 0x4E5D8FF: xlat_debug_attr (xlat.c:473) ==26224== by 0x4E61E4C: xlat_aprint (xlat.c:2306) ==26224== by 0x4E620E1: xlat_process (xlat.c:2398) ==26224== by 0x4E623F0: xlat_expand_struct (xlat.c:2470) ==26224== by 0x4E628F5: radius_axlat_struct (xlat.c:2611) ==26224== by 0x4E556D8: tmpl_aexpand (tmpl.c:1514) ==26224== by 0x4E47387: radius_evaluate_map (evaluate.c:684) ==26224== by 0x4E474E9: radius_evaluate_cond (evaluate.c:746) ==26224== by 0x42938F: modcall_recurse (modcall.c:483) ==26224== Address 0x7c5fb70 is 16 bytes after a block of size 128 alloc'd ==26224== at 0x4C29C4F: malloc (in /usr/lib64/valgrind/vgpreload_memcheck-amd64-linux.so) ==26224== by 0x5C22C80: _talloc_zero (in /usr/lib64/libtalloc.so.2.1.2) ==26224== by 0x4E5D8AF: xlat_debug_attr (xlat.c:472) ==26224== by 0x4E61E4C: xlat_aprint (xlat.c:2306) ==26224== by 0x4E620E1: xlat_process (xlat.c:2398) ==26224== by 0x4E623F0: xlat_expand_struct (xlat.c:2470) ==26224== by 0x4E628F5: radius_axlat_struct (xlat.c:2611) ==26224== by 0x4E556D8: tmpl_aexpand (tmpl.c:1514) ==26224== by 0x4E47387: radius_evaluate_map (evaluate.c:684) ==26224== by 0x4E474E9: radius_evaluate_cond (evaluate.c:746) ==26224== by 0x42938F: modcall_recurse (modcall.c:483) ==26224== by 0x429145: modcall_child (modcall.c:408) ==26224== ==26224== Invalid read of size 1 ==26224== at 0x5086A9A: print_abinary (filters.c:1096) ==26224== by 0x50A317A: value_data_aprints (value.c:1520) ==26224== by 0x4E5D941: xlat_debug_attr (xlat.c:477) ==26224== by 0x4E61E4C: xlat_aprint (xlat.c:2306) ==26224== by 0x4E620E1: xlat_process (xlat.c:2398) ==26224== by 0x4E623F0: xlat_expand_struct (xlat.c:2470) ==26224== by 0x4E628F5: radius_axlat_struct (xlat.c:2611) ==26224== by 0x4E556D8: tmpl_aexpand (tmpl.c:1514) ==26224== by 0x4E47387: radius_evaluate_map (evaluate.c:684) ==26224== by 0x4E474E9: radius_evaluate_cond (evaluate.c:746) ==26224== by 0x42938F: modcall_recurse (modcall.c:483) ==26224== by 0x429145: modcall_child (modcall.c:408) ==26224== Address 0x7c5fb60 is 0 bytes after a block of size 128 alloc'd ==26224== at 0x4C29C4F: malloc (in /usr/lib64/valgrind/vgpreload_memcheck-amd64-linux.so) ==26224== by 0x5C22C80: _talloc_zero (in /usr/lib64/libtalloc.so.2.1.2) ==26224== by 0x4E5D8AF: xlat_debug_attr (xlat.c:472) ==26224== by 0x4E61E4C: xlat_aprint (xlat.c:2306) ==26224== by 0x4E620E1: xlat_process (xlat.c:2398) ==26224== by 0x4E623F0: xlat_expand_struct (xlat.c:2470) ==26224== by 0x4E628F5: radius_axlat_struct (xlat.c:2611) ==26224== by 0x4E556D8: tmpl_aexpand (tmpl.c:1514) ==26224== by 0x4E47387: radius_evaluate_map (evaluate.c:684) ==26224== by 0x4E474E9: radius_evaluate_cond (evaluate.c:746) ==26224== by 0x42938F: modcall_recurse (modcall.c:483) ==26224== by 0x429145: modcall_child (modcall.c:408) ==26224== Sun Feb 4 18:43:53 2018 : Debug: (0) as abinary : 0x0001000000000000000000000000000000000000000000000000448813522c61e70b6d3590eafd1f12661ee916dbb9ce9e69 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 1 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 65536 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 1 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 65536 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 65536 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 281474976710656 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 281474976710656 Sun Feb 4 18:43:53 2018 : Info: (0) &request:Event-Timestamp = Feb 4 2018 18:43:52 UTC Sun Feb 4 18:43:53 2018 : Info: (0) Type : date Sun Feb 4 18:43:53 2018 : Info: (0) Length : 4 ==26224== Conditional jump or move depends on uninitialised value(s) ==26224== at 0x5091716: fr_prints (print.c:235) ==26224== by 0x50919C8: fr_prints_len (print.c:369) ==26224== by 0x50A2EDA: value_data_aprints (value.c:1466) ==26224== by 0x4E5D941: xlat_debug_attr (xlat.c:477) ==26224== by 0x4E61E4C: xlat_aprint (xlat.c:2306) ==26224== by 0x4E620E1: xlat_process (xlat.c:2398) ==26224== by 0x4E623F0: xlat_expand_struct (xlat.c:2470) ==26224== by 0x4E628F5: radius_axlat_struct (xlat.c:2611) ==26224== by 0x4E556D8: tmpl_aexpand (tmpl.c:1514) ==26224== by 0x4E47387: radius_evaluate_map (evaluate.c:684) ==26224== by 0x4E474E9: radius_evaluate_cond (evaluate.c:746) ==26224== by 0x42938F: modcall_recurse (modcall.c:483) ==26224== ==26224== Conditional jump or move depends on uninitialised value(s) ==26224== at 0x5091730: fr_prints (print.c:244) ==26224== by 0x50919C8: fr_prints_len (print.c:369) ==26224== by 0x50A2EDA: value_data_aprints (value.c:1466) ==26224== by 0x4E5D941: xlat_debug_attr (xlat.c:477) ==26224== by 0x4E61E4C: xlat_aprint (xlat.c:2306) ==26224== by 0x4E620E1: xlat_process (xlat.c:2398) ==26224== by 0x4E623F0: xlat_expand_struct (xlat.c:2470) ==26224== by 0x4E628F5: radius_axlat_struct (xlat.c:2611) ==26224== by 0x4E556D8: tmpl_aexpand (tmpl.c:1514) ==26224== by 0x4E47387: radius_evaluate_map (evaluate.c:684) ==26224== by 0x4E474E9: radius_evaluate_cond (evaluate.c:746) ==26224== by 0x42938F: modcall_recurse (modcall.c:483) ==26224== ==26224== Conditional jump or move depends on uninitialised value(s) ==26224== at 0x50911BE: fr_utf8_char (print.c:40) ==26224== by 0x5091826: fr_prints (print.c:300) ==26224== by 0x50919C8: fr_prints_len (print.c:369) ==26224== by 0x50A2EDA: value_data_aprints (value.c:1466) ==26224== by 0x4E5D941: xlat_debug_attr (xlat.c:477) ==26224== by 0x4E61E4C: xlat_aprint (xlat.c:2306) ==26224== by 0x4E620E1: xlat_process (xlat.c:2398) ==26224== by 0x4E623F0: xlat_expand_struct (xlat.c:2470) ==26224== by 0x4E628F5: radius_axlat_struct (xlat.c:2611) ==26224== by 0x4E556D8: tmpl_aexpand (tmpl.c:1514) ==26224== by 0x4E47387: radius_evaluate_map (evaluate.c:684) ==26224== by 0x4E474E9: radius_evaluate_cond (evaluate.c:746) ==26224== ==26224== Conditional jump or move depends on uninitialised value(s) ==26224== at 0x50916FA: fr_prints (print.c:227) ==26224== by 0x50919C8: fr_prints_len (print.c:369) ==26224== by 0x50A2EDA: value_data_aprints (value.c:1466) ==26224== by 0x4E5D941: xlat_debug_attr (xlat.c:477) ==26224== by 0x4E61E4C: xlat_aprint (xlat.c:2306) ==26224== by 0x4E620E1: xlat_process (xlat.c:2398) ==26224== by 0x4E623F0: xlat_expand_struct (xlat.c:2470) ==26224== by 0x4E628F5: radius_axlat_struct (xlat.c:2611) ==26224== by 0x4E556D8: tmpl_aexpand (tmpl.c:1514) ==26224== by 0x4E47387: radius_evaluate_map (evaluate.c:684) ==26224== by 0x4E474E9: radius_evaluate_cond (evaluate.c:746) ==26224== by 0x42938F: modcall_recurse (modcall.c:483) ==26224== ==26224== Conditional jump or move depends on uninitialised value(s) ==26224== at 0x5091716: fr_prints (print.c:235) ==26224== by 0x50A2F3F: value_data_aprints (value.c:1470) ==26224== by 0x4E5D941: xlat_debug_attr (xlat.c:477) ==26224== by 0x4E61E4C: xlat_aprint (xlat.c:2306) ==26224== by 0x4E620E1: xlat_process (xlat.c:2398) ==26224== by 0x4E623F0: xlat_expand_struct (xlat.c:2470) ==26224== by 0x4E628F5: radius_axlat_struct (xlat.c:2611) ==26224== by 0x4E556D8: tmpl_aexpand (tmpl.c:1514) ==26224== by 0x4E47387: radius_evaluate_map (evaluate.c:684) ==26224== by 0x4E474E9: radius_evaluate_cond (evaluate.c:746) ==26224== by 0x42938F: modcall_recurse (modcall.c:483) ==26224== by 0x429145: modcall_child (modcall.c:408) ==26224== ==26224== Conditional jump or move depends on uninitialised value(s) ==26224== at 0x5091730: fr_prints (print.c:244) ==26224== by 0x50A2F3F: value_data_aprints (value.c:1470) ==26224== by 0x4E5D941: xlat_debug_attr (xlat.c:477) ==26224== by 0x4E61E4C: xlat_aprint (xlat.c:2306) ==26224== by 0x4E620E1: xlat_process (xlat.c:2398) ==26224== by 0x4E623F0: xlat_expand_struct (xlat.c:2470) ==26224== by 0x4E628F5: radius_axlat_struct (xlat.c:2611) ==26224== by 0x4E556D8: tmpl_aexpand (tmpl.c:1514) ==26224== by 0x4E47387: radius_evaluate_map (evaluate.c:684) ==26224== by 0x4E474E9: radius_evaluate_cond (evaluate.c:746) ==26224== by 0x42938F: modcall_recurse (modcall.c:483) ==26224== by 0x429145: modcall_child (modcall.c:408) ==26224== ==26224== Conditional jump or move depends on uninitialised value(s) ==26224== at 0x50911BE: fr_utf8_char (print.c:40) ==26224== by 0x5091826: fr_prints (print.c:300) ==26224== by 0x50A2F3F: value_data_aprints (value.c:1470) ==26224== by 0x4E5D941: xlat_debug_attr (xlat.c:477) ==26224== by 0x4E61E4C: xlat_aprint (xlat.c:2306) ==26224== by 0x4E620E1: xlat_process (xlat.c:2398) ==26224== by 0x4E623F0: xlat_expand_struct (xlat.c:2470) ==26224== by 0x4E628F5: radius_axlat_struct (xlat.c:2611) ==26224== by 0x4E556D8: tmpl_aexpand (tmpl.c:1514) ==26224== by 0x4E47387: radius_evaluate_map (evaluate.c:684) ==26224== by 0x4E474E9: radius_evaluate_cond (evaluate.c:746) ==26224== by 0x42938F: modcall_recurse (modcall.c:483) ==26224== ==26224== Conditional jump or move depends on uninitialised value(s) ==26224== at 0x50916FA: fr_prints (print.c:227) ==26224== by 0x50A2F3F: value_data_aprints (value.c:1470) ==26224== by 0x4E5D941: xlat_debug_attr (xlat.c:477) ==26224== by 0x4E61E4C: xlat_aprint (xlat.c:2306) ==26224== by 0x4E620E1: xlat_process (xlat.c:2398) ==26224== by 0x4E623F0: xlat_expand_struct (xlat.c:2470) ==26224== by 0x4E628F5: radius_axlat_struct (xlat.c:2611) ==26224== by 0x4E556D8: tmpl_aexpand (tmpl.c:1514) ==26224== by 0x4E47387: radius_evaluate_map (evaluate.c:684) ==26224== by 0x4E474E9: radius_evaluate_cond (evaluate.c:746) ==26224== by 0x42938F: modcall_recurse (modcall.c:483) ==26224== by 0x429145: modcall_child (modcall.c:408) ==26224== Sun Feb 4 18:43:53 2018 : Debug: (0) as string : Feb 4 2018 18:43:52 UTC Sun Feb 4 18:43:53 2018 : Debug: (0) as ipaddr : 90.119.84.104 Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x5a775468 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : ::/0 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 1517769832 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 1517769832 Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND %{debug_attr:request:} Sun Feb 4 18:43:53 2018 : Debug: (0) --> Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:request:}" == '') -> TRUE Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:request:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: calling noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: returned from noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) [noop] = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # if ("%{debug_attr:request:}" == '') = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # policy debug_request = noop Sun Feb 4 18:43:53 2018 : Debug: (0) policy debug_coa { Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:coa:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND TMPL XLAT STRUCT Sun Feb 4 18:43:53 2018 : Info: (0) Attributes matching "coa:" Sun Feb 4 18:43:53 2018 : WARNING: (0) List "coa" is not available Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND %{debug_attr:coa:} Sun Feb 4 18:43:53 2018 : Debug: (0) --> Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:coa:}" == '') -> TRUE Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:coa:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: calling noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: returned from noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) [noop] = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # if ("%{debug_attr:coa:}" == '') = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # policy debug_coa = noop Sun Feb 4 18:43:53 2018 : Debug: (0) policy debug_reply { Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:reply:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND TMPL XLAT STRUCT Sun Feb 4 18:43:53 2018 : Info: (0) Attributes matching "reply:" Sun Feb 4 18:43:53 2018 : Info: (0) &reply:Reply-Message = Hello2, bob Sun Feb 4 18:43:53 2018 : Info: (0) Type : string Sun Feb 4 18:43:53 2018 : Info: (0) Length : 11 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x48656c6c6f322c20626f62 Sun Feb 4 18:43:53 2018 : Debug: (0) as byte : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint8 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 0 Sun Feb 4 18:43:53 2018 : Info: (0) &reply:Reply-Message = Hello3, bob Sun Feb 4 18:43:53 2018 : Info: (0) Type : string Sun Feb 4 18:43:53 2018 : Info: (0) Length : 11 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x48656c6c6f332c20626f62 Sun Feb 4 18:43:53 2018 : Debug: (0) as byte : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint8 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 0 Sun Feb 4 18:43:53 2018 : Info: (0) &reply:Reply-Message = Hello44, bob Sun Feb 4 18:43:53 2018 : Info: (0) Type : string Sun Feb 4 18:43:53 2018 : Info: (0) Length : 12 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x48656c6c6f34342c20626f62 Sun Feb 4 18:43:53 2018 : Debug: (0) as byte : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint8 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 0 Sun Feb 4 18:43:53 2018 : Info: (0) &reply:Reply-Message = Hello2222, bob Sun Feb 4 18:43:53 2018 : Info: (0) Type : string Sun Feb 4 18:43:53 2018 : Info: (0) Length : 14 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x48656c6c6f323232322c20626f62 Sun Feb 4 18:43:53 2018 : Debug: (0) as byte : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint8 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 0 Sun Feb 4 18:43:53 2018 : Info: (0) &reply:MS-CHAP-MPPE-Keys = 0xc007e3905f1d5f4a78f494fa8f0b5339e96dbfdb46fb98df Sun Feb 4 18:43:53 2018 : Info: (0) Vendor : 311 (Microsoft) Sun Feb 4 18:43:53 2018 : Info: (0) Type : octets Sun Feb 4 18:43:53 2018 : Info: (0) Length : 24 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 3221742480 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : 0xc007e3905f1d5f4a78f494fa8f0b5339e96dbfdb46fb98df Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 49159 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : -1073224816 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 49159 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 3221742480 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : -1073224816 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 13837278589329694538 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 13837278589329694538 Sun Feb 4 18:43:53 2018 : Info: (0) &reply:MS-MPPE-Encryption-Policy = Encryption-Allowed Sun Feb 4 18:43:53 2018 : Info: (0) Vendor : 311 (Microsoft) Sun Feb 4 18:43:53 2018 : Info: (0) Type : integer Sun Feb 4 18:43:53 2018 : Info: (0) Length : 4 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : Encryption-Allowed Sun Feb 4 18:43:53 2018 : Debug: (0) as ipaddr : 0.0.0.1 Sun Feb 4 18:43:53 2018 : Debug: (0) as date : Jan 1 1970 00:00:00 UTC Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x00000001 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : ::/0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 1 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 1 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 1 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 1 Sun Feb 4 18:43:53 2018 : Info: (0) &reply:MS-MPPE-Encryption-Types = RC4-40or128-bit-Allowed Sun Feb 4 18:43:53 2018 : Info: (0) Vendor : 311 (Microsoft) Sun Feb 4 18:43:53 2018 : Info: (0) Type : integer Sun Feb 4 18:43:53 2018 : Info: (0) Length : 4 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : RC4-40or128-bit-Allowed Sun Feb 4 18:43:53 2018 : Debug: (0) as ipaddr : 0.0.0.6 Sun Feb 4 18:43:53 2018 : Debug: (0) as date : Jan 1 1970 00:00:00 UTC Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x00000006 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : ::/0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 6 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 6 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 6 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 6 Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND %{debug_attr:reply:} Sun Feb 4 18:43:53 2018 : Debug: (0) --> Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:reply:}" == '') -> TRUE Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:reply:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: calling noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: returned from noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) [noop] = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # if ("%{debug_attr:reply:}" == '') = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # policy debug_reply = noop Sun Feb 4 18:43:53 2018 : Debug: (0) policy debug_session_state { Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:session-state:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND TMPL XLAT STRUCT Sun Feb 4 18:43:53 2018 : Info: (0) Attributes matching "session-state:" Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND %{debug_attr:session-state:} Sun Feb 4 18:43:53 2018 : Debug: (0) --> Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:session-state:}" == '') -> TRUE Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:session-state:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: calling noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: returned from noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) [noop] = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # if ("%{debug_attr:session-state:}" == '') = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # policy debug_session_state = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # policy debug_all = noop Sun Feb 4 18:43:53 2018 : Debug: (0) update { Sun Feb 4 18:43:53 2018 : Debug: (0) No attributes updated Sun Feb 4 18:43:53 2018 : Debug: (0) } # update = noop Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: calling exec (rlm_exec) Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: returned from exec (rlm_exec) Sun Feb 4 18:43:53 2018 : Debug: (0) [exec] = noop Sun Feb 4 18:43:53 2018 : Debug: (0) policy remove_reply_message_if_eap { Sun Feb 4 18:43:53 2018 : Debug: (0) if (&reply:EAP-Message && &reply:Reply-Message) { Sun Feb 4 18:43:53 2018 : Debug: (0) if (&reply:EAP-Message && &reply:Reply-Message) -> FALSE Sun Feb 4 18:43:53 2018 : Debug: (0) else { Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: calling noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: returned from noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) [noop] = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # else = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # policy remove_reply_message_if_eap = noop Sun Feb 4 18:43:53 2018 : Debug: (0) policy debug_all { Sun Feb 4 18:43:53 2018 : Debug: (0) policy debug_control { Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:control:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND TMPL XLAT STRUCT Sun Feb 4 18:43:53 2018 : Info: (0) Attributes matching "control:" Sun Feb 4 18:43:53 2018 : Info: (0) &control:Auth-Type = mschap Sun Feb 4 18:43:53 2018 : Info: (0) Type : integer Sun Feb 4 18:43:53 2018 : Info: (0) Length : 4 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : mschap Sun Feb 4 18:43:53 2018 : Debug: (0) as ipaddr : 0.142.156.152 Sun Feb 4 18:43:53 2018 : Debug: (0) as date : Jan 1 1970 00:00:00 UTC Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x008e9c98 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : ::/0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 9346200 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 9346200 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 9346200 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 9346200 Sun Feb 4 18:43:53 2018 : Info: (0) &control:Cleartext-Password := aristo1 Sun Feb 4 18:43:53 2018 : Info: (0) Type : string Sun Feb 4 18:43:53 2018 : Info: (0) Length : 7 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x61726973746f31 Sun Feb 4 18:43:53 2018 : Debug: (0) as byte : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint8 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 0 Sun Feb 4 18:43:53 2018 : Info: (0) &control:NT-Password = 0xefc86e9649eddc50c1c202e3fbed3760 Sun Feb 4 18:43:53 2018 : Info: (0) Type : octets Sun Feb 4 18:43:53 2018 : Info: (0) Length : 16 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 4022890134 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : 0xefc86e9649eddc50c1c202e3fbed3760 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6addr : efc8:6e96:49ed:dc50:c1c2:2e3:fbed:3760 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : 6e96:49ed:dc50:c1c2:2e3:fbed:3760:0/200 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 61384 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : -272077162 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 61384 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 4022890134 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : -272077162 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 17278181562171382864 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 17278181562171382864 Sun Feb 4 18:43:53 2018 : Info: (0) &control:LM-Password = 0xc007e3905f1d5f4aaad3b435b51404ee Sun Feb 4 18:43:53 2018 : Info: (0) Type : octets Sun Feb 4 18:43:53 2018 : Info: (0) Length : 16 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 3221742480 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : 0xc007e3905f1d5f4aaad3b435b51404ee Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6addr : c007:e390:5f1d:5f4a:aad3:b435:b514:4ee Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : e390:5f1d:5f4a:aad3:b435:b514:4ee:0/7 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 49159 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : -1073224816 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 49159 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 3221742480 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : -1073224816 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 13837278589329694538 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 13837278589329694538 Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND %{debug_attr:control:} Sun Feb 4 18:43:53 2018 : Debug: (0) --> Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:control:}" == '') -> TRUE Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:control:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: calling noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: returned from noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) [noop] = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # if ("%{debug_attr:control:}" == '') = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # policy debug_control = noop Sun Feb 4 18:43:53 2018 : Debug: (0) policy debug_request { Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:request:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND TMPL XLAT STRUCT Sun Feb 4 18:43:53 2018 : Info: (0) Attributes matching "request:" Sun Feb 4 18:43:53 2018 : Info: (0) &request:User-Name = bob Sun Feb 4 18:43:53 2018 : Info: (0) Type : string Sun Feb 4 18:43:53 2018 : Info: (0) Length : 3 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x626f62 Sun Feb 4 18:43:53 2018 : Debug: (0) as byte : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint8 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 0 Sun Feb 4 18:43:53 2018 : Info: (0) &request:NAS-IP-Address = 212.83.158.2 Sun Feb 4 18:43:53 2018 : Info: (0) Type : ipaddr Sun Feb 4 18:43:53 2018 : Info: (0) Length : 4 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : 212.83.158.2 Sun Feb 4 18:43:53 2018 : Debug: (0) as date : Nov 18 2082 17:48:50 UTC Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0xd4539e02 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6addr : ::ffff:212.83.158.2 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : ::ffff:212.83.158.2/128 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv4prefix : 212.83.158.2/32 Sun Feb 4 18:43:53 2018 : Debug: (0) as cidr : 212.83.158.2/32 Sun Feb 4 18:43:53 2018 : Info: (0) &request:NAS-Port = 0 Sun Feb 4 18:43:53 2018 : Info: (0) Type : integer Sun Feb 4 18:43:53 2018 : Info: (0) Length : 4 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipaddr : 0.0.0.0 Sun Feb 4 18:43:53 2018 : Debug: (0) as date : Jan 1 1970 00:00:00 UTC Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x00000000 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : ::/0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 0 Sun Feb 4 18:43:53 2018 : Info: (0) &request:Message-Authenticator = 0x8819963c0c38d936755d37e0994be200 Sun Feb 4 18:43:53 2018 : Info: (0) Type : octets Sun Feb 4 18:43:53 2018 : Info: (0) Length : 16 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 2283378236 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : 0x8819963c0c38d936755d37e0994be200 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6addr : 8819:963c:c38:d936:755d:37e0:994b:e200 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : 963c:c38:d936:755d:37e0:994b:e200:0/25 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 34841 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : -2011589060 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 34841 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 2283378236 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : -2011589060 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 9807034848223222070 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 9807034848223222070 Sun Feb 4 18:43:53 2018 : Info: (0) &request:MS-CHAP-Challenge = 0xcfa41b33fd5395ef Sun Feb 4 18:43:53 2018 : Info: (0) Vendor : 311 (Microsoft) Sun Feb 4 18:43:53 2018 : Info: (0) Type : octets Sun Feb 4 18:43:53 2018 : Info: (0) Length : 8 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 3483638579 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : 0xcfa41b33fd5395ef Sun Feb 4 18:43:53 2018 : Debug: (0) as ifid : cfa4:1b33:fd53:95ef Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : 1b33:fd53:95ef::/164 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 53156 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : -811328717 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 53156 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 3483638579 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : -811328717 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 14962113772139025903 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 14962113772139025903 Sun Feb 4 18:43:53 2018 : Info: (0) &request:MS-CHAP-Response = 0x0001000000000000000000000000000000000000000000000000448813522c61e70b6d3590eafd1f12661ee916dbb9ce9e69 Sun Feb 4 18:43:53 2018 : Info: (0) Vendor : 311 (Microsoft) Sun Feb 4 18:43:53 2018 : Info: (0) Type : octets Sun Feb 4 18:43:53 2018 : Info: (0) Length : 50 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 65536 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : 0x0001000000000000000000000000000000000000000000000000448813522c61e70b6d3590eafd1f12661ee916dbb9ce9e69 Sun Feb 4 18:43:53 2018 : Debug: (0) as abinary : 0x0001000000000000000000000000000000000000000000000000448813522c61e70b6d3590eafd1f12661ee916dbb9ce9e69 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 1 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 65536 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 1 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 65536 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 65536 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 281474976710656 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 281474976710656 Sun Feb 4 18:43:53 2018 : Info: (0) &request:Event-Timestamp = Feb 4 2018 18:43:52 UTC Sun Feb 4 18:43:53 2018 : Info: (0) Type : date Sun Feb 4 18:43:53 2018 : Info: (0) Length : 4 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : Feb 4 2018 18:43:52 UTC Sun Feb 4 18:43:53 2018 : Debug: (0) as ipaddr : 90.119.84.104 Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x5a775468 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : ::/0 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 1517769832 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 1517769832 Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND %{debug_attr:request:} Sun Feb 4 18:43:53 2018 : Debug: (0) --> Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:request:}" == '') -> TRUE Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:request:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: calling noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: returned from noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) [noop] = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # if ("%{debug_attr:request:}" == '') = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # policy debug_request = noop Sun Feb 4 18:43:53 2018 : Debug: (0) policy debug_coa { Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:coa:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND TMPL XLAT STRUCT Sun Feb 4 18:43:53 2018 : Info: (0) Attributes matching "coa:" Sun Feb 4 18:43:53 2018 : WARNING: (0) List "coa" is not available Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND %{debug_attr:coa:} Sun Feb 4 18:43:53 2018 : Debug: (0) --> Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:coa:}" == '') -> TRUE Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:coa:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: calling noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: returned from noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) [noop] = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # if ("%{debug_attr:coa:}" == '') = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # policy debug_coa = noop Sun Feb 4 18:43:53 2018 : Debug: (0) policy debug_reply { Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:reply:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND TMPL XLAT STRUCT Sun Feb 4 18:43:53 2018 : Info: (0) Attributes matching "reply:" Sun Feb 4 18:43:53 2018 : Info: (0) &reply:Reply-Message = Hello2, bob Sun Feb 4 18:43:53 2018 : Info: (0) Type : string Sun Feb 4 18:43:53 2018 : Info: (0) Length : 11 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x48656c6c6f322c20626f62 Sun Feb 4 18:43:53 2018 : Debug: (0) as byte : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint8 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 0 Sun Feb 4 18:43:53 2018 : Info: (0) &reply:Reply-Message = Hello3, bob Sun Feb 4 18:43:53 2018 : Info: (0) Type : string Sun Feb 4 18:43:53 2018 : Info: (0) Length : 11 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x48656c6c6f332c20626f62 Sun Feb 4 18:43:53 2018 : Debug: (0) as byte : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint8 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 0 Sun Feb 4 18:43:53 2018 : Info: (0) &reply:Reply-Message = Hello44, bob Sun Feb 4 18:43:53 2018 : Info: (0) Type : string Sun Feb 4 18:43:53 2018 : Info: (0) Length : 12 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x48656c6c6f34342c20626f62 Sun Feb 4 18:43:53 2018 : Debug: (0) as byte : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint8 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 0 Sun Feb 4 18:43:53 2018 : Info: (0) &reply:Reply-Message = Hello2222, bob Sun Feb 4 18:43:53 2018 : Info: (0) Type : string Sun Feb 4 18:43:53 2018 : Info: (0) Length : 14 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x48656c6c6f323232322c20626f62 Sun Feb 4 18:43:53 2018 : Debug: (0) as byte : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint8 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 0 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 0 Sun Feb 4 18:43:53 2018 : Info: (0) &reply:MS-CHAP-MPPE-Keys = 0xc007e3905f1d5f4a78f494fa8f0b5339e96dbfdb46fb98df Sun Feb 4 18:43:53 2018 : Info: (0) Vendor : 311 (Microsoft) Sun Feb 4 18:43:53 2018 : Info: (0) Type : octets Sun Feb 4 18:43:53 2018 : Info: (0) Length : 24 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer : 3221742480 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : 0xc007e3905f1d5f4a78f494fa8f0b5339e96dbfdb46fb98df Sun Feb 4 18:43:53 2018 : Debug: (0) as short : 49159 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : -1073224816 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint16 : 49159 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint32 : 3221742480 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : -1073224816 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 13837278589329694538 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 13837278589329694538 Sun Feb 4 18:43:53 2018 : Info: (0) &reply:MS-MPPE-Encryption-Policy = Encryption-Allowed Sun Feb 4 18:43:53 2018 : Info: (0) Vendor : 311 (Microsoft) Sun Feb 4 18:43:53 2018 : Info: (0) Type : integer Sun Feb 4 18:43:53 2018 : Info: (0) Length : 4 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : Encryption-Allowed Sun Feb 4 18:43:53 2018 : Debug: (0) as ipaddr : 0.0.0.1 Sun Feb 4 18:43:53 2018 : Debug: (0) as date : Jan 1 1970 00:00:00 UTC Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x00000001 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : ::/0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 1 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 1 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 1 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 1 Sun Feb 4 18:43:53 2018 : Info: (0) &reply:MS-MPPE-Encryption-Types = RC4-40or128-bit-Allowed Sun Feb 4 18:43:53 2018 : Info: (0) Vendor : 311 (Microsoft) Sun Feb 4 18:43:53 2018 : Info: (0) Type : integer Sun Feb 4 18:43:53 2018 : Info: (0) Length : 4 Sun Feb 4 18:43:53 2018 : Debug: (0) as string : RC4-40or128-bit-Allowed Sun Feb 4 18:43:53 2018 : Debug: (0) as ipaddr : 0.0.0.6 Sun Feb 4 18:43:53 2018 : Debug: (0) as date : Jan 1 1970 00:00:00 UTC Sun Feb 4 18:43:53 2018 : Debug: (0) as octets : 0x00000006 Sun Feb 4 18:43:53 2018 : Debug: (0) as ipv6prefix : ::/0 Sun Feb 4 18:43:53 2018 : Debug: (0) as signed : 6 Sun Feb 4 18:43:53 2018 : Debug: (0) as int32 : 6 Sun Feb 4 18:43:53 2018 : Debug: (0) as integer64 : 6 Sun Feb 4 18:43:53 2018 : Debug: (0) as uint64 : 6 Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND %{debug_attr:reply:} Sun Feb 4 18:43:53 2018 : Debug: (0) --> Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:reply:}" == '') -> TRUE Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:reply:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: calling noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: returned from noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) [noop] = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # if ("%{debug_attr:reply:}" == '') = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # policy debug_reply = noop Sun Feb 4 18:43:53 2018 : Debug: (0) policy debug_session_state { Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:session-state:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND TMPL XLAT STRUCT Sun Feb 4 18:43:53 2018 : Info: (0) Attributes matching "session-state:" Sun Feb 4 18:43:53 2018 : Debug: (0) EXPAND %{debug_attr:session-state:} Sun Feb 4 18:43:53 2018 : Debug: (0) --> Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:session-state:}" == '') -> TRUE Sun Feb 4 18:43:53 2018 : Debug: (0) if ("%{debug_attr:session-state:}" == '') { Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: calling noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) modsingle[post-auth]: returned from noop (rlm_always) Sun Feb 4 18:43:53 2018 : Debug: (0) [noop] = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # if ("%{debug_attr:session-state:}" == '') = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # policy debug_session_state = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # policy debug_all = noop Sun Feb 4 18:43:53 2018 : Debug: (0) } # post-auth = noop Sun Feb 4 18:43:53 2018 : Debug: (0) Sent Access-Accept Id 186 from 127.0.0.1:1812 to 127.0.0.1:35041 length 0 Sun Feb 4 18:43:53 2018 : Debug: (0) Reply-Message = "Hello2, bob" Sun Feb 4 18:43:53 2018 : Debug: (0) Reply-Message = "Hello3, bob" Sun Feb 4 18:43:53 2018 : Debug: (0) Reply-Message = "Hello44, bob" Sun Feb 4 18:43:53 2018 : Debug: (0) Reply-Message = "Hello2222, bob" Sun Feb 4 18:43:53 2018 : Debug: (0) MS-CHAP-MPPE-Keys = 0xc007e3905f1d5f4a78f494fa8f0b5339e96dbfdb46fb98df Sun Feb 4 18:43:53 2018 : Debug: (0) MS-MPPE-Encryption-Policy = Encryption-Allowed Sun Feb 4 18:43:53 2018 : Debug: (0) MS-MPPE-Encryption-Types = RC4-40or128-bit-Allowed 12 0d 48 65 6c 6c 6f 32 2c 20 62 6f 62 12 0d 48 65 6c 6c 6f 33 2c 20 62 6f 62 12 0e 48 65 6c 6c 6f 34 34 2c 20 62 6f 62 12 10 48 65 6c 6c 6f 32 32 32 32 2c 20 62 6f 62 0c 22 51 a1 21 0a f7 ea 28 7e f4 23 9e 95 ce 05 96 e2 b4 96 9d a7 2f 87 7d f3 99 88 2c 4f 7f dd 94 e0 1a 06 00000137 (311) 0c 22 51 a1 21 0a f7 ea 28 7e f4 23 9e 95 ce 05 96 e2 b4 96 9d a7 2f 87 7d f3 99 88 2c 4f 7f dd 94 e0 07 06 00 00 00 01 1a 06 00000137 (311) 07 06 00 00 00 01 08 06 00 00 00 06 1a 06 00000137 (311) 08 06 00 00 00 06 Socket: 8 Proto: 17 Src IP: 127.0.0.1 port: 1812 Dst IP: 127.0.0.1 port: 35041 Code: (2) Access-Accept Id: 186 Length: 140 Vector: ae18170a40fdc5bb76df9c9f7119bf95 Data: 12 0d 48 65 6c 6c 6f 32 2c 20 62 6f 62 12 0d 48 65 6c 6c 6f 33 2c 20 62 6f 62 12 0e 48 65 6c 6c 6f 34 34 2c 20 62 6f 62 12 10 48 65 6c 6c 6f 32 32 32 32 2c 20 62 6f 62 1a 28 00000137 (311) 0c 22 51 a1 21 0a f7 ea 28 7e f4 23 9e 95 ce 05 96 e2 b4 96 9d a7 2f 87 7d f3 99 88 2c 4f 7f dd 94 e0 1a 0c 00000137 (311) 07 06 00 00 00 01 1a 0c 00000137 (311) 08 06 00 00 00 06 Socket: 8 Proto: 17 Src IP: 127.0.0.1 port: 1812 Dst IP: 127.0.0.1 port: 35041 Code: (2) Access-Accept Id: 186 Length: 140 Vector: aa84fc7b0c906ed1d7138e2dbbcc1c6d Data: 12 0d 48 65 6c 6c 6f 32 2c 20 62 6f 62 12 0d 48 65 6c 6c 6f 33 2c 20 62 6f 62 12 0e 48 65 6c 6c 6f 34 34 2c 20 62 6f 62 12 10 48 65 6c 6c 6f 32 32 32 32 2c 20 62 6f 62 1a 28 00000137 (311) 0c 22 51 a1 21 0a f7 ea 28 7e f4 23 9e 95 ce 05 96 e2 b4 96 9d a7 2f 87 7d f3 99 88 2c 4f 7f dd 94 e0 1a 0c 00000137 (311) 07 06 00 00 00 01 1a 0c 00000137 (311) 08 06 00 00 00 06 Sun Feb 4 18:43:53 2018 : Debug: (0) Finished request Sun Feb 4 18:43:53 2018 : Debug: Waking up in 4.9 seconds. ^C==26224== ==26224== HEAP SUMMARY: ==26224== in use at exit: 2,697,571 bytes in 39,848 blocks ==26224== total heap usage: 45,295 allocs, 5,447 frees, 3,839,730 bytes allocated ==26224== ==26224== LEAK SUMMARY: ==26224== definitely lost: 96 bytes in 1 blocks ==26224== indirectly lost: 110 bytes in 1 blocks ==26224== possibly lost: 1,043,796 bytes in 6,480 blocks ==26224== still reachable: 1,653,569 bytes in 33,366 blocks ==26224== suppressed: 0 bytes in 0 blocks ==26224== Rerun with --leak-check=full to see details of leaked memory ==26224== ==26224== For counts of detected and suppressed errors, rerun with: -v ==26224== Use --track-origins=yes to see where uninitialised values come from ==26224== ERROR SUMMARY: 492 errors from 11 contexts (suppressed: 0 from 0) [root@kdc ~]#