Freeradius 8021x with LDAP

Francesc Zacarias francesc at spotify.com
Wed Jul 18 19:09:21 CEST 2012


Hi there!

We're trying to set up Freeradius wtih 8021x. Freeradius should query
a OpenLDAP server for autentication and check if the user belongs to
certain groups and return different VLAN IDs depending on that.
Unfortunately, we're having issues with the LDAP autentication part.
We only managed to get it to work with radtest. Example command:

$ radtest testuser4 testpass4 localhost 1812 testing123

But to accomplish that we had to uncomment these lines in sites-enabled/default:

        # Uncomment it if you want to use ldap for authentication
        #
        # Note that this means "check plain-text password against
        # the ldap database", which means that EAP won't work,
        # as it does not supply a plain-text password.
        #Auth-Type LDAP {
        #       ldap
        #}

As the comment states, this works with radtest but not with EAP. We do
our tests with a Macbook Pro running Mac OS X 10.7.4.
If I comment the above lines again, freeradius rejects the request.
Passwords are stored as cleartext in ldap.
I'm looking at the ldap queries performed by freeradius it is only
checking if the user exists. No password check at all.
This the output of freeradius -X while using our test laptop:

-----8<------


FreeRADIUS Version 2.1.10, for host x86_64-pc-linux-gnu, built on Nov
14 2010 at 21:12:30
Copyright (C) 1999-2009 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/expr
including configuration file /etc/freeradius/modules/mac2ip
including configuration file /etc/freeradius/modules/passwd
including configuration file /etc/freeradius/modules/preprocess
including configuration file /etc/freeradius/modules/unix
including configuration file /etc/freeradius/modules/smbpasswd
including configuration file /etc/freeradius/modules/attr_rewrite
including configuration file /etc/freeradius/modules/radutmp
including configuration file /etc/freeradius/modules/chap
including configuration file /etc/freeradius/modules/linelog
including configuration file /etc/freeradius/modules/perl
including configuration file /etc/freeradius/modules/wimax
including configuration file /etc/freeradius/modules/otp
including configuration file /etc/freeradius/modules/detail
including configuration file /etc/freeradius/modules/ippool
including configuration file /etc/freeradius/modules/detail.example.com
including configuration file /etc/freeradius/modules/mschap
including configuration file /etc/freeradius/modules/sql_log
including configuration file /etc/freeradius/modules/pam
including configuration file /etc/freeradius/modules/mac2vlan
including configuration file /etc/freeradius/modules/detail.log
including configuration file /etc/freeradius/modules/logintime
including configuration file /etc/freeradius/modules/realm
including configuration file /etc/freeradius/modules/digest
including configuration file /etc/freeradius/modules/dynamic_clients
including configuration file /etc/freeradius/modules/acct_unique
including configuration file /etc/freeradius/modules/sradutmp
including configuration file /etc/freeradius/modules/ntlm_auth
including configuration file /etc/freeradius/modules/counter
including configuration file /etc/freeradius/modules/always
including configuration file /etc/freeradius/modules/ldap
including configuration file /etc/freeradius/modules/exec
including configuration file /etc/freeradius/modules/files
including configuration file /etc/freeradius/modules/inner-eap
including configuration file /etc/freeradius/modules/echo
including configuration file /etc/freeradius/modules/opendirectory
including configuration file /etc/freeradius/modules/krb5
including configuration file /etc/freeradius/modules/attr_filter
including configuration file /etc/freeradius/modules/smsotp
including configuration file /etc/freeradius/modules/cui
including configuration file /etc/freeradius/modules/pap
including configuration file /etc/freeradius/modules/sqlcounter_expire_on_login
including configuration file /etc/freeradius/modules/expiration
including configuration file /etc/freeradius/modules/etc_group
including configuration file /etc/freeradius/modules/checkval
including configuration file /etc/freeradius/modules/policy
including configuration file /etc/freeradius/eap.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
main {
	user = "freerad"
	group = "freerad"
	allow_core_dumps = no
}
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
	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 = yes
	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"
 }
 client 194.236.60.0/24 {
	require_message_authenticator = no
	secret = "somesecret"
 }
radiusd: #### Instantiating modules ####
 instantiate {
 Module: Linked to module rlm_exec
 Module: Instantiating module "exec" from file /etc/freeradius/modules/exec
  exec {
	wait = no
	input_pairs = "request"
	shell_escape = yes
  }
 Module: Linked to module rlm_expr
 Module: Instantiating module "expr" from file /etc/freeradius/modules/expr
 Module: Linked to module rlm_expiration
 Module: Instantiating module "expiration" from file
/etc/freeradius/modules/expiration
  expiration {
	reply-message = "Password Has Expired  "
  }
 Module: Linked to module rlm_logintime
 Module: Instantiating module "logintime" from file
/etc/freeradius/modules/logintime
  logintime {
	reply-message = "You are calling outside your allowed timespan  "
	minimum-timeout = 60
  }
 }
