MySQL and PEAP not talking

Doe Hoe doehoe at gmail.com
Tue Mar 31 01:19:41 CEST 2009


Thanks for the quick reply.

>It is being read! But it does nothing when ntlm_auth is not configured.
>
>You most likely haven't enabled sql in inner-tunnel virtual server. But
>since you edited the debug there is no way to say for sure.

I'm not sure how to enable sql in inner-tunnel virtual server.
Please can you let me know.
Here is the full debug (didn't include it originally because I was
trying to be succinct as possible).

FreeRADIUS Version 2.1.5, for host i486-pc-linux-gnu, built on Mar 30
2009 at 12:27:13
Copyright (C) 1999-2008 The FreeRADIUS server project and contributors.
There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A
PARTICULAR PURPOSE.
You may redistribute copies of FreeRADIUS under the terms of the
GNU General Public License v2.
Starting - reading configuration files ...
including configuration file /etc/freeradius/radiusd.conf
including configuration file /etc/freeradius/proxy.conf
including configuration file /etc/freeradius/clients.conf
including files in directory /etc/freeradius/modules/
including configuration file /etc/freeradius/modules/perl
including configuration file /etc/freeradius/modules/digest
including configuration file /etc/freeradius/modules/preprocess
including configuration file /etc/freeradius/modules/expr
including configuration file /etc/freeradius/modules/attr_filter
including configuration file /etc/freeradius/modules/sqlcounter_expire_on_login
including configuration file /etc/freeradius/modules/files
including configuration file /etc/freeradius/modules/otp
including configuration file /etc/freeradius/modules/acct_unique
including configuration file /etc/freeradius/modules/attr_rewrite
including configuration file /etc/freeradius/modules/pap
including configuration file /etc/freeradius/modules/detail.log
including configuration file /etc/freeradius/modules/logintime
including configuration file /etc/freeradius/modules/etc_group
including configuration file /etc/freeradius/modules/sradutmp
including configuration file /etc/freeradius/modules/krb5
including configuration file /etc/freeradius/modules/detail.example.com
including configuration file /etc/freeradius/modules/ippool
including configuration file /etc/freeradius/modules/unix
including configuration file /etc/freeradius/modules/mac2vlan
including configuration file /etc/freeradius/modules/sql_log
including configuration file /etc/freeradius/modules/smsotp
including configuration file /etc/freeradius/modules/always
including configuration file /etc/freeradius/modules/inner-eap
including configuration file /etc/freeradius/modules/chap
including configuration file /etc/freeradius/modules/ldap
including configuration file /etc/freeradius/modules/radutmp
including configuration file /etc/freeradius/modules/detail
including configuration file /etc/freeradius/modules/mschap
including configuration file /etc/freeradius/modules/policy
including configuration file /etc/freeradius/modules/exec
including configuration file /etc/freeradius/modules/expiration
including configuration file /etc/freeradius/modules/checkval
including configuration file /etc/freeradius/modules/linelog
including configuration file /etc/freeradius/modules/passwd
including configuration file /etc/freeradius/modules/mac2ip
including configuration file /etc/freeradius/modules/smbpasswd
including configuration file /etc/freeradius/modules/echo
including configuration file /etc/freeradius/modules/pam
including configuration file /etc/freeradius/modules/wimax
including configuration file /etc/freeradius/modules/counter
including configuration file /etc/freeradius/modules/realm
including configuration file /etc/freeradius/eap.conf
including configuration file /etc/freeradius/sql.conf
including configuration file /etc/freeradius/sql/mysql/dialup.conf
including configuration file /etc/freeradius/policy.conf
including files in directory /etc/freeradius/sites-enabled/
including configuration file /etc/freeradius/sites-enabled/default
including configuration file /etc/freeradius/sites-enabled/inner-tunnel
including configuration file /etc/freeradius/sites-enabled/control-socket
group = freerad
user = freerad
including dictionary file /etc/freeradius/dictionary
main {
	prefix = "/usr"
	localstatedir = "/var"
	logdir = "/var/log/freeradius"
	libdir = "/usr/lib/freeradius"
	radacctdir = "/var/log/freeradius/radacct"
	hostname_lookups = no
	max_request_time = 30
	cleanup_delay = 5
	max_requests = 1024
	allow_core_dumps = no
	pidfile = "/var/run/freeradius/freeradius.pid"
	checkrad = "/usr/sbin/checkrad"
	debug_level = 0
	proxy_requests = yes
 log {
	stripped_names = no
	auth = no
	auth_badpass = no
	auth_goodpass = no
 }
 security {
	max_attributes = 200
	reject_delay = 1
	status_server = yes
 }
}
radiusd: #### Loading Realms and Home Servers ####
 proxy server {
	retry_delay = 5
	retry_count = 3
	default_fallback = no
	dead_time = 120
	wake_all_if_all_dead = no
 }
 home_server localhost {
	ipaddr = 127.0.0.1
	port = 1812
	type = "auth"
	secret = "testing123"
	response_window = 20
	max_outstanding = 65536
	require_message_authenticator = no
	zombie_period = 40
	status_check = "status-server"
	ping_interval = 30
	check_interval = 30
	num_answers_to_alive = 3
	num_pings_to_alive = 3
	revive_interval = 120
	status_check_timeout = 4
	irt = 2
	mrt = 16
	mrc = 5
	mrd = 30
 }
 home_server_pool my_auth_failover {
	type = fail-over
	home_server = localhost
 }
 realm example.com {
	auth_pool = my_auth_failover
 }
 realm LOCAL {
 }
