read_group=yes but radgroupreply doesn't work

Zulzig zulzig at gmail.com
Wed Oct 8 10:52:12 CEST 2014


Hello,

I use free radius version 2.1.12 for red hat.

I set up it with mysql.

In the sql.conf file, i have the read_groups settings to yes,

i send you the debug from my server

in my sql radgroupreply i have:

mysql> select * from radgroupreply;
+----+---------------------+-------------------------+----+---------------------------------------------------------------------------------------------------------------+
| id | groupname           | attribute               | op | value

                  |
+----+---------------------+-------------------------+----+---------------------------------------------------------------------------------------------------------------+
|  1 | XXXX-VLAN-REGIST    | Tunnel-type             | =  | 13

                   |
|  2 | XXXX-VLAN-REGIST    | Tunnel-Medium-Type      | =  | 6

                  |
|  3 | XXXX-VLAN-REGIST    | Tunnel-Private-Group-ID | =  | XXX

                  |
|  4 | XXXX-VLAN-REGIST    | Reply-Message           | =  |
XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXxXXX XXXX
                                  |
|  5 | XXXX-VLAN-ISOLATION | Tunnel-type             | =  | 13

                   |
|  6 | XXXX-VLAN-ISOLATION | Tunnel-Medium-Type      | =  | 6

                  |
|  7 | XXXX-VLAN-ISOLATION | Tunnel-Private-Group-ID | =  | XXX

                  |
|  8 | XXXX-VLAN-ISOLATION | Reply-Message           | =  |
XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXxxxxXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXxxxxxxxxx
|
|  9 | XXXX-VLAN-GUEST     | Reply-Message           | =  |
XXxxxXXXXXxxxXXXXXXxxxxxxxxxxxxxXXXXXXXXXX
                                   |
+----+---------------------+-------------------------+----+---------------------------------------------------------------------------------------------------------------+
9 rows in set (0.00 sec)

mysql> select * from radusergroup;
+----------------------------------+------------------+----------+
| username                         | groupname        | priority |
+----------------------------------+------------------+----------+
| host/XXXXXXXXXX.XXXXXXXXXXXXXXXX | XXXX-VLAN-REGIST |        1 |
| host/XXX-XXX-test                | XXXX-VLAN-REGIST |        1 |
+----------------------------------+------------------+----------+

mysql> select * from radgroupcheck;
+----+---------------------+-----------+----+--------+
| id | groupname           | attribute | op | value  |
+----+---------------------+-----------+----+--------+
|  1 | XXXX-VLAN-REGIST    | Auth-Type | := | EAP    |
|  2 | XXXX-VLAN-ISOLATION | Auth-Type | := | EAP    |
|  3 | XXXX-VLAN-GUEST     | Auth-Type | := | reject |
+----+---------------------+-----------+----+--------+
3 rows in set (0.00 sec)

Thank a lot for your help and support.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freeradius.org/pipermail/freeradius-users/attachments/20141008/7e64658b/attachment-0001.html>
-------------- next part --------------
XXeeRADIUS Version 2.1.12, for host x86_64-redhat-linux-gnu, built on Sep 24 2012 at 17:14:11
Copyright (C) 1999-2009 The XXeeRADIUS server project and contributors. 
There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A 
PARTICULAR PURPOSE. 
You may redistribute copies of XXeeRADIUS under the terms of the 
GNU General Public License v2. 
Starting - reading configuration files ...
including configuration file /etc/raddb/radiusd.conf
including configuration file /etc/raddb/clients.conf
including files in directory /etc/raddb/modules/
including configuration file /etc/raddb/modules/echo
including configuration file /etc/raddb/modules/mac2ip
including configuration file /etc/raddb/modules/ippool
including configuration file /etc/raddb/modules/expr
including configuration file /etc/raddb/modules/dynamic_clients
including configuration file /etc/raddb/modules/opendirectory
including configuration file /etc/raddb/modules/exec
including configuration file /etc/raddb/modules/acct_unique
including configuration file /etc/raddb/modules/ntlm_auth
including configuration file /etc/raddb/modules/wimax
including configuration file /etc/raddb/modules/attr_filter
including configuration file /etc/raddb/modules/sradutmp
including configuration file /etc/raddb/modules/unix
including configuration file /etc/raddb/modules/passwd
including configuration file /etc/raddb/modules/chap
including configuration file /etc/raddb/modules/counter
including configuration file /etc/raddb/modules/always
including configuration file /etc/raddb/modules/attr_rewrite
including configuration file /etc/raddb/modules/checkval
including configuration file /etc/raddb/modules/detail.example.com
including configuration file /etc/raddb/modules/otp
including configuration file /etc/raddb/modules/mschap
including configuration file /etc/raddb/modules/radutmp
including configuration file /etc/raddb/modules/linelog
including configuration file /etc/raddb/modules/sql_log
including configuration file /etc/raddb/modules/expiration
including configuration file /etc/raddb/modules/smbpasswd
including configuration file /etc/raddb/modules/pap
including configuration file /etc/raddb/modules/detail.log
including configuration file /etc/raddb/modules/digest
including configuration file /etc/raddb/modules/smsotp
including configuration file /etc/raddb/modules/etc_group
including configuration file /etc/raddb/modules/preprocess
including configuration file /etc/raddb/modules/pam
including configuration file /etc/raddb/modules/sqlcounter_expire_on_login
including configuration file /etc/raddb/modules/cui
including configuration file /etc/raddb/modules/logintime
including configuration file /etc/raddb/modules/mac2vlan
including configuration file /etc/raddb/modules/redis
including configuration file /etc/raddb/modules/files
including configuration file /etc/raddb/modules/soh
including configuration file /etc/raddb/modules/rediswho
including configuration file /etc/raddb/modules/policy
including configuration file /etc/raddb/modules/perl
including configuration file /etc/raddb/modules/detail
including configuration file /etc/raddb/modules/inner-eap
including configuration file /etc/raddb/modules/realm
including configuration file /etc/raddb/modules/replicate
including configuration file /etc/raddb/eap.conf
including configuration file /etc/raddb/sql.conf
including configuration file /etc/raddb/sql/mysql/dialup.conf
including configuration file /etc/raddb/sql/mysql/counter.conf
including configuration file /etc/raddb/policy.conf
including files in directory /etc/raddb/sites-enabled/
including configuration file /etc/raddb/sites-enabled/inner-tunnel
including configuration file /etc/raddb/sites-enabled/default
including configuration file /etc/raddb/sites-enabled/control-socket
main {
	user = "radiusd"
	group = "radiusd"
	allow_core_dumps = no
}
including dictionary file /etc/raddb/dictionary
main {
	name = "radiusd"
	prefix = "/usr"
	localstatedir = "/var"
	sbindir = "/usr/sbin"
	logdir = "/var/log/radius"
	run_dir = "/var/run/radiusd"
	libdir = "/usr/lib64/XXeeradius"
	radacctdir = "/var/log/radius/radacct"
	hostname_lookups = no
	max_request_time = 30
	cleanup_delay = 5
	max_requests = 10240
	pidfile = "/var/run/radiusd/radiusd.pid"
	checkrad = "/usr/sbin/checkrad"
	debug_level = 0
	proxy_requests = no
 log {
	stripped_names = no
	auth = no
	auth_badpass = yes
	auth_goodpass = yes
 }
 security {
	max_attributes = 200
	reject_delay = 1
	status_server = yes
 }
}
radiusd: #### Loading Realms and Home Servers ####
radiusd: #### Loading Clients ####
 client localhost {
	ipaddr = 127.0.0.1
	require_message_authenticator = no
	secret = "testing123"
	nastype = "other"
 }
radiusd: #### Instantiating modules ####
 instantiate {
 Module: Linked to module rlm_exec
 Module: Instantiating module "exec" XXom file /etc/raddb/modules/exec
  exec {
	wait = no
	input_pairs = "request"
	shell_escape = yes
  }
 Module: Linked to module rlm_expr
 Module: Instantiating module "expr" XXom file /etc/raddb/modules/expr
 Module: Linked to module rlm_expiration
 Module: Instantiating module "expiration" XXom file /etc/raddb/modules/expiration
  expiration {
	reply-message = "Password Has Expired  "
  }
 Module: Linked to module rlm_logintime
 Module: Instantiating module "logintime" XXom file /etc/raddb/modules/logintime
  logintime {
	reply-message = "You are calling outside your allowed timespan  "
	minimum-timeout = 60
  }
 }
