Authentication problems depending on connection type

22 Oct 2014

      Hello-

I am using FreeRadius to proxy connections to an OTP server for a WiFi solution.  It looks users up in LDAP and if they exist proxy’s the password in clear text to the Radius server.  I am testing on a Cisco WLC and using a Meraki device in production.  Not ideal for identical testing I know its buts all I have.  They are pointing to the same radius/ldap server.  I have things working as long as I am using the Meraki “splash page” to have users authenticate against.  I just keep the network open and they connect to the network to authenticate.  The problem is the login page comes up but is not consistent.  I would rather have my users authenticate with the OTP when joining the network via the OS.  With MAC’s this takes a custom profile.  In order to do that I have to send the passwords in the clear so I am using PAP-TTLS in the configuration utility to create the profile.  

If I use the splash page on the open network on the Meraki the access-request looks like this (with a User-Password field):

Sending Access-Request Id 211 from 0.0.0.0:39633 to 94.143.115.73:1812
	Acct-Session-Id = '569705352865660562'
	Called-Station-Id = '00-18-0A-32-AF-AA:company Devs'
	Calling-Station-Id = '14-10-9F-E1-B0-D3'
	Framed-IP-Address = 10.197.125.204
	NAS-Identifier = 'Cisco Meraki cloud RADIUS client'
	NAS-IP-Address = 108.161.147.80
	NAS-Port = 0
	NAS-Port-Id = 'Wireless-802.11'
	NAS-Port-Type = Wireless-802.11
	Service-Type = Login-User
	User-Name = 'haydn'
	User-Password = 'r4ffev'
	Event-Timestamp = 'Oct 22 2014 22:23:15 UTC'
	Message-Authenticator := 0x00
	Proxy-State = 0x32
Waking up in 0.1 seconds.

When I do it on the WLC with the OS network level connection I get this:

Sending Access-Request Id 123 from 0.0.0.0:58776 to 94.143.115.73:1812
	User-Name = 'alexgregory'
	Chargeable-User-Identity = 0x00
	Location-Capable = Civix-Location
	Calling-Station-Id = '78-31-c1-be-89-a8'
	Called-Station-Id = 'd4-a0-2a-15-7f-00:C2_8021x'
	NAS-Port = 4
	Cisco-AVPair = 'audit-session-id=0a210082000009be54483291'
	NAS-IP-Address = 10.33.0.130
	NAS-Identifier = 'isewlc'
	Airespace-Wlan-Id = 6
	Service-Type = Framed-User
	Framed-MTU = 1300
	NAS-Port-Type = Wireless-802.11
	EAP-Message = 0x0201001001616c6578677265676f7279
	Message-Authenticator = 0x40c1f142e786e20cf3836cfe05549f35
	Event-Timestamp = 'Oct 22 2014 22:41:38 UTC'
	Proxy-State = 0x3137
Waking up in 0.3 seconds.

My question is am I using the same radius config sections when I connect via the OS dialog?  What could cause the change on how things are forwarded from the FreeRadius server?  It looks like it does EAP which I do not want it to do. (full config and authentication below)

Thanks,

Alex