radiusd: #### Loading Clients ####
 client localhost {
	ipaddr = 127.0.0.1
	require_message_authenticator = no
	secret = "testing123"
	nastype = "other"
 }
radiusd: #### Instantiating modules ####
 instantiate {
 Module: Linked to module rlm_exec
 Module: Instantiating exec
  exec {
	wait = no
	input_pairs = "request"
	shell_escape = yes
  }
 Module: Linked to module rlm_expr
 Module: Instantiating expr
 Module: Linked to module rlm_expiration
 Module: Instantiating expiration
  expiration {
	reply-message = "Password Has Expired  "
  }
 Module: Linked to module rlm_logintime
 Module: Instantiating logintime
  logintime {
	reply-message = "You are calling outside your allowed timespan  "
	minimum-timeout = 60
  }
 }
radiusd: #### Loading Virtual Servers ####
server inner-tunnel {
 modules {
 Module: Checking authenticate {...} for more modules to load
 Module: Linked to module rlm_pap
 Module: Instantiating pap
  pap {
	encryption_scheme = "auto"
	auto_header = no
  }
 Module: Linked to module rlm_chap
 Module: Instantiating chap
 Module: Linked to module rlm_mschap
 Module: Instantiating mschap
  mschap {
	use_mppe = yes
	require_encryption = no
	require_strong = no
	with_ntdomain_hack = no
  }
 Module: Linked to module rlm_unix
 Module: Instantiating unix
  unix {
	radwtmp = "/var/log/freeradius/radwtmp"
  }
 Module: Linked to module rlm_eap
 Module: Instantiating eap
  eap {
	default_eap_type = "md5"
	timer_expire = 60
	ignore_unknown_eap_types = no
	cisco_accounting_username_bug = no
	max_sessions = 2048
  }
 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
	pem_file_type = yes
	private_key_file = "/etc/freeradius/certs/server.pem"
	certificate_file = "/etc/freeradius/certs/server.pem"
	CA_file = "/etc/freeradius/certs/ca.pem"
	private_key_password = "whatever"
	dh_file = "/etc/freeradius/certs/dh"
	random_file = "/etc/freeradius/certs/random"
	fragment_size = 1024
	include_length = yes
	check_crl = no
	cipher_list = "DEFAULT"
	make_cert_command = "/etc/freeradius/certs/bootstrap"
    cache {
	enable = no
	lifetime = 24
	max_entries = 255
    }
   }
 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"
   }
 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"
   }
 Module: Linked to sub-module rlm_eap_mschapv2
 Module: Instantiating eap-mschapv2
   mschapv2 {
	with_ntdomain_hack = no
   }
 Module: Checking authorize {...} for more modules to load
 Module: Linked to module rlm_realm
 Module: Instantiating suffix
  realm suffix {
	format = "suffix"
	delimiter = "@"
	ignore_default = no
	ignore_null = no
  }
 Module: Linked to module rlm_files
 Module: Instantiating files
  files {
	usersfile = "/etc/freeradius/users"
	acctusersfile = "/etc/freeradius/acct_users"
	preproxy_usersfile = "/etc/freeradius/preproxy_users"
	compat = "no"
  }
[/etc/freeradius/users]:90 WARNING! Check item "MS-CHAP-Use-NTLM-Auth"
	found in reply item list for user "user5". 	This attribute MUST go on
the first line with the other check items
 Module: Checking session {...} for more modules to load
 Module: Linked to module rlm_radutmp
 Module: Instantiating radutmp
  radutmp {
	filename = "/var/log/freeradius/radutmp"
	username = "%{User-Name}"
	case_sensitive = yes
	check_with_nas = yes
	perm = 384
	callerid = yes
  }
 Module: Checking post-proxy {...} for more modules to load
 Module: Checking post-auth {...} for more modules to load
 Module: Linked to module rlm_attr_filter
 Module: Instantiating attr_filter.access_reject
  attr_filter attr_filter.access_reject {
	attrsfile = "/etc/freeradius/attrs.access_reject"
	key = "%{User-Name}"
  }
 }
}
 modules {
 Module: Checking authenticate {...} for more modules to load
 Module: Checking authorize {...} for more modules to load
 Module: Linked to module rlm_preprocess
 Module: Instantiating preprocess
  preprocess {
	huntgroups = "/etc/freeradius/huntgroups"
	hints = "/etc/freeradius/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_sql
 Module: Instantiating sql
  sql {
	driver = "rlm_sql_mysql"
	server = "localhost"
	port = ""
	login = "radius"
	password = "radpass"
	radius_db = "radiusdb"
	read_groups = yes
	sqltrace = no
	sqltracefile = "/var/log/freeradius/sqltrace.sql"
	readclients = yes
	deletestalesessions = yes
	num_sql_socks = 5
	lifetime = 0
	max_queries = 0
	sql_user_name = "%{User-Name}"
	default_user_profile = ""
	nas_query = "SELECT id, nasname, shortname, type, secret FROM nas"
	authorize_check_query = "SELECT id, username, attribute, value, op
       FROM radcheck           WHERE username = '%{SQL-User-Name}'
      ORDER BY id"
	authorize_reply_query = "SELECT id, username, attribute, value, op
       FROM radreply           WHERE username = '%{SQL-User-Name}'
      ORDER BY id"
	authorize_group_check_query = "SELECT id, groupname, attribute,
    Value, op           FROM radgroupcheck           WHERE groupname =
'%{Sql-Group}'           ORDER BY id"
	authorize_group_reply_query = "SELECT id, groupname, attribute,
    value, op           FROM radgroupreply           WHERE groupname =
'%{Sql-Group}'           ORDER BY id"
	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
          framedipaddress = '%{Framed-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,      framedprotocol,    framedipaddress,
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}',
'%{Framed-Protocol}',              '%{Framed-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,
framedprotocol,   framedipaddress,              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}', '%{Framed-Protocol}',
'%{Framed-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, framedprotocol,
framedipaddress,              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}', '%{Framed-Protocol}',
'%{Framed-IP-Address}',              '0', '%{%{Acct-Delay-Time}:-0}')"
	group_membership_query = "SELECT groupname           FROM