radiusd: #### Loading Virtual Servers ####
server { # XXom file /etc/raddb/radiusd.conf
 modules {
  Module: Creating Auth-Type = digest
  Module: Creating Post-Auth-Type = REJECT
 Module: Checking authenticate {...} for more modules to load
 Module: Linked to module rlm_pap
 Module: Instantiating module "pap" XXom file /etc/raddb/modules/pap
  pap {
	encryption_scheme = "auto"
	auto_header = no
  }
 Module: Linked to module rlm_chap
 Module: Instantiating module "chap" XXom file /etc/raddb/modules/chap
 Module: Linked to module rlm_mschap
 Module: Instantiating module "mschap" XXom file /etc/raddb/modules/mschap
  mschap {
	use_mppe = yes
	require_encryption = no
	require_strong = no
	with_ntdomain_hack = no
	allow_retry = yes
  }
 Module: Linked to module rlm_digest
 Module: Instantiating module "digest" XXom file /etc/raddb/modules/digest
 Module: Linked to module rlm_unix
 Module: Instantiating module "unix" XXom file /etc/raddb/modules/unix
  unix {
	radwtmp = "/var/log/radius/radwtmp"
  }
 Module: Linked to module rlm_eap
 Module: Instantiating module "eap" XXom file /etc/raddb/eap.conf
  eap {
	default_eap_type = "tls"
	timer_expire = 60
	ignore_unknown_eap_types = no
	cisco_accounting_username_bug = no
	max_sessions = 4096
  }
 Module: Linked to sub-module rlm_eap_md5
 Module: Instantiating eap-md5
 Module: Linked to sub-module rlm_eap_leap
 Module: Instantiating eap-leap
 Module: Linked to sub-module rlm_eap_gtc
 Module: Instantiating eap-gtc
   gtc {
	challenge = "Password: "
	auth_type = "PAP"
   }
 Module: Linked to sub-module rlm_eap_tls
 Module: Instantiating eap-tls
   tls {
	rsa_key_exchange = no
	dh_key_exchange = yes
	rsa_key_length = 512
	dh_key_length = 512
	verify_depth = 0
	CA_path = "/etc/raddb/certs"
	pem_file_type = yes
	private_key_file = "/etc/raddb/certs/XXXXXXXXXX.pem"
	certificate_file = "/etc/raddb/certs/XXXXXXXXXX.pem"
	CA_file = "/etc/raddb/certs//XXXXXXXXXX.pem"
	private_key_password = "XXXXXXXXXX"
	dh_file = "/etc/raddb/certs/dh"
	random_file = "/etc/raddb/certs/random"
	XXagment_size = 1024
	include_length = yes
	check_crl = no
	check_cert_cn = "%{Stripped-User-Name}"
	cipher_list = "DEFAULT"
    cache {
	enable = no
	lifetime = 24
	max_entries = 255
    }
    verify {
    }
    ocsp {
	enable = no
	override_cert_url = yes
	url = "http://127.0.0.1/ocsp/"
    }
   }
 Module: Linked to sub-module rlm_eap_ttls
 Module: Instantiating eap-ttls
   ttls {
	default_eap_type = "md5"
	copy_request_to_tunnel = no
	use_tunneled_reply = no
	virtual_server = "inner-tunnel"
	include_length = yes
   }
 Module: Linked to sub-module rlm_eap_peap
 Module: Instantiating eap-peap
   peap {
	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
   }
 Module: Linked to sub-module rlm_eap_mschapv2
 Module: Instantiating eap-mschapv2
   mschapv2 {
	with_ntdomain_hack = no
	send_error = no
   }
 Module: Checking authorize {...} for more modules to load
 Module: Linked to module rlm_preprocess
 Module: Instantiating module "preprocess" XXom file /etc/raddb/modules/preprocess
  preprocess {
	huntgroups = "/etc/raddb/huntgroups"
	hints = "/etc/raddb/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
  }
 Module: Linked to module rlm_realm
 Module: Instantiating module "suffix" XXom file /etc/raddb/modules/realm
  realm suffix {
	format = "suffix"
	delimiter = "@"
	ignore_default = no
	ignore_null = no
  }
 Module: Linked to module rlm_sql
 Module: Instantiating module "sql" XXom file /etc/raddb/sql.conf
  sql {
	driver = "rlm_sql_mysql"
	server = "localhost"
	port = ""
	login = "radius"
	password = "radpass"
	radius_db = "radius"
	read_groups = yes
	sqltrace = yes
	sqltracefile = "/var/log/radius/sqltrace.sql"
	readclients = yes
	deletestalesessions = yes
	num_sql_socks = 10
	lifetime = 0
	max_queries = 0
	sql_user_name = "%{%{Stripped-User-Name}:-%{%{User-Name}:-DEFAULT}}"
	default_user_profile = ""
	nas_query = "SELECT id, nasname, shortname, type, secret, server XXOM nas"
	authorize_check_query = "SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = '%{SQL-User-Name}'           ORDER BY id"
	authorize_reply_query = "SELECT id, username, attribute, value, op           XXOM radreply           WHERE username = '%{SQL-User-Name}'           ORDER BY id"
	authorize_group_check_query = "SELECT id, groupname, attribute,           Value, op           XXOM radgroupcheck           WHERE groupname = '%{Sql-Group}'           ORDER BY id"
	authorize_group_reply_query = "SELECT id, groupname, attribute,           value, op           XXOM radgroupreply           WHERE groupname = '%{Sql-Group}'           ORDER BY id"
	accounting_onoff_query = "          UPDATE radacct           SET              acctstoptime       =  '%S',              acctsessiontime    =  unix_timestamp('%S') -                                    unix_timestamp(acctstarttime),              acctterminatecause =  '%{Acct-Terminate-Cause}',              acctstopdelay      =  %{%{Acct-Delay-Time}:-0}           WHERE acctstoptime IS NULL           AND nasipaddress      =  '%{NAS-IP-Address}'           AND acctstarttime     <= '%S'"
	accounting_update_query = "           UPDATE radacct           SET              XXamedipaddress = '%{XXamed-IP-Address}',              acctsessiontime     = '%{Acct-Session-Time}',              acctinputoctets     = '%{%{Acct-Input-Gigawords}:-0}'  << 32 |                                    '%{%{Acct-Input-Octets}:-0}',              acctoutputoctets    = '%{%{Acct-Output-Gigawords}:-0}' << 32 |                                    '%{%{Acct-Output-Octets}:-0}'           WHERE acctsessionid = '%{Acct-Session-Id}'           AND username        = '%{SQL-User-Name}'           AND nasipaddress    = '%{NAS-IP-Address}'"
	accounting_update_query_alt = "           INSERT INTO radacct             (acctsessionid,    acctuniqueid,      username,              realm,            nasipaddress,      nasportid,              nasporttype,      acctstarttime,     acctsessiontime,              acctauthentic,    connectinfo_start, acctinputoctets,              acctoutputoctets, calledstationid,   callingstationid,              servicetype,      XXamedprotocol,    XXamedipaddress,              acctstartdelay,   xascendsessionsvrkey)           VALUES             ('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}',              '%{SQL-User-Name}',              '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}',              '%{NAS-Port-Type}',              DATE_SUB('%S',                       INTERVAL (%{%{Acct-Session-Time}:-0} +                                 %{%{Acct-Delay-Time}:-0}) SECOND),                       '%{Acct-Session-Time}',              '%{Acct-Authentic}', '',              '%{%{Acct-Input-Gigawords}:-0}' << 32 |              '%{%{Acct-Input-Octets}:-0}',              '%{%{Acct-Output-Gigawords}:-0}' << 32 |              '%{%{Acct-Output-Octets}:-0}',              '%{Called-Station-Id}', '%{Calling-Station-Id}',              '%{Service-Type}', '%{XXamed-Protocol}',              '%{XXamed-IP-Address}',              '0', '%{X-Ascend-Session-Svr-Key}')"
	accounting_start_query = "           INSERT INTO radacct             (acctsessionid,    acctuniqueid,     username,              realm,            nasipaddress,     nasportid,              nasporttype,      acctstarttime,    acctstoptime,              acctsessiontime,  acctauthentic,    connectinfo_start,              connectinfo_stop, acctinputoctets,  acctoutputoctets,              calledstationid,  callingstationid, acctterminatecause,              servicetype,      XXamedprotocol,   XXamedipaddress,              acctstartdelay,   acctstopdelay,    xascendsessionsvrkey)           VALUES             ('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}',              '%{SQL-User-Name}',              '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}',              '%{NAS-Port-Type}', '%S', NULL,              '0', '%{Acct-Authentic}', '%{Connect-Info}',              '', '0', '0',              '%{Called-Station-Id}', '%{Calling-Station-Id}', '',              '%{Service-Type}', '%{XXamed-Protocol}', '%{XXamed-IP-Address}',              '%{%{Acct-Delay-Time}:-0}', '0', '%{X-Ascend-Session-Svr-Key}')"
	accounting_start_query_alt = "           UPDATE radacct SET              acctstarttime     = '%S',              acctstartdelay    = '%{%{Acct-Delay-Time}:-0}',              connectinfo_start = '%{Connect-Info}'           WHERE acctsessionid  = '%{Acct-Session-Id}'           AND username         = '%{SQL-User-Name}'           AND nasipaddress     = '%{NAS-IP-Address}'"
	accounting_stop_query = "           UPDATE radacct SET              acctstoptime       = '%S',              acctsessiontime    = '%{Acct-Session-Time}',              acctinputoctets    = '%{%{Acct-Input-Gigawords}:-0}' << 32 |                                   '%{%{Acct-Input-Octets}:-0}',              acctoutputoctets   = '%{%{Acct-Output-Gigawords}:-0}' << 32 |                                   '%{%{Acct-Output-Octets}:-0}',              acctterminatecause = '%{Acct-Terminate-Cause}',              acctstopdelay      = '%{%{Acct-Delay-Time}:-0}',              connectinfo_stop   = '%{Connect-Info}'           WHERE acctsessionid   = '%{Acct-Session-Id}'           AND username          = '%{SQL-User-Name}'           AND nasipaddress      = '%{NAS-IP-Address}'"
	accounting_stop_query_alt = "           INSERT INTO radacct             (acctsessionid, acctuniqueid, username,              realm, nasipaddress, nasportid,              nasporttype, acctstarttime, acctstoptime,              acctsessiontime, acctauthentic, connectinfo_start,              connectinfo_stop, acctinputoctets, acctoutputoctets,              calledstationid, callingstationid, acctterminatecause,              servicetype, XXamedprotocol, XXamedipaddress,              acctstartdelay, acctstopdelay)           VALUES             ('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}',              '%{SQL-User-Name}',              '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}',              '%{NAS-Port-Type}',              DATE_SUB('%S',                  INTERVAL (%{%{Acct-Session-Time}:-0} +                  %{%{Acct-Delay-Time}:-0}) SECOND),              '%S', '%{Acct-Session-Time}', '%{Acct-Authentic}', '',              '%{Connect-Info}',              '%{%{Acct-Input-Gigawords}:-0}' << 32 |              '%{%{Acct-Input-Octets}:-0}',              '%{%{Acct-Output-Gigawords}:-0}' << 32 |              '%{%{Acct-Output-Octets}:-0}',              '%{Called-Station-Id}', '%{Calling-Station-Id}',              '%{Acct-Terminate-Cause}',              '%{Service-Type}', '%{XXamed-Protocol}', '%{XXamed-IP-Address}',              '0', '%{%{Acct-Delay-Time}:-0}')"
	group_membership_query = "SELECT groupname           XXOM radusergroup           WHERE username = '%{SQL-User-Name}'           ORDER BY priority"
	connect_failure_retry_delay = 60
	simul_count_query = "SELECT COUNT(*)                              XXOM radacct                              WHERE username = '%{SQL-User-Name}'                              AND acctstoptime IS NULL"
	simul_verify_query = "SELECT radacctid, acctsessionid, username,                                nasipaddress, nasportid, XXamedipaddress,                                callingstationid, XXamedprotocol                                XXOM radacct                                WHERE username = '%{SQL-User-Name}'                                AND acctstoptime IS NULL"
	postauth_query = "INSERT INTO radpostauth                           (username, pass, reply, authdate)                           VALUES (                           '%{User-Name}',                           '%{%{User-Password}:-%{Chap-Password}}',                           '%{reply:Packet-Type}', '%S')"
	safe-characters = "@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /"
  }
rlm_sql (sql): Driver rlm_sql_mysql (module rlm_sql_mysql) loaded and linked
rlm_sql (sql): Attempting to connect to radius at localhost:/radius
rlm_sql (sql): starting 0
rlm_sql (sql): Attempting to connect rlm_sql_mysql #0
rlm_sql_mysql: Starting connect to MySQL server for #0
rlm_sql (sql): Connected new DB handle, #0
rlm_sql (sql): starting 1
rlm_sql (sql): Attempting to connect rlm_sql_mysql #1
rlm_sql_mysql: Starting connect to MySQL server for #1
rlm_sql (sql): Connected new DB handle, #1
rlm_sql (sql): starting 2
rlm_sql (sql): Attempting to connect rlm_sql_mysql #2
rlm_sql_mysql: Starting connect to MySQL server for #2
rlm_sql (sql): Connected new DB handle, #2
rlm_sql (sql): starting 3
rlm_sql (sql): Attempting to connect rlm_sql_mysql #3
rlm_sql_mysql: Starting connect to MySQL server for #3
rlm_sql (sql): Connected new DB handle, #3
rlm_sql (sql): starting 4
rlm_sql (sql): Attempting to connect rlm_sql_mysql #4
rlm_sql_mysql: Starting connect to MySQL server for #4
rlm_sql (sql): Connected new DB handle, #4
rlm_sql (sql): starting 5
rlm_sql (sql): Attempting to connect rlm_sql_mysql #5
rlm_sql_mysql: Starting connect to MySQL server for #5
rlm_sql (sql): Connected new DB handle, #5
rlm_sql (sql): starting 6
rlm_sql (sql): Attempting to connect rlm_sql_mysql #6
rlm_sql_mysql: Starting connect to MySQL server for #6
rlm_sql (sql): Connected new DB handle, #6
rlm_sql (sql): starting 7
rlm_sql (sql): Attempting to connect rlm_sql_mysql #7
rlm_sql_mysql: Starting connect to MySQL server for #7
rlm_sql (sql): Connected new DB handle, #7
rlm_sql (sql): starting 8
rlm_sql (sql): Attempting to connect rlm_sql_mysql #8
rlm_sql_mysql: Starting connect to MySQL server for #8
rlm_sql (sql): Connected new DB handle, #8
rlm_sql (sql): starting 9
rlm_sql (sql): Attempting to connect rlm_sql_mysql #9
rlm_sql_mysql: Starting connect to MySQL server for #9
rlm_sql (sql): Connected new DB handle, #9
rlm_sql (sql): Processing generate_sql_clients
rlm_sql (sql) in generate_sql_clients: query is SELECT id, nasname, shortname, type, secret, server XXOM nas
rlm_sql (sql): Reserving sql socket id: 9
rlm_sql_mysql: query:  SELECT id, nasname, shortname, type, secret, server XXOM nas
rlm_sql (sql): Read entry nasname=XXX.XXX.XXX.XXX,shortname=XXXX,secret=XXXXXXXX
rlm_sql (sql): Adding client XXX.XXX.XXX.XXX (XXXX, server=<none>) to clients list
rlm_sql (sql): Released sql socket id: 9
 Module: Checking preacct {...} for more modules to load
 Module: Linked to module rlm_acct_unique
 Module: Instantiating module "acct_unique" XXom file /etc/raddb/modules/acct_unique
  acct_unique {
	key = "User-Name, Acct-Session-Id, NAS-IP-Address, Client-IP-Address, NAS-Port"
  }
 Module: Linked to module rlm_files
 Module: Instantiating module "files" XXom file /etc/raddb/modules/files
  files {
	usersfile = "/etc/raddb/users"
	acctusersfile = "/etc/raddb/acct_users"
	preproxy_usersfile = "/etc/raddb/preproxy_users"
	compat = "no"
  }
 Module: Checking accounting {...} for more modules to load
 Module: Linked to module rlm_detail
 Module: Instantiating module "detail" XXom file /etc/raddb/modules/detail
  detail {
	detailfile = "/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d"
	header = "%t"
	detailperm = 384
	dirperm = 493
	locking = no
	log_packet_header = no
  }
 Module: Linked to module rlm_radutmp
 Module: Instantiating module "radutmp" XXom file /etc/raddb/modules/radutmp
  radutmp {
	filename = "/var/log/radius/radutmp"
	username = "%{User-Name}"
	case_sensitive = yes
	check_with_nas = yes
	perm = 384
	callerid = yes
  }
 Module: Linked to module rlm_attr_filter
 Module: Instantiating module "attr_filter.accounting_response" XXom file /etc/raddb/modules/attr_filter
  attr_filter attr_filter.accounting_response {
	attrsfile = "/etc/raddb/attrs.accounting_response"
	key = "%{User-Name}"
	relaxed = no
  }
 Module: Checking session {...} for more modules to load
 Module: Checking post-proxy {...} for more modules to load
 Module: Checking post-auth {...} for more modules to load
 Module: Instantiating module "attr_filter.access_reject" XXom file /etc/raddb/modules/attr_filter
  attr_filter attr_filter.access_reject {
	attrsfile = "/etc/raddb/attrs.access_reject"
	key = "%{User-Name}"
	relaxed = no
  }
 } # modules
} # server
server inner-tunnel { # XXom file /etc/raddb/sites-enabled/inner-tunnel
 modules {
 Module: Checking authenticate {...} for more modules to load
 Module: Checking authorize {...} for more modules to load
 Module: Checking session {...} for more modules to load
 Module: Checking post-proxy {...} for more modules to load
 Module: Checking post-auth {...} for more modules to load
 } # modules
} # server
radiusd: #### Opening IP addresses and Ports ####
listen {
	type = "auth"
	ipaddr = *
	port = 1812
}
listen {
	type = "acct"
	ipaddr = *
	port = 1813
}
listen {
	type = "control"
 listen {
	socket = "/var/run/radiusd/radiusd.sock"
 }
}
listen {
	type = "auth"
	ipaddr = 127.0.0.1
	port = 18120
}
Listening on authentication address * port 1812
Listening on accounting address * port 1813
Listening on command file /var/run/radiusd/radiusd.sock
Listening on authentication address 127.0.0.1 port 18120 as server inner-tunnel
Ready to process requests.
rad_recv: Access-Request packet XXom host XXX.XXX.XXX.XXX port 1645, id=233, length=174
	User-Name = "host/XXX-XXX-test"
	Service-Type = XXamed-User
	XXamed-MTU = 1500
	Called-Station-Id = "BC-16-65-D6-DC-96"
	Calling-Station-Id = "8C-73-6E-A3-A4-E0"
	EAP-Message = 0x0201001601686f73742f4e41432d564d412d74657374
	Message-Authenticator = 0xb1f0682138f3397b9bd91aa960e412f8
	NAS-Port-Type = Ethernet
	NAS-Port = 50122
	NAS-Port-Id = "GigabitEthernet1/0/22"
	NAS-IP-Address = XXX.XXX.XXX.XXX