radiusd: #### Loading Virtual Servers ####
server inner-tunnel { # from file /etc/freeradius/sites-enabled/inner-tunnel
 modules {
 Module: Checking authenticate {...} for more modules to load
 Module: Linked to module rlm_pap
 Module: Instantiating module "pap" from file /etc/freeradius/modules/pap
  pap {
	encryption_scheme = "auto"
	auto_header = no
  }
 Module: Linked to module rlm_chap
 Module: Instantiating module "chap" from file /etc/freeradius/modules/chap
 Module: Linked to module rlm_mschap
 Module: Instantiating module "mschap" from file /etc/freeradius/modules/mschap
  mschap {
	use_mppe = yes
	require_encryption = no
	require_strong = no
	with_ntdomain_hack = no
  }
 Module: Linked to module rlm_unix
 Module: Instantiating module "unix" from file /etc/freeradius/modules/unix
  unix {
	radwtmp = "/var/log/freeradius/radwtmp"
  }
 Module: Linked to module rlm_eap
 Module: Instantiating module "eap" from file /etc/freeradius/eap.conf
  eap {
	default_eap_type = "md5"
	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/freeradius/certs"
	pem_file_type = yes
	private_key_file = "/etc/freeradius/certs/server.key"
	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 = "/dev/urandom"
	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
    }
    verify {
    }
   }
 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"
   }
 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 module "suffix" from file /etc/freeradius/modules/realm
  realm suffix {
	format = "suffix"
	delimiter = "@"
	ignore_default = no
	ignore_null = no
  }
 Module: Linked to module rlm_files
 Module: Instantiating module "files" from file /etc/freeradius/modules/files
  files {
	usersfile = "/etc/freeradius/users"
	acctusersfile = "/etc/freeradius/acct_users"
	preproxy_usersfile = "/etc/freeradius/preproxy_users"
	compat = "no"
  }
 Module: Linked to module rlm_ldap
 Module: Instantiating module "ldap" from file /etc/freeradius/modules/ldap
  ldap {
	server = "haldap.int.sto.spotify.net"
	port = 389
	password = "somepasswd"
	identity = "uid=freeradius_ldap,cn=users,dc=host,dc=in,dc=a,dc=domain,dc=net"
	net_timeout = 1
	timeout = 4
	timelimit = 3
	tls_mode = no
	start_tls = no
	tls_require_cert = "allow"
   tls {
	start_tls = no
	require_cert = "allow"
   }
	basedn = "DC=host,DC=in,DC=a,DC=domain,DC=net"
	filter = "(uid=%{%{Stripped-User-Name}:-%{User-Name}})"
	base_filter = "(objectclass=radiusprofile)"
	auto_header = no
	access_attr_used_for_allow = yes
	groupname_attribute = "cn"
	groupmembership_filter = "(&(objectclass=apple-group)(memberUid=%{User-Name}))"
	dictionary_mapping = "/etc/freeradius/ldap.attrmap"
	ldap_debug = 0
	ldap_connections_number = 5
	compare_check_items = no
	do_xlat = yes
	edir_account_policy_check = no
	set_auth_type = yes
  }
rlm_ldap: Registering ldap_groupcmp for Ldap-Group
rlm_ldap: Registering ldap_xlat with xlat_name ldap
rlm_ldap: Over-riding set_auth_type, as there is no module ldap listed
in the "authenticate" section.
rlm_ldap: reading ldap<->radius mappings from file /etc/freeradius/ldap.attrmap
rlm_ldap: LDAP radiusCheckItem mapped to RADIUS $GENERIC$
rlm_ldap: LDAP radiusReplyItem mapped to RADIUS $GENERIC$
rlm_ldap: LDAP radiusAuthType mapped to RADIUS Auth-Type
rlm_ldap: LDAP radiusSimultaneousUse mapped to RADIUS Simultaneous-Use
rlm_ldap: LDAP radiusCalledStationId mapped to RADIUS Called-Station-Id
rlm_ldap: LDAP radiusCallingStationId mapped to RADIUS Calling-Station-Id
rlm_ldap: LDAP lmPassword mapped to RADIUS LM-Password
rlm_ldap: LDAP ntPassword mapped to RADIUS NT-Password
rlm_ldap: LDAP sambaLmPassword mapped to RADIUS LM-Password
rlm_ldap: LDAP sambaNtPassword mapped to RADIUS NT-Password
rlm_ldap: LDAP dBCSPwd mapped to RADIUS LM-Password
rlm_ldap: LDAP userPassword mapped to RADIUS Password-With-Header
rlm_ldap: LDAP acctFlags mapped to RADIUS SMB-Account-CTRL-TEXT
rlm_ldap: LDAP radiusExpiration mapped to RADIUS Expiration
rlm_ldap: LDAP radiusNASIpAddress mapped to RADIUS NAS-IP-Address
rlm_ldap: LDAP radiusServiceType mapped to RADIUS Service-Type
rlm_ldap: LDAP radiusFramedProtocol mapped to RADIUS Framed-Protocol
rlm_ldap: LDAP radiusFramedIPAddress mapped to RADIUS Framed-IP-Address
rlm_ldap: LDAP radiusFramedIPNetmask mapped to RADIUS Framed-IP-Netmask
rlm_ldap: LDAP radiusFramedRoute mapped to RADIUS Framed-Route
rlm_ldap: LDAP radiusFramedRouting mapped to RADIUS Framed-Routing
rlm_ldap: LDAP radiusFilterId mapped to RADIUS Filter-Id
rlm_ldap: LDAP radiusFramedMTU mapped to RADIUS Framed-MTU
rlm_ldap: LDAP radiusFramedCompression mapped to RADIUS Framed-Compression
rlm_ldap: LDAP radiusLoginIPHost mapped to RADIUS Login-IP-Host
rlm_ldap: LDAP radiusLoginService mapped to RADIUS Login-Service
rlm_ldap: LDAP radiusLoginTCPPort mapped to RADIUS Login-TCP-Port
rlm_ldap: LDAP radiusCallbackNumber mapped to RADIUS Callback-Number
rlm_ldap: LDAP radiusCallbackId mapped to RADIUS Callback-Id
rlm_ldap: LDAP radiusFramedIPXNetwork mapped to RADIUS Framed-IPX-Network
rlm_ldap: LDAP radiusClass mapped to RADIUS Class
rlm_ldap: LDAP radiusSessionTimeout mapped to RADIUS Session-Timeout
rlm_ldap: LDAP radiusIdleTimeout mapped to RADIUS Idle-Timeout
rlm_ldap: LDAP radiusTerminationAction mapped to RADIUS Termination-Action
rlm_ldap: LDAP radiusLoginLATService mapped to RADIUS Login-LAT-Service
rlm_ldap: LDAP radiusLoginLATNode mapped to RADIUS Login-LAT-Node
rlm_ldap: LDAP radiusLoginLATGroup mapped to RADIUS Login-LAT-Group
rlm_ldap: LDAP radiusFramedAppleTalkLink mapped to RADIUS Framed-AppleTalk-Link
rlm_ldap: LDAP radiusFramedAppleTalkNetwork mapped to RADIUS
Framed-AppleTalk-Network
rlm_ldap: LDAP radiusFramedAppleTalkZone mapped to RADIUS Framed-AppleTalk-Zone
rlm_ldap: LDAP radiusPortLimit mapped to RADIUS Port-Limit
rlm_ldap: LDAP radiusLoginLATPort mapped to RADIUS Login-LAT-Port
rlm_ldap: LDAP radiusReplyMessage mapped to RADIUS Reply-Message
rlm_ldap: LDAP radiusTunnelType mapped to RADIUS Tunnel-Type
rlm_ldap: LDAP radiusTunnelMediumType mapped to RADIUS Tunnel-Medium-Type
rlm_ldap: LDAP radiusTunnelPrivateGroupId mapped to RADIUS
Tunnel-Private-Group-Id
conns: 0xa9c8f0
 Module: Checking session {...} for more modules to load
 Module: Linked to module rlm_radutmp
 Module: Instantiating module "radutmp" from file