radusergroup           WHERE username = '%{SQL-User-Name}'
ORDER BY priority"
	connect_failure_retry_delay = 60
	simul_count_query = ""
	simul_verify_query = "SELECT radacctid, acctsessionid, username,
                          nasipaddress, nasportid, framedipaddress,
                            callingstationid, framedprotocol
                     FROM 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:/radiusdb
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): Processing generate_sql_clients
rlm_sql (sql) in generate_sql_clients: query is SELECT id, nasname,
shortname, type, secret FROM nas
rlm_sql (sql): Reserving sql socket id: 4
rlm_sql (sql): Read entry
nasname=192.168.16.15,shortname=WUCL4,secret=naspassword
rlm_sql (sql): Adding client 192.168.16.15 (WUCL4, server=<none>) to
clients list
rlm_sql (sql): Read entry
nasname=192.168.0.35,shortname=WUCL4-home,secret=naspassword
rlm_sql (sql): Adding client 192.168.0.35 (WUCL4-home, server=<none>)
to clients list
rlm_sql (sql): Released sql socket id: 4
 Module: Checking preacct {...} for more modules to load
 Module: Linked to module rlm_acct_unique
 Module: Instantiating acct_unique
  acct_unique {
	key = "User-Name, Acct-Session-Id, NAS-IP-Address, Client-IP-Address, NAS-Port"
  }
 Module: Checking accounting {...} for more modules to load
 Module: Linked to module rlm_detail
 Module: Instantiating detail
  detail {
	detailfile = "/var/log/freeradius/radacct/%{Client-IP-Address}/detail-%Y%m%d"
	header = "%t"
	detailperm = 384
	dirperm = 493
	locking = no
	log_packet_header = no
  }
 Module: Instantiating attr_filter.accounting_response
  attr_filter attr_filter.accounting_response {
	attrsfile = "/etc/freeradius/attrs.accounting_response"
	key = "%{User-Name}"
  }
 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
 }