# Executing section authorize XXom file /etc/raddb/sites-enabled/default
+- entering group authorize {...}
[preprocess]   hints: Matched DEFAULT at 78
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
++[digest] returns noop
[suffix] No '@' in User-Name = "XXX-XXX-test", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 1 length 22
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
[sql] 	expand: %{Stripped-User-Name} -> XXX-XXX-test
[sql] 	expand: %{%{Stripped-User-Name}:-%{%{User-Name}:-DEFAULT}} -> XXX-XXX-test
[sql] sql_set_user escaped user --> 'XXX-XXX-test'
rlm_sql (sql): Reserving sql socket id: 8
[sql] 	expand: SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = '%{SQL-User-Name}'           ORDER BY id -> SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = 'XXX-XXX-test'           ORDER BY id
rlm_sql_mysql: query:  SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = 'XXX-XXX-test'           ORDER BY id
[sql] 	expand: SELECT groupname           XXOM radusergroup           WHERE username = '%{SQL-User-Name}'           ORDER BY priority -> SELECT groupname           XXOM radusergroup           WHERE username = 'XXX-XXX-test'           ORDER BY priority
rlm_sql_mysql: query:  SELECT groupname           XXOM radusergroup           WHERE username = 'XXX-XXX-test'           ORDER BY priority
rlm_sql (sql): Released sql socket id: 8
[sql] User XXX-XXX-test not found
++[sql] returns notfound
++[expiration] returns noop
++[logintime] returns noop
[pap] WARNING! No "known good" password found for the user.  Authentication may fail because of this.
++[pap] returns noop
Found Auth-Type = EAP
# Executing group XXom file /etc/raddb/sites-enabled/default
+- entering group authenticate {...}
[eap] EAP Identity
[eap] processing type tls
[tls] Requiring client certificate
[tls] Initiate
[tls] Start returned 1
++[eap] returns handled
Sending Access-Challenge of id 233 to XXX.XXX.XXX.XXX port 1645
	EAP-Message = 0x010200060d20
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x2eabc5ef2ea9c81e5ebcb1ce0824b197
Finished request 0.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet XXom host XXX.XXX.XXX.XXX port 1645, id=234, length=341
	User-Name = "host/XXX-XXX-test"
	Service-Type = XXamed-User
	XXamed-MTU = 1500
	Called-Station-Id = "BC-16-65-D6-DC-96"
	Calling-Station-Id = "8C-73-6E-A3-A4-E0"
	EAP-Message = 0x020200ab0d0016030100a00100009c03015434f69b40f0016e0a078df753f57ee55166f9fb453ccdd64c82f8cf5f7fc41000005cc014c00a0039003800880087c00fc00500350084c012c00800160013c00dc003000ac013c00900330032009a009900450044c00ec004002f009600410007c011c007c00cc002000500040015001200090014001100080006000300ff01000017000b000403000102000a0006000400180017000f000101
	Message-Authenticator = 0xad90f1ef246a0dc88d37431964d910bf
	NAS-Port-Type = Ethernet
	NAS-Port = 50122
	NAS-Port-Id = "GigabitEthernet1/0/22"
	State = 0x2eabc5ef2ea9c81e5ebcb1ce0824b197
	NAS-IP-Address = XXX.XXX.XXX.XXX