/etc/freeradius/modules/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 module "attr_filter.access_reject" from file
/etc/freeradius/modules/attr_filter
  attr_filter attr_filter.access_reject {
	attrsfile = "/etc/freeradius/attrs.access_reject"
	key = "%{User-Name}"
  }
 } # modules
} # server
server { # from file /etc/freeradius/radiusd.conf
 modules {
 Module: Checking authenticate {...} for more modules to load
 Module: Linked to module rlm_digest
 Module: Instantiating module "digest" from file /etc/freeradius/modules/digest
 Module: Checking authorize {...} for more modules to load
 Module: Linked to module rlm_preprocess
 Module: Instantiating module "preprocess" from file
/etc/freeradius/modules/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: Checking preacct {...} for more modules to load
 Module: Linked to module rlm_acct_unique
 Module: Instantiating module "acct_unique" from file
/etc/freeradius/modules/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 module "detail" from file /etc/freeradius/modules/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 module "attr_filter.accounting_response" from
file /etc/freeradius/modules/attr_filter
  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
 } # modules
} # server
radiusd: #### Opening IP addresses and Ports ####
listen {
	type = "auth"
	ipaddr = *
	port = 0
}
listen {
	type = "acct"
	ipaddr = *
	port = 0
}
listen {
	type = "auth"
	ipaddr = 127.0.0.1
	port = 18120
}
Listening on authentication address * port 1812
Listening on accounting address * port 1813
Listening on authentication address 127.0.0.1 port 18120 as server inner-tunnel
Listening on proxy address * port 1814
Ready to process requests.
rad_recv: Access-Request packet from host 194.236.60.6 port 1438,
id=25, length=217
	Framed-MTU = 1480
	NAS-IP-Address = 194.236.60.6
	NAS-Identifier = "access-se1-302-001"
	User-Name = "testuser4"
	Service-Type = Framed-User
	Framed-Protocol = PPP
	NAS-Port = 20
	NAS-Port-Type = Ethernet
	NAS-Port-Id = "A20"
	Called-Station-Id = "00-1f-28-0c-49-00"
	Calling-Station-Id = "c8-bc-c8-9b-4b-d8"
	Connect-Info = "CONNECT Ethernet 1000Mbps Full duplex"
	Tunnel-Type:0 = VLAN
	Tunnel-Medium-Type:0 = IEEE-802
	Tunnel-Private-Group-Id:0 = "60"
	EAP-Message = 0x028f000d016672616e63657363
	Message-Authenticator = 0x0db21bd2c7b5dcf5562053f41f4b9e2b
# Executing section authorize from file /etc/freeradius/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
++[digest] returns noop
[suffix] No '@' in User-Name = "testuser4", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 143 length 13
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
[files] users: Matched entry DEFAULT at line 172
++[files] returns ok
[ldap] performing user authorization for testuser4
[ldap] 	expand: %{Stripped-User-Name} ->
[ldap] 	... expanding second conditional
[ldap] 	expand: %{User-Name} -> testuser4
[ldap] 	expand: (uid=%{%{Stripped-User-Name}:-%{User-Name}}) -> (uid=testuser4)
[ldap] 	expand: DC=host,DC=in,DC=a,DC=domain,DC=net ->
DC=host,DC=in,DC=a,DC=domain,DC=net
  [ldap] ldap_get_conn: Checking Id: 0
  [ldap] ldap_get_conn: Got Id: 0
  [ldap] attempting LDAP reconnection
  [ldap] (re)connect to haldap.int.sto.spotify.net:389, authentication 0
  [ldap] bind as