root@radius:/etc/init.d# /usr/local/sbin/radiusd -X
radiusd: FreeRADIUS Version 3.0.4, for host x86_64-unknown-linux-gnu, built on Sep 25 2014 at 20:37:45
Copyright (C) 1999-2014 The FreeRADIUS server project and contributors
There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A
PARTICULAR PURPOSE
You may redistribute copies of FreeRADIUS under the terms of the
GNU General Public License
For more information about these matters, see the file named COPYRIGHT
Starting - reading configuration files ...
including dictionary file /usr/local/share/freeradius/dictionary
including dictionary file /usr/local/share/freeradius/dictionary.dhcp
including dictionary file /usr/local/share/freeradius/dictionary.vqp
including dictionary file /usr/local/etc/raddb/dictionary
including configuration file /usr/local/etc/raddb/radiusd.conf
including configuration file /usr/local/etc/raddb/proxy.conf
including configuration file /usr/local/etc/raddb/clients.conf
including files in directory /usr/local/etc/raddb/mods-enabled/
including configuration file /usr/local/etc/raddb/mods-enabled/mschap
including configuration file /usr/local/etc/raddb/mods-enabled/passwd
including configuration file /usr/local/etc/raddb/mods-enabled/digest
including configuration file /usr/local/etc/raddb/mods-enabled/dhcp
including configuration file /usr/local/etc/raddb/mods-enabled/eap
including configuration file /usr/local/etc/raddb/mods-enabled/realm
including configuration file /usr/local/etc/raddb/mods-enabled/echo
including configuration file /usr/local/etc/raddb/mods-enabled/utf8
including configuration file /usr/local/etc/raddb/mods-enabled/always
including configuration file /usr/local/etc/raddb/mods-enabled/pap
including configuration file /usr/local/etc/raddb/mods-enabled/detail.log
including configuration file /usr/local/etc/raddb/mods-enabled/chap
including configuration file /usr/local/etc/raddb/mods-enabled/expr
including configuration file /usr/local/etc/raddb/mods-enabled/ntlm_auth
including configuration file /usr/local/etc/raddb/mods-enabled/sradutmp
including configuration file /usr/local/etc/raddb/mods-enabled/radutmp
including configuration file /usr/local/etc/raddb/mods-enabled/unix
including configuration file /usr/local/etc/raddb/mods-enabled/soh
including configuration file /usr/local/etc/raddb/mods-enabled/logintime
including configuration file /usr/local/etc/raddb/mods-enabled/dynamic_clients
including configuration file /usr/local/etc/raddb/mods-enabled/files
including configuration file /usr/local/etc/raddb/mods-enabled/unpack
including configuration file /usr/local/etc/raddb/mods-enabled/replicate
including configuration file /usr/local/etc/raddb/mods-enabled/ldap
including configuration file /usr/local/etc/raddb/mods-enabled/attr_filter
including configuration file /usr/local/etc/raddb/mods-enabled/linelog
including configuration file /usr/local/etc/raddb/mods-enabled/cache_eap
including configuration file /usr/local/etc/raddb/mods-enabled/detail
including configuration file /usr/local/etc/raddb/mods-enabled/ldap-dev
including configuration file /usr/local/etc/raddb/mods-enabled/exec
including configuration file /usr/local/etc/raddb/mods-enabled/expiration
including configuration file /usr/local/etc/raddb/mods-enabled/preprocess
including configuration file /usr/local/etc/raddb/mods-enabled/eap-dev
including files in directory /usr/local/etc/raddb/policy.d/
including configuration file /usr/local/etc/raddb/policy.d/dhcp
including configuration file /usr/local/etc/raddb/policy.d/eap
including configuration file /usr/local/etc/raddb/policy.d/cui
including configuration file /usr/local/etc/raddb/policy.d/debug
including configuration file /usr/local/etc/raddb/policy.d/filter
including configuration file /usr/local/etc/raddb/policy.d/operator-name
including configuration file /usr/local/etc/raddb/policy.d/accounting
including configuration file /usr/local/etc/raddb/policy.d/control
including configuration file /usr/local/etc/raddb/policy.d/canonicalization
including files in directory /usr/local/etc/raddb/sites-enabled/
including configuration file /usr/local/etc/raddb/sites-enabled/default-dev
including configuration file /usr/local/etc/raddb/sites-enabled/inner-tunnel-dev
including configuration file /usr/local/etc/raddb/sites-enabled/inner-tunnel
including configuration file /usr/local/etc/raddb/sites-enabled/default
main {
security {
	allow_core_dumps = no
}
}
main {
	name = "radiusd"
	prefix = "/usr/local"
	localstatedir = "/usr/local/var"
	sbindir = "/usr/local/sbin"
	logdir = "/usr/local/var/log/radius"
	run_dir = "/usr/local/var/run/radiusd"
	libdir = "/usr/local/lib"
	radacctdir = "/usr/local/var/log/radius/radacct"
	hostname_lookups = no
	max_request_time = 30
	cleanup_delay = 5
	max_requests = 1024
	pidfile = "/usr/local/var/run/radiusd/radiusd.pid"
	checkrad = "/usr/local/sbin/checkrad"
	debug_level = 0
	proxy_requests = yes
log {
	stripped_names = no
	auth = no
	auth_badpass = no
	auth_goodpass = no
	colourise = yes
	msg_denied = "You are already logged in - access denied"
}
security {
	max_attributes = 200
	reject_delay = 1
	status_server = yes
	allow_vulnerable_openssl = "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 inwebo1 {
	ipaddr = 94.143.115.73
	port = 1812
	type = "auth"
	secret = <<< secret >>>
	response_window = 30.000000
	response_timeouts = 1
	max_outstanding = 65536
	zombie_period = 40
	status_check = "status-server"
	ping_interval = 30
	check_interval = 30
	check_timeout = 4
	num_answers_to_alive = 3
	revive_interval = 300
 coa {
 	irt = 2
 	mrt = 16
 	mrc = 5
 	mrd = 30
 }
 limit {
 	max_connections = 16
 	max_requests = 0
 	lifetime = 0
 	idle_timeout = 0
 }
}
home_server inwebo2 {
	ipaddr = 94.143.115.75
	port = 1812
	type = "auth"
	secret = <<< secret >>>
	response_window = 30.000000
	response_timeouts = 1
	max_outstanding = 65536
	zombie_period = 40
	status_check = "status-server"
	ping_interval = 30
	check_interval = 30
	check_timeout = 4
	num_answers_to_alive = 3
	revive_interval = 300
 coa {
 	irt = 2
 	mrt = 16
 	mrc = 5
 	mrd = 30
 }
 limit {
 	max_connections = 16
 	max_requests = 0
 	lifetime = 0
 	idle_timeout = 0
 }
}
home_server_pool inwebo {
	type = fail-over
	home_server = inwebo1
	home_server = inwebo2
}
realm DEFAULT {
	auth_pool = inwebo
	nostrip
}
radiusd: #### Loading Clients ####
client allhosts {
	ipaddr = *
	require_message_authenticator = no
	secret = <<< secret >>>
	nas_type = "other"
	proto = "*"
 limit {
 	max_connections = 16
 	lifetime = 0
 	idle_timeout = 30
 }
}
client localhost {
	ipaddr = 127.0.0.1
	require_message_authenticator = no
	secret = <<< secret >>>
	nas_type = "other"
	proto = "*"
 limit {
 	max_connections = 16
 	lifetime = 0
 	idle_timeout = 30
 }
}
client localhost_ipv6 {
	ipv6addr = ::1
	require_message_authenticator = no
	secret = <<< secret >>>
 limit {
 	max_connections = 16
 	lifetime = 0
 	idle_timeout = 30
 }
}
radiusd: #### Instantiating modules ####
instantiate {
}
modules {
 # Loaded module rlm_mschap
 # Instantiating module "mschap" from file /usr/local/etc/raddb/mods-enabled/mschap
 mschap {
 	use_mppe = yes
 	require_encryption = no
 	require_strong = no
 	with_ntdomain_hack = yes
  passchange {
  }
 	allow_retry = yes
 }
 # Loaded module rlm_passwd
 # Instantiating module "etc_passwd" from file /usr/local/etc/raddb/mods-enabled/passwd
 passwd etc_passwd {
 	filename = "/etc/passwd"
 	format = "*User-Name:Crypt-Password:"
 	delimiter = ":"
 	ignore_nislike = no
 	ignore_empty = yes
 	allow_multiple_keys = no
 	hash_size = 100
 }
rlm_passwd: nfields: 3 keyfield 0(User-Name) listable: no
 # Loaded module rlm_digest
 # Instantiating module "digest" from file /usr/local/etc/raddb/mods-enabled/digest
 # Loaded module rlm_dhcp
 # Instantiating module "dhcp" from file /usr/local/etc/raddb/mods-enabled/dhcp
 # Loaded module rlm_eap
 # Instantiating module "eap" from file /usr/local/etc/raddb/mods-enabled/eap
 eap {
 	default_eap_type = "ttls"
 	timer_expire = 60
 	ignore_unknown_eap_types = no
 	mod_accounting_username_bug = no
 	max_sessions = 1024
 }
  # Linked to sub-module rlm_eap_md5
  # Linked to sub-module rlm_eap_leap
  # Linked to sub-module rlm_eap_gtc
  gtc {
  	challenge = "Password: "
  	auth_type = "PAP"
  }
  # Linked to sub-module rlm_eap_tls
  tls {
  	tls = "tls-common"
  }
  tls-config tls-common {
  	rsa_key_exchange = no
  	dh_key_exchange = yes
  	rsa_key_length = 512
  	dh_key_length = 512
  	verify_depth = 0
  	ca_path = "/usr/local/etc/raddb/certs"
  	pem_file_type = yes
  	private_key_file = "/usr/local/etc/raddb/certs/server.pem"
  	certificate_file = "/usr/local/etc/raddb/certs/server.pem"
  	ca_file = "/usr/local/etc/raddb/certs/ca.pem"
  	private_key_password = <<< secret >>>
  	dh_file = "/usr/local/etc/raddb/certs/dh"
  	fragment_size = 1024
  	include_length = yes
  	check_crl = no
  	cipher_list = "DEFAULT"
  	ecdh_curve = "prime256v1"
   cache {
   	enable = yes
   	lifetime = 24
   	max_entries = 255
   }
   verify {
   }
   ocsp {
   	enable = no
   	override_cert_url = yes
   	url = "http://127.0.0.1/ocsp/"
   	use_nonce = yes
   	timeout = 0
   	softfail = yes
   }
  }
  # Linked to sub-module rlm_eap_ttls
  ttls {
  	tls = "tls-common"
  	default_eap_type = "md5"
  	copy_request_to_tunnel = no
  	use_tunneled_reply = no
  	virtual_server = "inner-tunnel"
  	include_length = yes
  	require_client_cert = no
  }
Using cached TLS configuration from previous invocation
  # Linked to sub-module rlm_eap_peap
  peap {
  	tls = "tls-common"
  	default_method = "mschapv2"
  	copy_request_to_tunnel = no
  	use_tunneled_reply = no
  	proxy_tunneled_request_as_eap = yes
  	virtual_server = "inner-tunnel"
  	soh = no
  	require_client_cert = no
  }
Using cached TLS configuration from previous invocation
  # Linked to sub-module rlm_eap_mschapv2
  mschapv2 {
  	with_ntdomain_hack = no
  	send_error = no
  }
 # Loaded module rlm_realm
 # Instantiating module "IPASS" from file /usr/local/etc/raddb/mods-enabled/realm
 realm IPASS {
 	format = "prefix"
 	delimiter = "/"
 	ignore_default = no
 	ignore_null = no
 }
 # Instantiating module "suffix" from file /usr/local/etc/raddb/mods-enabled/realm
 realm suffix {
 	format = "suffix"
 	delimiter = "@"
 	ignore_default = no
 	ignore_null = no
 }
 # Instantiating module "realmpercent" from file /usr/local/etc/raddb/mods-enabled/realm
 realm realmpercent {
 	format = "suffix"
 	delimiter = "%"
 	ignore_default = no
 	ignore_null = no
 }
 # Instantiating module "ntdomain" from file /usr/local/etc/raddb/mods-enabled/realm
 realm ntdomain {
 	format = "prefix"
 	delimiter = "\"
 	ignore_default = no
 	ignore_null = no
 }
 # Loaded module rlm_exec
 # Instantiating module "echo" from file /usr/local/etc/raddb/mods-enabled/echo
 exec echo {
 	wait = yes
 	program = "/bin/echo %{User-Name}"
 	input_pairs = "request"
 	output_pairs = "reply"
 	shell_escape = yes
 }
 # Loaded module rlm_utf8
 # Instantiating module "utf8" from file /usr/local/etc/raddb/mods-enabled/utf8
 # Loaded module rlm_always
 # Instantiating module "reject" from file /usr/local/etc/raddb/mods-enabled/always
 always reject {
 	rcode = "reject"
 	simulcount = 0
 	mpp = no
 }
 # Instantiating module "fail" from file /usr/local/etc/raddb/mods-enabled/always
 always fail {
 	rcode = "fail"
 	simulcount = 0
 	mpp = no
 }
 # Instantiating module "ok" from file /usr/local/etc/raddb/mods-enabled/always
 always ok {
 	rcode = "ok"
 	simulcount = 0
 	mpp = no
 }
 # Instantiating module "handled" from file /usr/local/etc/raddb/mods-enabled/always
 always handled {
 	rcode = "handled"
 	simulcount = 0
 	mpp = no
 }
 # Instantiating module "invalid" from file /usr/local/etc/raddb/mods-enabled/always
 always invalid {
 	rcode = "invalid"
 	simulcount = 0
 	mpp = no
 }
 # Instantiating module "userlock" from file /usr/local/etc/raddb/mods-enabled/always
 always userlock {
 	rcode = "userlock"
 	simulcount = 0
 	mpp = no
 }
 # Instantiating module "notfound" from file /usr/local/etc/raddb/mods-enabled/always
 always notfound {
 	rcode = "notfound"
 	simulcount = 0
 	mpp = no
 }
 # Instantiating module "noop" from file /usr/local/etc/raddb/mods-enabled/always
 always noop {
 	rcode = "noop"
 	simulcount = 0
 	mpp = no
 }
 # Instantiating module "updated" from file /usr/local/etc/raddb/mods-enabled/always
 always updated {
 	rcode = "updated"
 	simulcount = 0
 	mpp = no
 }
 # Loaded module rlm_pap
 # Instantiating module "pap" from file /usr/local/etc/raddb/mods-enabled/pap
 pap {
 	normalise = yes
 }
 # Loaded module rlm_detail
 # Instantiating module "auth_log" from file /usr/local/etc/raddb/mods-enabled/detail.log
 detail auth_log {
 	filename = "/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d"
 	header = "%t"
 	permissions = 384
 	locking = no
 	log_packet_header = no
 }
rlm_detail (auth_log): 'User-Password' suppressed, will not appear in detail output
 # Instantiating module "reply_log" from file /usr/local/etc/raddb/mods-enabled/detail.log
 detail reply_log {
 	filename = "/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/reply-detail-%Y%m%d"
 	header = "%t"
 	permissions = 384
 	locking = no
 	log_packet_header = no
 }
 # Instantiating module "pre_proxy_log" from file /usr/local/etc/raddb/mods-enabled/detail.log
 detail pre_proxy_log {
 	filename = "/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/pre-proxy-detail-%Y%m%d"
 	header = "%t"
 	permissions = 384
 	locking = no
 	log_packet_header = no
 }
 # Instantiating module "post_proxy_log" from file /usr/local/etc/raddb/mods-enabled/detail.log
 detail post_proxy_log {
 	filename = "/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/post-proxy-detail-%Y%m%d"
 	header = "%t"
 	permissions = 384
 	locking = no
 	log_packet_header = no
 }
 # Loaded module rlm_chap
 # Instantiating module "chap" from file /usr/local/etc/raddb/mods-enabled/chap
 # Loaded module rlm_expr
 # Instantiating module "expr" from file /usr/local/etc/raddb/mods-enabled/expr
 expr {
 	safe_characters = "@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /"
 }
 # Instantiating module "ntlm_auth" from file /usr/local/etc/raddb/mods-enabled/ntlm_auth
 exec ntlm_auth {
 	wait = yes
 	program = "/path/to/ntlm_auth --request-nt-key --domain=MYDOMAIN --username=%{mschap:User-Name} --password=%{User-Password}"
 	shell_escape = yes
 }
 # Loaded module rlm_radutmp
 # Instantiating module "sradutmp" from file /usr/local/etc/raddb/mods-enabled/sradutmp
 radutmp sradutmp {
 	filename = "/usr/local/var/log/radius/sradutmp"
 	username = "%{User-Name}"
 	case_sensitive = yes
 	check_with_nas = yes
 	permissions = 420
 	caller_id = no
 }
 # Instantiating module "radutmp" from file /usr/local/etc/raddb/mods-enabled/radutmp
 radutmp {
 	filename = "/usr/local/var/log/radius/radutmp"
 	username = "%{User-Name}"
 	case_sensitive = yes
 	check_with_nas = yes
 	permissions = 384
 	caller_id = yes
 }
 # Loaded module rlm_unix
 # Instantiating module "unix" from file /usr/local/etc/raddb/mods-enabled/unix
 unix {
 	radwtmp = "/usr/local/var/log/radius/radwtmp"
 }
 # Loaded module rlm_soh
 # Instantiating module "soh" from file /usr/local/etc/raddb/mods-enabled/soh
 soh {
 	dhcp = yes
 }
 # Loaded module rlm_logintime
 # Instantiating module "logintime" from file /usr/local/etc/raddb/mods-enabled/logintime
 logintime {
 	minimum_timeout = 60
 }
 # Loaded module rlm_dynamic_clients
 # Instantiating module "dynamic_clients" from file /usr/local/etc/raddb/mods-enabled/dynamic_clients
 # Loaded module rlm_files
 # Instantiating module "files" from file /usr/local/etc/raddb/mods-enabled/files
 files {
 	filename = "/usr/local/etc/raddb/mods-config/files/authorize"
 	usersfile = "/usr/local/etc/raddb/mods-config/files/authorize"
 	acctusersfile = "/usr/local/etc/raddb/mods-config/files/accounting"
 	preproxy_usersfile = "/usr/local/etc/raddb/mods-config/files/pre-proxy"
 	compat = "cistron"
 }
reading pairlist file /usr/local/etc/raddb/mods-config/files/authorize
[/usr/local/etc/raddb/mods-config/files/authorize]:181 Cistron compatibility checks for entry DEFAULT ...
[/usr/local/etc/raddb/mods-config/files/authorize]:188 Cistron compatibility checks for entry DEFAULT ...
[/usr/local/etc/raddb/mods-config/files/authorize]:195 Cistron compatibility checks for entry DEFAULT ...
reading pairlist file /usr/local/etc/raddb/mods-config/files/authorize
[/usr/local/etc/raddb/mods-config/files/authorize]:181 Cistron compatibility checks for entry DEFAULT ...
[/usr/local/etc/raddb/mods-config/files/authorize]:188 Cistron compatibility checks for entry DEFAULT ...
[/usr/local/etc/raddb/mods-config/files/authorize]:195 Cistron compatibility checks for entry DEFAULT ...
reading pairlist file /usr/local/etc/raddb/mods-config/files/accounting
reading pairlist file /usr/local/etc/raddb/mods-config/files/pre-proxy
 # Loaded module rlm_unpack
 # Instantiating module "unpack" from file /usr/local/etc/raddb/mods-enabled/unpack
 # Loaded module rlm_replicate
 # Instantiating module "replicate" from file /usr/local/etc/raddb/mods-enabled/replicate
 # Loaded module rlm_ldap
 # Instantiating module "ldap" from file /usr/local/etc/raddb/mods-enabled/ldap
 ldap {
 	server = "ldap.team.company.com"
 	port = 389
 	password = <<< secret >>>
 	identity = "cn=admin,dc=team,dc=company,dc=com"
  user {
  	filter = "(uid=%{%{Stripped-User-Name}:-%{User-Name}})"
  	scope = "sub"
  	base_dn = "ou=corp,ou=Users,dc=team,dc=company,dc=com"
  	access_positive = yes
  }
  group {
  	filter = "(objectClass=posixGroup)"
  	scope = "sub"
  	base_dn = "ou=corp,ou=Users,dc=team,dc=company,dc=com"
  	name_attribute = "cn"
  	membership_attribute = "memberOf"
  	cacheable_name = no
  	cacheable_dn = no
  }
  client {
  	filter = "(objectClass=frClient)"
  	scope = "sub"
  	base_dn = "ou=corp,ou=Users,dc=team,dc=company,dc=com"
   attribute {
   	identifier = "radiusClientIdentifier"
   	shortname = "cn"
   	secret = "radiusClientSecret"
   }
  }
  profile {
  	filter = "(&)"
  }
  options {
  	ldap_debug = 40
  	chase_referrals = yes
  	rebind = yes
  	net_timeout = 1
  	res_timeout = 20
  	srv_timelimit = 20
  	idle = 60
  	probes = 3
  	interval = 3
  }
  tls {
  	start_tls = no
  }
 }
rlm_ldap: Falling back to build time libldap version info.  Query for LDAP_OPT_API_INFO returned: -1
rlm_ldap: libldap vendor: OpenLDAP version: 20431
  accounting {
  	reference = "%{tolower:type.%{Acct-Status-Type}}"
  }
  post-auth {
  	reference = "."
  }
rlm_ldap (ldap): Initialising connection pool
  pool {
  	start = 5
  	min = 4
  	max = 32
  	spare = 3
  	uses = 0
  	lifetime = 0
  	cleanup_interval = 30
  	idle_timeout = 60
  	retry_delay = 1
  	spread = no
  }
rlm_ldap (ldap): Opening additional connection (0)
rlm_ldap (ldap): Connecting to ldap.team.company.com:389
rlm_ldap (ldap): Waiting for bind result...
rlm_ldap (ldap): Bind successful
rlm_ldap (ldap): Opening additional connection (1)
rlm_ldap (ldap): Connecting to ldap.team.company.com:389
rlm_ldap (ldap): Waiting for bind result...
rlm_ldap (ldap): Bind successful
rlm_ldap (ldap): Opening additional connection (2)
rlm_ldap (ldap): Connecting to ldap.team.company.com:389
rlm_ldap (ldap): Waiting for bind result...
rlm_ldap (ldap): Bind successful
rlm_ldap (ldap): Opening additional connection (3)
rlm_ldap (ldap): Connecting to ldap.team.company.com:389
rlm_ldap (ldap): Waiting for bind result...
rlm_ldap (ldap): Bind successful
rlm_ldap (ldap): Opening additional connection (4)
rlm_ldap (ldap): Connecting to ldap.team.company.com:389
rlm_ldap (ldap): Waiting for bind result...
rlm_ldap (ldap): Bind successful
 # Loaded module rlm_attr_filter
 # Instantiating module "attr_filter.post-proxy" from file /usr/local/etc/raddb/mods-enabled/attr_filter
 attr_filter attr_filter.post-proxy {
 	filename = "/usr/local/etc/raddb/mods-config/attr_filter/post-proxy"
 	key = "%{Realm}"
 	relaxed = no
 }
reading pairlist file /usr/local/etc/raddb/mods-config/attr_filter/post-proxy
 # Instantiating module "attr_filter.pre-proxy" from file /usr/local/etc/raddb/mods-enabled/attr_filter
 attr_filter attr_filter.pre-proxy {
 	filename = "/usr/local/etc/raddb/mods-config/attr_filter/pre-proxy"
 	key = "%{Realm}"
 	relaxed = no
 }
reading pairlist file /usr/local/etc/raddb/mods-config/attr_filter/pre-proxy
 # Instantiating module "attr_filter.access_reject" from file /usr/local/etc/raddb/mods-enabled/attr_filter
 attr_filter attr_filter.access_reject {
 	filename = "/usr/local/etc/raddb/mods-config/attr_filter/access_reject"
 	key = "%{User-Name}"
 	relaxed = no
 }
reading pairlist file /usr/local/etc/raddb/mods-config/attr_filter/access_reject
 # Instantiating module "attr_filter.access_challenge" from file /usr/local/etc/raddb/mods-enabled/attr_filter
 attr_filter attr_filter.access_challenge {
 	filename = "/usr/local/etc/raddb/mods-config/attr_filter/access_challenge"
 	key = "%{User-Name}"
 	relaxed = no
 }
reading pairlist file /usr/local/etc/raddb/mods-config/attr_filter/access_challenge
 # Instantiating module "attr_filter.accounting_response" from file /usr/local/etc/raddb/mods-enabled/attr_filter
 attr_filter attr_filter.accounting_response {
 	filename = "/usr/local/etc/raddb/mods-config/attr_filter/accounting_response"
 	key = "%{User-Name}"
 	relaxed = no
 }
reading pairlist file /usr/local/etc/raddb/mods-config/attr_filter/accounting_response
 # Loaded module rlm_linelog
 # Instantiating module "linelog" from file /usr/local/etc/raddb/mods-enabled/linelog
 linelog {
 	filename = "/usr/local/var/log/radius/linelog"
 	permissions = 384
 	format = "This is a log message for %{User-Name}"
 	reference = "messages.%{%{Packet-Type}:-default}"
 }
 # Instantiating module "log_accounting" from file /usr/local/etc/raddb/mods-enabled/linelog
 linelog log_accounting {
 	filename = "/usr/local/var/log/radius/linelog-accounting"
 	permissions = 384
 	format = ""
 	reference = "Accounting-Request.%{%{Acct-Status-Type}:-unknown}"
 }
 # Loaded module rlm_cache
 # Instantiating module "cache_eap" from file /usr/local/etc/raddb/mods-enabled/cache_eap
 cache cache_eap {
 	key = "%{%{control:State}:-%{%{reply:State}:-%{State}}}"
 	ttl = 15
 	max_entries = 16384
 	epoch = 0
 	add_stats = no
 }
 # Instantiating module "detail" from file /usr/local/etc/raddb/mods-enabled/detail
 detail {
 	filename = "/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d"
 	header = "%t"
 	permissions = 384
 	locking = no
 	log_packet_header = no
 }
 # Instantiating module "ldap-dev" from file /usr/local/etc/raddb/mods-enabled/ldap-dev
 ldap ldap-dev {
 	server = "ldap.team.company.com"
 	port = 389
 	password = <<< secret >>>
 	identity = "cn=admin,dc=team,dc=company,dc=com"
  user {
  	filter = "(uid=%{%{Stripped-User-Name}:-%{User-Name}})"
  	scope = "sub"
  	base_dn = "ou=dev,ou=Users,dc=team,dc=company,dc=com"
  	access_positive = yes
  }
  group {
  	filter = "(objectClass=posixGroup)"
  	scope = "sub"
  	base_dn = "ou=dev,ou=Users,dc=team,dc=company,dc=com"
  	name_attribute = "cn"
  	membership_attribute = "memberOf"
  	cacheable_name = no
  	cacheable_dn = no
  }
  client {
  	filter = "(objectClass=frClient)"
  	scope = "sub"
  	base_dn = "ou=dev,ou=Users,dc=team,dc=company,dc=com"
   attribute {
   	identifier = "radiusClientIdentifier"
   	shortname = "cn"
   	secret = "radiusClientSecret"
   }
  }
  profile {
  	filter = "(&)"
  }
  options {
  	ldap_debug = 40
  	chase_referrals = yes
  	rebind = yes
  	net_timeout = 1
  	res_timeout = 20
  	srv_timelimit = 20
  	idle = 60
  	probes = 3
  	interval = 3
  }
  tls {
  	start_tls = no
  }
 }
  accounting {
  	reference = "%{tolower:type.%{Acct-Status-Type}}"
  }
  post-auth {
  	reference = "."
  }
rlm_ldap (ldap-dev): Initialising connection pool
  pool {
  	start = 5
  	min = 4
  	max = 32
  	spare = 3
  	uses = 0
  	lifetime = 0
  	cleanup_interval = 30
  	idle_timeout = 60
  	retry_delay = 1
  	spread = no
  }
rlm_ldap (ldap-dev): Opening additional connection (0)
rlm_ldap (ldap-dev): Connecting to ldap.team.company.com:389
rlm_ldap (ldap-dev): Waiting for bind result...
rlm_ldap (ldap-dev): Bind successful
rlm_ldap (ldap-dev): Opening additional connection (1)
rlm_ldap (ldap-dev): Connecting to ldap.team.company.com:389
rlm_ldap (ldap-dev): Waiting for bind result...
rlm_ldap (ldap-dev): Bind successful
rlm_ldap (ldap-dev): Opening additional connection (2)
rlm_ldap (ldap-dev): Connecting to ldap.team.company.com:389
rlm_ldap (ldap-dev): Waiting for bind result...
rlm_ldap (ldap-dev): Bind successful
rlm_ldap (ldap-dev): Opening additional connection (3)
rlm_ldap (ldap-dev): Connecting to ldap.team.company.com:389
rlm_ldap (ldap-dev): Waiting for bind result...
rlm_ldap (ldap-dev): Bind successful
rlm_ldap (ldap-dev): Opening additional connection (4)
rlm_ldap (ldap-dev): Connecting to ldap.team.company.com:389
rlm_ldap (ldap-dev): Waiting for bind result...
rlm_ldap (ldap-dev): Bind successful
 # Instantiating module "exec" from file /usr/local/etc/raddb/mods-enabled/exec
 exec {
 	wait = no
 	input_pairs = "request"
 	shell_escape = yes
 	timeout = 10
 }
 # Loaded module rlm_expiration
 # Instantiating module "expiration" from file /usr/local/etc/raddb/mods-enabled/expiration
 # Loaded module rlm_preprocess
 # Instantiating module "preprocess" from file /usr/local/etc/raddb/mods-enabled/preprocess
 preprocess {
 	huntgroups = "/usr/local/etc/raddb/mods-config/preprocess/huntgroups"
 	hints = "/usr/local/etc/raddb/mods-config/preprocess/hints"
 	with_ascend_hack = no
 	ascend_channels_per_line = 23
 	with_ntdomain_hack = no
 	with_specialix_jetstream_hack = no
 	with_cisco_vsa_hack = no
 	with_alvarion_vsa_hack = no
 }
reading pairlist file /usr/local/etc/raddb/mods-config/preprocess/huntgroups
reading pairlist file /usr/local/etc/raddb/mods-config/preprocess/hints
} # modules
radiusd: #### Loading Virtual Servers ####
server { # from file /usr/local/etc/raddb/radiusd.conf
} # server
server default-dev { # from file /usr/local/etc/raddb/sites-enabled/default-dev
# Creating Auth-Type = digest
# Loading authenticate {...}
# Loading authorize {...}
Ignoring "sql" (see raddb/mods-available/README.rst)
# Loading preacct {...}
# Loading accounting {...}
# Loading post-proxy {...}
# Loading post-auth {...}
} # server default-dev
server inner-tunnel-dev { # from file /usr/local/etc/raddb/sites-enabled/inner-tunnel-dev
# Loading authenticate {...}
# Loading authorize {...}
# Loading session {...}
# Loading post-proxy {...}
# Loading post-auth {...}
} # server inner-tunnel-dev
server inner-tunnel { # from file /usr/local/etc/raddb/sites-enabled/inner-tunnel
# Loading authenticate {...}
# Loading authorize {...}
# Loading session {...}
# Loading post-proxy {...}
# Loading post-auth {...}
} # server inner-tunnel
server default { # from file /usr/local/etc/raddb/sites-enabled/default
# Loading authenticate {...}
# Loading authorize {...}
# Loading preacct {...}
# Loading accounting {...}
# Loading post-proxy {...}
# Loading post-auth {...}
} # server default
radiusd: #### Opening IP addresses and Ports ####
listen {
 	type = "auth"
 	ipaddr = *
 	port = 1814
  limit {
  	max_connections = 16
  	lifetime = 0
  	idle_timeout = 30
  }
}
listen {
 	type = "acct"
 	ipaddr = *
 	port = 1815
  limit {
  	max_connections = 16
  	lifetime = 0
  	idle_timeout = 30
  }
}
listen {
 	type = "auth"
 	ipv6addr = ::
 	port = 18144
  limit {
  	max_connections = 16
  	lifetime = 0
  	idle_timeout = 30
  }
}
listen {
 	type = "acct"
 	ipv6addr = ::
 	port = 18155
  limit {
  	max_connections = 16
  	lifetime = 0
  	idle_timeout = 30
  }
}
listen {
 	type = "auth"
 	ipaddr = 127.0.0.1
 	port = 18121
}
listen {
 	type = "auth"
 	ipaddr = 127.0.0.1
 	port = 18120
}
listen {
 	type = "auth"
 	ipaddr = *
 	port = 0
  limit {
  	max_connections = 16
  	lifetime = 0
  	idle_timeout = 30
  }
}
listen {
 	type = "acct"
 	ipaddr = *
 	port = 0
  limit {
  	max_connections = 16
  	lifetime = 0
  	idle_timeout = 30
  }
}
listen {
 	type = "auth"
 	ipv6addr = ::
 	port = 0
  limit {
  	max_connections = 16
  	lifetime = 0
  	idle_timeout = 30
  }
}
listen {
 	type = "acct"
 	ipv6addr = ::
 	port = 0
  limit {
  	max_connections = 16
  	lifetime = 0
  	idle_timeout = 30
  }
}
Listening on auth address * port 1814 as server default-dev
Listening on acct address * port 1815 as server default-dev
Listening on auth address :: port 18144 as server default-dev
Listening on acct address :: port 18155 as server default-dev
Listening on auth address 127.0.0.1 port 18121 as server inner-tunnel-dev
Listening on auth address 127.0.0.1 port 18120 as server inner-tunnel
Listening on auth address * port 1812 as server default
Listening on acct address * port 1813 as server default
Listening on auth address :: port 1812 as server default
Listening on acct address :: port 1813 as server default
Opening new proxy socket 'proxy address * port 0'
Listening on proxy address * port 32995
Ready to process requests

root@radius:/usr/local/etc/raddb# Received Access-Request Id 32 from x.x.x.x:32768 to 10.11.1.102:1812 length 224
	User-Name = 'alexgregory'
	Chargeable-User-Identity = 0x00
	Location-Capable = Civix-Location
	Calling-Station-Id = '80-be-05-37-1d-7e'
	Called-Station-Id = 'd4-a0-2a-15-7f-00:C2_8021x'
	NAS-Port = 4
	Cisco-AVPair = 'audit-session-id=0a210082000009d954484123'
	NAS-IP-Address = 10.33.0.130
	NAS-Identifier = 'isewlc'
	Airespace-Wlan-Id = 6
	Service-Type = Framed-User
	Framed-MTU = 1300
	NAS-Port-Type = Wireless-802.11
	EAP-Message = 0x0201001001616c6578677265676f7279
	Message-Authenticator = 0x2b99d4f0f2119601ec9e7d2c51197379
(36) Received Access-Request packet from host x.x.x.x port 32768, id=32, length=224
(36) 	User-Name = 'alexgregory'
(36) 	Chargeable-User-Identity = 0x00
(36) 	Location-Capable = Civix-Location
(36) 	Calling-Station-Id = '80-be-05-37-1d-7e'
(36) 	Called-Station-Id = 'd4-a0-2a-15-7f-00:C2_8021x'
(36) 	NAS-Port = 4
(36) 	Cisco-AVPair = 'audit-session-id=0a210082000009d954484123'
(36) 	NAS-IP-Address = 10.33.0.130
(36) 	NAS-Identifier = 'isewlc'
(36) 	Airespace-Wlan-Id = 6
(36) 	Service-Type = Framed-User
(36) 	Framed-MTU = 1300
(36) 	NAS-Port-Type = Wireless-802.11
(36) 	EAP-Message = 0x0201001001616c6578677265676f7279
(36) 	Message-Authenticator = 0x2b99d4f0f2119601ec9e7d2c51197379
(36) # Executing section authorize from file /usr/local/etc/raddb/sites-enabled/default
(36)   authorize {
(36)   filter_username filter_username {
(36)     if (!&User-Name) 
(36)     if (!&User-Name)  -> FALSE
(36)     if (&User-Name =~ / /) 
(36)     if (&User-Name =~ / /)  -> FALSE
(36)     if (&User-Name =~ /@.*@/ ) 
(36)     if (&User-Name =~ /@.*@/ )  -> FALSE
(36)     if (&User-Name =~ /\\.\\./ ) 
(36)     if (&User-Name =~ /\\.\\./ )  -> FALSE
(36)     if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\\.(.+)$/))  
(36)     if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\\.(.+)$/))   -> FALSE
(36)     if (&User-Name =~ /\\.$/)  
(36)     if (&User-Name =~ /\\.$/)   -> FALSE
(36)     if (&User-Name =~ /@\\./)  
(36)     if (&User-Name =~ /@\\./)   -> FALSE
(36)   } # filter_username filter_username = notfound
(36)   [preprocess] = ok
(36)   [chap] = noop
(36)   [mschap] = noop
(36)   [digest] = noop
(36)  suffix : Checking for suffix after "@"
(36)  suffix : No '@' in User-Name = "alexgregory", looking up realm NULL
(36)  suffix : Found realm "DEFAULT"
(36)  suffix : Adding Realm = "DEFAULT"
(36)  suffix : Proxying request from user alexgregory to realm DEFAULT
(36)  suffix : Preparing to proxy authentication request to realm "DEFAULT" 
(36)   [suffix] = updated
(36)  eap : Request is supposed to be proxied to Realm DEFAULT. Not doing EAP.
(36)   [eap] = noop
(36)   [files] = noop
rlm_ldap (ldap): Reserved connection (24)
(36)  ldap : EXPAND (uid=%{%{Stripped-User-Name}:-%{User-Name}})
(36)  ldap :    --> (uid=alexgregory)
(36)  ldap : EXPAND ou=corp,ou=Users,dc=team,dc=company,dc=com
(36)  ldap :    --> ou=corp,ou=Users,dc=team,dc=company,dc=com
(36)  ldap : Performing search in 'ou=corp,ou=Users,dc=team,dc=company,dc=com' with filter '(uid=alexgregory)', scope 'sub'
(36)  ldap : Waiting for search result...
(36)  ldap : User object found at DN "cn=Alex Gregory,ou=corp,ou=Users,dc=team,dc=company,dc=com"
(36)  ldap : Processing user attributes
(36)  ldap : 	control:Password-With-Header += '{MD5}CMlNz7iY6jR52tEK4gzKkQ=='
rlm_ldap (ldap): Released connection (24)
rlm_ldap (ldap): Closing connection (23): Hit idle_timeout, was idle for 1927 seconds
rlm_ldap (ldap): You probably need to lower "min"
rlm_ldap (ldap): Closing connection (22): Hit idle_timeout, was idle for 1934 seconds
rlm_ldap (ldap): You probably need to lower "min"
rlm_ldap (ldap): Closing connection (21): Hit idle_timeout, was idle for 1941 seconds
rlm_ldap (ldap): You probably need to lower "min"
(36)   [ldap] = ok
(36)    if (notfound) 
(36)    if (notfound)  -> FALSE
(36)   [expiration] = noop
(36)   [logintime] = noop
(36)   [pap] = noop
(36)  } #  authorize = updated
(36) Proxying request to home server 94.143.115.73 port 1812 timeout 30.000000
(36) Sending Access-Request packet to host 94.143.115.73 port 1812, id=115, length=0
(36) 	User-Name = 'alexgregory'
(36) 	Chargeable-User-Identity = 0x00
(36) 	Location-Capable = Civix-Location
(36) 	Calling-Station-Id = '80-be-05-37-1d-7e'
(36) 	Called-Station-Id = 'd4-a0-2a-15-7f-00:C2_8021x'
(36) 	NAS-Port = 4
(36) 	Cisco-AVPair = 'audit-session-id=0a210082000009d954484123'
(36) 	NAS-IP-Address = 10.33.0.130
(36) 	NAS-Identifier = 'isewlc'
(36) 	Airespace-Wlan-Id = 6
(36) 	Service-Type = Framed-User
(36) 	Framed-MTU = 1300
(36) 	NAS-Port-Type = Wireless-802.11
(36) 	EAP-Message = 0x0201001001616c6578677265676f7279
(36) 	Message-Authenticator = 0x2b99d4f0f2119601ec9e7d2c51197379
(36) 	Event-Timestamp = 'Oct 22 2014 23:43:34 UTC'
(36) 	Realm = 'DEFAULT'
(36) 	EAP-Type = Identity
(36) 	Proxy-State = 0x3332
Sending Access-Request Id 115 from 0.0.0.0:58776 to 94.143.115.73:1812
	User-Name = 'alexgregory'
	Chargeable-User-Identity = 0x00
	Location-Capable = Civix-Location
	Calling-Station-Id = '80-be-05-37-1d-7e'
	Called-Station-Id = 'd4-a0-2a-15-7f-00:C2_8021x'
	NAS-Port = 4
	Cisco-AVPair = 'audit-session-id=0a210082000009d954484123'
	NAS-IP-Address = 10.33.0.130
	NAS-Identifier = 'isewlc'
	Airespace-Wlan-Id = 6
	Service-Type = Framed-User
	Framed-MTU = 1300
	NAS-Port-Type = Wireless-802.11
	EAP-Message = 0x0201001001616c6578677265676f7279
	Message-Authenticator = 0x2b99d4f0f2119601ec9e7d2c51197379
	Event-Timestamp = 'Oct 22 2014 23:43:34 UTC'
	Proxy-State = 0x3332