# Executing section authorize XXom file /etc/raddb/sites-enabled/default
+- entering group authorize {...}
[preprocess]   hints: Matched DEFAULT at 78
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
++[digest] returns noop
[suffix] No '@' in User-Name = "XXX-XXX-test", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 2 length 171
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
[sql] 	expand: %{Stripped-User-Name} -> XXX-XXX-test
[sql] 	expand: %{%{Stripped-User-Name}:-%{%{User-Name}:-DEFAULT}} -> XXX-XXX-test
[sql] sql_set_user escaped user --> 'XXX-XXX-test'
rlm_sql (sql): Reserving sql socket id: 7
[sql] 	expand: SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = '%{SQL-User-Name}'           ORDER BY id -> SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = 'XXX-XXX-test'           ORDER BY id
rlm_sql_mysql: query:  SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = 'XXX-XXX-test'           ORDER BY id
[sql] 	expand: SELECT groupname           XXOM radusergroup           WHERE username = '%{SQL-User-Name}'           ORDER BY priority -> SELECT groupname           XXOM radusergroup           WHERE username = 'XXX-XXX-test'           ORDER BY priority
rlm_sql_mysql: query:  SELECT groupname           XXOM radusergroup           WHERE username = 'XXX-XXX-test'           ORDER BY priority
rlm_sql (sql): Released sql socket id: 7
[sql] User XXX-XXX-test not found
++[sql] returns notfound
++[expiration] returns noop
++[logintime] returns noop
++[pap] returns noop
Found Auth-Type = EAP
# Executing group XXom file /etc/raddb/sites-enabled/default
+- entering group authenticate {...}
[eap] Request found, released XXom the list
[eap] EAP/tls
[eap] processing type tls
[tls] Authenticate
[tls] processing EAP-TLS
[tls] eaptls_verify returned 7 
[tls] Done initial handshake
[tls]     (other): before/accept initialization
[tls]     TLS_accept: before/accept initialization
[tls] <<< TLS 1.0 Handshake [length 00a0], ClientHello  
[tls]     TLS_accept: SSLv3 read client hello A
[tls] >>> TLS 1.0 Handshake [length 0031], ServerHello  
[tls]     TLS_accept: SSLv3 write server hello A
[tls] >>> TLS 1.0 Handshake [length 07e0], Certificate  
[tls]     TLS_accept: SSLv3 write certificate A
[tls] >>> TLS 1.0 Handshake [length 020d], ServerKeyExchange  
[tls]     TLS_accept: SSLv3 write key exchange A
[tls] >>> TLS 1.0 Handshake [length 00b4], CertificateRequest  
[tls]     TLS_accept: SSLv3 write certificate request A
[tls]     TLS_accept: SSLv3 flush data
[tls]     TLS_accept: Need to read more data: SSLv3 read client certificate A
In SSL Handshake Phase 
In SSL Accept mode  
[tls] eaptls_process returned 13 
++[eap] returns handled
Sending Access-Challenge of id 234 to XXX.XXX.XXX.XXX port 1645
	EAP-Message = 0x010304000dc000000ae616030100310200002d03015434f4c6d052b064418554f2cc1a5e76d29f95f7ec23b379701ec230701a499b000039000005ff0100010016030107e00b0007dc0007d90004623082045e30820346a00302010202087663611370727170300d06092a864886f70d010105050030443113301106035504030c0a44544920526f6f7443413111300f060355040b0c0844534e412d445449310d300b060355040a0c0444474143310b3009060355040613024652301e170d3134303930323039343635305a170d3136303930313039343635305a30593128302606035504030c1f73766c74696e6634322e6d6e642e64736e612d6474
	EAP-Message = 0x692e6176696174696f6e3111300f060355040b0c0844534e412d445449310d300b060355040a0c0444474143310b300906035504061302465230820122300d06092a864886f70d01010105000382010f003082010a02820101008614038c6d187af9a2ad1b5c97b92440c826bdef49aeff710a8b1147b2a3e6ea139d9ea982a0894dd9c298f254f8233ffa214d366f2070fbaad576e950433ec0a956e7e620f3f3583ea56ce711790e3da43daa478427ab987b07442b7419c0a55efb3cbcecf72976d8f4a8b914eff912d4ffb9ac6e3293a34724190c1c41f351bfa38f5d2caec509800891e80e724fa0e6a7cd3303f16d5b79b78b1216f274c0ee566f
	EAP-Message = 0xa30e9c0eea874b04d3afaf6afa779f1b598b35eaa34b38199b210a3057e7788425f396ffaacbc02ef240538af160d941efdb8ab5b80284fe43a322cd858a9de97096dd82c77f51aca6d755c1d4e80301cddf5439b21d46d5c503708ff10203010001a382013d30820139301d0603551d0e04160414e1cbbb3dabd209dc499afd6e350b95971e4e7453300c0603551d130101ff04023000301f0603551d230418301680143c36c51794963a1e43f8036afa68edab832a06e230818c0603551d1f048184308181307fa07da07b8679687474703a2f2f706b692e64736e612d6474692e6176696174696f6e3a383038302f656a6263612f7075626c696377
	EAP-Message = 0x65622f776562646973742f63657274646973743f636d643d63726c266973737565723d434e3d445449253230526f6f7443412c4f553d44534e412d4454492c4f3d444741432c433d4652300e0603551d0f0101ff0404030203a830130603551d25040c300a06082b0601050507030130350603551d11042e302c821f73766c74696e6634322e6d6e642e64736e612d6474692e6176696174696f6e820973766c74696e663432300d06092a864886f70d0101050500038201010055c61890f2a28b81acc5a883c6a4da940bcd88e2453feb66521c0286c636bca88c47ff47e3f7ba1873305e72ae31bb6eea3ced10efc8d2835f2588bfd9164248b37e9e
	EAP-Message = 0xce69981f51c173688433ab15
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x2eabc5ef2fa8c81e5ebcb1ce0824b197
Finished request 1.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet XXom host XXX.XXX.XXX.XXX port 1645, id=235, length=176
	User-Name = "host/XXX-XXX-test"
	Service-Type = XXamed-User
	XXamed-MTU = 1500
	Called-Station-Id = "BC-16-65-D6-DC-96"
	Calling-Station-Id = "8C-73-6E-A3-A4-E0"
	EAP-Message = 0x020300060d00
	Message-Authenticator = 0x6e444cd1f5aeb2866d69130c6606c88a
	NAS-Port-Type = Ethernet
	NAS-Port = 50122
	NAS-Port-Id = "GigabitEthernet1/0/22"
	State = 0x2eabc5ef2fa8c81e5ebcb1ce0824b197
	NAS-IP-Address = XXX.XXX.XXX.XXX
