Salted SHA512

[Laurens Vets]

On 2016-09-06 07:22, Alan DeKok wrote:
> On Sep 5, 2016, at 7:54 PM, Laurens Vets <laurens at daemon.be> wrote:
>> ///
>> (1) sql: EXPAND SELECT id, username, attribute, value, op FROM 
>> radcheck WHERE username = '%{SQL-User-Name}' ORDER BY id
>> (1) sql:    --> SELECT id, username, attribute, value, op FROM 
>> radcheck WHERE username = 'laurens7' ORDER BY id
>> (1) sql: Executing select query: SELECT id, username, attribute, 
>> value, op FROM radcheck WHERE username = 'laurens7' ORDER BY id
>> (1) sql: User found in radcheck table
>> (1) sql: Conditional check items matched, merging assignment check 
>> items
>> (1) sql:   SSHA2-512-Password := 
>> 0x61363138336661396262643730366336373331616366343165393231386339616239343836376234623333376533643666616432393032376135363661353765393134666334633832613763356665376662376231393435663539633863333731353164656230663765663563643361616235
> 
>   Which is hex-encoded ASCII text.  That decodes do:
> 
> a6183fa9bbd706c6731acf41e9218c9ab94867b4b337e3d6fad29027a566a57e914fc4c82a7c5fe7fb7b1945f59c8c37151deb0f7ef5cd3aab5...
> 
>   Which is the real value of the SSHA2-512 hash you need.
> 
>   i.e. you put the HEX data into SQL, but did not prefix it with "0x",
> which tells the server that it's hex data.
> 
>   Put the data into SQL as:
> 
> 0xa6183fa9bbd706c6731acf41e9218c9ab94867b4b337e3d6fad29027a566a57e914fc4c82a7c5fe7fb7b1945f59c8c37151deb0f7ef5cd3aab5...
> 
>   and it should work.

Sorry to bother the list again, but it still doesn't work. I've put '0x' 
in front of that string and I still get a byte error message. See debug 
#1 below.

Most likely, I'm doing something completely wrong, so here's my full 
password generating method:

root at server:~# perl ssha2passwd.pl -l 512 -saltlen 8 laurens7
oiaYnKzLDNazrhlH95szGQkh0eSQzBd8lFWK6lOEqbVV9S1gdhNIbUvEU0esQsgkFsIE7GYsYZl7pzN3aq3sQKibKTo9EUWk
root at server:~#

MariaDB [radius]> UPDATE radcheck SET 
value='0xoiaYnKzLDNazrhlH95szGQkh0eSQzBd8lFWK6lOEqbVV9S1gdhNIbUvEU0esQsgkFsIE7GYsYZl7pzN3aq3sQKibKTo9EUWk' 
WHERE id=5;
Query OK, 1 row affected (0.13 sec)
Rows matched: 1  Changed: 1  Warnings: 0

MariaDB [radius]> select * from radcheck where id='5';
+----+----------+--------------------+----+----------------------------------------------------------------------------------------------------+
| id | username | attribute          | op | value                        
                                                                       |
+----+----------+--------------------+----+----------------------------------------------------------------------------------------------------+
|  5 | laurens7 | SSHA2-512-Password | := | 
0xoiaYnKzLDNazrhlH95szGQkh0eSQzBd8lFWK6lOEqbVV9S1gdhNIbUvEU0esQsgkFsIE7GYsYZl7pzN3aq3sQKibKTo9EUWk 
|
+----+----------+--------------------+----+----------------------------------------------------------------------------------------------------+
1 row in set (0.00 sec)

MariaDB [radius]>

The resulting debug is now a bit different ('invalid hex data'), but it 
still doesn't work. See debug #2 below.

Debug #1:

Server was built with:
   accounting               : yes
   authentication           : yes
   ascend-binary-attributes : yes
   coa                      : yes
   control-socket           : yes
   detail                   : yes
   dhcp                     : yes
   dynamic-clients          : yes
   osfc2                    : no
   proxy                    : yes
   regex-pcre               : yes
   regex-posix              : no
   regex-posix-extended     : no
   session-management       : yes
   stats                    : yes
   tcp                      : yes
   threads                  : yes
   tls                      : yes
   unlang                   : yes
   vmps                     : yes
   developer                : no
Server core libs:
   freeradius-server        : 3.0.11
   talloc                   : 2.0.*
   ssl                      : 1.0.1t release
   pcre                     : 8.35 2014-04-04
Endianness:
   little
Compilation flags:
   cppflags : -D_FORTIFY_SOURCE=2
   cflags   : -I/home/laurens/freeradius3/freeradius-server-3.0.11 
-I/home/laurens/freeradius3/freeradius-server-3.0.11/src -include 
/home/laurens/freeradius3/freeradius-server-3.0.11/src/freeradius-devel/autoconf.h 
-include 
/home/laurens/freeradius3/freeradius-server-3.0.11/src/freeradius-devel/build.h 
-include 
/home/laurens/freeradius3/freeradius-server-3.0.11/src/freeradius-devel/features.h 
-include 
/home/laurens/freeradius3/freeradius-server-3.0.11/src/freeradius-devel/radpaths.h 
-fno-strict-aliasing -g -O2 -fstack-protector-strong -Wformat 
-Werror=format-security -O2 -Wall -std=c99 -D_GNU_SOURCE -D_REENTRANT 
-D_POSIX_PTHREAD_SEMANTICS -DOPENSSL_NO_KRB5 -DNDEBUG -DIS_MODULE=1
   ldflags  :  -Wl,-z,relro
   libs     : -lcrypto -lssl -ltalloc -lpcre -lcap -lnsl -lresolv -ldl 
-lpthread -lreadline

Copyright (C) 1999-2016 The FreeRADIUS server project and contributors
There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A
PARTICULAR PURPOSE
You may redistribute copies of FreeRADIUS under the terms of the
GNU General Public License
For more information about these matters, see the file named COPYRIGHT
Starting - reading configuration files ...
including dictionary file /usr/share/freeradius/dictionary
including dictionary file /usr/share/freeradius/dictionary.dhcp
including dictionary file /usr/share/freeradius/dictionary.vqp
including dictionary file /etc/freeradius/dictionary
including configuration file /etc/freeradius/radiusd.conf
including configuration file /etc/freeradius/proxy.conf
including configuration file /etc/freeradius/clients.conf
including files in directory /etc/freeradius/mods-enabled/
including configuration file /etc/freeradius/mods-enabled/files
including configuration file /etc/freeradius/mods-enabled/logintime
including configuration file /etc/freeradius/mods-enabled/radutmp
including configuration file /etc/freeradius/mods-enabled/expiration
including configuration file /etc/freeradius/mods-enabled/sql
including configuration file 
/etc/freeradius/mods-config/sql/main/mysql/queries.conf
including configuration file /etc/freeradius/mods-enabled/digest
including configuration file /etc/freeradius/mods-enabled/unpack
including configuration file /etc/freeradius/mods-enabled/cache_eap
including configuration file /etc/freeradius/mods-enabled/chap
including configuration file /etc/freeradius/mods-enabled/soh
including configuration file /etc/freeradius/mods-enabled/unix
including configuration file /etc/freeradius/mods-enabled/sradutmp
including configuration file /etc/freeradius/mods-enabled/linelog
including configuration file /etc/freeradius/mods-enabled/attr_filter
including configuration file /etc/freeradius/mods-enabled/passwd
including configuration file /etc/freeradius/mods-enabled/replicate
including configuration file /etc/freeradius/mods-enabled/preprocess
including configuration file /etc/freeradius/mods-enabled/realm
including configuration file /etc/freeradius/mods-enabled/detail
including configuration file /etc/freeradius/mods-enabled/dhcp_sqlippool
including configuration file 
/etc/freeradius/mods-config/sql/ippool-dhcp/mysql/queries.conf
including configuration file /etc/freeradius/mods-enabled/detail.log
including configuration file /etc/freeradius/mods-enabled/ntlm_auth
including configuration file /etc/freeradius/mods-enabled/mschap
including configuration file /etc/freeradius/mods-enabled/always
including configuration file 
/etc/freeradius/mods-enabled/dynamic_clients
including configuration file /etc/freeradius/mods-enabled/echo
including configuration file /etc/freeradius/mods-enabled/expr
including configuration file /etc/freeradius/mods-enabled/utf8
including configuration file /etc/freeradius/mods-enabled/eap
including configuration file /etc/freeradius/mods-enabled/pap
including configuration file /etc/freeradius/mods-enabled/exec
including files in directory /etc/freeradius/policy.d/
including configuration file /etc/freeradius/policy.d/debug
including configuration file /etc/freeradius/policy.d/cui
including configuration file /etc/freeradius/policy.d/filter
including configuration file /etc/freeradius/policy.d/canonicalization
including configuration file /etc/freeradius/policy.d/control
including configuration file /etc/freeradius/policy.d/accounting
including configuration file /etc/freeradius/policy.d/dhcp
including configuration file /etc/freeradius/policy.d/abfab-tr
including configuration file /etc/freeradius/policy.d/operator-name
including configuration file /etc/freeradius/policy.d/eap
including files in directory /etc/freeradius/sites-enabled/
including configuration file /etc/freeradius/sites-enabled/inner-tunnel
including configuration file /etc/freeradius/sites-enabled/dhcp
including configuration file /etc/freeradius/sites-enabled/default
main {
  security {
  	user = "freerad"
  	group = "freerad"
  	allow_core_dumps = no
  }
	name = "freeradius"
	prefix = "/usr"
	localstatedir = "/var"
	logdir = "/var/log/freeradius"
	run_dir = "/var/run/freeradius"
}
main {
	name = "freeradius"
	prefix = "/usr"
	localstatedir = "/var"
	sbindir = "/usr/sbin"
	logdir = "/var/log/freeradius"
	run_dir = "/var/run/freeradius"
	libdir = "/usr/lib/freeradius"
	radacctdir = "/var/log/freeradius/radacct"
	hostname_lookups = no
	max_request_time = 30
	cleanup_delay = 5
	max_requests = 16384
	pidfile = "/var/run/freeradius/freeradius.pid"
	checkrad = "/usr/sbin/checkrad"
	debug_level = 0
	proxy_requests = yes
  log {
  	stripped_names = no
  	auth = no
  	auth_badpass = no
  	auth_goodpass = no
  	colourise = yes
  	msg_denied = "You are already logged in - access denied"
  }
  resources {
  }
  security {
  	max_attributes = 200
  	reject_delay = 1.000000
  	status_server = yes
  }
}
radiusd: #### Loading Realms and Home Servers ####
  proxy server {
  	retry_delay = 5
  	retry_count = 3
  	default_fallback = no
  	dead_time = 120
  	wake_all_if_all_dead = no
  }
  home_server localhost {
  	ipaddr = 127.0.0.1
  	port = 1812
  	type = "auth"
  	secret = <<< secret >>>
  	response_window = 20.000000
  	response_timeouts = 1
  	max_outstanding = 65536
  	zombie_period = 40
  	status_check = "status-server"
  	ping_interval = 30
  	check_interval = 30
  	check_timeout = 4
  	num_answers_to_alive = 3
  	revive_interval = 120
   limit {
   	max_connections = 16
   	max_requests = 0
   	lifetime = 0
   	idle_timeout = 0
   }
   coa {
   	irt = 2
   	mrt = 16
   	mrc = 5
   	mrd = 30
   }
  }
  home_server_pool my_auth_failover {
	type = fail-over
	home_server = localhost
  }
  realm example.com {
	auth_pool = my_auth_failover
  }
  realm LOCAL {
  }
radiusd: #### Loading Clients ####
  client localhost {
  	ipaddr = 10.1.0.1
  	require_message_authenticator = no
  	secret = <<< secret >>>
  	nas_type = "other"
  	proto = "*"
   limit {
   	max_connections = 16
   	lifetime = 0
   	idle_timeout = 30
   }
  }
  client arali {
  	ipaddr = 188.40.47.70
  	require_message_authenticator = no
  	secret = <<< secret >>>
   limit {
   	max_connections = 16
   	lifetime = 0
   	idle_timeout = 30
   }
  }
Debugger not attached
  # Creating Auth-Type = PAP
  # Creating Auth-Type = CHAP
  # Creating Auth-Type = MS-CHAP
  # Creating Auth-Type = eap
  # Creating Auth-Type = digest