uid=freeradius_ldap,cn=users,dc=host,dc=in,dc=a,dc=domain,dc=net/somepasswd
to haldap.int.sto.spotify.net:389
  [ldap] waiting for bind result ...
  [ldap] Bind was successful
  [ldap] performing search in DC=host,DC=in,DC=a,DC=domain,DC=net,
with filter (uid=testuser4)
[ldap] No default NMAS login sequence
[ldap] looking for check items in directory...
  [ldap] userPassword -> Password-With-Header == "{SASL}testuser4 at SPOTIFY.NET"
[ldap] looking for reply items in directory...
[ldap] user testuser4 authorized to use remote access
  [ldap] ldap_release_conn: Release Id: 0
++[ldap] returns ok
++[expiration] returns noop
++[logintime] returns noop
[pap] Found unknown header {{SASL}}: Not doing anything
[pap] WARNING: Auth-Type already set.  Not setting to PAP
++[pap] returns noop
Found Auth-Type = EAP
# Executing group from file /etc/freeradius/sites-enabled/default
+- entering group authenticate {...}
[eap] EAP Identity
[eap] processing type md5
rlm_eap_md5: Issuing Challenge
++[eap] returns handled
Sending Access-Challenge of id 25 to 194.236.60.6 port 1438
	Framed-Protocol = PPP
	Framed-Compression = Van-Jacobson-TCP-IP
	EAP-Message = 0x019000160410f44fc318a9b15bdded328eeb09dc194f
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x21c973372159771a4c8d5d9438e5c8c8
Finished request 0.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 194.236.60.6 port 1438,
id=26, length=228
	Framed-MTU = 1480
	NAS-IP-Address = 194.236.60.6
	NAS-Identifier = "access-se1-302-001"
	User-Name = "testuser4"
	Service-Type = Framed-User
	Framed-Protocol = PPP
	NAS-Port = 20
	NAS-Port-Type = Ethernet
	NAS-Port-Id = "A20"
	Called-Station-Id = "00-1f-28-0c-49-00"
	Calling-Station-Id = "c8-bc-c8-9b-4b-d8"
	Connect-Info = "CONNECT Ethernet 1000Mbps Full duplex"
	Tunnel-Type:0 = VLAN
	Tunnel-Medium-Type:0 = IEEE-802
	Tunnel-Private-Group-Id:0 = "60"
	State = 0x21c973372159771a4c8d5d9438e5c8c8
	EAP-Message = 0x029000060319
	Message-Authenticator = 0xe8bd2b07f3fd0ac471adac170c24f681
# Executing section authorize from file /etc/freeradius/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
++[digest] returns noop
[suffix] No '@' in User-Name = "testuser4", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 144 length 6
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
[files] users: Matched entry DEFAULT at line 172
++[files] returns ok
[ldap] performing user authorization for testuser4
[ldap] 	expand: %{Stripped-User-Name} ->
[ldap] 	... expanding second conditional
[ldap] 	expand: %{User-Name} -> testuser4
[ldap] 	expand: (uid=%{%{Stripped-User-Name}:-%{User-Name}}) -> (uid=testuser4)
[ldap] 	expand: DC=host,DC=in,DC=a,DC=domain,DC=net ->
DC=host,DC=in,DC=a,DC=domain,DC=net
  [ldap] ldap_get_conn: Checking Id: 0
  [ldap] ldap_get_conn: Got Id: 0
  [ldap] performing search in DC=host,DC=in,DC=a,DC=domain,DC=net,
with filter (uid=testuser4)
[ldap] No default NMAS login sequence
[ldap] looking for check items in directory...
  [ldap] userPassword -> Password-With-Header == "{SASL}testuser4 at SPOTIFY.NET"
[ldap] looking for reply items in directory...
[ldap] user testuser4 authorized to use remote access
  [ldap] ldap_release_conn: Release Id: 0
++[ldap] returns ok
++[expiration] returns noop
++[logintime] returns noop
[pap] Found unknown header {{SASL}}: Not doing anything
[pap] WARNING: Auth-Type already set.  Not setting to PAP
++[pap] returns noop
Found Auth-Type = EAP
# Executing group from file /etc/freeradius/sites-enabled/default
+- 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 26 to 194.236.60.6 port 1438
	Framed-Protocol = PPP
	Framed-Compression = Van-Jacobson-TCP-IP
	EAP-Message = 0x019100061920
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x21c9733720586a1a4c8d5d9438e5c8c8
Finished request 1.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 194.236.60.6 port 1438,
id=27, length=354
	Framed-MTU = 1480
	NAS-IP-Address = 194.236.60.6
	NAS-Identifier = "access-se1-302-001"
	User-Name = "testuser4"
	Service-Type = Framed-User
	Framed-Protocol = PPP
	NAS-Port = 20
	NAS-Port-Type = Ethernet
	NAS-Port-Id = "A20"
	Called-Station-Id = "00-1f-28-0c-49-00"
	Calling-Station-Id = "c8-bc-c8-9b-4b-d8"
	Connect-Info = "CONNECT Ethernet 1000Mbps Full duplex"
	Tunnel-Type:0 = VLAN
	Tunnel-Medium-Type:0 = IEEE-802
	Tunnel-Private-Group-Id:0 = "60"
	State = 0x21c9733720586a1a4c8d5d9438e5c8c8
	EAP-Message = 0x0291008419800000007a16030100750100007103015006ea1a6a261693e45d56951bdf2ab629d1cdbb0cfd14babcfc0660e52a659c000036c00ac009c007c008c013c014c011c012c004c005c002c003c00ec00fc00cc00d002f000500040035000a00320033003800390016001301000012000a00080006001700180019000b00020100
	Message-Authenticator = 0x4ba49e5403066e3dec33fd466957677c