Waking up in 0.3 seconds.
Waking up in 0.1 seconds.
(36) Expecting proxy response no later than 29.499710 seconds from now
Waking up in 29.4 seconds.
Received Access-Reject Id 115 from 94.143.115.73:1812 to 10.11.1.102:58776 length 24
	Proxy-State = 0x3332
(36) Received Access-Reject packet from host 94.143.115.73 port 1812, id=115, length=24
(36) 	Proxy-State = 0x3332
(36) # Executing section post-proxy from file /usr/local/etc/raddb/sites-enabled/default
(36)   post-proxy {
(36)  eap : No pre-existing handler found
(36)   [eap] = noop
(36)  } #  post-proxy = noop
(36) Using Post-Auth-Type Reject
(36) # Executing group from file /usr/local/etc/raddb/sites-enabled/default
(36)  Post-Auth-Type REJECT {
(36)  attr_filter.access_reject : EXPAND %{User-Name}
(36)  attr_filter.access_reject :    --> alexgregory
(36)  attr_filter.access_reject : Matched entry DEFAULT at line 11
(36)   [attr_filter.access_reject] = updated
(36)  eap : Request was previously rejected, inserting EAP-Failure
(36)   [eap] = updated
(36)   remove_reply_message_if_eap remove_reply_message_if_eap {
(36)     if (&reply:EAP-Message && &reply:Reply-Message) 
(36)     if (&reply:EAP-Message && &reply:Reply-Message)  -> FALSE
(36)    else else {
(36)     [noop] = noop
(36)    } # else else = noop
(36)   } # remove_reply_message_if_eap remove_reply_message_if_eap = noop
(36)  } # Post-Auth-Type REJECT = updated
(36) Delaying response for 1 seconds
Waking up in 0.3 seconds.
Waking up in 0.6 seconds.
Received Access-Request Id 32 from x.x.x.x:32768 to 10.11.1.102:1812 length 224
(36) Discarding duplicate request from client allhosts port 32768 - ID: 32 due to delayed response
Waking up in 0.1 seconds.
(36) Sending delayed response
(36) Sending Access-Reject packet to host x.x.x.x port 32768, id=32, length=0
(36) 	EAP-Message = 0x04010004
(36) 	Message-Authenticator = 0x00000000000000000000000000000000
Sending Access-Reject Id 32 from 10.11.1.102:1812 to x.x.x.x:32768
	EAP-Message = 0x04010004
	Message-Authenticator = 0x00000000000000000000000000000000
Waking up in 3.9 seconds.
(36) Cleaning up request packet ID 32 with timestamp +4144
Ready to process requests