radiusd: #### Opening IP addresses and Ports ####
listen {
	type = "auth"
	ipaddr = *
	port = 0
}
listen {
	type = "acct"
	ipaddr = *
	port = 0
}
listen {
	type = "control"
 listen {
	socket = "/var/run/freeradius/freeradius.sock"
 }
}
Listening on authentication address * port 1812
Listening on accounting address * port 1813
Listening on command file /var/run/freeradius/freeradius.sock
Listening on proxy address * port 1814
Ready to process requests.
rad_recv: Access-Request packet from host 192.168.0.35 port 2049,
id=0, length=123
	User-Name = "user8"
	NAS-IP-Address = 192.168.0.35
	Called-Station-Id = "001d7ee38635"
	Calling-Station-Id = "001f3c4c9fa5"
	NAS-Identifier = "001d7ee38635"
	NAS-Port = 54
	Framed-MTU = 1400
	NAS-Port-Type = Wireless-802.11
	EAP-Message = 0x0200000a017573657238
	Message-Authenticator = 0x243cb00a8988a09594db0c0b08c1930d
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "user8", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 0 length 10
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
++[unix] returns notfound
++[files] returns noop
[sql] 	expand: %{User-Name} -> user8
[sql] sql_set_user escaped user --> 'user8'
rlm_sql (sql): Reserving sql socket id: 3
[sql] 	expand: SELECT id, username, attribute, value, op
FROM radcheck           WHERE username = '%{SQL-User-Name}'
ORDER BY id -> SELECT id, username, attribute, value, op
FROM radcheck           WHERE username = 'user8'           ORDER BY id
[sql] User found in radcheck table
[sql] 	expand: SELECT id, username, attribute, value, op
FROM radreply           WHERE username = '%{SQL-User-Name}'
ORDER BY id -> SELECT id, username, attribute, value, op
FROM radreply           WHERE username = 'user8'           ORDER BY id
[sql] 	expand: SELECT groupname           FROM radusergroup
WHERE username = '%{SQL-User-Name}'           ORDER BY priority ->
SELECT groupname           FROM radusergroup           WHERE username
= 'user8'           ORDER BY priority
rlm_sql (sql): Released sql socket id: 3
++[sql] returns ok
++[expiration] returns noop
++[logintime] returns noop
[pap] Found existing Auth-Type, not changing it.
++[pap] returns noop
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] EAP Identity
[eap] processing type md5
rlm_eap_md5: Issuing Challenge
++[eap] returns handled
Sending Access-Challenge of id 0 to 192.168.0.35 port 2049
	EAP-Message = 0x010100160410809d6df3f8df4bba73741937ab176391
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xff012498ff0020d7b47dc9629d4004b0
Finished request 0.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 192.168.0.35 port 2049,
id=0, length=137
Cleaning up request 0 ID 0 with timestamp +8
	User-Name = "user8"
	NAS-IP-Address = 192.168.0.35
	Called-Station-Id = "001d7ee38635"
	Calling-Station-Id = "001f3c4c9fa5"
	NAS-Identifier = "001d7ee38635"
	NAS-Port = 54
	Framed-MTU = 1400
	State = 0xff012498ff0020d7b47dc9629d4004b0
	NAS-Port-Type = Wireless-802.11
	EAP-Message = 0x020100060319
	Message-Authenticator = 0x1912a69b7e8cae051c40b8db7afdb0d7
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "user8", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 1 length 6
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
++[unix] returns notfound
++[files] returns noop
[sql] 	expand: %{User-Name} -> user8
[sql] sql_set_user escaped user --> 'user8'
rlm_sql (sql): Reserving sql socket id: 2
[sql] 	expand: SELECT id, username, attribute, value, op
FROM radcheck           WHERE username = '%{SQL-User-Name}'
ORDER BY id -> SELECT id, username, attribute, value, op
FROM radcheck           WHERE username = 'user8'           ORDER BY id
[sql] User found in radcheck table
[sql] 	expand: SELECT id, username, attribute, value, op
FROM radreply           WHERE username = '%{SQL-User-Name}'
ORDER BY id -> SELECT id, username, attribute, value, op
FROM radreply           WHERE username = 'user8'           ORDER BY id
[sql] 	expand: SELECT groupname           FROM radusergroup
WHERE username = '%{SQL-User-Name}'           ORDER BY priority ->
SELECT groupname           FROM radusergroup           WHERE username
= 'user8'           ORDER BY priority
rlm_sql (sql): Released sql socket id: 2
++[sql] returns ok
++[expiration] returns noop
++[logintime] returns noop
[pap] Found existing Auth-Type, not changing it.
++[pap] returns noop
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP NAK
[eap] EAP-NAK asked for EAP-Type/peap
[eap] processing type tls
[tls] Initiate
[tls] Start returned 1
++[eap] returns handled
Sending Access-Challenge of id 0 to 192.168.0.35 port 2049
	EAP-Message = 0x010200061920
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xff012498fe033dd7b47dc9629d4004b0
Finished request 1.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 192.168.0.35 port 2049,
id=0, length=247
Cleaning up request 1 ID 0 with timestamp +8
	User-Name = "user8"
	NAS-IP-Address = 192.168.0.35
	Called-Station-Id = "001d7ee38635"
	Calling-Station-Id = "001f3c4c9fa5"
	NAS-Identifier = "001d7ee38635"
	NAS-Port = 54
	Framed-MTU = 1400
	State = 0xff012498fe033dd7b47dc9629d4004b0
	NAS-Port-Type = Wireless-802.11
	EAP-Message = 0x0202007419800000006a160301006501000061030149d136ae87a6f65b9a41e97dbbf23c32e8157c338fd0d07c2aee02278eaa2ead000018002f00350005000ac009c00ac013c0140032003800130004010000200000000a00080000057573657238000a00080006001700180019000b00020100
	Message-Authenticator = 0x1d266220d9e118c87931afbd2a48cbb6
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "user8", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 2 length 116
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/peap
[eap] processing type peap
[peap] processing EAP-TLS
  TLS Length 106