# Executing section authorize from file /etc/freeradius/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
++[digest] returns noop
[suffix] No '@' in User-Name = "testuser4", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 145 length 132
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
# Executing group from file /etc/freeradius/sites-enabled/default
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/peap
[eap] processing type peap
[peap] processing EAP-TLS
  TLS Length 122
[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 0075], 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 02d4], 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 27 to 194.236.60.6 port 1438
	EAP-Message = 0x019203171900160301002a0200002603015006ea1a06a6cd5b8af0f414c9e86819ac469d68b02fa3d88c852b5f7f08bea400002f0016030102d40b0002d00002cd0002ca308202c6308201ae020900a997f795878568eb300d06092a864886f70d01010505003025312330210603550403131a71756172747a2e696e742e73746f2e73706f746966792e6e6574301e170d3132303630383039333335305a170d3232303630363039333335305a3025312330210603550403131a71756172747a2e696e742e73746f2e73706f746966792e6e657430820122300d06092a864886f70d01010105000382010f003082010a0282010100abc2aea6f96cc6ae
	EAP-Message = 0x3b760e32d33279a1e4948d6c813a647fc1c8bf08d7d8497965646def76a1f046e934568a868e55fba093efc62337515e82217c80f59eb89b2261c4347d31220e7433040228dc1793c3c1a88e9c8903ffad4f9ca946099a96e145ad98eee738ef3bd126c973881f4a0ebcf52405cd7f70d564ade956c8a024bd849e1bb64f503b1b3cd6c5771540f44c962ed6c495436f096630a1a3f8ecaaebcdea993b1746a4c8b62cc89ddd137a2873881d8d14f2e5315e579bbb2d93447e9dadb8726550a4c02b0c0e79389c97c24b3efeaff7b7dadb9c9485760a18ae65dac956d9530ca09ff4df9a2cad48b2f55a08a88fa566873e5bca38369c54610203010001
	EAP-Message = 0x300d06092a864886f70d010105050003820101000cb643b97a5e5969f44ae07b7115ccd3f81dbf46d23f58cdb970bf5bebb74f29d65246f3819f153d67fac184ba7a9d405ecd99d137af42fae91c327434eebb1c3db59a35f54f32d5587ca1b9ae7bcae562f443df78000ad4d4ee70d50b95bb7822287fcddfb72b0ceb7b29ffa4811297e996770bd291d59be103a3c56aff3a1e5b85bd1c686f41c8c757fa53caac4782efed0bd204fb262ffafc93f5a4195f764496d845eb2bd7e8dbabbf46cc50b55d66ba188681c391ce7736400f1c1f61a8366b7b6d1d050694fd3fb2e20fc41a68ffc81de96fec0f3fef35cfebc35bb37b08df98a36243ecc99d
	EAP-Message = 0x4502687e7035107baa4454b9fc8a3279edd794635ce6ba16030100040e000000
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x21c97337235b6a1a4c8d5d9438e5c8c8
Finished request 2.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 194.236.60.6 port 1438,
id=28, length=560
	Framed-MTU = 1480
	NAS-IP-Address = 194.236.60.6
	NAS-Identifier = "access-se1-302-001"
	User-Name = "testuser4"
	Service-Type = Framed-User
	Framed-Protocol = PPP
	NAS-Port = 20
	NAS-Port-Type = Ethernet
	NAS-Port-Id = "A20"
	Called-Station-Id = "00-1f-28-0c-49-00"
	Calling-Station-Id = "c8-bc-c8-9b-4b-d8"
	Connect-Info = "CONNECT Ethernet 1000Mbps Full duplex"
	Tunnel-Type:0 = VLAN
	Tunnel-Medium-Type:0 = IEEE-802
	Tunnel-Private-Group-Id:0 = "60"
	State = 0x21c97337235b6a1a4c8d5d9438e5c8c8
	EAP-Message = 0x02920150198000000146160301010610000102010005a283f8370a6f39d45d32af199ff5688c81224dabde4db0832821c9c9c3e92a8f93fd4dc57eaf6e654a2064ad3984128220f71ac254c675167c19f507c12f61dd6856631b2e921b594eddc6100d91fbdd5da88544dc6fdaff60bcef55ff9e35e4ea4e6bfc5680a9f7fd3cedb6cbd1f24d4c0db4cd0796c20f7bfb50b8b5346888830518430543e7e70a3bf5e4d30ea1c983331fb911cb863a906b7d7edd7c73a979e7d825b6f80a358e11af0c3830606a0d546c644764bb1ed31f9689b01ff205da7a2f2574a3875acae3d0dfcc5247c42c8a4787ddf533b4f1cde23cadf8997d9fe3936a2b83e1
	EAP-Message = 0xbec1e583d51e15e0775799fb2fedc91e30465f04f48e733c1403010001011603010030a3f977700de3d38fda560ec1f9f6ea8c2e1322df84d7ce1a060c7bc3b35631beeba2998c1a409eee6b7cb7ee558b343a
	Message-Authenticator = 0xd711fe582d9b5cd194ed166907f68314