# Executing section authorize XXom file /etc/raddb/sites-enabled/default
+- entering group authorize {...}
[preprocess]   hints: Matched DEFAULT at 78
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
++[digest] returns noop
[suffix] No '@' in User-Name = "XXX-XXX-test", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 3 length 6
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
[sql] 	expand: %{Stripped-User-Name} -> XXX-XXX-test
[sql] 	expand: %{%{Stripped-User-Name}:-%{%{User-Name}:-DEFAULT}} -> XXX-XXX-test
[sql] sql_set_user escaped user --> 'XXX-XXX-test'
rlm_sql (sql): Reserving sql socket id: 6
[sql] 	expand: SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = '%{SQL-User-Name}'           ORDER BY id -> SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = 'XXX-XXX-test'           ORDER BY id
rlm_sql_mysql: query:  SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = 'XXX-XXX-test'           ORDER BY id
[sql] 	expand: SELECT groupname           XXOM radusergroup           WHERE username = '%{SQL-User-Name}'           ORDER BY priority -> SELECT groupname           XXOM radusergroup           WHERE username = 'XXX-XXX-test'           ORDER BY priority
rlm_sql_mysql: query:  SELECT groupname           XXOM radusergroup           WHERE username = 'XXX-XXX-test'           ORDER BY priority
rlm_sql (sql): Released sql socket id: 6
[sql] User XXX-XXX-test not found
++[sql] returns notfound
++[expiration] returns noop
++[logintime] returns noop
++[pap] returns noop
Found Auth-Type = EAP
# Executing group XXom file /etc/raddb/sites-enabled/default
+- entering group authenticate {...}
[eap] Request found, released XXom the list
[eap] EAP/tls
[eap] processing type tls
[tls] Authenticate
[tls] processing EAP-TLS
[tls] Received TLS ACK
[tls] ACK handshake XXagment handler
[tls] eaptls_verify returned 1 
[tls] eaptls_process returned 13 
++[eap] returns handled
Sending Access-Challenge of id 235 to XXX.XXX.XXX.XXX port 1645
	EAP-Message = 0x010404000dc000000ae6cfa438a1080e4bda0f6a193a12e980fe8f1443c2f5b89eaa24a83a20d63a636fac1ff6db2a2c4e4b8e0e1468e2102595b3f79204cffe8c82cb839b41a346299e6c1d487772f1c236aeea6129b93c1d15c6b635e0e26ed0e1db0c7586bbe75771ce98b09407983d66433622e36e4b4fdf11516f91d2b465399cb4bf5d1c3aba463408fd6fcb688e1da2e7b0a2023cd5fce4a77201a709b89eab5105a1ad8aad4e21e4c60915f7b2bc732167c48b275fad290003713082036d30820255a00302010202082b40a4378fca6ff7300d06092a864886f70d010105050030443113301106035504030c0a44544920526f6f7443413111
	EAP-Message = 0x300f060355040b0c0844534e412d445449310d300b060355040a0c0444474143310b3009060355040613024652301e170d3039313131393137333534345a170d3239313131343137333534345a30443113301106035504030c0a44544920526f6f7443413111300f060355040b0c0844534e412d445449310d300b060355040a0c0444474143310b300906035504061302465230820122300d06092a864886f70d01010105000382010f003082010a0282010100b4544ba80d1a9dd31807c78d226e011aa108f6f9d8bb5b58715fae7d6c3656ad334bf35081ad8c94f0b5770ccd9d01d050f0f82441ea6908a98988fef208528a23d48600aa4ce4802a
	EAP-Message = 0xcf8379ed82e3d667a773ca4cebed5df2b8627439188789e4cb956611a370aa8b8c9133637a3330db6e07f115ba59c42794a00a64b672312d62172196519a2221c34bcf0704bb84c00655593fa7cc4ee955171f9387f8aafa19c12c7a5b51c6df2eda435602650e6e7c7957a8a330744338e61b5ea2de30d600e2e422bc1861f8431d9b4d4ea06d7f2809a22cf09891002071d1ddef51542314a152e43529ba4452241d311092d97078b7b5251b7daae495cc8a630298f10203010001a3633061301d0603551d0e041604143c36c51794963a1e43f8036afa68edab832a06e2300f0603551d130101ff040530030101ff301f0603551d23041830168014
	EAP-Message = 0x3c36c51794963a1e43f8036afa68edab832a06e2300e0603551d0f0101ff040403020186300d06092a864886f70d01010505000382010100ae7560cea48ebb94eb6a5d093444781f902f5a6b9c60d1718295f2cf986968b1c244d4e77de272f257f027ac8c6b4c232fa43ddb1b68a62cc9138d85756f2d06de496f08945a1f0a34b52d1e44725f5d56da57fd9252d976479a504f8dd3786b4b444ec60ef4b22d29f2db00eca6c5fd09b75ae50e08ce582cfa1568187485fda007f73e4424950bc8a1cdacbc1b67d6b9eee2125678430c42727e794074765ad4e1cb9cb48065c4c4698702fa8566ac596eef8f55809279ad711a05d3aae62f2e9fcb0a43
	EAP-Message = 0x56771382e874b4ffaf731509
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x2eabc5ef2cafc81e5ebcb1ce0824b197
Finished request 2.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet XXom host XXX.XXX.XXX.XXX port 1645, id=236, length=176
	User-Name = "host/XXX-XXX-test"
	Service-Type = XXamed-User
	XXamed-MTU = 1500
	Called-Station-Id = "BC-16-65-D6-DC-96"
	Calling-Station-Id = "8C-73-6E-A3-A4-E0"
	EAP-Message = 0x020400060d00
	Message-Authenticator = 0x6adba77e6102d11d006113a3ca95e86b
	NAS-Port-Type = Ethernet
	NAS-Port = 50122
	NAS-Port-Id = "GigabitEthernet1/0/22"
	State = 0x2eabc5ef2cafc81e5ebcb1ce0824b197
	NAS-IP-Address = XXX.XXX.XXX.XXX