[peap] Length Included
[peap] eaptls_verify returned 11
[peap]     (other): before/accept initialization
[peap]     TLS_accept: before/accept initialization
[peap] <<< TLS 1.0 Handshake [length 0065], ClientHello
[peap]     TLS_accept: SSLv3 read client hello A
[peap] >>> TLS 1.0 Handshake [length 002a], ServerHello
[peap]     TLS_accept: SSLv3 write server hello A
[peap] >>> TLS 1.0 Handshake [length 085e], Certificate
[peap]     TLS_accept: SSLv3 write certificate A
[peap] >>> TLS 1.0 Handshake [length 0004], ServerHelloDone
[peap]     TLS_accept: SSLv3 write server done A
[peap]     TLS_accept: SSLv3 flush data
[peap]     TLS_accept: Need to read more data: SSLv3 read client certificate A
In SSL Handshake Phase
In SSL Accept mode
[peap] eaptls_process returned 13
[peap] EAPTLS_HANDLED
++[eap] returns handled
Sending Access-Challenge of id 0 to 192.168.0.35 port 2049
	EAP-Message = 0x0103040019c00000089b160301002a02000026030149d136aef5b33cb34ac450a8a52326533cea7f74fbe9e6f461fff50023144e2100002f00160301085e0b00085a0008570003a6308203a23082028aa003020102020101300d06092a864886f70d0101040500308193310b3009060355040613024652310f300d060355040813065261646975733112301006035504071309536f6d65776865726531153013060355040a130c4578616d706c6520496e632e3120301e06092a864886f70d010901161161646d696e406578616d706c652e636f6d312630240603550403131d4578616d706c6520436572746966696361746520417574686f72697479
	EAP-Message = 0x301e170d3039303333303131353531355a170d3130303333303131353531355a307c310b3009060355040613024652310f300d0603550408130652616469757331153013060355040a130c4578616d706c6520496e632e312330210603550403131a4578616d706c65205365727665722043657274696669636174653120301e06092a864886f70d010901161161646d696e406578616d706c652e636f6d30820122300d06092a864886f70d01010105000382010f003082010a0282010100c81cf63fe3e7a64b45cb69c0e7499882b745a116d072cd038e3f221dc60d597e2c5da97a6f2a6ebd7626738aaf53627a121d81663bca8b77215fb8566016
	EAP-Message = 0xcc5ccdc8d3faa4b694dbcd83c8b37d383b60ab0638a94cf84a0abad260a7b040c65935d5551f344dd3a3b7acce2900c517e1f094047dd37bd10126d6fb150680000dd78c4afb595109c9f713361fe669d056d2ed904f77100bea394cd7ab602ad18bed2ea7ccf04230f8ce2c8b09d9c2f404fa392a6e10fbb535e85cabe12095ba2c90a67c58e325bc9769e65639a4d90c9c2e4cc5c902dc4f4a3b30ab59e1db6ae1a41286ef8bd7fafb3085fb27ba941457e4c58566f7765c1dcefbadcfae6ebd2f0203010001a317301530130603551d25040c300a06082b06010505070301300d06092a864886f70d0101040500038201010019037e839fa4cd24e5
	EAP-Message = 0x2dc80da66f6fae24574a2a35a19fb4c5158641da0541b79ccb4baeeb4c1a4d77d6d468c11c105e0e5e53e3ba20cc2453b0fda0ffafbeb558311147f08b1e9ce5f3a2d59cf7b6624fc6dda4c5552c3514232a0fea32bc256529a1195797bbf431b09316a379490553fe5cc5feb6ab80e222d4837afbccacc208c2ba1a28a9e3c22183937091fd835d90a3bc2ef02f0788025e6b7b5b1c3b673e4fd99d9219623f731a78c7f71fdc8ee84cd5da31fedebf10e67d6da34f375ecc0a5dbcd50409ded9a20d2315282ec87db9b108ef13671d63f679fd3167d29a70cae568bd75246438fbddf74dfdb0f9ddf997c9f3869f05746c83da4a76ed0004ab308204
	EAP-Message = 0xa73082038fa0030201020209
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xff012498fd023dd7b47dc9629d4004b0
Finished request 2.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 192.168.0.35 port 2049,
id=0, length=137
Cleaning up request 2 ID 0 with timestamp +8
	User-Name = "user8"
	NAS-IP-Address = 192.168.0.35
	Called-Station-Id = "001d7ee38635"
	Calling-Station-Id = "001f3c4c9fa5"
	NAS-Identifier = "001d7ee38635"
	NAS-Port = 54
	Framed-MTU = 1400
	State = 0xff012498fd023dd7b47dc9629d4004b0
	NAS-Port-Type = Wireless-802.11
	EAP-Message = 0x020300061900
	Message-Authenticator = 0x1980409f5ad580df0d4a64c565c63695
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "user8", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 3 length 6
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/peap
[eap] processing type peap
[peap] processing EAP-TLS
[peap] Received TLS ACK
[peap] ACK handshake fragment handler
[peap] eaptls_verify returned 1
[peap] eaptls_process returned 13
[peap] EAPTLS_HANDLED
++[eap] returns handled
Sending Access-Challenge of id 0 to 192.168.0.35 port 2049
	EAP-Message = 0x010403fc194000f26bdcc6c0f85d54300d06092a864886f70d0101050500308193310b3009060355040613024652310f300d060355040813065261646975733112301006035504071309536f6d65776865726531153013060355040a130c4578616d706c6520496e632e3120301e06092a864886f70d010901161161646d696e406578616d706c652e636f6d312630240603550403131d4578616d706c6520436572746966696361746520417574686f72697479301e170d3039303333303131353532305a170d3130303333303131353532305a308193310b3009060355040613024652310f300d060355040813065261646975733112301006035504
	EAP-Message = 0x071309536f6d65776865726531153013060355040a130c4578616d706c6520496e632e3120301e06092a864886f70d010901161161646d696e406578616d706c652e636f6d312630240603550403131d4578616d706c6520436572746966696361746520417574686f7269747930820122300d06092a864886f70d01010105000382010f003082010a0282010100be49e763bdbbb78aaafb19c24efe2caa302461363e8d27871cdca7b8e9abd238f544593a8104d45ff67fde08c848391fedf69d6b5d81fcac2de3c817b0e3490e4130392eb83ace33d88b724fbe23cdb2c8504ba6c46a0c3494187531f51d4557799318c64c0618cde6fe9e5fd8d3b7
	EAP-Message = 0x3904b8497ce60c3dc61dea3409f8c8711f8cb110de5dc3ce0298c8bb7d845258223392546bce008ddcc564e11d7070e0bb57165bf0b00d05c56cf00c7010e5dc6ea1f793fd541120625077420ce9d2f849d8e899860545d31d3ec290bfc9d741ef9661d576aaac06ec36860798ac9eef195f6d789290c6e9e743f64be48d4c5bf4ba4379d17a5be0898665cf4b2f4076ed0203010001a381fb3081f8301d0603551d0e04160414a23890b0533c3a608f9707fd0801c98896cea7363081c80603551d230481c03081bd8014a23890b0533c3a608f9707fd0801c98896cea736a18199a48196308193310b3009060355040613024652310f300d06035504
	EAP-Message = 0x0813065261646975733112301006035504071309536f6d65776865726531153013060355040a130c4578616d706c6520496e632e3120301e06092a864886f70d010901161161646d696e406578616d706c652e636f6d312630240603550403131d4578616d706c6520436572746966696361746520417574686f72697479820900f26bdcc6c0f85d54300c0603551d13040530030101ff300d06092a864886f70d01010505000382010100aa1b95d3f797b17a37a00a065adfce0d5674a32fac0f84867d77042925aa19977da69d4ad7d5a935aeb96b702653d8eba2ea446aa112f747482a79112b64b7d5e83651a55c0e2af79e4c8ab7f0e2ebd7b5f4
	EAP-Message = 0xeaeaf68cbb563c46
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xff012498fc053dd7b47dc9629d4004b0
Finished request 3.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 192.168.0.35 port 2049,
id=0, length=137
Cleaning up request 3 ID 0 with timestamp +8
	User-Name = "user8"
	NAS-IP-Address = 192.168.0.35
	Called-Station-Id = "001d7ee38635"
	Calling-Station-Id = "001f3c4c9fa5"
	NAS-Identifier = "001d7ee38635"
	NAS-Port = 54
	Framed-MTU = 1400
	State = 0xff012498fc053dd7b47dc9629d4004b0
	NAS-Port-Type = Wireless-802.11
	EAP-Message = 0x020400061900
	Message-Authenticator = 0x62529f43fd695685cb57c22a8d28eda0
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "user8", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 4 length 6
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/peap
[eap] processing type peap
[peap] processing EAP-TLS
[peap] Received TLS ACK
[peap] ACK handshake fragment handler
[peap] eaptls_verify returned 1
[peap] eaptls_process returned 13
[peap] EAPTLS_HANDLED
++[eap] returns handled
Sending Access-Challenge of id 0 to 192.168.0.35 port 2049
	EAP-Message = 0x010500b51900dca7443bf653e0f14f2aabcbd154602744b4c1c5035e69092ebd9ed340118785305272c37a8528e08bd6e7e33f2572dcd2103004567cae4e13ac618da757c24538ad780c39dad5dff304971cb1f54453a7dc75d67a19a48803c750cce798b7a6d308cbded5b7938b40884f83f677dabaa7a7b5efe0770bc53d3d610c7dd61b7af9b20e2511eb16afa6089c1affb63db2dad8b2b6fa40a6766a731dd876d8e06a632feeb26d0816030100040e000000
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xff012498fb043dd7b47dc9629d4004b0
Finished request 4.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 192.168.0.35 port 2049,
id=0, length=469
Cleaning up request 4 ID 0 with timestamp +8
	User-Name = "user8"
	NAS-IP-Address = 192.168.0.35
	Called-Station-Id = "001d7ee38635"
	Calling-Station-Id = "001f3c4c9fa5"
	NAS-Identifier = "001d7ee38635"
	NAS-Port = 54
	Framed-MTU = 1400
	State = 0xff012498fb043dd7b47dc9629d4004b0
	NAS-Port-Type = Wireless-802.11
	EAP-Message = 0x020501501980000001461603010106100001020100950750e30f558174b031de6e2f71dd89fd6129bd28b48bb106517261969932ca2eb2340b9fe4609dac1ad575661b64044e5d9d24def90be92a8cd700d9a18beb0e0894f9695880903069eab1a2739a3f5d2054adbb109bdf1d00265f56a7c64915f55cf3e28e10cf3b165ad8f29b676da945f5374aae6919139853970c1f24573fb840b8f668e4a95f88378c26560046f56e60748216e31fafd7368edd7cadc3f4827f7e680a7ae856c4d034975bbdfde754ff6445dfdf1f3b30b6b3319b224e6897c8a6083aa47cd5cae8b4b1f09d32a0bf13dc2f117123c567e1219af72a0d1bd9d4e5daf05f74
	EAP-Message = 0x18be2132f96f6c12bcaa2215349cef128269073b1f4e90071403010001011603010030ebf8f5592e516a6023acf420500a5966981120d0b1216300686aeae938d37689d7719e0f10fd492dd839d2bc70b9f6c9
	Message-Authenticator = 0xf594a35846bf647e2d975567654f4d9f
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "user8", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 5 length 253
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/peap
[eap] processing type peap
[peap] processing EAP-TLS
  TLS Length 326
