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@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=) 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.