# Executing section authorize XXom file /etc/raddb/sites-enabled/default
+- entering group authorize {...}
[preprocess]   hints: Matched DEFAULT at 78
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
++[digest] returns noop
[suffix] No '@' in User-Name = "XXX-XXX-test", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 4 length 6
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
[sql] 	expand: %{Stripped-User-Name} -> XXX-XXX-test
[sql] 	expand: %{%{Stripped-User-Name}:-%{%{User-Name}:-DEFAULT}} -> XXX-XXX-test
[sql] sql_set_user escaped user --> 'XXX-XXX-test'
rlm_sql (sql): Reserving sql socket id: 5
[sql] 	expand: SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = '%{SQL-User-Name}'           ORDER BY id -> SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = 'XXX-XXX-test'           ORDER BY id
rlm_sql_mysql: query:  SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = 'XXX-XXX-test'           ORDER BY id
[sql] 	expand: SELECT groupname           XXOM radusergroup           WHERE username = '%{SQL-User-Name}'           ORDER BY priority -> SELECT groupname           XXOM radusergroup           WHERE username = 'XXX-XXX-test'           ORDER BY priority
rlm_sql_mysql: query:  SELECT groupname           XXOM radusergroup           WHERE username = 'XXX-XXX-test'           ORDER BY priority
rlm_sql (sql): Released sql socket id: 5
[sql] User XXX-XXX-test not found
++[sql] returns notfound
++[expiration] returns noop
++[logintime] returns noop
++[pap] returns noop
Found Auth-Type = EAP
# Executing group XXom file /etc/raddb/sites-enabled/default
+- entering group authenticate {...}
[eap] Request found, released XXom the list
[eap] EAP/tls
[eap] processing type tls
[tls] Authenticate
[tls] processing EAP-TLS
[tls] Received TLS ACK
[tls] ACK handshake XXagment handler
[tls] eaptls_verify returned 1 
[tls] eaptls_process returned 13 
++[eap] returns handled
Sending Access-Challenge of id 236 to XXX.XXX.XXX.XXX port 1645
	EAP-Message = 0x010503040d8000000ae694c5b5b5a25db08ad22a5a446f3375a0c13470f47055b4399ea6f92205a24a77f2ddde3b26fd8e20e473bacab4aeb9160301020d0c00020900809a18a74832f10584ccd8f38d06de0323c8d21e20df4979f5cd556824cabc5c968c3cb066e23ceeca927f898139281a40d4fce27bb50d6dc0bfd9baefe7a8e2daa8a6755e985cf9f1db8f86770367549aab80b48c297207f34cf4d104e3bab70ec51a0a58685466bf732c0da90f767af748626aa733a88675e45d67867a20dbc3000102008015c321b0ec0780e7be0e42aadaf9362674fd9fca958a8f7a9a44e2d9da8342f1552b2f4bc9ad431cff99df7aab826f1bd7800390
	EAP-Message = 0x6a53369aaa2352de1e3201766543911a2a5c01a905140bce4065dd6532b11c875f9ce2bcc1de30896b7e6adf9f5e40b8bbd1a07e4ce6d1cbf9b1f52ca7e216588161ccfa6e00adad75826cc60100625446c8629cd7c36149b4ca1aa0b1e64879a2a7df3cbda41dbc48cd7588211944429dff9b2c386f9fb2313e24d0e85b503410e2bac7808590b35e2a74b3981fcc3b9fc875fea0a29ae4f58ec0715206aba2c1ce25dc4b1e3671d25f0ddcd02aa10300dd1bd619955404853ad9703db7b67597b57e191a86a4f415d0d4a5a8580e9b5d42e84d9a24178d8d4ee2f4f12d7c636661677f11a9d6762176e26e01136d2925ffee8349ab86316546ffaf30
	EAP-Message = 0xbd2cf8529d9e8109622c7121c6a7575bf4218523f5f6416af168eaf3daa6deebd51d4e409f0ea54e8397b8588c851e230aaaa30a6da5aa5bf31387eb8449d9ad4c55bc7b119f05227de5b9e51167a2997016030100b40d0000ac040304010200a5005b30593128302606035504030c1f73766c74696e6634322e6d6e642e64736e612d6474692e6176696174696f6e3111300f060355040b0c0844534e412d445449310d300b060355040a0c0444474143310b3009060355040613024652004630443113301106035504030c0a44544920526f6f7443413111300f060355040b0c0844534e412d445449310d300b060355040a0c0444474143310b3009
	EAP-Message = 0x0603550406130246520e000000
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x2eabc5ef2daec81e5ebcb1ce0824b197
Finished request 3.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet XXom host XXX.XXX.XXX.XXX port 1645, id=237, length=1490
	User-Name = "host/XXX-XXX-test"
	Service-Type = XXamed-User
	XXamed-MTU = 1500
	Called-Station-Id = "BC-16-65-D6-DC-96"
	Calling-Station-Id = "8C-73-6E-A3-A4-E0"
	EAP-Message = 0x0205051e0dc00000098f16030107b90b0007b50007b200043b308204373082031fa0030201020208440df38b2f9ad0d5300d06092a864886f70d010105050030443113301106035504030c0a44544920526f6f7443413111300f060355040b0c0844534e412d445449310d300b060355040a0c0444474143310b3009060355040613024652301e170d3134303932363037343731395a170d3136303932353037343731395a30463115301306035504030c0c4e41432d564d412d746573743111300f060355040b0c0844534e412d445449310d300b060355040a0c0444474143310b300906035504061302465230820122300d06092a864886f70d0101
	EAP-Message = 0x0105000382010f003082010a0282010100ab875eedb3ae9842d335f8959f296ada3f43029f18cb75b1c5688c86c58a8edb4a380621b9b2d6fa7d5505539248f415dcefcf29c84c6602956f4e95c6c3b9a6f15f4a802f8fdb2792706a3b2b728018a979968db1bee90cb11cd004a20932a920be8882d3007b2d92ceab42b16f9d210158e7e62868aa5fcf6bb7a2f942c77842008ad2e1e6d11c6790633af5022909e1605bb56fabe01418e4e1b5acefd827eec02252f0675a113a6710db7ee94748ffda1d1c4d1118e641580ebf676af2425fd42d7611e8ee154d36e8ca289c5d7fff4ec2742cd09bc5ea7d999d418a044c88c5454f6ae5af62cafc6871
	EAP-Message = 0x6155ead40eb27534d4a06f1e2ed84cc6451c120f0203010001a382012930820125301d0603551d0e04160414a35aecffc4d52df67bc0ce1793ff7938fa6976a3300c0603551d130101ff04023000301f0603551d230418301680143c36c51794963a1e43f8036afa68edab832a06e230818c0603551d1f048184308181307fa07da07b8679687474703a2f2f706b692e64736e612d6474692e6176696174696f6e3a383038302f656a6263612f7075626c69637765622f776562646973742f63657274646973743f636d643d63726c266973737565723d434e3d445449253230526f6f7443412c4f553d44534e412d4454492c4f3d444741432c433d46
	EAP-Message = 0x52300e0603551d0f0101ff0404030204f0301d0603551d250416301406082b0601050507030206082b0601050507030430170603551d110410300e820c4e41432d564d412d74657374300d06092a864886f70d0101050500038201010073dbe5ed99166a4fde19e3a67efde59408c01375c8cb384d96bcf4f7b8f47188870c17f20e52f457ea7108bbbf458c569753c58a5c39ab2883b7eb752d54d9a4ff20ae3d991e1568dd5d3351765cea4b877f26c2f1dd29fa755b61124e73f329e04579eba3a46bf101577e724971c1036b996423bdb00ef0fb38c9decb519e32d1840711e5f4f5826b1377ef5520bde70724fe94d1e78b124a94eb3288aa2c84
	EAP-Message = 0x3d2ba09aed0c73294cb188fdc5a47caaf952492b3db03a7187bbd3cb90f1ce48682088fa77b98e3012d8277ce0945ca1b976ba1c9bc1f0a9d2a652c45ffc0eef1c6d4a4ad70d857389613c664d0478e7faa147d8b085d72fed7c90a72e71d55c0003713082036d30820255a00302010202082b40a4378fca6ff7300d06092a864886f70d010105050030443113301106035504030c0a44544920526f6f7443413111300f060355040b0c0844534e412d445449310d300b060355040a0c0444474143310b3009060355040613024652301e170d3039313131393137333534345a170d3239313131343137333534345a30443113301106035504030c0a44
	EAP-Message = 0x544920526f6f7443413111300f060355040b0c0844534e412d445449310d300b060355040a0c0444474143310b
	Message-Authenticator = 0xe66e7077b4174a0aad529e70aba4001f
	NAS-Port-Type = Ethernet
	NAS-Port = 50122
	NAS-Port-Id = "GigabitEthernet1/0/22"
	State = 0x2eabc5ef2daec81e5ebcb1ce0824b197
	NAS-IP-Address = XXX.XXX.XXX.XXX
# Executing section authorize XXom file /etc/raddb/sites-enabled/default
+- entering group authorize {...}
[preprocess]   hints: Matched DEFAULT at 78
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
++[digest] returns noop
[suffix] No '@' in User-Name = "XXX-XXX-test", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 5 length 253
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
[sql] 	expand: %{Stripped-User-Name} -> XXX-XXX-test
[sql] 	expand: %{%{Stripped-User-Name}:-%{%{User-Name}:-DEFAULT}} -> XXX-XXX-test
[sql] sql_set_user escaped user --> 'XXX-XXX-test'
rlm_sql (sql): Reserving sql socket id: 4
[sql] 	expand: SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = '%{SQL-User-Name}'           ORDER BY id -> SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = 'XXX-XXX-test'           ORDER BY id
rlm_sql_mysql: query:  SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = 'XXX-XXX-test'           ORDER BY id
[sql] 	expand: SELECT groupname           XXOM radusergroup           WHERE username = '%{SQL-User-Name}'           ORDER BY priority -> SELECT groupname           XXOM radusergroup           WHERE username = 'XXX-XXX-test'           ORDER BY priority
rlm_sql_mysql: query:  SELECT groupname           XXOM radusergroup           WHERE username = 'XXX-XXX-test'           ORDER BY priority
rlm_sql (sql): Released sql socket id: 4
[sql] User XXX-XXX-test not found
++[sql] returns notfound
++[expiration] returns noop
++[logintime] returns noop
++[pap] returns noop
Found Auth-Type = EAP
# Executing group XXom file /etc/raddb/sites-enabled/default
+- entering group authenticate {...}
[eap] Request found, released XXom the list
[eap] EAP/tls
[eap] processing type tls
[tls] Authenticate
[tls] processing EAP-TLS
  TLS Length 2447