[peap] Length Included
[peap] eaptls_verify returned 11
[peap] <<< TLS 1.0 Handshake [length 0106], ClientKeyExchange
[peap]     TLS_accept: SSLv3 read client key exchange A
[peap] <<< TLS 1.0 ChangeCipherSpec [length 0001]
[peap] <<< TLS 1.0 Handshake [length 0010], Finished
[peap]     TLS_accept: SSLv3 read finished A
[peap] >>> TLS 1.0 ChangeCipherSpec [length 0001]
[peap]     TLS_accept: SSLv3 write change cipher spec A
[peap] >>> TLS 1.0 Handshake [length 0010], Finished
[peap]     TLS_accept: SSLv3 write finished A
[peap]     TLS_accept: SSLv3 flush data
[peap]     (other): SSL negotiation finished successfully
SSL Connection Established
[peap] eaptls_process returned 13
[peap] EAPTLS_HANDLED
++[eap] returns handled
Sending Access-Challenge of id 0 to 192.168.0.35 port 2049
	EAP-Message = 0x0106004119001403010001011603010030d34f300a2e14c9951439684742d8e0a04c16c7729f4a953cde88262b32aafe56ae0678532ab3b8679fe53e21aa64315b
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xff012498fa073dd7b47dc9629d4004b0
Finished request 5.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 192.168.0.35 port 2049,
id=0, length=137
Cleaning up request 5 ID 0 with timestamp +8
	User-Name = "user8"
	NAS-IP-Address = 192.168.0.35
	Called-Station-Id = "001d7ee38635"
	Calling-Station-Id = "001f3c4c9fa5"
	NAS-Identifier = "001d7ee38635"
	NAS-Port = 54
	Framed-MTU = 1400
	State = 0xff012498fa073dd7b47dc9629d4004b0
	NAS-Port-Type = Wireless-802.11
	EAP-Message = 0x020600061900
	Message-Authenticator = 0x330f239b6c059b3a656ea807ae412342
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "user8", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 6 length 6
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/peap
[eap] processing type peap
[peap] processing EAP-TLS
[peap] Received TLS ACK
[peap] ACK handshake is finished
[peap] eaptls_verify returned 3
[peap] eaptls_process returned 3
[peap] EAPTLS_SUCCESS
++[eap] returns handled
Sending Access-Challenge of id 0 to 192.168.0.35 port 2049
	EAP-Message = 0x0107002b1900170301002082436ab7fc041f52dcfeef5e83d85f46b3a2b20012295a37e0d4c210f338bdce
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xff012498f9063dd7b47dc9629d4004b0
Finished request 6.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 192.168.0.35 port 2049,
id=0, length=174
Cleaning up request 6 ID 0 with timestamp +8
	User-Name = "user8"
	NAS-IP-Address = 192.168.0.35
	Called-Station-Id = "001d7ee38635"
	Calling-Station-Id = "001f3c4c9fa5"
	NAS-Identifier = "001d7ee38635"
	NAS-Port = 54
	Framed-MTU = 1400
	State = 0xff012498f9063dd7b47dc9629d4004b0
	NAS-Port-Type = Wireless-802.11
	EAP-Message = 0x0207002b19001703010020cf8d6f2f15d8ba825d8b4aee4a6a322b4be39b2badde4866c5b54361961cc0d2
	Message-Authenticator = 0x8aac4b4fdc3e47d1a4f374b65473cbb5
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "user8", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 7 length 43
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/peap
[eap] processing type peap
[peap] processing EAP-TLS
[peap] eaptls_verify returned 7
[peap] Done initial handshake
[peap] eaptls_process returned 7
[peap] EAPTLS_OK
[peap] Session established.  Decoding tunneled attributes.
[peap] Identity - user8
[peap] Got tunneled request
	EAP-Message = 0x0207000a017573657238