radiusd: #### Instantiating modules ####
  modules {
   # Loaded module rlm_files
   # Loading module "files" from file /etc/freeradius/mods-enabled/files
   files {
   	filename = "/etc/freeradius/mods-config/files/authorize"
   	acctusersfile = "/etc/freeradius/mods-config/files/accounting"
   	preproxy_usersfile = "/etc/freeradius/mods-config/files/pre-proxy"
   }
   # Loaded module rlm_logintime
   # Loading module "logintime" from file 
/etc/freeradius/mods-enabled/logintime
   logintime {
   	minimum_timeout = 60
   }
   # Loaded module rlm_radutmp
   # Loading module "radutmp" from file 
/etc/freeradius/mods-enabled/radutmp
   radutmp {
   	filename = "/var/log/freeradius/radutmp"
   	username = "%{User-Name}"
   	case_sensitive = yes
   	check_with_nas = yes
   	permissions = 384
   	caller_id = yes
   }
   # Loaded module rlm_expiration
   # Loading module "expiration" from file 
/etc/freeradius/mods-enabled/expiration
   # Loaded module rlm_sql
   # Loading module "sql" from file /etc/freeradius/mods-enabled/sql
   sql {
   	driver = "rlm_sql_mysql"
   	server = "localhost"
   	port = 3306
   	login = "radius"
   	password = <<< secret >>>
   	radius_db = "radius"
   	read_groups = yes
   	read_profiles = yes
   	read_clients = no
   	delete_stale_sessions = yes
   	sql_user_name = "%{User-Name}"
   	default_user_profile = ""
   	client_query = "SELECT id, nasname, shortname, type, secret, server 
FROM nas"
   	authorize_check_query = "SELECT id, username, attribute, value, op 
FROM radcheck WHERE username = '%{SQL-User-Name}' ORDER BY id"
   	authorize_reply_query = "SELECT id, username, attribute, value, op 
FROM radreply WHERE username = '%{SQL-User-Name}' ORDER BY id"
   	authorize_group_check_query = "SELECT id, groupname, attribute, 
Value, op FROM radgroupcheck WHERE groupname = '%{SQL-Group}' ORDER BY 
id"
   	authorize_group_reply_query = "SELECT id, groupname, attribute, 
value, op FROM radgroupreply WHERE groupname = '%{SQL-Group}' ORDER BY 
id"
   	group_membership_query = "SELECT groupname FROM radusergroup WHERE 
username = '%{SQL-User-Name}' ORDER BY priority"
   	simul_count_query = "SELECT COUNT(*) FROM radacct WHERE username = 
'%{SQL-User-Name}' AND acctstoptime IS NULL"
   	simul_verify_query = "SELECT radacctid, acctsessionid, username, 
nasipaddress, nasportid, framedipaddress, callingstationid, 
framedprotocol FROM radacct WHERE username = '%{SQL-User-Name}' AND 
acctstoptime IS NULL"
   	safe_characters = 
"@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /"
    accounting {
    	reference = "%{tolower:type.%{Acct-Status-Type}.query}"
     type {
      accounting-on {
      	query = "UPDATE radacct SET acctstoptime = 
FROM_UNIXTIME(%{integer:Event-Timestamp}), acctsessiontime	= 
'%{integer:Event-Timestamp}' - UNIX_TIMESTAMP(acctstarttime), 
acctterminatecause = '%{%{Acct-Terminate-Cause}:-NAS-Reboot}' WHERE 
acctstoptime IS NULL AND nasipaddress   = '%{NAS-IP-Address}' AND 
acctstarttime <= FROM_UNIXTIME(%{integer:Event-Timestamp})"
      }
      accounting-off {
      	query = "UPDATE radacct SET acctstoptime = 
FROM_UNIXTIME(%{integer:Event-Timestamp}), acctsessiontime	= 
'%{integer:Event-Timestamp}' - UNIX_TIMESTAMP(acctstarttime), 
acctterminatecause = '%{%{Acct-Terminate-Cause}:-NAS-Reboot}' WHERE 
acctstoptime IS NULL AND nasipaddress   = '%{NAS-IP-Address}' AND 
acctstarttime <= FROM_UNIXTIME(%{integer:Event-Timestamp})"
      }
      start {
      	query = "INSERT INTO radacct 
(acctsessionid,		acctuniqueid,		username, 
realm,			nasipaddress,		nasportid, 
nasporttype,		acctstarttime,		acctupdatetime, 
acctstoptime,		acctsessiontime, 	acctauthentic, 
connectinfo_start,	connectinfo_stop, 	acctinputoctets, 
acctoutputoctets,	calledstationid, 	callingstationid, 
acctterminatecause,	servicetype,		framedprotocol, framedipaddress) 
VALUES ('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}', 
'%{SQL-User-Name}', '%{Realm}', '%{NAS-IP-Address}', 
'%{%{NAS-Port-ID}:-%{NAS-Port}}', '%{NAS-Port-Type}', 
FROM_UNIXTIME(%{integer:Event-Timestamp}), 
FROM_UNIXTIME(%{integer:Event-Timestamp}), NULL, '0', 
'%{Acct-Authentic}', '%{Connect-Info}', '', '0', '0', 
'%{Called-Station-Id}', '%{Calling-Station-Id}', '', '%{Service-Type}', 
'%{Framed-Protocol}', '%{Framed-IP-Address}')"
      }
      interim-update {
      	query = "UPDATE radacct SET acctupdatetime  = 
(@acctupdatetime_old:=acctupdatetime), acctupdatetime  = 
FROM_UNIXTIME(%{integer:Event-Timestamp}), acctinterval    = 
%{integer:Event-Timestamp} - UNIX_TIMESTAMP(@acctupdatetime_old), 
framedipaddress = '%{Framed-IP-Address}', acctsessiontime = 
%{%{Acct-Session-Time}:-NULL}, acctinputoctets = 
'%{%{Acct-Input-Gigawords}:-0}' << 32 | '%{%{Acct-Input-Octets}:-0}', 
acctoutputoctets = '%{%{Acct-Output-Gigawords}:-0}' << 32 | 
'%{%{Acct-Output-Octets}:-0}' WHERE AcctUniqueId = 
'%{Acct-Unique-Session-Id}'"
      }
      stop {
      	query = "UPDATE radacct SET acctstoptime	= 
FROM_UNIXTIME(%{integer:Event-Timestamp}), acctsessiontime	= 
%{%{Acct-Session-Time}:-NULL}, acctinputoctets	= 
'%{%{Acct-Input-Gigawords}:-0}' << 32 | '%{%{Acct-Input-Octets}:-0}', 
acctoutputoctets = '%{%{Acct-Output-Gigawords}:-0}' << 32 | 
'%{%{Acct-Output-Octets}:-0}', acctterminatecause = 
'%{Acct-Terminate-Cause}', connectinfo_stop = '%{Connect-Info}' WHERE 
AcctUniqueId = '%{Acct-Unique-Session-Id}'"
      }
     }
    }
    post-auth {
    	reference = ".query"
    	query = "INSERT INTO radpostauth (username, pass, reply, authdate) 
VALUES ( '%{SQL-User-Name}', '%{%{User-Password}:-%{Chap-Password}}', 
'%{reply:Packet-Type}', '%S')"
    }
   }
rlm_sql (sql): Driver rlm_sql_mysql (module rlm_sql_mysql) loaded and 
linked
Creating attribute SQL-Group
   # Loaded module rlm_digest
   # Loading module "digest" from file 
/etc/freeradius/mods-enabled/digest
   # Loaded module rlm_unpack
   # Loading module "unpack" from file 
/etc/freeradius/mods-enabled/unpack
   # Loaded module rlm_cache
   # Loading module "cache_eap" from file 
/etc/freeradius/mods-enabled/cache_eap
   cache cache_eap {
   	driver = "rlm_cache_rbtree"
   	key = "%{%{control:State}:-%{%{reply:State}:-%{State}}}"
   	ttl = 15
   	max_entries = 0
   	epoch = 0
   	add_stats = no
   }
   # Loaded module rlm_chap
   # Loading module "chap" from file /etc/freeradius/mods-enabled/chap
   # Loaded module rlm_soh
   # Loading module "soh" from file /etc/freeradius/mods-enabled/soh
   soh {
   	dhcp = yes
   }
   # Loaded module rlm_unix
   # Loading module "unix" from file /etc/freeradius/mods-enabled/unix
   unix {
   	radwtmp = "/var/log/freeradius/radwtmp"
   }
Creating attribute Unix-Group
   # Loading module "sradutmp" from file 
/etc/freeradius/mods-enabled/sradutmp
   radutmp sradutmp {
   	filename = "/var/log/freeradius/sradutmp"
   	username = "%{User-Name}"
   	case_sensitive = yes
   	check_with_nas = yes
   	permissions = 420
   	caller_id = no
   }
   # Loaded module rlm_linelog
   # Loading module "linelog" from file 
/etc/freeradius/mods-enabled/linelog
   linelog {
   	filename = "/var/log/freeradius/linelog"
   	escape_filenames = no
   	syslog_severity = "info"
   	permissions = 384
   	format = "This is a log message for %{User-Name}"
   	reference = "messages.%{%{reply:Packet-Type}:-default}"
   }
   # Loading module "log_accounting" from file 
/etc/freeradius/mods-enabled/linelog
   linelog log_accounting {
   	filename = "/var/log/freeradius/linelog-accounting"
   	escape_filenames = no
   	syslog_severity = "info"
   	permissions = 384
   	format = ""
   	reference = "Accounting-Request.%{%{Acct-Status-Type}:-unknown}"
   }
   # Loaded module rlm_attr_filter
   # Loading module "attr_filter.post-proxy" from file 
/etc/freeradius/mods-enabled/attr_filter
   attr_filter attr_filter.post-proxy {
   	filename = "/etc/freeradius/mods-config/attr_filter/post-proxy"
   	key = "%{Realm}"
   	relaxed = no
   }
   # Loading module "attr_filter.pre-proxy" from file 
/etc/freeradius/mods-enabled/attr_filter
   attr_filter attr_filter.pre-proxy {
   	filename = "/etc/freeradius/mods-config/attr_filter/pre-proxy"
   	key = "%{Realm}"
   	relaxed = no
   }
   # Loading module "attr_filter.access_reject" from file 
/etc/freeradius/mods-enabled/attr_filter
   attr_filter attr_filter.access_reject {
   	filename = "/etc/freeradius/mods-config/attr_filter/access_reject"
   	key = "%{User-Name}"
   	relaxed = no
   }
   # Loading module "attr_filter.access_challenge" from file 
/etc/freeradius/mods-enabled/attr_filter
   attr_filter attr_filter.access_challenge {
   	filename = "/etc/freeradius/mods-config/attr_filter/access_challenge"
   	key = "%{User-Name}"
   	relaxed = no
   }
   # Loading module "attr_filter.accounting_response" from file 
/etc/freeradius/mods-enabled/attr_filter
   attr_filter attr_filter.accounting_response {
   	filename = 
"/etc/freeradius/mods-config/attr_filter/accounting_response"
   	key = "%{User-Name}"
   	relaxed = no
   }
   # Loaded module rlm_passwd
   # Loading module "etc_passwd" from file 
/etc/freeradius/mods-enabled/passwd
   passwd etc_passwd {
   	filename = "/etc/passwd"
   	format = "*User-Name:Crypt-Password:"
   	delimiter = ":"
   	ignore_nislike = no
   	ignore_empty = yes
   	allow_multiple_keys = no
   	hash_size = 100
   }
   # Loaded module rlm_replicate
   # Loading module "replicate" from file 
/etc/freeradius/mods-enabled/replicate
   # Loaded module rlm_preprocess
   # Loading module "preprocess" from file 
/etc/freeradius/mods-enabled/preprocess
   preprocess {
   	huntgroups = "/etc/freeradius/mods-config/preprocess/huntgroups"
   	hints = "/etc/freeradius/mods-config/preprocess/hints"
   	with_ascend_hack = no
   	ascend_channels_per_line = 23
   	with_ntdomain_hack = no
   	with_specialix_jetstream_hack = no
   	with_cisco_vsa_hack = no
   	with_alvarion_vsa_hack = no
   }
   # Loaded module rlm_realm
   # Loading module "IPASS" from file /etc/freeradius/mods-enabled/realm
   realm IPASS {
   	format = "prefix"
   	delimiter = "/"
   	ignore_default = no
   	ignore_null = no
   }
   # Loading module "suffix" from file /etc/freeradius/mods-enabled/realm
   realm suffix {
   	format = "suffix"
   	delimiter = "@"
   	ignore_default = no
   	ignore_null = no
   }
   # Loading module "realmpercent" from file 
/etc/freeradius/mods-enabled/realm
   realm realmpercent {
   	format = "suffix"
   	delimiter = "%"
   	ignore_default = no
   	ignore_null = no
   }
   # Loading module "ntdomain" from file 
/etc/freeradius/mods-enabled/realm
   realm ntdomain {
   	format = "prefix"
   	delimiter = "\\"
   	ignore_default = no
   	ignore_null = no
   }
   # Loaded module rlm_detail
   # Loading module "detail" from file 
/etc/freeradius/mods-enabled/detail
   detail {
   	filename = 
"/var/log/freeradius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d"
   	header = "%t"
   	permissions = 384
   	locking = no
   	escape_filenames = no
   	log_packet_header = no
   }
   # Loaded module rlm_sqlippool
   # Loading module "dhcp_sqlippool" from file 
/etc/freeradius/mods-enabled/dhcp_sqlippool
   sqlippool dhcp_sqlippool {
   	sql_module_instance = "sql"
   	lease_duration = 7200
   	pool_name = ""
   	default_pool = "main_pool"
   	allocate_begin = "START TRANSACTION"
   	allocate_clear = "UPDATE radippool SET nasipaddress = '', pool_key = 
0, callingstationid = '', username = '', expiry_time = NOW() WHERE 
expiry_time <= NOW() - INTERVAL 1 SECOND AND nasipaddress = 
'%{Nas-IP-Address}'"
   	allocate_find = "SELECT framedipaddress FROM radippool WHERE 
pool_name = '%{control:Pool-Name}' AND (callingstationid = 
'%{Calling-Station-Id}' or callingstationid = '') ORDER BY 
(callingstationid <> '%{Calling-Station-Id}'), expiry_time LIMIT 1 FOR 
UPDATE"
   	allocate_update = "UPDATE radippool SET nasipaddress = 
'%{NAS-IP-Address}', pool_key = '%{Calling-Station-Id}', 
callingstationid = '%{Calling-Station-Id}', username = '%{User-Name}', 
expiry_time = NOW() + INTERVAL 7200 SECOND WHERE framedipaddress = '%I'"
   	allocate_commit = "COMMIT"
   	pool_check = "SELECT id FROM radippool WHERE 
pool_name='%{control:Pool-Name}' LIMIT 1"
   	start_begin = "START TRANSACTION"
   	start_update = "UPDATE radippool SET expiry_time = NOW() + INTERVAL 
7200 SECOND WHERE nasipaddress = '%{NAS-IP-Address}' AND pool_key = 
'%{Calling-Station-Id}' AND username = '%{User-Name}' AND 
callingstationid = '%{Calling-Station-Id}' AND framedipaddress = 
'%{Framed-IP-Address}'"
   	start_commit = "COMMIT"
   	alive_begin = "START TRANSACTION"
   	alive_update = "UPDATE radippool SET expiry_time = NOW() + INTERVAL 
7200 SECOND WHERE nasipaddress = '%{Nas-IP-Address}' AND pool_key = 
'%{Calling-Station-Id}' AND username = '%{User-Name}' AND 
callingstationid = '%{Calling-Station-Id}' AND framedipaddress = 
'%{Framed-IP-Address}'"
   	alive_commit = "COMMIT"
   	stop_begin = "START TRANSACTION"
   	stop_clear = "UPDATE radippool 	SET 		nasipaddress = '', 		pool_key = 
0, 		callingstationid = '', 		username = '', 		expiry_time = NOW() 
	WHERE nasipaddress = '%{Nas-IP-Address}' 	AND pool_key = 
'%{Calling-Station-Id}' 	AND username = '%{User-Name}' 	AND 
callingstationid = '%{Calling-Station-Id}' 	AND framedipaddress = 
'%{Framed-IP-Address}'"
   	stop_commit = "COMMIT"
   	on_begin = "START TRANSACTION"
   	on_clear = "UPDATE radippool SET nasipaddress = '', pool_key = 0, 
callingstationid = '', username = '', expiry_time = NOW() WHERE 
nasipaddress = '%{Nas-IP-Address}'"
   	on_commit = "COMMIT"
   	off_begin = "START TRANSACTION"
   	off_clear = "UPDATE radippool SET nasipaddress = '', pool_key = 0, 
callingstationid = '', username = '', expiry_time = NOW() WHERE 
nasipaddress = '%{Nas-IP-Address}'"
   	off_commit = "COMMIT"
    messages {
    }
   }
   # Loading module "auth_log" from file 
/etc/freeradius/mods-enabled/detail.log
   detail auth_log {
   	filename = 
"/var/log/freeradius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d"
   	header = "%t"
   	permissions = 384
   	locking = no
   	escape_filenames = no
   	log_packet_header = no
   }
   # Loading module "reply_log" from file 
/etc/freeradius/mods-enabled/detail.log
   detail reply_log {
   	filename = 
"/var/log/freeradius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/reply-detail-%Y%m%d"
   	header = "%t"
   	permissions = 384
   	locking = no
   	escape_filenames = no
   	log_packet_header = no
   }
   # Loading module "pre_proxy_log" from file 
/etc/freeradius/mods-enabled/detail.log
   detail pre_proxy_log {
   	filename = 
"/var/log/freeradius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/pre-proxy-detail-%Y%m%d"
   	header = "%t"
   	permissions = 384
   	locking = no
   	escape_filenames = no
   	log_packet_header = no
   }
   # Loading module "post_proxy_log" from file 
/etc/freeradius/mods-enabled/detail.log
   detail post_proxy_log {
   	filename = 
"/var/log/freeradius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/post-proxy-detail-%Y%m%d"
   	header = "%t"
   	permissions = 384
   	locking = no
   	escape_filenames = no
   	log_packet_header = no
   }
   # Loaded module rlm_exec
   # Loading module "ntlm_auth" from file 
/etc/freeradius/mods-enabled/ntlm_auth
   exec ntlm_auth {
   	wait = yes
   	program = "/path/to/ntlm_auth --request-nt-key --domain=MYDOMAIN 
--username=%{mschap:User-Name} --password=%{User-Password}"
   	shell_escape = yes
   }
   # Loaded module rlm_mschap
   # Loading module "mschap" from file 
/etc/freeradius/mods-enabled/mschap
   mschap {
   	use_mppe = yes
   	require_encryption = no
   	require_strong = no
   	with_ntdomain_hack = yes
    passchange {
    }
   	allow_retry = yes
   }
   # Loaded module rlm_always
   # Loading module "reject" from file 
/etc/freeradius/mods-enabled/always
   always reject {
   	rcode = "reject"
   	simulcount = 0
   	mpp = no
   }
   # Loading module "fail" from file /etc/freeradius/mods-enabled/always
   always fail {
   	rcode = "fail"
   	simulcount = 0
   	mpp = no
   }
   # Loading module "ok" from file /etc/freeradius/mods-enabled/always
   always ok {
   	rcode = "ok"
   	simulcount = 0
   	mpp = no
   }
   # Loading module "handled" from file 
/etc/freeradius/mods-enabled/always
   always handled {
   	rcode = "handled"
   	simulcount = 0
   	mpp = no
   }
   # Loading module "invalid" from file 
/etc/freeradius/mods-enabled/always
   always invalid {
   	rcode = "invalid"
   	simulcount = 0
   	mpp = no
   }
   # Loading module "userlock" from file 
/etc/freeradius/mods-enabled/always
   always userlock {
   	rcode = "userlock"
   	simulcount = 0
   	mpp = no
   }
   # Loading module "notfound" from file 
/etc/freeradius/mods-enabled/always
   always notfound {
   	rcode = "notfound"
   	simulcount = 0
   	mpp = no
   }
   # Loading module "noop" from file /etc/freeradius/mods-enabled/always
   always noop {
   	rcode = "noop"
   	simulcount = 0
   	mpp = no
   }
   # Loading module "updated" from file 
/etc/freeradius/mods-enabled/always
   always updated {
   	rcode = "updated"
   	simulcount = 0
   	mpp = no
   }
   # Loaded module rlm_dynamic_clients
   # Loading module "dynamic_clients" from file 
/etc/freeradius/mods-enabled/dynamic_clients
   # Loading module "echo" from file /etc/freeradius/mods-enabled/echo
   exec echo {
   	wait = yes
   	program = "/bin/echo %{User-Name}"
   	input_pairs = "request"
   	output_pairs = "reply"
   	shell_escape = yes
   }
   # Loaded module rlm_expr
   # Loading module "expr" from file /etc/freeradius/mods-enabled/expr
   expr {
   	safe_characters = 
"@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: 
/äéöüàâæçèéêëîïôœùûüaÿÄÉÖÜßÀÂÆÇÈÉÊËÎÏÔŒÙÛÜŸ"
   }
   # Loaded module rlm_utf8
   # Loading module "utf8" from file /etc/freeradius/mods-enabled/utf8
   # Loaded module rlm_eap
   # Loading module "eap" from file /etc/freeradius/mods-enabled/eap
   eap {
   	default_eap_type = "gtc"
   	timer_expire = 60
   	ignore_unknown_eap_types = no
   	cisco_accounting_username_bug = no
   	max_sessions = 16384
   }
   # Loaded module rlm_pap
   # Loading module "pap" from file /etc/freeradius/mods-enabled/pap
   pap {
   	normalise = yes
   }
   # Loading module "exec" from file /etc/freeradius/mods-enabled/exec
   exec {
   	wait = no
   	input_pairs = "request"
   	shell_escape = yes
   	timeout = 10
   }
   instantiate {
   }
   # Instantiating module "files" from file 
/etc/freeradius/mods-enabled/files
reading pairlist file /etc/freeradius/mods-config/files/authorize
reading pairlist file /etc/freeradius/mods-config/files/accounting
reading pairlist file /etc/freeradius/mods-config/files/pre-proxy
   # Instantiating module "logintime" from file 
/etc/freeradius/mods-enabled/logintime
   # Instantiating module "expiration" from file 
/etc/freeradius/mods-enabled/expiration
   # Instantiating module "sql" from file 
/etc/freeradius/mods-enabled/sql
rlm_sql_mysql: libmysql version: 5.5.49
    mysql {
     tls {
     }
    	warnings = "auto"
    }
rlm_sql (sql): Attempting to connect to database "radius"
rlm_sql (sql): Initialising connection pool
    pool {
    	start = 5
    	min = 3
    	max = 32
    	spare = 10
    	uses = 0
    	lifetime = 0
    	cleanup_interval = 30
    	idle_timeout = 60
    	retry_delay = 30
    	spread = no
    }
rlm_sql (sql): Opening additional connection (0), 1 of 32 pending slots 
used
rlm_sql_mysql: Starting connect to MySQL server
rlm_sql_mysql: Connected to database 'radius' on Localhost via UNIX 
socket, server version 5.5.5-10.0.25-MariaDB-0+deb8u1, protocol version 
10
rlm_sql (sql): Opening additional connection (1), 1 of 31 pending slots 
used
rlm_sql_mysql: Starting connect to MySQL server
rlm_sql_mysql: Connected to database 'radius' on Localhost via UNIX 
socket, server version 5.5.5-10.0.25-MariaDB-0+deb8u1, protocol version 
10
rlm_sql (sql): Opening additional connection (2), 1 of 30 pending slots 
used
rlm_sql_mysql: Starting connect to MySQL server
rlm_sql_mysql: Connected to database 'radius' on Localhost via UNIX 
socket, server version 5.5.5-10.0.25-MariaDB-0+deb8u1, protocol version 
10
rlm_sql (sql): Opening additional connection (3), 1 of 29 pending slots 
used
rlm_sql_mysql: Starting connect to MySQL server
rlm_sql_mysql: Connected to database 'radius' on Localhost via UNIX 
socket, server version 5.5.5-10.0.25-MariaDB-0+deb8u1, protocol version 
10
rlm_sql (sql): Opening additional connection (4), 1 of 28 pending slots 
used
rlm_sql_mysql: Starting connect to MySQL server
rlm_sql_mysql: Connected to database 'radius' on Localhost via UNIX 
socket, server version 5.5.5-10.0.25-MariaDB-0+deb8u1, protocol version 
10
   # Instantiating module "cache_eap" from file 
/etc/freeradius/mods-enabled/cache_eap
rlm_cache (cache_eap): Driver rlm_cache_rbtree (module rlm_cache_rbtree) 
loaded and linked
   # Instantiating module "linelog" from file 
/etc/freeradius/mods-enabled/linelog
   # Instantiating module "log_accounting" from file 
/etc/freeradius/mods-enabled/linelog
   # Instantiating module "attr_filter.post-proxy" from file 
/etc/freeradius/mods-enabled/attr_filter
reading pairlist file /etc/freeradius/mods-config/attr_filter/post-proxy
   # Instantiating module "attr_filter.pre-proxy" from file 
/etc/freeradius/mods-enabled/attr_filter
reading pairlist file /etc/freeradius/mods-config/attr_filter/pre-proxy
   # Instantiating module "attr_filter.access_reject" from file 
/etc/freeradius/mods-enabled/attr_filter
reading pairlist file 
/etc/freeradius/mods-config/attr_filter/access_reject
[/etc/freeradius/mods-config/attr_filter/access_reject]:11 Check item 
"FreeRADIUS-Response-Delay" 	found in filter list for realm "DEFAULT".
[/etc/freeradius/mods-config/attr_filter/access_reject]:11 Check item 
"FreeRADIUS-Response-Delay-USec" 	found in filter list for realm 
"DEFAULT".
   # Instantiating module "attr_filter.access_challenge" from file 
/etc/freeradius/mods-enabled/attr_filter
reading pairlist file 
/etc/freeradius/mods-config/attr_filter/access_challenge
   # Instantiating module "attr_filter.accounting_response" from file 
/etc/freeradius/mods-enabled/attr_filter
reading pairlist file 
/etc/freeradius/mods-config/attr_filter/accounting_response
   # Instantiating module "etc_passwd" from file 
/etc/freeradius/mods-enabled/passwd
rlm_passwd: nfields: 3 keyfield 0(User-Name) listable: no
   # Instantiating module "preprocess" from file 
/etc/freeradius/mods-enabled/preprocess
reading pairlist file /etc/freeradius/mods-config/preprocess/huntgroups
reading pairlist file /etc/freeradius/mods-config/preprocess/hints
   # Instantiating module "IPASS" from file 
/etc/freeradius/mods-enabled/realm
   # Instantiating module "suffix" from file 
/etc/freeradius/mods-enabled/realm
   # Instantiating module "realmpercent" from file 
/etc/freeradius/mods-enabled/realm
   # Instantiating module "ntdomain" from file 
/etc/freeradius/mods-enabled/realm
   # Instantiating module "detail" from file 
/etc/freeradius/mods-enabled/detail
   # Instantiating module "dhcp_sqlippool" from file 
/etc/freeradius/mods-enabled/dhcp_sqlippool
   # Instantiating module "auth_log" from file 
/etc/freeradius/mods-enabled/detail.log
rlm_detail (auth_log): 'User-Password' suppressed, will not appear in 
detail output
   # Instantiating module "reply_log" from file 
/etc/freeradius/mods-enabled/detail.log
   # Instantiating module "pre_proxy_log" from file 
/etc/freeradius/mods-enabled/detail.log
   # Instantiating module "post_proxy_log" from file 
/etc/freeradius/mods-enabled/detail.log
   # Instantiating module "mschap" from file 
/etc/freeradius/mods-enabled/mschap
rlm_mschap (mschap): using internal authentication
   # Instantiating module "reject" from file 
/etc/freeradius/mods-enabled/always
   # Instantiating module "fail" from file 
/etc/freeradius/mods-enabled/always
   # Instantiating module "ok" from file 
/etc/freeradius/mods-enabled/always
   # Instantiating module "handled" from file 
/etc/freeradius/mods-enabled/always
   # Instantiating module "invalid" from file 
/etc/freeradius/mods-enabled/always
   # Instantiating module "userlock" from file 
/etc/freeradius/mods-enabled/always
   # Instantiating module "notfound" from file 
/etc/freeradius/mods-enabled/always
   # Instantiating module "noop" from file 
/etc/freeradius/mods-enabled/always
   # Instantiating module "updated" from file 
/etc/freeradius/mods-enabled/always
   # Instantiating module "eap" from file 
/etc/freeradius/mods-enabled/eap
    # Linked to sub-module rlm_eap_md5
    # Linked to sub-module rlm_eap_leap
    # Linked to sub-module rlm_eap_gtc
    gtc {
    	challenge = "Password: "
    	auth_type = "PAP"
    }
    # Linked to sub-module rlm_eap_tls
    tls {
    	tls = "tls-common"
    }
    tls-config tls-common {
    	verify_depth = 0
    	ca_path = "/etc/freeradius/certs"
    	pem_file_type = yes
    	private_key_file = "/etc/freeradius/certs/server.pem"
    	certificate_file = "/etc/freeradius/certs/server.pem"
    	ca_file = "/etc/freeradius/certs/ca.pem"
    	private_key_password = <<< secret >>>
    	dh_file = "/etc/freeradius/certs/dh"
    	fragment_size = 1024
    	include_length = yes
    	auto_chain = yes
    	check_crl = no
    	check_all_crl = no
    	cipher_list = "DEFAULT"
    	ecdh_curve = "prime256v1"
     cache {
     	enable = yes
     	lifetime = 24
     	max_entries = 255
     }
     verify {
     	skip_if_ocsp_ok = no
     }
     ocsp {
     	enable = no
     	override_cert_url = yes
     	url = "http://127.0.0.1/ocsp/"
     	use_nonce = yes
     	timeout = 0
     	softfail = no
     }
    }
    # Linked to sub-module rlm_eap_ttls
    ttls {
    	tls = "tls-common"
    	default_eap_type = "md5"
    	copy_request_to_tunnel = no
    	use_tunneled_reply = no
    	virtual_server = "inner-tunnel"
    	include_length = yes
    	require_client_cert = no
    }
tls: Using cached TLS configuration from previous invocation
    # Linked to sub-module rlm_eap_peap
    peap {
    	tls = "tls-common"
    	default_eap_type = "mschapv2"
    	copy_request_to_tunnel = no
    	use_tunneled_reply = no
    	proxy_tunneled_request_as_eap = yes
    	virtual_server = "inner-tunnel"
    	soh = no
    	require_client_cert = no
    }
tls: Using cached TLS configuration from previous invocation
    # Linked to sub-module rlm_eap_mschapv2
    mschapv2 {
    	with_ntdomain_hack = no
    	send_error = no
    }
   # Instantiating module "pap" from file 
/etc/freeradius/mods-enabled/pap
  } # modules