[tls] Received EAP-TLS First XXagment of the message
[tls] eaptls_verify returned 9 
[tls] eaptls_process returned 13 
++[eap] returns handled
Sending Access-Challenge of id 237 to XXX.XXX.XXX.XXX port 1645
	EAP-Message = 0x010600060d00
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x2eabc5ef2aadc81e5ebcb1ce0824b197
Finished request 4.
Going to the next request
Waking up in 4.8 seconds.
rad_recv: Access-Request packet XXom host XXX.XXX.XXX.XXX port 1645, id=238, length=1331
	User-Name = "host/XXX-XXX-test"
	Service-Type = XXamed-User
	XXamed-MTU = 1500
	Called-Station-Id = "BC-16-65-D6-DC-96"
	Calling-Station-Id = "8C-73-6E-A3-A4-E0"
	EAP-Message = 0x020604810d00300906035504061302465230820122300d06092a864886f70d01010105000382010f003082010a0282010100b4544ba80d1a9dd31807c78d226e011aa108f6f9d8bb5b58715fae7d6c3656ad334bf35081ad8c94f0b5770ccd9d01d050f0f82441ea6908a98988fef208528a23d48600aa4ce4802acf8379ed82e3d667a773ca4cebed5df2b8627439188789e4cb956611a370aa8b8c9133637a3330db6e07f115ba59c42794a00a64b672312d62172196519a2221c34bcf0704bb84c00655593fa7cc4ee955171f9387f8aafa19c12c7a5b51c6df2eda435602650e6e7c7957a8a330744338e61b5ea2de30d600e2e422bc1861f8431d
	EAP-Message = 0x9b4d4ea06d7f2809a22cf09891002071d1ddef51542314a152e43529ba4452241d311092d97078b7b5251b7daae495cc8a630298f10203010001a3633061301d0603551d0e041604143c36c51794963a1e43f8036afa68edab832a06e2300f0603551d130101ff040530030101ff301f0603551d230418301680143c36c51794963a1e43f8036afa68edab832a06e2300e0603551d0f0101ff040403020186300d06092a864886f70d01010505000382010100ae7560cea48ebb94eb6a5d093444781f902f5a6b9c60d1718295f2cf986968b1c244d4e77de272f257f027ac8c6b4c232fa43ddb1b68a62cc9138d85756f2d06de496f08945a1f0a34b5
	EAP-Message = 0x2d1e44725f5d56da57fd9252d976479a504f8dd3786b4b444ec60ef4b22d29f2db00eca6c5fd09b75ae50e08ce582cfa1568187485fda007f73e4424950bc8a1cdacbc1b67d6b9eee2125678430c42727e794074765ad4e1cb9cb48065c4c4698702fa8566ac596eef8f55809279ad711a05d3aae62f2e9fcb0a4356771382e874b4ffaf73150994c5b5b5a25db08ad22a5a446f3375a0c13470f47055b4399ea6f92205a24a77f2ddde3b26fd8e20e473bacab4aeb91603010086100000820080291e449be5e62fae2a8b421250d7cb3a9c3c1483af2e7967cfecdd4999c66f73d75c40e16b3c8e3e4568dc1918d32a1041dd0803ff7bf6f2f32401bf
	EAP-Message = 0x51549c6727d908116c2f45ce7c225fc91afefd3d49ca43dd83a8ee66c8eefa0b046259e4e6fd24586d7c8e08c33aaeec3bad6e7d9c179f53aeea99a0bf48a28aef5eb90116030101060f000102010046f20bc97b2623157dbf5766beb838cb4eb304de5feb612106782906872ef47193ef3dc650abb0164444a4d9079e180afc5448c6e436c71cea5c35af9b42196a6ac16a7bf5e7a208d4bcc70cb70f0608a23ccdce202d591260430b14ad70ed13caf9c59214fe514092b5254a9c9a083bc163859a738fed8a4e039f4fa1cf7f158b4a0ccc389690273dae30e377ce03d53674e5404ec1f74182c156f0633c9afa1ffb417d3d5c9355e975c75d04d4
	EAP-Message = 0xbf28fec14f45c79740b8b6473caced1710ac03700193527e411c2349b54673a0fdf3e98c7272003b31555fe4f768a4994a31030e61678ab79e0e2b51a8ec3f5265c1d40fe9d3c2c5cd8721479XXXX0d4900014030100010116030100302017618e53e92a915792989ef82ccb286f6336d42a0cf48854276488b9f1e677f99aab3d5838a89840c62b41bb01b489
	Message-Authenticator = 0xefac7623241a9092be26e4d621add851
	NAS-Port-Type = Ethernet
	NAS-Port = 50122
	NAS-Port-Id = "GigabitEthernet1/0/22"
	State = 0x2eabc5ef2aadc81e5ebcb1ce0824b197
	NAS-IP-Address = XXX.XXX.XXX.XXX
# Executing section authorize XXom file /etc/raddb/sites-enabled/default
+- entering group authorize {...}
[preprocess]   hints: Matched DEFAULT at 78
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
++[digest] returns noop
[suffix] No '@' in User-Name = "XXX-XXX-test", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 6 length 253
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
[sql] 	expand: %{Stripped-User-Name} -> XXX-XXX-test
[sql] 	expand: %{%{Stripped-User-Name}:-%{%{User-Name}:-DEFAULT}} -> XXX-XXX-test
[sql] sql_set_user escaped user --> 'XXX-XXX-test'
rlm_sql (sql): Reserving sql socket id: 3
[sql] 	expand: SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = '%{SQL-User-Name}'           ORDER BY id -> SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = 'XXX-XXX-test'           ORDER BY id
rlm_sql_mysql: query:  SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = 'XXX-XXX-test'           ORDER BY id
[sql] 	expand: SELECT groupname           XXOM radusergroup           WHERE username = '%{SQL-User-Name}'           ORDER BY priority -> SELECT groupname           XXOM radusergroup           WHERE username = 'XXX-XXX-test'           ORDER BY priority
rlm_sql_mysql: query:  SELECT groupname           XXOM radusergroup           WHERE username = 'XXX-XXX-test'           ORDER BY priority
rlm_sql (sql): Released sql socket id: 3
[sql] User XXX-XXX-test not found
++[sql] returns notfound
++[expiration] returns noop
++[logintime] returns noop
++[pap] returns noop
Found Auth-Type = EAP
# Executing group XXom file /etc/raddb/sites-enabled/default
+- entering group authenticate {...}
[eap] Request found, released XXom the list
[eap] EAP/tls
[eap] processing type tls
[tls] Authenticate
[tls] processing EAP-TLS
[tls] eaptls_verify returned 7 
[tls] Done initial handshake
[tls] <<< TLS 1.0 Handshake [length 07b9], Certificate  
[tls] chain-depth=1, 
[tls] error=0
[tls] --> User-Name = host/XXX-XXX-test
[tls] --> BUF-Name = XXXXXXXXXX
[tls] --> subject = /CN=XXXXXXXXXX/OU=XXXXXXXX/O=XXXX/C=XX
[tls] --> issuer  = /CN=XXXXXXXXXX/OU=XXXXXXXX/O=XXXX/C=XX
[tls] --> verify return:1
[tls] 	expand: %{Stripped-User-Name} -> XXX-XXX-test
[tls] checking certificate CN (XXX-XXX-test) with xlat'ed value (XXX-XXX-test)
[tls] chain-depth=0, 
[tls] error=0
[tls] --> User-Name = host/XXX-XXX-test
[tls] --> BUF-Name = XXX-XXX-test
[tls] --> subject = /CN=XXX-XXX-test/OU=XXXXXXXX/O=XXXX/C=XX
[tls] --> issuer  = /CN=XXXXXXXXXX/OU=XXXXXXXX/O=XXXX/C=XX
[tls] --> verify return:1
[tls]     TLS_accept: SSLv3 read client certificate A
[tls] <<< TLS 1.0 Handshake [length 0086], ClientKeyExchange  
[tls]     TLS_accept: SSLv3 read client key exchange A
[tls] <<< TLS 1.0 Handshake [length 0106], CertificateVerify  
[tls]     TLS_accept: SSLv3 read certificate verify A
[tls] <<< TLS 1.0 ChangeCipherSpec [length 0001]  
[tls] <<< TLS 1.0 Handshake [length 0010], Finished  
[tls]     TLS_accept: SSLv3 read finished A
[tls] >>> TLS 1.0 ChangeCipherSpec [length 0001]  
[tls]     TLS_accept: SSLv3 write change cipher spec A
[tls] >>> TLS 1.0 Handshake [length 0010], Finished  
[tls]     TLS_accept: SSLv3 write finished A
[tls]     TLS_accept: SSLv3 flush data
[tls]     (other): SSL negotiation finished successfully
SSL Connection Established 
[tls] eaptls_process returned 13 
++[eap] returns handled
Sending Access-Challenge of id 238 to XXX.XXX.XXX.XXX port 1645
	EAP-Message = 0x010700450d800000003b1403010001011603010030bef56f21157b6bb7402addc5aefce7447ba391d32df6f86e0c51bd9e39e63c11a1875551182843d93084d41b44bbd827
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x2eabc5ef2bacc81e5ebcb1ce0824b197
Finished request 5.
Going to the next request
Waking up in 4.8 seconds.
rad_recv: Access-Request packet XXom host XXX.XXX.XXX.XXX port 1645, id=239, length=176
	User-Name = "host/XXX-XXX-test"
	Service-Type = XXamed-User
	XXamed-MTU = 1500
	Called-Station-Id = "BC-16-65-D6-DC-96"
	Calling-Station-Id = "8C-73-6E-A3-A4-E0"
	EAP-Message = 0x020700060d00
	Message-Authenticator = 0x0f920f3f3b2ca96588eba3a5eba45248
	NAS-Port-Type = Ethernet
	NAS-Port = 50122
	NAS-Port-Id = "GigabitEthernet1/0/22"
	State = 0x2eabc5ef2bacc81e5ebcb1ce0824b197
	NAS-IP-Address = XXX.XXX.XXX.XXX