server  {
  PEAP: Got tunneled identity of user8
  PEAP: Setting default EAP type for tunneled EAP session.
  PEAP: Setting User-Name to user8
Sending tunneled request
	EAP-Message = 0x0207000a017573657238
	FreeRADIUS-Proxied-To = 127.0.0.1
	User-Name = "user8"
server inner-tunnel {
+- entering group authorize {...}
++[chap] returns noop
++[mschap] returns noop
++[unix] returns notfound
[suffix] No '@' in User-Name = "user8", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[control] returns noop
[eap] EAP packet type response id 7 length 10
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
++[files] returns noop
++[expiration] returns noop
++[logintime] returns noop
++[pap] returns noop
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] EAP Identity
[eap] processing type mschapv2
rlm_eap_mschapv2: Issuing Challenge
++[eap] returns handled
} # server inner-tunnel
[peap] Got tunneled reply code 11
	EAP-Message = 0x0108001f1a0108001a1025124f5e6a8bc7778687e657b729c16b7573657238
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x31f7332531ff293ae0350b28678bf4db
[peap] Got tunneled reply RADIUS code 11
	EAP-Message = 0x0108001f1a0108001a1025124f5e6a8bc7778687e657b729c16b7573657238
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x31f7332531ff293ae0350b28678bf4db
[peap] Got tunneled Access-Challenge
++[eap] returns handled
Sending Access-Challenge of id 0 to 192.168.0.35 port 2049
	EAP-Message = 0x0108003b1900170301003046c317b81eab1e16b0655ae83286ec17cf12bf50f4125f9153429537c5f8c3f8c87d5be89dd1826d5c69302b58540e56
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xff012498f8093dd7b47dc9629d4004b0
Finished request 7.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 192.168.0.35 port 2049,
id=0, length=238
Cleaning up request 7 ID 0 with timestamp +8
	User-Name = "user8"
	NAS-IP-Address = 192.168.0.35
	Called-Station-Id = "001d7ee38635"
	Calling-Station-Id = "001f3c4c9fa5"
	NAS-Identifier = "001d7ee38635"
	NAS-Port = 54
	Framed-MTU = 1400
	State = 0xff012498f8093dd7b47dc9629d4004b0
	NAS-Port-Type = Wireless-802.11
	EAP-Message = 0x0208006b19001703010060513fd1c52ed36f8f5f7eb642f204f2c76b0002e55ba1305ca80222c384752994ec38fd3636adab3bf9b33b2863578d8b59aa40e88f0a0ae6ab05f9b8f903f65fd5ff83fa3be1ab5a26a3db4e25d38804df2514638fe6f75fffaeabbf4b936d59
	Message-Authenticator = 0x04a58bdbe623c7c0cc7b2829a7ba492d
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "user8", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 8 length 107
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/peap
[eap] processing type peap
[peap] processing EAP-TLS
[peap] eaptls_verify returned 7
[peap] Done initial handshake
[peap] eaptls_process returned 7
[peap] EAPTLS_OK
[peap] Session established.  Decoding tunneled attributes.
[peap] EAP type mschapv2
[peap] Got tunneled request
	EAP-Message = 0x020800401a0208003b31d7091e2dba0b622af184ecb1e2ff023f000000000000000064beb943ee4347cd697fa6418a54b4622df1c99508589633007573657238