# Executing section authorize from file /etc/freeradius/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
++[digest] returns noop
[suffix] No '@' in User-Name = "testuser4", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 146 length 253
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
# Executing group from file /etc/freeradius/sites-enabled/default
+- 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 28 to 194.236.60.6 port 1438
	EAP-Message = 0x01930041190014030100010116030100308694c16e1732bbd663ab1fec150c4fff3d6d83ab7b30a81cfe129511de7942d8d8027d2b881bbbb51027216138c147cd
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x21c97337225a6a1a4c8d5d9438e5c8c8
Finished request 3.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 194.236.60.6 port 1438,
id=29, length=228
	Framed-MTU = 1480
	NAS-IP-Address = 194.236.60.6
	NAS-Identifier = "access-se1-302-001"
	User-Name = "testuser4"
	Service-Type = Framed-User
	Framed-Protocol = PPP
	NAS-Port = 20
	NAS-Port-Type = Ethernet
	NAS-Port-Id = "A20"
	Called-Station-Id = "00-1f-28-0c-49-00"
	Calling-Station-Id = "c8-bc-c8-9b-4b-d8"
	Connect-Info = "CONNECT Ethernet 1000Mbps Full duplex"
	Tunnel-Type:0 = VLAN
	Tunnel-Medium-Type:0 = IEEE-802
	Tunnel-Private-Group-Id:0 = "60"
	State = 0x21c97337225a6a1a4c8d5d9438e5c8c8
	EAP-Message = 0x029300061900
	Message-Authenticator = 0x4c81d4f9c40214c6153e062989a8833c
# Executing section authorize from file /etc/freeradius/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
++[digest] returns noop
[suffix] No '@' in User-Name = "testuser4", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 147 length 6
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
# Executing group from file /etc/freeradius/sites-enabled/default
+- 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
[peap] Session established.  Decoding tunneled attributes.
[peap] Peap state TUNNEL ESTABLISHED
++[eap] returns handled
Sending Access-Challenge of id 29 to 194.236.60.6 port 1438
	EAP-Message = 0x0194002b19001703010020b9116214add324abd801cacf1ab59a6b27a3fee99b7d812b7caa85b899f969cc
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x21c97337255d6a1a4c8d5d9438e5c8c8
Finished request 4.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 194.236.60.6 port 1438,
id=30, length=265
	Framed-MTU = 1480
	NAS-IP-Address = 194.236.60.6
	NAS-Identifier = "access-se1-302-001"
	User-Name = "testuser4"
	Service-Type = Framed-User
	Framed-Protocol = PPP
	NAS-Port = 20
	NAS-Port-Type = Ethernet
	NAS-Port-Id = "A20"
	Called-Station-Id = "00-1f-28-0c-49-00"
	Calling-Station-Id = "c8-bc-c8-9b-4b-d8"
	Connect-Info = "CONNECT Ethernet 1000Mbps Full duplex"
	Tunnel-Type:0 = VLAN
	Tunnel-Medium-Type:0 = IEEE-802
	Tunnel-Private-Group-Id:0 = "60"
	State = 0x21c97337255d6a1a4c8d5d9438e5c8c8
	EAP-Message = 0x0294002b19001703010020416f52107037b88b1de08c35b2dd1218afe123747d53e5f9643b47a2da3a69ce
	Message-Authenticator = 0xc68800a0978696090a2eb9bb1e0003e7
# Executing section authorize from file /etc/freeradius/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
++[digest] returns noop
[suffix] No '@' in User-Name = "testuser4", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 148 length 43
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
# Executing group from file /etc/freeradius/sites-enabled/default
+- 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] Peap state WAITING FOR INNER IDENTITY
[peap] Identity - testuser4
[peap] Got inner identity 'testuser4'
[peap] Setting default EAP type for tunneled EAP session.
[peap] Got tunneled request
	EAP-Message = 0x0294000d016672616e63657363