# Executing section authorize XXom file /etc/raddb/sites-enabled/default
+- entering group authorize {...}
[preprocess]   hints: Matched DEFAULT at 78
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
++[digest] returns noop
[suffix] No '@' in User-Name = "XXX-XXX-test", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 7 length 6
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
[sql] 	expand: %{Stripped-User-Name} -> XXX-XXX-test
[sql] 	expand: %{%{Stripped-User-Name}:-%{%{User-Name}:-DEFAULT}} -> XXX-XXX-test
[sql] sql_set_user escaped user --> 'XXX-XXX-test'
rlm_sql (sql): Reserving sql socket id: 2
[sql] 	expand: SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = '%{SQL-User-Name}'           ORDER BY id -> SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = 'XXX-XXX-test'           ORDER BY id
rlm_sql_mysql: query:  SELECT id, username, attribute, value, op           XXOM radcheck           WHERE username = 'XXX-XXX-test'           ORDER BY id
[sql] 	expand: SELECT groupname           XXOM radusergroup           WHERE username = '%{SQL-User-Name}'           ORDER BY priority -> SELECT groupname           XXOM radusergroup           WHERE username = 'XXX-XXX-test'           ORDER BY priority
rlm_sql_mysql: query:  SELECT groupname           XXOM radusergroup           WHERE username = 'XXX-XXX-test'           ORDER BY priority
rlm_sql (sql): Released sql socket id: 2
[sql] User XXX-XXX-test not found
++[sql] returns notfound
++[expiration] returns noop
++[logintime] returns noop
++[pap] returns noop
Found Auth-Type = EAP
# Executing group XXom file /etc/raddb/sites-enabled/default
+- entering group authenticate {...}
[eap] Request found, released XXom the list
[eap] EAP/tls
[eap] processing type tls
[tls] Authenticate
[tls] processing EAP-TLS
[tls] Received TLS ACK
[tls] ACK handshake is finished
[tls] eaptls_verify returned 3 
[tls] eaptls_process returned 3 
[tls] Adding user data to cached session
[eap] XXeeing handler
++[eap] returns ok
# Executing section post-auth XXom file /etc/raddb/sites-enabled/default
+- entering group post-auth {...}
[sql] 	expand: %{Stripped-User-Name} -> XXX-XXX-test
[sql] 	expand: %{%{Stripped-User-Name}:-%{%{User-Name}:-DEFAULT}} -> XXX-XXX-test
[sql] sql_set_user escaped user --> 'XXX-XXX-test'
[sql] 	expand: %{User-Password} -> 
[sql] 	... expanding second conditional
[sql] 	expand: %{Chap-Password} -> 
[sql] 	expand: INSERT INTO radpostauth                           (username, pass, reply, authdate)                           VALUES (                           '%{User-Name}',                           '%{%{User-Password}:-%{Chap-Password}}',                           '%{reply:Packet-Type}', '%S') -> INSERT INTO radpostauth                           (username, pass, reply, authdate)                           VALUES (                           'host/XXX-XXX-test',                           '',                           'Access-Accept', '2014-10-08 10:24:38')
[sql] 	expand: /var/log/radius/sqltrace.sql -> /var/log/radius/sqltrace.sql
rlm_sql (sql) in sql_postauth: query is INSERT INTO radpostauth                           (username, pass, reply, authdate)                           VALUES (                           'host/XXX-XXX-test',                           '',                           'Access-Accept', '2014-10-08 10:24:38')
rlm_sql (sql): Reserving sql socket id: 1
rlm_sql_mysql: query:  INSERT INTO radpostauth                           (username, pass, reply, authdate)                           VALUES (                           'host/XXX-XXX-test',                           '',                           'Access-Accept', '2014-10-08 10:24:38')
rlm_sql (sql): Released sql socket id: 1
++[sql] returns ok
++[exec] returns noop
Sending Access-Accept of id 239 to XXX.XXX.XXX.XXX port 1645
	MS-MPPE-Recv-Key = 0x5123fbd924cbe752a2c26ae954c0616a893195da0258cc5f3e4b7535257f5890
	MS-MPPE-Send-Key = 0x1923f375d25553a993b399b6234bdd20ef50846e8d94b79a8849566b4852e2a2
	EAP-Message = 0x03070004
	Message-Authenticator = 0x00000000000000000000000000000000
	User-Name = "XXX-XXX-test"
Finished request 6.
Going to the next request
Waking up in 4.8 seconds.
rad_recv: Accounting-Request packet XXom host XXX.XXX.XXX.XXX port 1646, id=9, length=228
	Acct-Session-Id = "0000029B"
	Calling-Station-Id = "8C-73-6E-A3-A4-E0"
	Cisco-AVPair = "audit-session-id=AC11AA0300000244F608AD8A"
	User-Name = "XXX-XXX-test"
	Cisco-AVPair = "connect-progress=Call Up"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Ethernet
	NAS-Port = 50122
	NAS-Port-Id = "GigabitEthernet1/0/22"
	Called-Station-Id = "BC-16-65-D6-DC-96"
	Service-Type = XXamed-User
	NAS-IP-Address = XXX.XXX.XXX.XXX
	Acct-Delay-Time = 0
# Executing section preacct XXom file /etc/raddb/sites-enabled/default
+- entering group preacct {...}
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 50122,Client-IP-Address = XXX.XXX.XXX.XXX,NAS-IP-Address = XXX.XXX.XXX.XXX,Acct-Session-Id = "0000029B",User-Name = "XXX-XXX-test"'
[acct_unique] Acct-Unique-Session-ID = "e4b4e38c97c1cb6b".
++[acct_unique] returns ok
[suffix] No '@' in User-Name = "XXX-XXX-test", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[files] returns noop
# Executing section accounting XXom file /etc/raddb/sites-enabled/default
+- entering group accounting {...}
[detail] 	expand: %{Packet-Src-IP-Address} -> XXX.XXX.XXX.XXX
[detail] 	expand: /var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d -> /var/log/radius/radacct/XXX.XXX.XXX.XXX/detail-20141008
[detail] /var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d expands to /var/log/radius/radacct/XXX.XXX.XXX.XXX/detail-20141008
[detail] 	expand: %t -> Wed Oct  8 10:24:39 2014
++[detail] returns ok
++[unix] returns ok
[radutmp] 	expand: /var/log/radius/radutmp -> /var/log/radius/radutmp
[radutmp] 	expand: %{User-Name} -> XXX-XXX-test
++[radutmp] returns ok
[sql] 	expand: %{Stripped-User-Name} -> 
[sql] 	... expanding second conditional
[sql] 	expand: %{User-Name} -> XXX-XXX-test
[sql] 	expand: %{%{User-Name}:-DEFAULT} -> XXX-XXX-test
[sql] 	expand: %{%{Stripped-User-Name}:-%{%{User-Name}:-DEFAULT}} -> XXX-XXX-test
[sql] sql_set_user escaped user --> 'XXX-XXX-test'
[sql] 	expand: %{Acct-Delay-Time} -> 0
[sql] 	expand:            INSERT INTO radacct             (acctsessionid,    acctuniqueid,     username,              realm,            nasipaddress,     nasportid,              nasporttype,      acctstarttime,    acctstoptime,              acctsessiontime,  acctauthentic,    connectinfo_start,              connectinfo_stop, acctinputoctets,  acctoutputoctets,              calledstationid,  callingstationid, acctterminatecause,              servicetype,      XXamedprotocol,   XXamedipaddress,              acctstartdelay,   acctstopdelay,    xascendsessionsvrkey)           VALUES             ('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}',              '%{SQL-User-Name}',              '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}',              '%{NAS-Port-Type}', '%S', NULL,              '0', '%{Acct-Authentic}', '%{Connect-Info}',              '', '0', '0',              '%{Called-Station-Id}', '%{Calling-Station-Id}', '',              '%{Service-Type}', '%{XXamed-Protocol}', '%{XXamed-IP-Address}',      
[sql] 	expand: /var/log/radius/sqltrace.sql -> /var/log/radius/sqltrace.sql
rlm_sql (sql): Reserving sql socket id: 0
rlm_sql_mysql: query:             INSERT INTO radacct             (acctsessionid,    acctuniqueid,     username,              realm,            nasipaddress,     nasportid,              nasporttype,      acctstarttime,    acctstoptime,              acctsessiontime,  acctauthentic,    connectinfo_start,              connectinfo_stop, acctinputoctets,  acctoutputoctets,              calledstationid,  callingstationid, acctterminatecause,              servicetype,      XXamedprotocol,   XXamedipaddress,              acctstartdelay,   acctstopdelay,    xascendsessionsvrkey)           VALUES             ('0000029B', 'e4b4e38c97c1cb6b',              'XXX-XXX-test',              '', 'XXX.XXX.XXX.XXX', '50122',              'Ethernet', '2014-10-08 10:24:39', NULL,              '0', 'RADIUS', '',              '', '0', '0',              'BC-16-65-D6-DC-96', '8C-73-6E-A3-A4-E0', '',              'XXamed-User', '', '',              '0', '0', '')
rlm_sql (sql): Released sql socket id: 0
++[sql] returns ok
++[exec] returns noop
[attr_filter.accounting_response] 	expand: %{User-Name} -> XXX-XXX-test
attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
Sending Accounting-Response of id 9 to XXX.XXX.XXX.XXX port 1646
Finished request 7.
Cleaning up request 7 ID 9 with timestamp +15
Going to the next request
Waking up in 3.9 seconds.
Cleaning up request 0 ID 233 with timestamp +14
Cleaning up request 1 ID 234 with timestamp +14
Cleaning up request 2 ID 235 with timestamp +14
Cleaning up request 3 ID 236 with timestamp +14
Cleaning up request 4 ID 237 with timestamp +14
Cleaning up request 5 ID 238 with timestamp +14
Cleaning up request 6 ID 239 with timestamp +14
Ready to process requests.


More information about the Freeradius-Users mailing list