server  {
  PEAP: Setting User-Name to user8
Sending tunneled request
	EAP-Message = 0x020800401a0208003b31d7091e2dba0b622af184ecb1e2ff023f000000000000000064beb943ee4347cd697fa6418a54b4622df1c99508589633007573657238
	FreeRADIUS-Proxied-To = 127.0.0.1
	User-Name = "user8"
	State = 0x31f7332531ff293ae0350b28678bf4db
server inner-tunnel {
+- entering group authorize {...}
++[chap] returns noop
++[mschap] returns noop
++[unix] returns notfound
[suffix] No '@' in User-Name = "user8", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[control] returns noop
[eap] EAP packet type response id 8 length 64
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
++[files] returns noop
++[expiration] returns noop
++[logintime] returns noop
++[pap] returns noop
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/mschapv2
[eap] processing type mschapv2
[mschapv2] +- entering group MS-CHAP {...}
[mschap] No Cleartext-Password configured.  Cannot create LM-Password.
[mschap] No Cleartext-Password configured.  Cannot create NT-Password.
[mschap] Told to do MS-CHAPv2 for user8 with NT-Password
[mschap] FAILED: No NT/LM-Password.  Cannot perform authentication.
[mschap] FAILED: MS-CHAP2-Response is incorrect
++[mschap] returns reject
[eap] Freeing handler
++[eap] returns reject
Failed to authenticate the user.
} # server inner-tunnel
[peap] Got tunneled reply code 3
	MS-CHAP-Error = "\010E=691 R=1"
	EAP-Message = 0x04080004
	Message-Authenticator = 0x00000000000000000000000000000000
[peap] Got tunneled reply RADIUS code 3
	MS-CHAP-Error = "\010E=691 R=1"
	EAP-Message = 0x04080004
	Message-Authenticator = 0x00000000000000000000000000000000
[peap] Tunneled authentication was rejected.
[peap] FAILURE
++[eap] returns handled
Sending Access-Challenge of id 0 to 192.168.0.35 port 2049
	EAP-Message = 0x0109002b19001703010020e8be43bc92348cee5bae6dcc027532ce209dcca208d0c17217243a93e8bd911c
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xff012498f7083dd7b47dc9629d4004b0
Finished request 8.
Going to the next request
Waking up in 5.0 seconds.
rad_recv: Access-Request packet from host 192.168.0.35 port 2049,
id=0, length=174
Cleaning up request 8 ID 0 with timestamp +9
	User-Name = "user8"
	NAS-IP-Address = 192.168.0.35
	Called-Station-Id = "001d7ee38635"
	Calling-Station-Id = "001f3c4c9fa5"
	NAS-Identifier = "001d7ee38635"
	NAS-Port = 54
	Framed-MTU = 1400
	State = 0xff012498f7083dd7b47dc9629d4004b0
	NAS-Port-Type = Wireless-802.11
	EAP-Message = 0x0209002b190017030100205f589882da7346a7820593926475f1cb12367a6348e564e1e0e3dd281ccbd4dd
	Message-Authenticator = 0x0c3dbd22516662922a69d075e8ebe7aa
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "user8", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 9 length 43
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/peap
[eap] processing type peap
[peap] processing EAP-TLS
[peap] eaptls_verify returned 7
[peap] Done initial handshake
[peap] eaptls_process returned 7
[peap] EAPTLS_OK
[peap] Session established.  Decoding tunneled attributes.
[peap] Received EAP-TLV response.
[peap]  Had sent TLV failure.  User was rejected earlier in this session.
[eap] Handler failed in EAP/peap
[eap] Failed in EAP select
++[eap] returns invalid
Failed to authenticate the user.
Using Post-Auth-Type Reject
+- entering group REJECT {...}
[attr_filter.access_reject] 	expand: %{User-Name} -> user8
 attr_filter: Matched entry DEFAULT at line 11
++[attr_filter.access_reject] returns updated
Delaying reject of request 9 for 1 seconds
Going to the next request
Waking up in 0.9 seconds.
Sending delayed reject for request 9
Sending Access-Reject of id 0 to 192.168.0.35 port 2049
	EAP-Message = 0x04090004
	Message-Authenticator = 0x00000000000000000000000000000000
Waking up in 4.9 seconds.
Cleaning up request 9 ID 0 with timestamp +9
Ready to process requests.




More information about the Freeradius-Users mailing list