server  {
  PEAP: Setting User-Name to testuser4
Sending tunneled request
	EAP-Message = 0x0294000d016672616e63657363
	FreeRADIUS-Proxied-To = 127.0.0.1
	User-Name = "testuser4"
server inner-tunnel {
# Executing section authorize from file
/etc/freeradius/sites-enabled/inner-tunnel
+- entering group authorize {...}
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "testuser4", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[control] returns noop
[eap] EAP packet type response id 148 length 13
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
++[files] returns noop
[ldap] performing user authorization for testuser4
[ldap] 	expand: %{Stripped-User-Name} ->
[ldap] 	... expanding second conditional
[ldap] 	expand: %{User-Name} -> testuser4
[ldap] 	expand: (uid=%{%{Stripped-User-Name}:-%{User-Name}}) -> (uid=testuser4)
[ldap] 	expand: DC=host,DC=in,DC=a,DC=domain,DC=net ->
DC=host,DC=in,DC=a,DC=domain,DC=net
  [ldap] ldap_get_conn: Checking Id: 0
  [ldap] ldap_get_conn: Got Id: 0
  [ldap] performing search in DC=host,DC=in,DC=a,DC=domain,DC=net,
with filter (uid=testuser4)
[ldap] No default NMAS login sequence
[ldap] looking for check items in directory...
  [ldap] userPassword -> Password-With-Header == "{SASL}testuser4 at SPOTIFY.NET"
[ldap] looking for reply items in directory...
[ldap] user testuser4 authorized to use remote access
  [ldap] ldap_release_conn: Release Id: 0
++[ldap] returns ok
++[expiration] returns noop
++[logintime] returns noop
[pap] Found unknown header {{SASL}}: Not doing anything
[pap] WARNING: Auth-Type already set.  Not setting to PAP
++[pap] returns noop
Found Auth-Type = EAP
# Executing group from file /etc/freeradius/sites-enabled/inner-tunnel
+- 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 = 0x019500221a0195001d10c0bdfc0144d79a88c5213c546a9236ef6672616e63657363
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x69fe7606696b6c295226f098739615c2
[peap] Got tunneled reply RADIUS code 11
	EAP-Message = 0x019500221a0195001d10c0bdfc0144d79a88c5213c546a9236ef6672616e63657363
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x69fe7606696b6c295226f098739615c2
[peap] Got tunneled Access-Challenge
++[eap] returns handled
Sending Access-Challenge of id 30 to 194.236.60.6 port 1438
	EAP-Message = 0x0195004b190017030100408b0708d439e500424334ff22e1d99fc72c03cf545e7da4d98e32b7717d8fdd01549051a25f1cbcb12acf0b2dd2cc1af0fd2ab8d3ce69214c4049145dbe89cb44
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x21c97337245c6a1a4c8d5d9438e5c8c8
Finished request 5.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 194.236.60.6 port 1438,
id=31, length=329
	Framed-MTU = 1480
	NAS-IP-Address = 194.236.60.6
	NAS-Identifier = "access-se1-302-001"
	User-Name = "testuser4"
	Service-Type = Framed-User
	Framed-Protocol = PPP
	NAS-Port = 20
	NAS-Port-Type = Ethernet
	NAS-Port-Id = "A20"
	Called-Station-Id = "00-1f-28-0c-49-00"
	Calling-Station-Id = "c8-bc-c8-9b-4b-d8"
	Connect-Info = "CONNECT Ethernet 1000Mbps Full duplex"
	Tunnel-Type:0 = VLAN
	Tunnel-Medium-Type:0 = IEEE-802
	Tunnel-Private-Group-Id:0 = "60"
	State = 0x21c97337245c6a1a4c8d5d9438e5c8c8
	EAP-Message = 0x0295006b1900170301006097d8cdcd8a34cf3194a9fda7e3f39151d5b26ac29afaa5f077339820e6994962f0b14366db12b799dc419cc60ec47d13238ce2837abf6990e036dbbd66f322062bf87b3607cf70a916944e77f769b8098ed23210300c9ff5e711a60d271bf4f8
	Message-Authenticator = 0x9a72d15df4f23c4691c9359f405c2176
# Executing section authorize from file /etc/freeradius/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
++[digest] returns noop
[suffix] No '@' in User-Name = "testuser4", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 149 length 107
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
# Executing group from file /etc/freeradius/sites-enabled/default
+- 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] Peap state phase2
[peap] EAP type mschapv2
[peap] Got tunneled request
	EAP-Message = 0x029500431a0295003e31e585188413185f32ca1a4e41c24add86000000000000000012a75f668e30a221718c00e5c7b321033f96ff40bf5b688e006672616e63657363
server  {
  PEAP: Setting User-Name to testuser4
Sending tunneled request
	EAP-Message = 0x029500431a0295003e31e585188413185f32ca1a4e41c24add86000000000000000012a75f668e30a221718c00e5c7b321033f96ff40bf5b688e006672616e63657363
	FreeRADIUS-Proxied-To = 127.0.0.1
	User-Name = "testuser4"
	State = 0x69fe7606696b6c295226f098739615c2
server inner-tunnel {
# Executing section authorize from file
/etc/freeradius/sites-enabled/inner-tunnel
+- entering group authorize {...}
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "testuser4", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[control] returns noop
[eap] EAP packet type response id 149 length 67
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
++[files] returns noop
[ldap] performing user authorization for testuser4
[ldap] 	expand: %{Stripped-User-Name} ->
[ldap] 	... expanding second conditional
[ldap] 	expand: %{User-Name} -> testuser4
[ldap] 	expand: (uid=%{%{Stripped-User-Name}:-%{User-Name}}) -> (uid=testuser4)
[ldap] 	expand: DC=host,DC=in,DC=a,DC=domain,DC=net ->
DC=host,DC=in,DC=a,DC=domain,DC=net
  [ldap] ldap_get_conn: Checking Id: 0
  [ldap] ldap_get_conn: Got Id: 0
  [ldap] performing search in DC=host,DC=in,DC=a,DC=domain,DC=net,
with filter (uid=testuser4)
[ldap] No default NMAS login sequence
[ldap] looking for check items in directory...
  [ldap] userPassword -> Password-With-Header == "{SASL}testuser4 at SPOTIFY.NET"
[ldap] looking for reply items in directory...
[ldap] user testuser4 authorized to use remote access
  [ldap] ldap_release_conn: Release Id: 0
++[ldap] returns ok
++[expiration] returns noop
++[logintime] returns noop
[pap] Found unknown header {{SASL}}: Not doing anything
[pap] WARNING: Auth-Type already set.  Not setting to PAP
++[pap] returns noop
Found Auth-Type = EAP
# Executing group from file /etc/freeradius/sites-enabled/inner-tunnel
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/mschapv2
[eap] processing type mschapv2
[mschapv2] # Executing group from file
/etc/freeradius/sites-enabled/inner-tunnel
[mschapv2] +- entering group MS-CHAP {...}
[mschap] No Cleartext-Password configured.  Cannot create LM-Password.
[mschap] No Cleartext-Password configured.  Cannot create NT-Password.
[mschap] Creating challenge hash with username: testuser4
[mschap] Told to do MS-CHAPv2 for testuser4 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 = "\225E=691 R=1"
	EAP-Message = 0x04950004
	Message-Authenticator = 0x00000000000000000000000000000000
[peap] Got tunneled reply RADIUS code 3
	MS-CHAP-Error = "\225E=691 R=1"
	EAP-Message = 0x04950004
	Message-Authenticator = 0x00000000000000000000000000000000
[peap] Tunneled authentication was rejected.
[peap] FAILURE
++[eap] returns handled
Sending Access-Challenge of id 31 to 194.236.60.6 port 1438
	EAP-Message = 0x0196002b19001703010020d212e9083be574e3b8fd376ca128702a1f3fa8c3788742fdc1af6659b4ee82aa
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x21c97337275f6a1a4c8d5d9438e5c8c8
Finished request 6.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 194.236.60.6 port 1438,
id=32, length=265
	Framed-MTU = 1480
	NAS-IP-Address = 194.236.60.6
	NAS-Identifier = "access-se1-302-001"
	User-Name = "testuser4"
	Service-Type = Framed-User
	Framed-Protocol = PPP
	NAS-Port = 20
	NAS-Port-Type = Ethernet
	NAS-Port-Id = "A20"
	Called-Station-Id = "00-1f-28-0c-49-00"
	Calling-Station-Id = "c8-bc-c8-9b-4b-d8"
	Connect-Info = "CONNECT Ethernet 1000Mbps Full duplex"
	Tunnel-Type:0 = VLAN
	Tunnel-Medium-Type:0 = IEEE-802
	Tunnel-Private-Group-Id:0 = "60"
	State = 0x21c97337275f6a1a4c8d5d9438e5c8c8
	EAP-Message = 0x0296002b190017030100208bc4203b2a2402fbc5000f71853972a02334860f1a6f9830ba495f782507f7fd
	Message-Authenticator = 0xb54c11beffff4ff0e3210d345b00417d
# Executing section authorize from file /etc/freeradius/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
++[digest] returns noop
[suffix] No '@' in User-Name = "testuser4", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 150 length 43
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
# Executing group from file /etc/freeradius/sites-enabled/default
+- 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] Peap state send tlv failure
[peap] Received EAP-TLV response.
[peap]  The users session was previously rejected: returning reject (again.)
[peap]  *** This means you need to read the PREVIOUS messages in the
debug output
[peap]  *** to find out the reason why the user was rejected.
[peap]  *** Look for "reject" or "fail".  Those earlier messages will tell you.
[peap]  *** what went wrong, and how to fix the problem.
[eap] Handler failed in EAP/peap
[eap] Failed in EAP select
++[eap] returns invalid
Failed to authenticate the user.
Using Post-Auth-Type Reject
# Executing group from file /etc/freeradius/sites-enabled/default
+- entering group REJECT {...}
[attr_filter.access_reject] 	expand: %{User-Name} -> testuser4
 attr_filter: Matched entry DEFAULT at line 11
++[attr_filter.access_reject] returns updated
Delaying reject of request 7 for 1 seconds
Going to the next request
Waking up in 0.9 seconds.
Sending delayed reject for request 7
Sending Access-Reject of id 32 to 194.236.60.6 port 1438
	EAP-Message = 0x04960004
	Message-Authenticator = 0x00000000000000000000000000000000
Waking up in 3.9 seconds.
Cleaning up request 0 ID 25 with timestamp +2
Cleaning up request 1 ID 26 with timestamp +2
Cleaning up request 2 ID 27 with timestamp +2
Cleaning up request 3 ID 28 with timestamp +2
Cleaning up request 4 ID 29 with timestamp +2
Cleaning up request 5 ID 30 with timestamp +2
Cleaning up request 6 ID 31 with timestamp +2
Waking up in 1.0 seconds.
Cleaning up request 7 ID 32 with timestamp +2
Ready to process requests.


----->8------


Notice the lines:

[eap] Request found, released from the list
[eap] EAP/mschapv2
[eap] processing type mschapv2
[mschapv2] # Executing group from file
/etc/freeradius/sites-enabled/inner-tunnel
[mschapv2] +- entering group MS-CHAP {...}
[mschap] No Cleartext-Password configured.  Cannot create LM-Password.
[mschap] No Cleartext-Password configured.  Cannot create NT-Password.
[mschap] Creating challenge hash with username: testuser4
[mschap] Told to do MS-CHAPv2 for testuser4 with NT-Password
[mschap] FAILED: No NT/LM-Password.  Cannot perform authentication.
[mschap] FAILED: MS-CHAP2-Response is incorrect
++[mschap] returns reject

I wonder what is this module doing.
Please, could you give us some advice?

Kind regards,
Francesc


More information about the Freeradius-Users mailing list