radiusd: #### Loading Virtual Servers ####
server { # from file /etc/freeradius/radiusd.conf
} # server
server inner-tunnel { # from file 
/etc/freeradius/sites-enabled/inner-tunnel
  # Loading authenticate {...}
  # Loading authorize {...}
Ignoring "ldap" (see raddb/mods-available/README.rst)
  # Loading session {...}
  # Loading post-proxy {...}
  # Loading post-auth {...}
} # server inner-tunnel
server dhcp { # from file /etc/freeradius/sites-enabled/dhcp
  # Loading dhcp DHCP-Discover {...}
  # Loading dhcp DHCP-Request {...}
  # Loading dhcp DHCP-Decline {...}
  # Loading dhcp DHCP-Inform {...}
  # Loading dhcp DHCP-Release {...}
  # Loading dhcp DHCP-Lease-Query {...}
} # server dhcp
server default { # from file /etc/freeradius/sites-enabled/default
  # Loading authenticate {...}
  # Loading authorize {...}
  # Loading preacct {...}
  # Loading accounting {...}
  # Loading session {...}
  # Loading post-proxy {...}
  # Loading post-auth {...}
} # server default
radiusd: #### Opening IP addresses and Ports ####
listen {
   	type = "auth"
   	ipaddr = 127.0.0.1
   	port = 18120
}
listen {
   	type = "dhcp"
   	ipaddr = 10.1.0.1
   	port = 6700
No "interface" setting is defined.  Only unicast DHCP will work
   	src_ipaddr = 10.1.0.1
}
listen {
   	type = "auth"
   	ipaddr = 10.1.0.1
   	port = 1812
    limit {
    	max_connections = 16
    	lifetime = 0
    	idle_timeout = 30
    }
}
listen {
   	type = "acct"
   	ipaddr = 10.1.0.1
   	port = 1813
    limit {
    	max_connections = 16
    	lifetime = 0
    	idle_timeout = 30
    }
}
Listening on auth address 127.0.0.1 port 18120 bound to server 
inner-tunnel
Listening on dhcp address 10.1.0.1 port 6700 bound to server dhcp
Listening on auth address 10.1.0.1 port 1812 bound to server default
Listening on acct address 10.1.0.1 port 1813 bound to server default
Listening on proxy address * port 35198
Ready to process requests
(0) Received Access-Request Id 163 from 10.1.0.1:53996 to 10.1.0.1:1812 
length 158
(0)   User-Name = "laurens7"
(0)   NAS-Port-Type = Virtual
(0)   Service-Type = Framed-User
(0)   NAS-Port = 97
(0)   NAS-Port-Id = "iOS-radius"
(0)   NAS-IP-Address = 1.2.3.4
(0)   Called-Station-Id = "1.2.3.4[4500]"
(0)   Calling-Station-Id = "70.69.64.246[47355]"
(0)   EAP-Message = 0x0200000d016c617572656e7337
(0)   NAS-Identifier = "ipsec-gateway"
(0)   Message-Authenticator = 0x1870e852bdfc9431862850bd5554aaaf
(0) # Executing section authorize from file 
/etc/freeradius/sites-enabled/default
(0)   authorize {
(0)     policy filter_username {
(0)       if (&User-Name) {
(0)       if (&User-Name)  -> TRUE
(0)       if (&User-Name)  {
(0)         if (&User-Name =~ / /) {
(0)         if (&User-Name =~ / /)  -> FALSE
(0)         if (&User-Name =~ /@[^@]*@/ ) {
(0)         if (&User-Name =~ /@[^@]*@/ )  -> FALSE
(0)         if (&User-Name =~ /\.\./ ) {
(0)         if (&User-Name =~ /\.\./ )  -> FALSE
(0)         if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))  
{
(0)         if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))   
-> FALSE
(0)         if (&User-Name =~ /\.$/)  {
(0)         if (&User-Name =~ /\.$/)   -> FALSE
(0)         if (&User-Name =~ /@\./)  {
(0)         if (&User-Name =~ /@\./)   -> FALSE
(0)       } # if (&User-Name)  = notfound
(0)     } # policy filter_username = notfound
(0)     [preprocess] = ok
(0)     [chap] = noop
(0)     [mschap] = noop
(0)     [digest] = noop
(0) suffix: Checking for suffix after "@"
(0) suffix: No '@' in User-Name = "laurens7", looking up realm NULL
(0) suffix: No such realm "NULL"
(0)     [suffix] = noop
(0) eap: Peer sent EAP Response (code 2) ID 0 length 13
(0) eap: EAP-Identity reply, returning 'ok' so we can short-circuit the 
rest of authorize
(0)     [eap] = ok
(0)   } # authorize = ok
(0) Found Auth-Type = eap
(0) # Executing group from file /etc/freeradius/sites-enabled/default
(0)   authenticate {
(0) eap: Peer sent packet with method EAP Identity (1)
(0) eap: Calling submodule eap_gtc to process data
(0) eap_gtc: EXPAND Password:
(0) eap_gtc:    --> Password:
(0) eap: Sending EAP Request (code 1) ID 1 length 15
(0) eap: EAP session adding &reply:State = 0xa1674e02a16648ba
(0)     [eap] = handled
(0)   } # authenticate = handled
(0) Using Post-Auth-Type Challenge
(0) Post-Auth-Type sub-section not found.  Ignoring.
(0) # Executing group from file /etc/freeradius/sites-enabled/default
(0) Sent Access-Challenge Id 163 from 10.1.0.1:1812 to 10.1.0.1:53996 
length 0
(0)   EAP-Message = 0x0101000f0650617373776f72643a20
(0)   Message-Authenticator = 0x00000000000000000000000000000000
(0)   State = 0xa1674e02a16648bae738fa2a9a19a960
(0) Finished request
Waking up in 4.9 seconds.
(1) Received Access-Request Id 164 from 10.1.0.1:53996 to 10.1.0.1:1812 
length 176
(1)   User-Name = "laurens7"
(1)   NAS-Port-Type = Virtual
(1)   Service-Type = Framed-User
(1)   NAS-Port = 97
(1)   NAS-Port-Id = "iOS-radius"
(1)   NAS-IP-Address = 1.2.3.4
(1)   Called-Station-Id = "1.2.3.4[4500]"
(1)   Calling-Station-Id = "70.69.64.246[47355]"
(1)   EAP-Message = 0x0201000d066c617572656e7337
(1)   NAS-Identifier = "ipsec-gateway"
(1)   State = 0xa1674e02a16648bae738fa2a9a19a960
(1)   Message-Authenticator = 0xa7349a1a96a746375d190389d9ad7d78
(1) session-state: No cached attributes
(1) # Executing section authorize from file 
/etc/freeradius/sites-enabled/default
(1)   authorize {
(1)     policy filter_username {
(1)       if (&User-Name) {
(1)       if (&User-Name)  -> TRUE
(1)       if (&User-Name)  {
(1)         if (&User-Name =~ / /) {
(1)         if (&User-Name =~ / /)  -> FALSE
(1)         if (&User-Name =~ /@[^@]*@/ ) {
(1)         if (&User-Name =~ /@[^@]*@/ )  -> FALSE
(1)         if (&User-Name =~ /\.\./ ) {
(1)         if (&User-Name =~ /\.\./ )  -> FALSE
(1)         if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))  
{
(1)         if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))   
-> FALSE
(1)         if (&User-Name =~ /\.$/)  {
(1)         if (&User-Name =~ /\.$/)   -> FALSE
(1)         if (&User-Name =~ /@\./)  {
(1)         if (&User-Name =~ /@\./)   -> FALSE
(1)       } # if (&User-Name)  = notfound
(1)     } # policy filter_username = notfound
(1)     [preprocess] = ok
(1)     [chap] = noop
(1)     [mschap] = noop
(1)     [digest] = noop
(1) suffix: Checking for suffix after "@"
(1) suffix: No '@' in User-Name = "laurens7", looking up realm NULL
(1) suffix: No such realm "NULL"
(1)     [suffix] = noop
(1) eap: Peer sent EAP Response (code 2) ID 1 length 13
(1) eap: No EAP Start, assuming it's an on-going EAP conversation
(1)     [eap] = updated
(1) files: users: Matched entry DEFAULT at line 47
(1)     [files] = ok
(1) sql: EXPAND %{User-Name}
(1) sql:    --> laurens7
(1) sql: SQL-User-Name set to 'laurens7'
rlm_sql (sql): Reserved connection (0)
(1) sql: EXPAND SELECT id, username, attribute, value, op FROM radcheck 
WHERE username = '%{SQL-User-Name}' ORDER BY id
(1) sql:    --> SELECT id, username, attribute, value, op FROM radcheck 
WHERE username = 'laurens7' ORDER BY id
(1) sql: Executing select query: SELECT id, username, attribute, value, 
op FROM radcheck WHERE username = 'laurens7' ORDER BY id
(1) sql: User found in radcheck table
(1) sql: Conditional check items matched, merging assignment check items
(1) sql:   SSHA2-512-Password := 
0xa6183fa9bbd706c6731acf41e9218c9ab94867b4b337e3d6fad29027a566a57e914fc4c82a7c5fe7fb7b1945f59c8c37151deb0f7ef5cd3aab53f18e0ead3df8
(1) sql: EXPAND SELECT id, username, attribute, value, op FROM radreply 
WHERE username = '%{SQL-User-Name}' ORDER BY id
(1) sql:    --> SELECT id, username, attribute, value, op FROM radreply 
WHERE username = 'laurens7' ORDER BY id
(1) sql: Executing select query: SELECT id, username, attribute, value, 
op FROM radreply WHERE username = 'laurens7' ORDER BY id
(1) sql: EXPAND SELECT groupname FROM radusergroup WHERE username = 
'%{SQL-User-Name}' ORDER BY priority
(1) sql:    --> SELECT groupname FROM radusergroup WHERE username = 
'laurens7' ORDER BY priority
(1) sql: Executing select query: SELECT groupname FROM radusergroup 
WHERE username = 'laurens7' ORDER BY priority
(1) sql: User not found in any groups
rlm_sql (sql): Released connection (0)
rlm_sql (sql): Need 5 more connections to reach 10 spares
rlm_sql (sql): Opening additional connection (5), 1 of 27 pending slots 
used
rlm_sql_mysql: Starting connect to MySQL server
rlm_sql_mysql: Connected to database 'radius' on Localhost via UNIX 
socket, server version 5.5.5-10.0.25-MariaDB-0+deb8u1, protocol version 
10
(1)     [sql] = ok
(1)     [expiration] = noop
(1)     [logintime] = noop
(1) pap: WARNING: Auth-Type already set.  Not setting to PAP
(1)     [pap] = noop
(1)   } # authorize = updated
(1) Found Auth-Type = eap
(1) # Executing group from file /etc/freeradius/sites-enabled/default
(1)   authenticate {
(1) eap: Expiring EAP session with state 0xa1674e02a16648ba
(1) eap: Finished EAP session with state 0xa1674e02a16648ba
(1) eap: Previous EAP request found for state 0xa1674e02a16648ba, 
released from the list
(1) eap: Peer sent packet with method EAP GTC (6)
(1) eap: Calling submodule eap_gtc to process data
(1) eap_gtc: # Executing group from file 
/etc/freeradius/sites-enabled/default
(1) eap_gtc:   Auth-Type PAP {
(1) pap: Login attempt with password
(1) pap: Comparing with "known-good" SSHA2-512-Password
(1) pap: ERROR: "known-good" SSHA2-512-Password has incorrect length, 
got 64 bytes, need at least 65 bytes
(1)     [pap] = invalid
(1)   } # Auth-Type PAP = invalid
(1) eap: ERROR: Failed continuing EAP GTC (6) session.  EAP sub-module 
failed
(1) eap: Sending EAP Failure (code 4) ID 1 length 4
(1) eap: Failed in EAP select
(1)     [eap] = invalid
(1)   } # authenticate = invalid
(1) Failed to authenticate the user
(1) Using Post-Auth-Type Reject
(1) # Executing group from file /etc/freeradius/sites-enabled/default
(1)   Post-Auth-Type REJECT {
(1) sql: EXPAND .query
(1) sql:    --> .query
(1) sql: Using query template 'query'
rlm_sql (sql): Reserved connection (1)
(1) sql: EXPAND %{User-Name}
(1) sql:    --> laurens7
(1) sql: SQL-User-Name set to 'laurens7'
(1) sql: EXPAND INSERT INTO radpostauth (username, pass, reply, 
authdate) VALUES ( '%{SQL-User-Name}', 
'%{%{User-Password}:-%{Chap-Password}}', '%{reply:Packet-Type}', '%S')
(1) sql:    --> INSERT INTO radpostauth (username, pass, reply, 
authdate) VALUES ( 'laurens7', 'laurens7', 'Access-Reject', '2016-09-08 
01:00:53')
(1) sql: Executing query: INSERT INTO radpostauth (username, pass, 
reply, authdate) VALUES ( 'laurens7', 'laurens7', 'Access-Reject', 
'2016-09-08 01:00:53')
(1) sql: SQL query returned: success
(1) sql: 1 record(s) updated
rlm_sql (sql): Released connection (1)
(1)     [sql] = ok
(1) attr_filter.access_reject: EXPAND %{User-Name}
(1) attr_filter.access_reject:    --> laurens7
(1) attr_filter.access_reject: Matched entry DEFAULT at line 11
(1)     [attr_filter.access_reject] = updated
(1)     [eap] = noop
(1)     policy remove_reply_message_if_eap {
(1)       if (&reply:EAP-Message && &reply:Reply-Message) {
(1)       if (&reply:EAP-Message && &reply:Reply-Message)  -> FALSE
(1)       else {
(1)         [noop] = noop
(1)       } # else = noop
(1)     } # policy remove_reply_message_if_eap = noop
(1)   } # Post-Auth-Type REJECT = updated
(1) Delaying response for 1.000000 seconds
Waking up in 0.1 seconds.
Waking up in 0.8 seconds.
(1) Sending delayed response
(1) Sent Access-Reject Id 164 from 10.1.0.1:1812 to 10.1.0.1:53996 
length 44
(1)   EAP-Message = 0x04010004
(1)   Message-Authenticator = 0x00000000000000000000000000000000
Waking up in 3.7 seconds.
(0) Cleaning up request packet ID 163 with timestamp +9
Waking up in 0.2 seconds.
(1) Cleaning up request packet ID 164 with timestamp +9
Ready to process requests

Debug #2:

Server was built with:
   accounting               : yes
   authentication           : yes
   ascend-binary-attributes : yes
   coa                      : yes
   control-socket           : yes
   detail                   : yes
   dhcp                     : yes
   dynamic-clients          : yes
   osfc2                    : no
   proxy                    : yes
   regex-pcre               : yes
   regex-posix              : no
   regex-posix-extended     : no
   session-management       : yes
   stats                    : yes
   tcp                      : yes
   threads                  : yes
   tls                      : yes
   unlang                   : yes
   vmps                     : yes
   developer                : no
Server core libs:
   freeradius-server        : 3.0.11
   talloc                   : 2.0.*
   ssl                      : 1.0.1t release
   pcre                     : 8.35 2014-04-04
Endianness:
   little
Compilation flags:
   cppflags : -D_FORTIFY_SOURCE=2
   cflags   : -I/home/laurens/freeradius3/freeradius-server-3.0.11 
-I/home/laurens/freeradius3/freeradius-server-3.0.11/src -include 
/home/laurens/freeradius3/freeradius-server-3.0.11/src/freeradius-devel/autoconf.h 
-include 
/home/laurens/freeradius3/freeradius-server-3.0.11/src/freeradius-devel/build.h 
-include 
/home/laurens/freeradius3/freeradius-server-3.0.11/src/freeradius-devel/features.h 
-include 
/home/laurens/freeradius3/freeradius-server-3.0.11/src/freeradius-devel/radpaths.h 
-fno-strict-aliasing -g -O2 -fstack-protector-strong -Wformat 
-Werror=format-security -O2 -Wall -std=c99 -D_GNU_SOURCE -D_REENTRANT 
-D_POSIX_PTHREAD_SEMANTICS -DOPENSSL_NO_KRB5 -DNDEBUG -DIS_MODULE=1
   ldflags  :  -Wl,-z,relro
   libs     : -lcrypto -lssl -ltalloc -lpcre -lcap -lnsl -lresolv -ldl 
-lpthread -lreadline

Copyright (C) 1999-2016 The FreeRADIUS server project and contributors
There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A
PARTICULAR PURPOSE
You may redistribute copies of FreeRADIUS under the terms of the
GNU General Public License
For more information about these matters, see the file named COPYRIGHT
Starting - reading configuration files ...
including dictionary file /usr/share/freeradius/dictionary
including dictionary file /usr/share/freeradius/dictionary.dhcp
including dictionary file /usr/share/freeradius/dictionary.vqp
including dictionary file /etc/freeradius/dictionary
including configuration file /etc/freeradius/radiusd.conf
including configuration file /etc/freeradius/proxy.conf
including configuration file /etc/freeradius/clients.conf
including files in directory /etc/freeradius/mods-enabled/
including configuration file /etc/freeradius/mods-enabled/files
including configuration file /etc/freeradius/mods-enabled/logintime
including configuration file /etc/freeradius/mods-enabled/radutmp
including configuration file /etc/freeradius/mods-enabled/expiration
including configuration file /etc/freeradius/mods-enabled/sql
including configuration file 
/etc/freeradius/mods-config/sql/main/mysql/queries.conf
including configuration file /etc/freeradius/mods-enabled/digest
including configuration file /etc/freeradius/mods-enabled/unpack
including configuration file /etc/freeradius/mods-enabled/cache_eap
including configuration file /etc/freeradius/mods-enabled/chap
including configuration file /etc/freeradius/mods-enabled/soh
including configuration file /etc/freeradius/mods-enabled/unix
including configuration file /etc/freeradius/mods-enabled/sradutmp
including configuration file /etc/freeradius/mods-enabled/linelog
including configuration file /etc/freeradius/mods-enabled/attr_filter
including configuration file /etc/freeradius/mods-enabled/passwd
including configuration file /etc/freeradius/mods-enabled/replicate
including configuration file /etc/freeradius/mods-enabled/preprocess
including configuration file /etc/freeradius/mods-enabled/realm
including configuration file /etc/freeradius/mods-enabled/detail
including configuration file /etc/freeradius/mods-enabled/dhcp_sqlippool
including configuration file 
/etc/freeradius/mods-config/sql/ippool-dhcp/mysql/queries.conf
including configuration file /etc/freeradius/mods-enabled/detail.log
including configuration file /etc/freeradius/mods-enabled/ntlm_auth
including configuration file /etc/freeradius/mods-enabled/mschap
including configuration file /etc/freeradius/mods-enabled/always
including configuration file 
/etc/freeradius/mods-enabled/dynamic_clients
including configuration file /etc/freeradius/mods-enabled/echo
including configuration file /etc/freeradius/mods-enabled/expr
including configuration file /etc/freeradius/mods-enabled/utf8
including configuration file /etc/freeradius/mods-enabled/eap
including configuration file /etc/freeradius/mods-enabled/pap
including configuration file /etc/freeradius/mods-enabled/exec
including files in directory /etc/freeradius/policy.d/
including configuration file /etc/freeradius/policy.d/debug
including configuration file /etc/freeradius/policy.d/cui
including configuration file /etc/freeradius/policy.d/filter
including configuration file /etc/freeradius/policy.d/canonicalization
including configuration file /etc/freeradius/policy.d/control
including configuration file /etc/freeradius/policy.d/accounting
including configuration file /etc/freeradius/policy.d/dhcp
including configuration file /etc/freeradius/policy.d/abfab-tr
including configuration file /etc/freeradius/policy.d/operator-name
including configuration file /etc/freeradius/policy.d/eap
including files in directory /etc/freeradius/sites-enabled/
including configuration file /etc/freeradius/sites-enabled/inner-tunnel
including configuration file /etc/freeradius/sites-enabled/dhcp
including configuration file /etc/freeradius/sites-enabled/default
main {
  security {
  	user = "freerad"
  	group = "freerad"
  	allow_core_dumps = no
  }
	name = "freeradius"
	prefix = "/usr"
	localstatedir = "/var"
	logdir = "/var/log/freeradius"
	run_dir = "/var/run/freeradius"
}
main {
	name = "freeradius"
	prefix = "/usr"
	localstatedir = "/var"
	sbindir = "/usr/sbin"
	logdir = "/var/log/freeradius"
	run_dir = "/var/run/freeradius"
	libdir = "/usr/lib/freeradius"
	radacctdir = "/var/log/freeradius/radacct"
	hostname_lookups = no
	max_request_time = 30
	cleanup_delay = 5
	max_requests = 16384
	pidfile = "/var/run/freeradius/freeradius.pid"
	checkrad = "/usr/sbin/checkrad"
	debug_level = 0
	proxy_requests = yes
  log {
  	stripped_names = no
  	auth = no
  	auth_badpass = no
  	auth_goodpass = no
  	colourise = yes
  	msg_denied = "You are already logged in - access denied"
  }
  resources {
  }
  security {
  	max_attributes = 200
  	reject_delay = 1.000000
  	status_server = yes
  }
}
radiusd: #### Loading Realms and Home Servers ####
  proxy server {
  	retry_delay = 5
  	retry_count = 3
  	default_fallback = no
  	dead_time = 120
  	wake_all_if_all_dead = no
  }
  home_server localhost {
  	ipaddr = 127.0.0.1
  	port = 1812
  	type = "auth"
  	secret = <<< secret >>>
  	response_window = 20.000000
  	response_timeouts = 1
  	max_outstanding = 65536
  	zombie_period = 40
  	status_check = "status-server"
  	ping_interval = 30
  	check_interval = 30
  	check_timeout = 4
  	num_answers_to_alive = 3
  	revive_interval = 120
   limit {
   	max_connections = 16
   	max_requests = 0
   	lifetime = 0
   	idle_timeout = 0
   }
   coa {
   	irt = 2
   	mrt = 16
   	mrc = 5
   	mrd = 30
   }
  }
  home_server_pool my_auth_failover {
	type = fail-over
	home_server = localhost
  }
  realm example.com {
	auth_pool = my_auth_failover
  }
  realm LOCAL {
  }
radiusd: #### Loading Clients ####
  client localhost {
  	ipaddr = 10.1.0.1
  	require_message_authenticator = no
  	secret = <<< secret >>>
  	nas_type = "other"
  	proto = "*"
   limit {
   	max_connections = 16
   	lifetime = 0
   	idle_timeout = 30
   }
  }
  client arali {
  	ipaddr = 188.40.47.70
  	require_message_authenticator = no
  	secret = <<< secret >>>
   limit {
   	max_connections = 16
   	lifetime = 0
   	idle_timeout = 30
   }
  }
Debugger not attached
  # Creating Auth-Type = PAP
  # Creating Auth-Type = CHAP
  # Creating Auth-Type = MS-CHAP
  # Creating Auth-Type = eap
  # Creating Auth-Type = digest
radiusd: #### Instantiating modules ####
  modules {
   # Loaded module rlm_files
   # Loading module "files" from file /etc/freeradius/mods-enabled/files
   files {
   	filename = "/etc/freeradius/mods-config/files/authorize"
   	acctusersfile = "/etc/freeradius/mods-config/files/accounting"
   	preproxy_usersfile = "/etc/freeradius/mods-config/files/pre-proxy"
   }
   # Loaded module rlm_logintime
   # Loading module "logintime" from file 
/etc/freeradius/mods-enabled/logintime
   logintime {
   	minimum_timeout = 60
   }
   # Loaded module rlm_radutmp
   # Loading module "radutmp" from file 
/etc/freeradius/mods-enabled/radutmp
   radutmp {
   	filename = "/var/log/freeradius/radutmp"
   	username = "%{User-Name}"
   	case_sensitive = yes
   	check_with_nas = yes
   	permissions = 384
   	caller_id = yes
   }
   # Loaded module rlm_expiration
   # Loading module "expiration" from file 
/etc/freeradius/mods-enabled/expiration
   # Loaded module rlm_sql
   # Loading module "sql" from file /etc/freeradius/mods-enabled/sql
   sql {
   	driver = "rlm_sql_mysql"
   	server = "localhost"
   	port = 3306
   	login = "radius"
   	password = <<< secret >>>
   	radius_db = "radius"
   	read_groups = yes
   	read_profiles = yes
   	read_clients = no
   	delete_stale_sessions = yes
   	sql_user_name = "%{User-Name}"
   	default_user_profile = ""
   	client_query = "SELECT id, nasname, shortname, type, secret, server 
FROM nas"
   	authorize_check_query = "SELECT id, username, attribute, value, op 
FROM radcheck WHERE username = '%{SQL-User-Name}' ORDER BY id"
   	authorize_reply_query = "SELECT id, username, attribute, value, op 
FROM radreply WHERE username = '%{SQL-User-Name}' ORDER BY id"
   	authorize_group_check_query = "SELECT id, groupname, attribute, 
Value, op FROM radgroupcheck WHERE groupname = '%{SQL-Group}' ORDER BY 
id"
   	authorize_group_reply_query = "SELECT id, groupname, attribute, 
value, op FROM radgroupreply WHERE groupname = '%{SQL-Group}' ORDER BY 
id"
   	group_membership_query = "SELECT groupname FROM radusergroup WHERE 
username = '%{SQL-User-Name}' ORDER BY priority"
   	simul_count_query = "SELECT COUNT(*) FROM radacct WHERE username = 
'%{SQL-User-Name}' AND acctstoptime IS NULL"
   	simul_verify_query = "SELECT radacctid, acctsessionid, username, 
nasipaddress, nasportid, framedipaddress, callingstationid, 
framedprotocol FROM radacct WHERE username = '%{SQL-User-Name}' AND 
acctstoptime IS NULL"
   	safe_characters = 
"@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /"
    accounting {
    	reference = "%{tolower:type.%{Acct-Status-Type}.query}"
     type {
      accounting-on {
      	query = "UPDATE radacct SET acctstoptime = 
FROM_UNIXTIME(%{integer:Event-Timestamp}), acctsessiontime	= 
'%{integer:Event-Timestamp}' - UNIX_TIMESTAMP(acctstarttime), 
acctterminatecause = '%{%{Acct-Terminate-Cause}:-NAS-Reboot}' WHERE 
acctstoptime IS NULL AND nasipaddress   = '%{NAS-IP-Address}' AND 
acctstarttime <= FROM_UNIXTIME(%{integer:Event-Timestamp})"
      }
      accounting-off {
      	query = "UPDATE radacct SET acctstoptime = 
FROM_UNIXTIME(%{integer:Event-Timestamp}), acctsessiontime	= 
'%{integer:Event-Timestamp}' - UNIX_TIMESTAMP(acctstarttime), 
acctterminatecause = '%{%{Acct-Terminate-Cause}:-NAS-Reboot}' WHERE 
acctstoptime IS NULL AND nasipaddress   = '%{NAS-IP-Address}' AND 
acctstarttime <= FROM_UNIXTIME(%{integer:Event-Timestamp})"
      }
      start {
      	query = "INSERT INTO radacct 
(acctsessionid,		acctuniqueid,		username, 
realm,			nasipaddress,		nasportid, 
nasporttype,		acctstarttime,		acctupdatetime, 
acctstoptime,		acctsessiontime, 	acctauthentic, 
connectinfo_start,	connectinfo_stop, 	acctinputoctets, 
acctoutputoctets,	calledstationid, 	callingstationid, 
acctterminatecause,	servicetype,		framedprotocol, framedipaddress) 
VALUES ('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}', 
'%{SQL-User-Name}', '%{Realm}', '%{NAS-IP-Address}', 
'%{%{NAS-Port-ID}:-%{NAS-Port}}', '%{NAS-Port-Type}', 
FROM_UNIXTIME(%{integer:Event-Timestamp}), 
FROM_UNIXTIME(%{integer:Event-Timestamp}), NULL, '0', 
'%{Acct-Authentic}', '%{Connect-Info}', '', '0', '0', 
'%{Called-Station-Id}', '%{Calling-Station-Id}', '', '%{Service-Type}', 
'%{Framed-Protocol}', '%{Framed-IP-Address}')"
      }
      interim-update {
      	query = "UPDATE radacct SET acctupdatetime  = 
(@acctupdatetime_old:=acctupdatetime), acctupdatetime  = 
FROM_UNIXTIME(%{integer:Event-Timestamp}), acctinterval    = 
%{integer:Event-Timestamp} - UNIX_TIMESTAMP(@acctupdatetime_old), 
framedipaddress = '%{Framed-IP-Address}', acctsessiontime = 
%{%{Acct-Session-Time}:-NULL}, acctinputoctets = 
'%{%{Acct-Input-Gigawords}:-0}' << 32 | '%{%{Acct-Input-Octets}:-0}', 
acctoutputoctets = '%{%{Acct-Output-Gigawords}:-0}' << 32 | 
'%{%{Acct-Output-Octets}:-0}' WHERE AcctUniqueId = 
'%{Acct-Unique-Session-Id}'"
      }
      stop {
      	query = "UPDATE radacct SET acctstoptime	= 
FROM_UNIXTIME(%{integer:Event-Timestamp}), acctsessiontime	= 
%{%{Acct-Session-Time}:-NULL}, acctinputoctets	= 
'%{%{Acct-Input-Gigawords}:-0}' << 32 | '%{%{Acct-Input-Octets}:-0}', 
acctoutputoctets = '%{%{Acct-Output-Gigawords}:-0}' << 32 | 
'%{%{Acct-Output-Octets}:-0}', acctterminatecause = 
'%{Acct-Terminate-Cause}', connectinfo_stop = '%{Connect-Info}' WHERE 
AcctUniqueId = '%{Acct-Unique-Session-Id}'"
      }
     }
    }
    post-auth {
    	reference = ".query"
    	query = "INSERT INTO radpostauth (username, pass, reply, authdate) 
VALUES ( '%{SQL-User-Name}', '%{%{User-Password}:-%{Chap-Password}}', 
'%{reply:Packet-Type}', '%S')"
    }
   }
rlm_sql (sql): Driver rlm_sql_mysql (module rlm_sql_mysql) loaded and 
linked
Creating attribute SQL-Group
   # Loaded module rlm_digest
   # Loading module "digest" from file 
/etc/freeradius/mods-enabled/digest
   # Loaded module rlm_unpack
   # Loading module "unpack" from file 
/etc/freeradius/mods-enabled/unpack
   # Loaded module rlm_cache
   # Loading module "cache_eap" from file 
/etc/freeradius/mods-enabled/cache_eap
   cache cache_eap {
   	driver = "rlm_cache_rbtree"
   	key = "%{%{control:State}:-%{%{reply:State}:-%{State}}}"
   	ttl = 15
   	max_entries = 0
   	epoch = 0
   	add_stats = no
   }
   # Loaded module rlm_chap
   # Loading module "chap" from file /etc/freeradius/mods-enabled/chap
   # Loaded module rlm_soh
   # Loading module "soh" from file /etc/freeradius/mods-enabled/soh
   soh {
   	dhcp = yes
   }
   # Loaded module rlm_unix
   # Loading module "unix" from file /etc/freeradius/mods-enabled/unix
   unix {
   	radwtmp = "/var/log/freeradius/radwtmp"
   }
Creating attribute Unix-Group
   # Loading module "sradutmp" from file 
/etc/freeradius/mods-enabled/sradutmp
   radutmp sradutmp {
   	filename = "/var/log/freeradius/sradutmp"
   	username = "%{User-Name}"
   	case_sensitive = yes
   	check_with_nas = yes
   	permissions = 420
   	caller_id = no
   }
   # Loaded module rlm_linelog
   # Loading module "linelog" from file 
/etc/freeradius/mods-enabled/linelog
   linelog {
   	filename = "/var/log/freeradius/linelog"
   	escape_filenames = no
   	syslog_severity = "info"
   	permissions = 384
   	format = "This is a log message for %{User-Name}"
   	reference = "messages.%{%{reply:Packet-Type}:-default}"
   }
   # Loading module "log_accounting" from file 
/etc/freeradius/mods-enabled/linelog
   linelog log_accounting {
   	filename = "/var/log/freeradius/linelog-accounting"
   	escape_filenames = no
   	syslog_severity = "info"
   	permissions = 384
   	format = ""
   	reference = "Accounting-Request.%{%{Acct-Status-Type}:-unknown}"
   }
   # Loaded module rlm_attr_filter
   # Loading module "attr_filter.post-proxy" from file 
/etc/freeradius/mods-enabled/attr_filter
   attr_filter attr_filter.post-proxy {
   	filename = "/etc/freeradius/mods-config/attr_filter/post-proxy"
   	key = "%{Realm}"
   	relaxed = no
   }
   # Loading module "attr_filter.pre-proxy" from file 
/etc/freeradius/mods-enabled/attr_filter
   attr_filter attr_filter.pre-proxy {
   	filename = "/etc/freeradius/mods-config/attr_filter/pre-proxy"
   	key = "%{Realm}"
   	relaxed = no
   }
   # Loading module "attr_filter.access_reject" from file 
/etc/freeradius/mods-enabled/attr_filter
   attr_filter attr_filter.access_reject {
   	filename = "/etc/freeradius/mods-config/attr_filter/access_reject"
   	key = "%{User-Name}"
   	relaxed = no
   }
   # Loading module "attr_filter.access_challenge" from file 
/etc/freeradius/mods-enabled/attr_filter
   attr_filter attr_filter.access_challenge {
   	filename = "/etc/freeradius/mods-config/attr_filter/access_challenge"
   	key = "%{User-Name}"
   	relaxed = no
   }
   # Loading module "attr_filter.accounting_response" from file 
/etc/freeradius/mods-enabled/attr_filter
   attr_filter attr_filter.accounting_response {
   	filename = 
"/etc/freeradius/mods-config/attr_filter/accounting_response"
   	key = "%{User-Name}"
   	relaxed = no
   }
   # Loaded module rlm_passwd
   # Loading module "etc_passwd" from file 
/etc/freeradius/mods-enabled/passwd
   passwd etc_passwd {
   	filename = "/etc/passwd"
   	format = "*User-Name:Crypt-Password:"
   	delimiter = ":"
   	ignore_nislike = no
   	ignore_empty = yes
   	allow_multiple_keys = no
   	hash_size = 100
   }
   # Loaded module rlm_replicate
   # Loading module "replicate" from file 
/etc/freeradius/mods-enabled/replicate
   # Loaded module rlm_preprocess
   # Loading module "preprocess" from file 
/etc/freeradius/mods-enabled/preprocess
   preprocess {
   	huntgroups = "/etc/freeradius/mods-config/preprocess/huntgroups"
   	hints = "/etc/freeradius/mods-config/preprocess/hints"
   	with_ascend_hack = no
   	ascend_channels_per_line = 23
   	with_ntdomain_hack = no
   	with_specialix_jetstream_hack = no
   	with_cisco_vsa_hack = no
   	with_alvarion_vsa_hack = no
   }
   # Loaded module rlm_realm
   # Loading module "IPASS" from file /etc/freeradius/mods-enabled/realm
   realm IPASS {
   	format = "prefix"
   	delimiter = "/"
   	ignore_default = no
   	ignore_null = no
   }
   # Loading module "suffix" from file /etc/freeradius/mods-enabled/realm
   realm suffix {
   	format = "suffix"
   	delimiter = "@"
   	ignore_default = no
   	ignore_null = no
   }
   # Loading module "realmpercent" from file 
/etc/freeradius/mods-enabled/realm
   realm realmpercent {
   	format = "suffix"
   	delimiter = "%"
   	ignore_default = no
   	ignore_null = no
   }
   # Loading module "ntdomain" from file 
/etc/freeradius/mods-enabled/realm
   realm ntdomain {
   	format = "prefix"
   	delimiter = "\\"
   	ignore_default = no
   	ignore_null = no
   }
   # Loaded module rlm_detail
   # Loading module "detail" from file 
/etc/freeradius/mods-enabled/detail
   detail {
   	filename = 
"/var/log/freeradius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d"
   	header = "%t"
   	permissions = 384
   	locking = no
   	escape_filenames = no
   	log_packet_header = no
   }
   # Loaded module rlm_sqlippool
   # Loading module "dhcp_sqlippool" from file 
/etc/freeradius/mods-enabled/dhcp_sqlippool
   sqlippool dhcp_sqlippool {
   	sql_module_instance = "sql"
   	lease_duration = 7200
   	pool_name = ""
   	default_pool = "main_pool"
   	allocate_begin = "START TRANSACTION"
   	allocate_clear = "UPDATE radippool SET nasipaddress = '', pool_key = 
0, callingstationid = '', username = '', expiry_time = NOW() WHERE 
expiry_time <= NOW() - INTERVAL 1 SECOND AND nasipaddress = 
'%{Nas-IP-Address}'"
   	allocate_find = "SELECT framedipaddress FROM radippool WHERE 
pool_name = '%{control:Pool-Name}' AND (callingstationid = 
'%{Calling-Station-Id}' or callingstationid = '') ORDER BY 
(callingstationid <> '%{Calling-Station-Id}'), expiry_time LIMIT 1 FOR 
UPDATE"
   	allocate_update = "UPDATE radippool SET nasipaddress = 
'%{NAS-IP-Address}', pool_key = '%{Calling-Station-Id}', 
callingstationid = '%{Calling-Station-Id}', username = '%{User-Name}', 
expiry_time = NOW() + INTERVAL 7200 SECOND WHERE framedipaddress = '%I'"
   	allocate_commit = "COMMIT"
   	pool_check = "SELECT id FROM radippool WHERE 
pool_name='%{control:Pool-Name}' LIMIT 1"
   	start_begin = "START TRANSACTION"
   	start_update = "UPDATE radippool SET expiry_time = NOW() + INTERVAL 
7200 SECOND WHERE nasipaddress = '%{NAS-IP-Address}' AND pool_key = 
'%{Calling-Station-Id}' AND username = '%{User-Name}' AND 
callingstationid = '%{Calling-Station-Id}' AND framedipaddress = 
'%{Framed-IP-Address}'"
   	start_commit = "COMMIT"
   	alive_begin = "START TRANSACTION"
   	alive_update = "UPDATE radippool SET expiry_time = NOW() + INTERVAL 
7200 SECOND WHERE nasipaddress = '%{Nas-IP-Address}' AND pool_key = 
'%{Calling-Station-Id}' AND username = '%{User-Name}' AND 
callingstationid = '%{Calling-Station-Id}' AND framedipaddress = 
'%{Framed-IP-Address}'"
   	alive_commit = "COMMIT"
   	stop_begin = "START TRANSACTION"
   	stop_clear = "UPDATE radippool 	SET 		nasipaddress = '', 		pool_key = 
0, 		callingstationid = '', 		username = '', 		expiry_time = NOW() 
	WHERE nasipaddress = '%{Nas-IP-Address}' 	AND pool_key = 
'%{Calling-Station-Id}' 	AND username = '%{User-Name}' 	AND 
callingstationid = '%{Calling-Station-Id}' 	AND framedipaddress = 
'%{Framed-IP-Address}'"
   	stop_commit = "COMMIT"
   	on_begin = "START TRANSACTION"
   	on_clear = "UPDATE radippool SET nasipaddress = '', pool_key = 0, 
callingstationid = '', username = '', expiry_time = NOW() WHERE 
nasipaddress = '%{Nas-IP-Address}'"
   	on_commit = "COMMIT"
   	off_begin = "START TRANSACTION"
   	off_clear = "UPDATE radippool SET nasipaddress = '', pool_key = 0, 
callingstationid = '', username = '', expiry_time = NOW() WHERE 
nasipaddress = '%{Nas-IP-Address}'"
   	off_commit = "COMMIT"
    messages {
    }
   }
   # Loading module "auth_log" from file 
/etc/freeradius/mods-enabled/detail.log
   detail auth_log {
   	filename = 
"/var/log/freeradius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d"
   	header = "%t"
   	permissions = 384
   	locking = no
   	escape_filenames = no
   	log_packet_header = no
   }
   # Loading module "reply_log" from file 
/etc/freeradius/mods-enabled/detail.log
   detail reply_log {
   	filename = 
"/var/log/freeradius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/reply-detail-%Y%m%d"
   	header = "%t"
   	permissions = 384
   	locking = no
   	escape_filenames = no
   	log_packet_header = no
   }
   # Loading module "pre_proxy_log" from file 
/etc/freeradius/mods-enabled/detail.log
   detail pre_proxy_log {
   	filename = 
"/var/log/freeradius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/pre-proxy-detail-%Y%m%d"
   	header = "%t"
   	permissions = 384
   	locking = no
   	escape_filenames = no
   	log_packet_header = no
   }
   # Loading module "post_proxy_log" from file 
/etc/freeradius/mods-enabled/detail.log
   detail post_proxy_log {
   	filename = 
"/var/log/freeradius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/post-proxy-detail-%Y%m%d"
   	header = "%t"
   	permissions = 384
   	locking = no
   	escape_filenames = no
   	log_packet_header = no
   }
   # Loaded module rlm_exec
   # Loading module "ntlm_auth" from file 
/etc/freeradius/mods-enabled/ntlm_auth
   exec ntlm_auth {
   	wait = yes
   	program = "/path/to/ntlm_auth --request-nt-key --domain=MYDOMAIN 
--username=%{mschap:User-Name} --password=%{User-Password}"
   	shell_escape = yes
   }
   # Loaded module rlm_mschap
   # Loading module "mschap" from file 
/etc/freeradius/mods-enabled/mschap
   mschap {
   	use_mppe = yes
   	require_encryption = no
   	require_strong = no
   	with_ntdomain_hack = yes
    passchange {
    }
   	allow_retry = yes
   }
   # Loaded module rlm_always
   # Loading module "reject" from file 
/etc/freeradius/mods-enabled/always
   always reject {
   	rcode = "reject"
   	simulcount = 0
   	mpp = no
   }
   # Loading module "fail" from file /etc/freeradius/mods-enabled/always
   always fail {
   	rcode = "fail"
   	simulcount = 0
   	mpp = no
   }
   # Loading module "ok" from file /etc/freeradius/mods-enabled/always
   always ok {
   	rcode = "ok"
   	simulcount = 0
   	mpp = no
   }
   # Loading module "handled" from file 
/etc/freeradius/mods-enabled/always
   always handled {
   	rcode = "handled"
   	simulcount = 0
   	mpp = no
   }
   # Loading module "invalid" from file 
/etc/freeradius/mods-enabled/always
   always invalid {
   	rcode = "invalid"
   	simulcount = 0
   	mpp = no
   }
   # Loading module "userlock" from file 
/etc/freeradius/mods-enabled/always
   always userlock {
   	rcode = "userlock"
   	simulcount = 0
   	mpp = no
   }
   # Loading module "notfound" from file 
/etc/freeradius/mods-enabled/always
   always notfound {
   	rcode = "notfound"
   	simulcount = 0
   	mpp = no
   }
   # Loading module "noop" from file /etc/freeradius/mods-enabled/always
   always noop {
   	rcode = "noop"
   	simulcount = 0
   	mpp = no
   }
   # Loading module "updated" from file 
/etc/freeradius/mods-enabled/always
   always updated {
   	rcode = "updated"
   	simulcount = 0
   	mpp = no
   }
   # Loaded module rlm_dynamic_clients
   # Loading module "dynamic_clients" from file 
/etc/freeradius/mods-enabled/dynamic_clients
   # Loading module "echo" from file /etc/freeradius/mods-enabled/echo
   exec echo {
   	wait = yes
   	program = "/bin/echo %{User-Name}"
   	input_pairs = "request"
   	output_pairs = "reply"
   	shell_escape = yes
   }
   # Loaded module rlm_expr
   # Loading module "expr" from file /etc/freeradius/mods-enabled/expr
   expr {
   	safe_characters = 
"@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: 
/äéöüàâæçèéêëîïôœùûüaÿÄÉÖÜßÀÂÆÇÈÉÊËÎÏÔŒÙÛÜŸ"
   }
   # Loaded module rlm_utf8
   # Loading module "utf8" from file /etc/freeradius/mods-enabled/utf8
   # Loaded module rlm_eap
   # Loading module "eap" from file /etc/freeradius/mods-enabled/eap
   eap {
   	default_eap_type = "gtc"
   	timer_expire = 60
   	ignore_unknown_eap_types = no
   	cisco_accounting_username_bug = no
   	max_sessions = 16384
   }
   # Loaded module rlm_pap
   # Loading module "pap" from file /etc/freeradius/mods-enabled/pap
   pap {
   	normalise = yes
   }
   # Loading module "exec" from file /etc/freeradius/mods-enabled/exec
   exec {
   	wait = no
   	input_pairs = "request"
   	shell_escape = yes
   	timeout = 10
   }
   instantiate {
   }
   # Instantiating module "files" from file 
/etc/freeradius/mods-enabled/files
reading pairlist file /etc/freeradius/mods-config/files/authorize
reading pairlist file /etc/freeradius/mods-config/files/accounting
reading pairlist file /etc/freeradius/mods-config/files/pre-proxy
   # Instantiating module "logintime" from file 
/etc/freeradius/mods-enabled/logintime
   # Instantiating module "expiration" from file 
/etc/freeradius/mods-enabled/expiration
   # Instantiating module "sql" from file 
/etc/freeradius/mods-enabled/sql
rlm_sql_mysql: libmysql version: 5.5.49
    mysql {
     tls {
     }
    	warnings = "auto"
    }
rlm_sql (sql): Attempting to connect to database "radius"
rlm_sql (sql): Initialising connection pool
    pool {
    	start = 5
    	min = 3
    	max = 32
    	spare = 10
    	uses = 0
    	lifetime = 0
    	cleanup_interval = 30
    	idle_timeout = 60
    	retry_delay = 30
    	spread = no
    }
rlm_sql (sql): Opening additional connection (0), 1 of 32 pending slots 
used
rlm_sql_mysql: Starting connect to MySQL server
rlm_sql_mysql: Connected to database 'radius' on Localhost via UNIX 
socket, server version 5.5.5-10.0.25-MariaDB-0+deb8u1, protocol version 
10
rlm_sql (sql): Opening additional connection (1), 1 of 31 pending slots 
used
rlm_sql_mysql: Starting connect to MySQL server
rlm_sql_mysql: Connected to database 'radius' on Localhost via UNIX 
socket, server version 5.5.5-10.0.25-MariaDB-0+deb8u1, protocol version 
10
rlm_sql (sql): Opening additional connection (2), 1 of 30 pending slots 
used
rlm_sql_mysql: Starting connect to MySQL server
rlm_sql_mysql: Connected to database 'radius' on Localhost via UNIX 
socket, server version 5.5.5-10.0.25-MariaDB-0+deb8u1, protocol version 
10
rlm_sql (sql): Opening additional connection (3), 1 of 29 pending slots 
used
rlm_sql_mysql: Starting connect to MySQL server
rlm_sql_mysql: Connected to database 'radius' on Localhost via UNIX 
socket, server version 5.5.5-10.0.25-MariaDB-0+deb8u1, protocol version 
10
rlm_sql (sql): Opening additional connection (4), 1 of 28 pending slots 
used
rlm_sql_mysql: Starting connect to MySQL server
rlm_sql_mysql: Connected to database 'radius' on Localhost via UNIX 
socket, server version 5.5.5-10.0.25-MariaDB-0+deb8u1, protocol version 
10
   # Instantiating module "cache_eap" from file 
/etc/freeradius/mods-enabled/cache_eap
rlm_cache (cache_eap): Driver rlm_cache_rbtree (module rlm_cache_rbtree) 
loaded and linked
   # Instantiating module "linelog" from file 
/etc/freeradius/mods-enabled/linelog
   # Instantiating module "log_accounting" from file 
/etc/freeradius/mods-enabled/linelog
   # Instantiating module "attr_filter.post-proxy" from file 
/etc/freeradius/mods-enabled/attr_filter
reading pairlist file /etc/freeradius/mods-config/attr_filter/post-proxy
   # Instantiating module "attr_filter.pre-proxy" from file 
/etc/freeradius/mods-enabled/attr_filter
reading pairlist file /etc/freeradius/mods-config/attr_filter/pre-proxy
   # Instantiating module "attr_filter.access_reject" from file 
/etc/freeradius/mods-enabled/attr_filter
reading pairlist file 
/etc/freeradius/mods-config/attr_filter/access_reject
[/etc/freeradius/mods-config/attr_filter/access_reject]:11 Check item 
"FreeRADIUS-Response-Delay" 	found in filter list for realm "DEFAULT".
[/etc/freeradius/mods-config/attr_filter/access_reject]:11 Check item 
"FreeRADIUS-Response-Delay-USec" 	found in filter list for realm 
"DEFAULT".
   # Instantiating module "attr_filter.access_challenge" from file 
/etc/freeradius/mods-enabled/attr_filter
reading pairlist file 
/etc/freeradius/mods-config/attr_filter/access_challenge
   # Instantiating module "attr_filter.accounting_response" from file 
/etc/freeradius/mods-enabled/attr_filter
reading pairlist file 
/etc/freeradius/mods-config/attr_filter/accounting_response
   # Instantiating module "etc_passwd" from file 
/etc/freeradius/mods-enabled/passwd
rlm_passwd: nfields: 3 keyfield 0(User-Name) listable: no
   # Instantiating module "preprocess" from file 
/etc/freeradius/mods-enabled/preprocess
reading pairlist file /etc/freeradius/mods-config/preprocess/huntgroups
reading pairlist file /etc/freeradius/mods-config/preprocess/hints
   # Instantiating module "IPASS" from file 
/etc/freeradius/mods-enabled/realm
   # Instantiating module "suffix" from file 
/etc/freeradius/mods-enabled/realm
   # Instantiating module "realmpercent" from file 
/etc/freeradius/mods-enabled/realm
   # Instantiating module "ntdomain" from file 
/etc/freeradius/mods-enabled/realm
   # Instantiating module "detail" from file 
/etc/freeradius/mods-enabled/detail
   # Instantiating module "dhcp_sqlippool" from file 
/etc/freeradius/mods-enabled/dhcp_sqlippool
   # Instantiating module "auth_log" from file 
/etc/freeradius/mods-enabled/detail.log
rlm_detail (auth_log): 'User-Password' suppressed, will not appear in 
detail output
   # Instantiating module "reply_log" from file 
/etc/freeradius/mods-enabled/detail.log
   # Instantiating module "pre_proxy_log" from file 
/etc/freeradius/mods-enabled/detail.log
   # Instantiating module "post_proxy_log" from file 
/etc/freeradius/mods-enabled/detail.log
   # Instantiating module "mschap" from file 
/etc/freeradius/mods-enabled/mschap
rlm_mschap (mschap): using internal authentication
   # Instantiating module "reject" from file 
/etc/freeradius/mods-enabled/always
   # Instantiating module "fail" from file 
/etc/freeradius/mods-enabled/always
   # Instantiating module "ok" from file 
/etc/freeradius/mods-enabled/always
   # Instantiating module "handled" from file 
/etc/freeradius/mods-enabled/always
   # Instantiating module "invalid" from file 
/etc/freeradius/mods-enabled/always
   # Instantiating module "userlock" from file 
/etc/freeradius/mods-enabled/always
   # Instantiating module "notfound" from file 
/etc/freeradius/mods-enabled/always
   # Instantiating module "noop" from file 
/etc/freeradius/mods-enabled/always
   # Instantiating module "updated" from file 
/etc/freeradius/mods-enabled/always
   # Instantiating module "eap" from file 
/etc/freeradius/mods-enabled/eap
    # Linked to sub-module rlm_eap_md5
    # Linked to sub-module rlm_eap_leap
    # Linked to sub-module rlm_eap_gtc
    gtc {
    	challenge = "Password: "
    	auth_type = "PAP"
    }
    # Linked to sub-module rlm_eap_tls
    tls {
    	tls = "tls-common"
    }
    tls-config tls-common {
    	verify_depth = 0
    	ca_path = "/etc/freeradius/certs"
    	pem_file_type = yes
    	private_key_file = "/etc/freeradius/certs/server.pem"
    	certificate_file = "/etc/freeradius/certs/server.pem"
    	ca_file = "/etc/freeradius/certs/ca.pem"
    	private_key_password = <<< secret >>>
    	dh_file = "/etc/freeradius/certs/dh"
    	fragment_size = 1024
    	include_length = yes
    	auto_chain = yes
    	check_crl = no
    	check_all_crl = no
    	cipher_list = "DEFAULT"
    	ecdh_curve = "prime256v1"
     cache {
     	enable = yes
     	lifetime = 24
     	max_entries = 255
     }
     verify {
     	skip_if_ocsp_ok = no
     }
     ocsp {
     	enable = no
     	override_cert_url = yes
     	url = "http://127.0.0.1/ocsp/"
     	use_nonce = yes
     	timeout = 0
     	softfail = no
     }
    }
    # Linked to sub-module rlm_eap_ttls
    ttls {
    	tls = "tls-common"
    	default_eap_type = "md5"
    	copy_request_to_tunnel = no
    	use_tunneled_reply = no
    	virtual_server = "inner-tunnel"
    	include_length = yes
    	require_client_cert = no
    }
tls: Using cached TLS configuration from previous invocation
    # Linked to sub-module rlm_eap_peap
    peap {
    	tls = "tls-common"
    	default_eap_type = "mschapv2"
    	copy_request_to_tunnel = no
    	use_tunneled_reply = no
    	proxy_tunneled_request_as_eap = yes
    	virtual_server = "inner-tunnel"
    	soh = no
    	require_client_cert = no
    }
tls: Using cached TLS configuration from previous invocation
    # Linked to sub-module rlm_eap_mschapv2
    mschapv2 {
    	with_ntdomain_hack = no
    	send_error = no
    }
   # Instantiating module "pap" from file 
/etc/freeradius/mods-enabled/pap
  } # modules
radiusd: #### Loading Virtual Servers ####
server { # from file /etc/freeradius/radiusd.conf
} # server
server inner-tunnel { # from file 
/etc/freeradius/sites-enabled/inner-tunnel
  # Loading authenticate {...}
  # Loading authorize {...}
Ignoring "ldap" (see raddb/mods-available/README.rst)
  # Loading session {...}
  # Loading post-proxy {...}
  # Loading post-auth {...}
} # server inner-tunnel
server dhcp { # from file /etc/freeradius/sites-enabled/dhcp
  # Loading dhcp DHCP-Discover {...}
  # Loading dhcp DHCP-Request {...}
  # Loading dhcp DHCP-Decline {...}
  # Loading dhcp DHCP-Inform {...}
  # Loading dhcp DHCP-Release {...}
  # Loading dhcp DHCP-Lease-Query {...}
} # server dhcp
server default { # from file /etc/freeradius/sites-enabled/default
  # Loading authenticate {...}
  # Loading authorize {...}
  # Loading preacct {...}
  # Loading accounting {...}
  # Loading session {...}
  # Loading post-proxy {...}
  # Loading post-auth {...}
} # server default
radiusd: #### Opening IP addresses and Ports ####
listen {
   	type = "auth"
   	ipaddr = 127.0.0.1
   	port = 18120
}
listen {
   	type = "dhcp"
   	ipaddr = 10.1.0.1
   	port = 6700
No "interface" setting is defined.  Only unicast DHCP will work
   	src_ipaddr = 10.1.0.1
}
listen {
   	type = "auth"
   	ipaddr = 10.1.0.1
   	port = 1812
    limit {
    	max_connections = 16
    	lifetime = 0
    	idle_timeout = 30
    }
}
listen {
   	type = "acct"
   	ipaddr = 10.1.0.1
   	port = 1813
    limit {
    	max_connections = 16
    	lifetime = 0
    	idle_timeout = 30
    }
}
Listening on auth address 127.0.0.1 port 18120 bound to server 
inner-tunnel
Listening on dhcp address 10.1.0.1 port 6700 bound to server dhcp
Listening on auth address 10.1.0.1 port 1812 bound to server default
Listening on acct address 10.1.0.1 port 1813 bound to server default
Listening on proxy address * port 44178
Ready to process requests
(0) Received Access-Request Id 165 from 10.1.0.1:53996 to 10.1.0.1:1812 
length 158
(0)   User-Name = "laurens7"
(0)   NAS-Port-Type = Virtual
(0)   Service-Type = Framed-User
(0)   NAS-Port = 99
(0)   NAS-Port-Id = "iOS-radius"
(0)   NAS-IP-Address = 1.2.3.4
(0)   Called-Station-Id = "1.2.3.4[4500]"
(0)   Calling-Station-Id = "70.69.64.246[36358]"
(0)   EAP-Message = 0x0200000d016c617572656e7337
(0)   NAS-Identifier = "ipsec-gateway"
(0)   Message-Authenticator = 0xe4666e2c4700d85a692adebddaf4859b
(0) # Executing section authorize from file 
/etc/freeradius/sites-enabled/default
(0)   authorize {
(0)     policy filter_username {
(0)       if (&User-Name) {
(0)       if (&User-Name)  -> TRUE
(0)       if (&User-Name)  {
(0)         if (&User-Name =~ / /) {
(0)         if (&User-Name =~ / /)  -> FALSE
(0)         if (&User-Name =~ /@[^@]*@/ ) {
(0)         if (&User-Name =~ /@[^@]*@/ )  -> FALSE
(0)         if (&User-Name =~ /\.\./ ) {
(0)         if (&User-Name =~ /\.\./ )  -> FALSE
(0)         if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))  
{
(0)         if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))   
-> FALSE
(0)         if (&User-Name =~ /\.$/)  {
(0)         if (&User-Name =~ /\.$/)   -> FALSE
(0)         if (&User-Name =~ /@\./)  {
(0)         if (&User-Name =~ /@\./)   -> FALSE
(0)       } # if (&User-Name)  = notfound
(0)     } # policy filter_username = notfound
(0)     [preprocess] = ok
(0)     [chap] = noop
(0)     [mschap] = noop
(0)     [digest] = noop
(0) suffix: Checking for suffix after "@"
(0) suffix: No '@' in User-Name = "laurens7", looking up realm NULL
(0) suffix: No such realm "NULL"
(0)     [suffix] = noop
(0) eap: Peer sent EAP Response (code 2) ID 0 length 13
(0) eap: EAP-Identity reply, returning 'ok' so we can short-circuit the 
rest of authorize
(0)     [eap] = ok
(0)   } # authorize = ok
(0) Found Auth-Type = eap
(0) # Executing group from file /etc/freeradius/sites-enabled/default
(0)   authenticate {
(0) eap: Peer sent packet with method EAP Identity (1)
(0) eap: Calling submodule eap_gtc to process data
(0) eap_gtc: EXPAND Password:
(0) eap_gtc:    --> Password:
(0) eap: Sending EAP Request (code 1) ID 1 length 15
(0) eap: EAP session adding &reply:State = 0xb05adb4db05bddc4
(0)     [eap] = handled
(0)   } # authenticate = handled
(0) Using Post-Auth-Type Challenge
(0) Post-Auth-Type sub-section not found.  Ignoring.
(0) # Executing group from file /etc/freeradius/sites-enabled/default
(0) Sent Access-Challenge Id 165 from 10.1.0.1:1812 to 10.1.0.1:53996 
length 0
(0)   EAP-Message = 0x0101000f0650617373776f72643a20
(0)   Message-Authenticator = 0x00000000000000000000000000000000
(0)   State = 0xb05adb4db05bddc411d1bca86d6a4a79
(0) Finished request
Waking up in 4.9 seconds.
(1) Received Access-Request Id 166 from 10.1.0.1:53996 to 10.1.0.1:1812 
length 176
(1)   User-Name = "laurens7"
(1)   NAS-Port-Type = Virtual
(1)   Service-Type = Framed-User
(1)   NAS-Port = 99
(1)   NAS-Port-Id = "iOS-radius"
(1)   NAS-IP-Address = 1.2.3.4
(1)   Called-Station-Id = "1.2.3.4[4500]"
(1)   Calling-Station-Id = "70.69.64.246[36358]"
(1)   EAP-Message = 0x0201000d066c617572656e7337
(1)   NAS-Identifier = "ipsec-gateway"
(1)   State = 0xb05adb4db05bddc411d1bca86d6a4a79
(1)   Message-Authenticator = 0x9be4f26cd205da5cdc9a59e49346a66f
(1) session-state: No cached attributes
(1) # Executing section authorize from file 
/etc/freeradius/sites-enabled/default
(1)   authorize {
(1)     policy filter_username {
(1)       if (&User-Name) {
(1)       if (&User-Name)  -> TRUE
(1)       if (&User-Name)  {
(1)         if (&User-Name =~ / /) {
(1)         if (&User-Name =~ / /)  -> FALSE
(1)         if (&User-Name =~ /@[^@]*@/ ) {
(1)         if (&User-Name =~ /@[^@]*@/ )  -> FALSE
(1)         if (&User-Name =~ /\.\./ ) {
(1)         if (&User-Name =~ /\.\./ )  -> FALSE
(1)         if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))  
{
(1)         if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))   
-> FALSE
(1)         if (&User-Name =~ /\.$/)  {
(1)         if (&User-Name =~ /\.$/)   -> FALSE
(1)         if (&User-Name =~ /@\./)  {
(1)         if (&User-Name =~ /@\./)   -> FALSE
(1)       } # if (&User-Name)  = notfound
(1)     } # policy filter_username = notfound
(1)     [preprocess] = ok
(1)     [chap] = noop
(1)     [mschap] = noop
(1)     [digest] = noop
(1) suffix: Checking for suffix after "@"
(1) suffix: No '@' in User-Name = "laurens7", looking up realm NULL
(1) suffix: No such realm "NULL"
(1)     [suffix] = noop
(1) eap: Peer sent EAP Response (code 2) ID 1 length 13
(1) eap: No EAP Start, assuming it's an on-going EAP conversation
(1)     [eap] = updated
(1) files: users: Matched entry DEFAULT at line 47
(1)     [files] = ok
(1) sql: EXPAND %{User-Name}
(1) sql:    --> laurens7
(1) sql: SQL-User-Name set to 'laurens7'
rlm_sql (sql): Reserved connection (0)
(1) sql: EXPAND SELECT id, username, attribute, value, op FROM radcheck 
WHERE username = '%{SQL-User-Name}' ORDER BY id
(1) sql:    --> SELECT id, username, attribute, value, op FROM radcheck 
WHERE username = 'laurens7' ORDER BY id
(1) sql: Executing select query: SELECT id, username, attribute, value, 
op FROM radcheck WHERE username = 'laurens7' ORDER BY id
(1) sql: ERROR: Error parsing value: Invalid hex data
(1) sql: ERROR: Error parsing user data from database result
(1) sql: ERROR: Error getting check attributes
rlm_sql (sql): Released connection (0)
rlm_sql (sql): Need 5 more connections to reach 10 spares
rlm_sql (sql): Opening additional connection (5), 1 of 27 pending slots 
used
rlm_sql_mysql: Starting connect to MySQL server
rlm_sql_mysql: Connected to database 'radius' on Localhost via UNIX 
socket, server version 5.5.5-10.0.25-MariaDB-0+deb8u1, protocol version 
10
(1)     [sql] = fail
(1)   } # authorize = fail
(1) Using Post-Auth-Type Reject
(1) # Executing group from file /etc/freeradius/sites-enabled/default
(1)   Post-Auth-Type REJECT {
(1) sql: EXPAND .query
(1) sql:    --> .query
(1) sql: Using query template 'query'
rlm_sql (sql): Reserved connection (1)
(1) sql: EXPAND %{User-Name}
(1) sql:    --> laurens7
(1) sql: SQL-User-Name set to 'laurens7'
(1) sql: EXPAND INSERT INTO radpostauth (username, pass, reply, 
authdate) VALUES ( '%{SQL-User-Name}', 
'%{%{User-Password}:-%{Chap-Password}}', '%{reply:Packet-Type}', '%S')
(1) sql:    --> INSERT INTO radpostauth (username, pass, reply, 
authdate) VALUES ( 'laurens7', '', 'Access-Reject', '2016-09-08 
01:04:45')
(1) sql: Executing query: INSERT INTO radpostauth (username, pass, 
reply, authdate) VALUES ( 'laurens7', '', 'Access-Reject', '2016-09-08 
01:04:45')
(1) sql: SQL query returned: success
(1) sql: 1 record(s) updated
rlm_sql (sql): Released connection (1)
(1)     [sql] = ok
(1) attr_filter.access_reject: EXPAND %{User-Name}
(1) attr_filter.access_reject:    --> laurens7
(1) attr_filter.access_reject: Matched entry DEFAULT at line 11
(1)     [attr_filter.access_reject] = updated
(1) eap: Expiring EAP session with state 0xb05adb4db05bddc4
(1) eap: Finished EAP session with state 0xb05adb4db05bddc4
(1) eap: Previous EAP request found for state 0xb05adb4db05bddc4, 
released from the list
(1) eap: Request was previously rejected, inserting EAP-Failure
(1) eap: Sending EAP Failure (code 4) ID 1 length 4
(1)     [eap] = updated
(1)     policy remove_reply_message_if_eap {
(1)       if (&reply:EAP-Message && &reply:Reply-Message) {
(1)       if (&reply:EAP-Message && &reply:Reply-Message)  -> FALSE
(1)       else {
(1)         [noop] = noop
(1)       } # else = noop
(1)     } # policy remove_reply_message_if_eap = noop
(1)   } # Post-Auth-Type REJECT = updated
(1) Delaying response for 1.000000 seconds
Waking up in 0.1 seconds.
Waking up in 0.8 seconds.
(1) Sending delayed response
(1) Sent Access-Reject Id 166 from 10.1.0.1:1812 to 10.1.0.1:53996 
length 44
(1)   EAP-Message = 0x04010004
(1)   Message-Authenticator = 0x00000000000000000000000000000000
Waking up in 3.7 seconds.
(0) Cleaning up request packet ID 165 with timestamp +15
Waking up in 0.2 seconds.
(1) Cleaning up request packet ID 166 with timestamp +16
Ready to process requests