radlast output

10 Jul 2012

      Hi,

radlast shows  NAS-Identifier trunked

lbazch   009:AP-PV-PB                  Tue Jul 10 12:10   still logged in
mfembe   004:AP-PI-PB                  Tue Jul 10 12:10   still logged in
msabad   005:oficina-                  Tue Jul 10 12:10   still logged in

Why? Is a bug? A misconfiguration?

You want the debug output, ok you have it :)

FreeRADIUS Version 2.1.12, for host x86_64-unknown-linux-gnu, built on
Jan  3 2012 at 16:18:16
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/raddb-testing/radiusd.conf
including configuration file /etc/raddb-testing/proxy.conf
including configuration file /etc/raddb-testing/clients.conf
including files in directory /etc/raddb-testing/modules/
including configuration file /etc/raddb-testing/modules/chap
including configuration file /etc/raddb-testing/modules/mschap
including configuration file
/etc/raddb-testing/modules/sqlcounter_expire_on_login
including configuration file /etc/raddb-testing/modules/exec
including configuration file /etc/raddb-testing/modules/realm
including configuration file /etc/raddb-testing/modules/checkval
including configuration file /etc/raddb-testing/modules/rediswho
including configuration file /etc/raddb-testing/modules/passwd
including configuration file /etc/raddb-testing/modules/attr_filter
including configuration file /etc/raddb-testing/modules/linelog
including configuration file /etc/raddb-testing/modules/wimax
including configuration file /etc/raddb-testing/modules/pam
including configuration file /etc/raddb-testing/modules/inner-eap
including configuration file /etc/raddb-testing/modules/echo
including configuration file /etc/raddb-testing/modules/soh
including configuration file /etc/raddb-testing/modules/replicate
including configuration file /etc/raddb-testing/modules/acct_unique
including configuration file /etc/raddb-testing/modules/etc_group
including configuration file /etc/raddb-testing/modules/pap
including configuration file /etc/raddb-testing/modules/expr
including configuration file /etc/raddb-testing/modules/smbpasswd
including configuration file /etc/raddb-testing/modules/attr_rewrite
including configuration file /etc/raddb-testing/modules/radutmp
including configuration file /etc/raddb-testing/modules/mac2ip
including configuration file /etc/raddb-testing/modules/logintime
including configuration file /etc/raddb-testing/modules/sql_log
including configuration file /etc/raddb-testing/modules/smsotp
including configuration file /etc/raddb-testing/modules/preprocess
including configuration file /etc/raddb-testing/modules/policy
including configuration file /etc/raddb-testing/modules/cui
including configuration file /etc/raddb-testing/modules/perl
including configuration file /etc/raddb-testing/modules/digest
including configuration file /etc/raddb-testing/modules/mac2vlan
including configuration file /etc/raddb-testing/modules/otp
including configuration file /etc/raddb-testing/modules/files
including configuration file /etc/raddb-testing/modules/always
including configuration file /etc/raddb-testing/modules/ntlm_auth
including configuration file /etc/raddb-testing/modules/detail
including configuration file /etc/raddb-testing/modules/krb5
including configuration file /etc/raddb-testing/modules/sradutmp
including configuration file /etc/raddb-testing/modules/opendirectory
including configuration file /etc/raddb-testing/modules/counter
including configuration file /etc/raddb-testing/modules/detail.example.com
including configuration file /etc/raddb-testing/modules/ippool
including configuration file /etc/raddb-testing/modules/expiration
including configuration file /etc/raddb-testing/modules/dynamic_clients
including configuration file /etc/raddb-testing/modules/detail.log
including configuration file /etc/raddb-testing/modules/redis
including configuration file /etc/raddb-testing/modules/ldap
including configuration file /etc/raddb-testing/modules/unix
including configuration file /etc/raddb-testing/eap.conf
including configuration file /etc/raddb-testing/policy.conf
including files in directory /etc/raddb-testing/sites-enabled/
including configuration file /etc/raddb-testing/sites-enabled/status
including configuration file /etc/raddb-testing/sites-enabled/control-socket
including configuration file /etc/raddb-testing/sites-enabled/inner-tunnel
including configuration file /etc/raddb-testing/sites-enabled/default
including configuration file /etc/raddb-testing/sites-enabled/inner-tunnel-peap
main {
	user = "radiusd"
	group = "radiusd"
	allow_core_dumps = no
}
including dictionary file /etc/raddb-testing/dictionary
main {
	name = "radiusd"
	prefix = "/usr/local-test"
	localstatedir = "/usr/local-test/var"
	sbindir = "/usr/local-test/sbin"
	logdir = "/usr/local-test/var/log/radius"
	run_dir = "/usr/local-test/var/run/radiusd"
	libdir = "/usr/local-test/lib"
	radacctdir = "/usr/local-test/var/log/radius/radacct"
	hostname_lookups = no
	max_request_time = 30
	cleanup_delay = 5
	max_requests = 1024
	pidfile = "/usr/local-test/var/run/radiusd/radiusd.pid"
	checkrad = "/usr/local-test/sbin/checkrad"
	debug_level = 0
	proxy_requests = no
 log {
	stripped_names = yes
	auth = yes
	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 = "nvam890"
	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
  coa {
	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 = "nvam890"
	nastype = "other"
 }
 client 192.168.1.5 {
	require_message_authenticator = no
	secret = "nvam890"
	shortname = "vivalapepa.sarlanga.edu"
 }
 client 10.129.100.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-3-winnie"
	shortname = "AP-PI-PB"
 }
 client 10.129.10.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-3-winnie"
	shortname = "AP-PI-SS"
 }
 client 10.129.11.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-3-winnie"
	shortname = "AP-PI-1"
 }
 client 10.129.111.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-3-winnie"
	shortname = "AP-PI-1"
 }
 client 10.129.12.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-3-winnie"
	shortname = "AP-PI-2"
 }
 client 10.129.13.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-3-winnie"
	shortname = "AP-PI-3"
 }
 client 10.129.14.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-3-winnie"
	shortname = "AP-PI-4"
 }
 client 10.129.199.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "PIVOT-LINKSYS"
 }
 client 10.129.200.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "PIVOT-TPLINK"
 }
 client 10.129.254.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "Transitional"
 }
 client 10.129.15.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-3-winnie"
	shortname = "AP-PI-5"
 }
 client 10.129.16.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-3-winnie"
	shortname = "AP-PI-6"
 }
 client 10.129.17.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-3-winnie"
	shortname = "AP-PI-7"
 }
 client 10.129.60.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-Sociales-0"
 }
 client 10.129.61.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-Sociales-I"
 }
 client 10.129.62.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-Sociales-I"
 }
 client 10.129.158.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-SS"
 }
 client 10.129.80.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-PB"
 }
 client 10.129.180.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-PB-SUM"
 }
 client 10.128.255.81 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-I"
 }
 client 10.129.81.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-I"
 }
 client 10.128.255.82 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-II"
 }
 client 10.129.82.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-II"
 }
 client 10.128.250.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-Bridge"
 }
 client 10.128.255.172 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-I_bis_test"
 }
 client 10.128.255.83 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-III"
 }
 client 10.129.83.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-III"
 }
 client 10.129.183.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-III"
 }
 client 10.129.84.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-IV"
 }
 client 10.129.184.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-IV-Bis"
 }
 client 10.129.194.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-IV-Bis-2"
 }
 client 10.128.255.181 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-I_bis"
 }
 client 10.129.181.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-I_bis"
 }
 client 10.129.162.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-I_bis_2"
 }
 client 10.128.255.86 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-VI"
 }
 client 10.129.86.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-VI"
 }
 client 10.128.255.87 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-VII"
 }
 client 10.129.87.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-VII"
 }
 client 10.129.187.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-VII-Derecho"
 }
 client 10.129.99.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-Pivot"
 }
 client 10.129.52.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-PV-PB"
 }
 client 10.128.255.85 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-V"
 }
 client 10.129.85.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-V"
 }
 client 10.129.189.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-IX-HD"
 }
 client 10.129.88.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-VIII"
 }
 client 10.129.89.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "oficina-test"
 }
 client 10.129.160.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-Sociales-Auditorio"
 }
 client 10.129.182.1 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-yocadorneotucadorneas-X"
 }
 client 192.168.2.53 {
	require_message_authenticator = no
	secret = "akantilad0-Green-22"
	shortname = "sarlanga3"
 }
 client 192.168.45 {
	require_message_authenticator = no
	secret = "sarlangalad0-black-54"
	shortname = "sarlanga4"
 }
 client 192.168.3.201 {
	require_message_authenticator = no
	secret = "sarlangalad0-blue-246692"
	shortname = "sarlanga7"
 }
 client 192.168.4 {
	require_message_authenticator = no
	secret = "Zarag0Za-Muppet5-32"
	shortname = "AP-sarlanga7"
 }
 client 192.168.142.240 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-Cabre-1"
 }
 client 192.168.142.241 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-Cabre-2"
 }
 client 192.168.142.242 {
	require_message_authenticator = no
	secret = "sarlangalad0-Red-398952"
	shortname = "AP-Cabre-3"
 }
radiusd: #### Instantiating modules ####
 instantiate {
 Module: Linked to module rlm_exec
 Module: Instantiating module "exec" from file /etc/raddb-testing/modules/exec
  exec {
	wait = no
	input_pairs = "request"
	shell_escape = yes
  }
 Module: Linked to module rlm_expr
 Module: Instantiating module "expr" from file /etc/raddb-testing/modules/expr
 Module: Linked to module rlm_expiration
 Module: Instantiating module "expiration" from file
/etc/raddb-testing/modules/expiration
  expiration {
	reply-message = "Password Has Expired  "
  }
 Module: Linked to module rlm_logintime
 Module: Instantiating module "logintime" from file
/etc/raddb-testing/modules/logintime
  logintime {
	reply-message = "You are calling outside your allowed timespan  "
	minimum-timeout = 60
  }
 }
radiusd: #### Loading Virtual Servers ####
server { # from file /etc/raddb-testing/radiusd.conf
 modules {
  Module: Creating Post-Auth-Type = REJECT
 Module: Checking authenticate {...} for more modules to load
 Module: Linked to module rlm_pap
 Module: Instantiating module "pap" from file /etc/raddb-testing/modules/pap
  pap {
	encryption_scheme = "auto"
	auto_header = yes
  }
 Module: Linked to module rlm_chap
 Module: Instantiating module "chap" from file /etc/raddb-testing/modules/chap
 Module: Linked to module rlm_mschap
 Module: Instantiating module "mschap" from file
/etc/raddb-testing/modules/mschap
  mschap {
	use_mppe = yes
	require_encryption = no
	require_strong = no
	with_ntdomain_hack = no
	allow_retry = yes
  }
 Module: Linked to module rlm_pam
 Module: Instantiating module "pam" from file /etc/raddb-testing/modules/pam
  pam {
	pam_auth = "radiusd"
  }
 Module: Linked to module rlm_unix
 Module: Instantiating module "unix" from file /etc/raddb-testing/modules/unix
  unix {
	radwtmp = "/usr/local-test/var/log/radius/radwtmp"
  }
 Module: Linked to module rlm_eap
 Module: Instantiating module "eap" from file /etc/raddb-testing/eap.conf
  eap {
	default_eap_type = "md5"
	timer_expire = 600
	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
	pem_file_type = yes
	private_key_file = "/etc/pki/tls/certs/ips-vivalapepa-key.pem"
	certificate_file = "/etc/pki/tls/certs/vivalapepa.sarlanga.edu.cer"
	CA_file = "/etc/pki/tls/certs/IPS-IPSCABUNDLE.crt"
	dh_file = "/etc/raddb-testing/certs/dh"
	random_file = "/etc/raddb-testing/certs/random"
	fragment_size = 1024
	include_length = yes
	check_crl = no
	cipher_list = "DEFAULT"
    cache {
	enable = yes
	lifetime = 6
	max_entries = 255
    }
    verify {
    }
    ocsp {
	enable = no
	override_cert_url = yes
	url = "http://127.0.0.1/ocsp/"
    }
   }
 Module: Linked to sub-module rlm_eap_ttls
 Module: Instantiating eap-ttls
   ttls {
	default_eap_type = "md5"
	copy_request_to_tunnel = yes
	use_tunneled_reply = yes
	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 = yes
	use_tunneled_reply = yes
	proxy_tunneled_request_as_eap = yes
	virtual_server = "inner-tunnel"
	soh = no
   }
 Module: Linked to sub-module rlm_eap_mschapv2
 Module: Instantiating eap-mschapv2
   mschapv2 {
	with_ntdomain_hack = no
	send_error = yes
   }
 Module: Checking authorize {...} for more modules to load
 Module: Linked to module rlm_preprocess
 Module: Instantiating module "preprocess" from file
/etc/raddb-testing/modules/preprocess
  preprocess {
	huntgroups = "/etc/raddb-testing/huntgroups"
	hints = "/etc/raddb-testing/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_detail
 Module: Instantiating module "auth_log" from file
/etc/raddb-testing/modules/detail.log
  detail auth_log {
	detailfile = "/usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d"
	header = "%t"
	detailperm = 384
	dirperm = 493
	locking = no
	log_packet_header = no
  }
 Module: Linked to module rlm_realm
 Module: Instantiating module "suffix" from file
/etc/raddb-testing/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/raddb-testing/modules/files
  files {
	usersfile = "/etc/raddb-testing/users"
	acctusersfile = "/etc/raddb-testing/acct_users"
	preproxy_usersfile = "/etc/raddb-testing/preproxy_users"
	compat = "no"
  }
 Module: Checking preacct {...} for more modules to load
 Module: Linked to module rlm_acct_unique
 Module: Instantiating module "acct_unique" from file
/etc/raddb-testing/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: Instantiating module "detail" from file
/etc/raddb-testing/modules/detail
  detail {
	detailfile = "/usr/local-test/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d"
	header = "%t"
	detailperm = 384
	dirperm = 493
	locking = no
	log_packet_header = yes
  }
 Module: Linked to module rlm_radutmp
 Module: Instantiating module "radutmp" from file
/etc/raddb-testing/modules/radutmp
  radutmp {
	filename = "/usr/local-test/var/log/radius/radutmp"
	username = "%{User-Name}"
	case_sensitive = yes
	check_with_nas = yes
	perm = 384
	callerid = yes
  }
 Module: Linked to module rlm_attr_filter
 Module: Instantiating module "attr_filter.accounting_response" from
file /etc/raddb-testing/modules/attr_filter
  attr_filter attr_filter.accounting_response {
	attrsfile = "/etc/raddb-testing/attrs.accounting_response"
	key = "%{User-Name}"
	relaxed = no
  }
 Module: Checking session {...} for more modules to load
 Module: Checking post-proxy {...} for more modules to load
 Module: Checking post-auth {...} for more modules to load
 Module: Linked to module rlm_linelog
 Module: Instantiating module "linelog" from file
/etc/raddb-testing/modules/linelog
  linelog {
	filename = "/usr/local-test/var/log/radius/linelog"
	permissions = 384
	format = "%{Packet-Type} %{reply:Packet-Type} %{User-Name}
%{EAP-Type:-PAP} %{NAS-IP-Address} %{NAS-Identifier}"
  }
 Module: Instantiating module "reply_log" from file
/etc/raddb-testing/modules/detail.log
  detail reply_log {
	detailfile = "/usr/local-test/var/log/radius/radacct/replies/%{Client-IP-Address}/reply-detail-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d"
	header = "%t"
	detailperm = 384
	dirperm = 493
	locking = no
	log_packet_header = no
  }
 Module: Instantiating module "attr_filter.access_reject" from file
/etc/raddb-testing/modules/attr_filter
  attr_filter attr_filter.access_reject {
	attrsfile = "/etc/raddb-testing/attrs.access_reject"
	key = "%{User-Name}"
	relaxed = no
  }
 } # modules
} # server
server status { # from file /etc/raddb-testing/sites-enabled/status
 modules {
  Module: Creating Autz-Type = Status-Server
 Module: Checking authorize {...} for more modules to load
 Module: Linked to module rlm_always
 Module: Instantiating module "ok" from file /etc/raddb-testing/modules/always
  always ok {
	rcode = "ok"
	simulcount = 0
	mpp = no
  }
 } # modules
} # server
server inner-tunnel { # from file /etc/raddb-testing/sites-enabled/inner-tunnel
 modules {
  Module: Creating Auth-Type = LDAP
 Module: Checking authenticate {...} for more modules to load
 Module: Linked to module rlm_ldap
 Module: Instantiating module "ldap" from file /etc/raddb-testing/modules/ldap
  ldap {
	server = "ldap.sarlanga.edu"
	port = 636
	password = "sarlanga"
	identity = "cn=freeradius,ou=applications,dc=sarlanga,dc=edu"
	net_timeout = 10
	timeout = 120
	timelimit = 30
	tls_mode = no
	start_tls = no
	tls_require_cert = "allow"
   tls {
	start_tls = no
	cacertfile = "/etc/raddb-testing/cacert.pem"
	randfile = "/dev/urandom"
	require_cert = "demand"
   }
	basedn = "ou=people,dc=sarlanga,dc=edu"
	filter = "(uid=%u)"
	base_filter = "(objectclass=radiusprofile)"
	auto_header = no
	access_attr = "radiusAllowed"
	access_attr_used_for_allow = yes
	groupname_attribute = "cn"
	groupmembership_filter =
"(|(&(objectClass=GroupOfNames)(member=%{Ldap-UserDn}))(&(objectClass=GroupOfUniqueNames)(uniquemember=%{Ldap-UserDn})))"
	dictionary_mapping = "/etc/raddb-testing/ldap.attrmap"
	ldap_debug = 40
	ldap_connections_number = 15
	compare_check_items = no
	do_xlat = yes
	set_auth_type = yes
   keepalive {
	interval = 3
   }
  }
rlm_ldap: Registering ldap_groupcmp for Ldap-Group
rlm_ldap: Registering ldap_xlat with xlat_name ldap
rlm_ldap: reading ldap<->radius mappings from file
/etc/raddb-testing/ldap.attrmap
rlm_ldap: LDAP radiusCheckItem mapped to RADIUS $GENERIC$
rlm_ldap: LDAP radiusReplyItem mapped to RADIUS $GENERIC$
rlm_ldap: LDAP radiusPassword mapped to RADIUS Cleartext-Password
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: 0x6cb0ac0
 Module: Checking authorize {...} for more modules to load
 Module: Checking session {...} for more modules to load
 Module: Checking post-proxy {...} for more modules to load
 Module: Checking post-auth {...} for more modules to load
 } # modules
} # server
server inner-tunnel-peap { # from file
/etc/raddb-testing/sites-enabled/inner-tunnel-peap
 modules {
 Module: Checking authenticate {...} for more modules to load
 Module: Checking authorize {...} for more modules to load
 Module: Checking session {...} for more modules to load
 Module: Checking post-proxy {...} for more modules to load
 Module: Checking post-auth {...} for more modules to load
 } # modules
} # server
radiusd: #### Opening IP addresses and Ports ####
listen {
	type = "auth"
	ipaddr = 192.168.1.5
	port = 0
}
listen {
	type = "acct"
	ipaddr = 192.168.1.5
	port = 0
}
listen {
	type = "control"
 listen {
	socket = "/usr/local-test/var/run/radiusd/radiusd.sock"
 }
}
listen {
	type = "status"
	ipaddr = 127.0.0.1
	port = 18120
  client admin {
	ipaddr = 127.0.0.1
	require_message_authenticator = no
	secret = "YellowSubmarine"
  }
}
listen {
	type = "auth"
	ipaddr = 127.0.0.1
	port = 18121
}
Listening on authentication address 192.168.1.5 port 1812
Listening on accounting address 192.168.1.5 port 1813
Listening on command file /usr/local-test/var/run/radiusd/radiusd.sock
Listening on status address 127.0.0.1 port 18120 as server status
Listening on authentication address 127.0.0.1 port 18121 as server inner-tunnel
Ready to process requests.
rad_recv: Access-Request packet from host 10.129.180.1 port 55764,
id=58, length=167
	User-Name = "yyyyy"
	NAS-Identifier = "AP-yocadorneotucadorneas-PB-SUM"
	NAS-Port = 5
	Called-Station-Id = "00-XX-XX-XX-25-D0:sarlanga-I"
	Calling-Station-Id = "A8-FF-FF-AA-E7-48"
	Framed-MTU = 1400
	NAS-Port-Type = Wireless-802.11
	Connect-Info = "CONNECT 54Mbps 802.11g"
	EAP-Message = 0x02a1000b016d6c656e6f69
	Message-Authenticator = 0x88fcf4ca35b563fb01a0f63ff55a27d4
# Executing section authorize from file /etc/raddb-testing/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
[auth_log] 	expand: %{Virtual-Server} ->
[auth_log] 	... expanding second conditional
[auth_log] 	expand:
/usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] /usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "yyyyy", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 161 length 11
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
++[unix] returns notfound
++[files] returns noop
++[expiration] returns noop
++[logintime] returns noop
[pap] WARNING! No "known good" password found for the user.
Authentication may fail because of this.
++[pap] returns noop
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/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 58 to 10.129.180.1 port 55764
	EAP-Message = 0x01a200160410eba586f1a4e43e386b7cba670d11fc58
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x8814deeb88b6daa97df52ad5b75d5386
Finished request 0.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 10.129.180.1 port 55764,
id=59, length=180
	User-Name = "yyyyy"
	NAS-Identifier = "AP-yocadorneotucadorneas-PB-SUM"
	NAS-Port = 5
	Called-Station-Id = "00-XX-XX-XX-25-D0:sarlanga-I"
	Calling-Station-Id = "A8-FF-FF-AA-E7-48"
	Framed-MTU = 1400
	NAS-Port-Type = Wireless-802.11
	Connect-Info = "CONNECT 54Mbps 802.11g"
	EAP-Message = 0x02a200060319
	State = 0x8814deeb88b6daa97df52ad5b75d5386
	Message-Authenticator = 0x1e5fcf13dc906487e9bc6138d2def512
# Executing section authorize from file /etc/raddb-testing/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
[auth_log] 	expand: %{Virtual-Server} ->
[auth_log] 	... expanding second conditional
[auth_log] 	expand:
/usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] /usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "yyyyy", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 162 length 6
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
++[unix] returns notfound
++[files] returns noop
++[expiration] returns noop
++[logintime] returns noop
[pap] WARNING! No "known good" password found for the user.
Authentication may fail because of this.
++[pap] returns noop
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/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] Flushing SSL sessions (of #0)
[tls] Initiate
[tls] Start returned 1
++[eap] returns handled
Sending Access-Challenge of id 59 to 10.129.180.1 port 55764
	EAP-Message = 0x01a300061920
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x8814deeb89b7c7a97df52ad5b75d5386
Finished request 1.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 10.129.180.1 port 55764,
id=60, length=240
	User-Name = "yyyyy"
	NAS-Identifier = "AP-yocadorneotucadorneas-PB-SUM"
	NAS-Port = 5
	Called-Station-Id = "00-XX-XX-XX-25-D0:sarlanga-I"
	Calling-Station-Id = "A8-FF-FF-AA-E7-48"
	Framed-MTU = 1400
	NAS-Port-Type = Wireless-802.11
	Connect-Info = "CONNECT 54Mbps 802.11g"
	EAP-Message = 0x02a30042190016030100370100003303014ffc4751a6a0a85eeb3cb3cc0924f3d862ce97c74999acb91562f01c3c4ccef500000c0013000a0033002f000500040100
	State = 0x8814deeb89b7c7a97df52ad5b75d5386
	Message-Authenticator = 0x3fba2ff705f2bf305cb8c711deff0b96
# Executing section authorize from file /etc/raddb-testing/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
[auth_log] 	expand: %{Virtual-Server} ->
[auth_log] 	... expanding second conditional
[auth_log] 	expand:
/usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] /usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "yyyyy", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 163 length 66
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/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]     (other): before/accept initialization
[peap]     TLS_accept: before/accept initialization
[peap] <<< TLS 1.0 Handshake [length 0037], ClientHello
[peap]     TLS_accept: SSLv3 read client hello A
[peap] >>> TLS 1.0 Handshake [length 004a], ServerHello
[peap]     TLS_accept: SSLv3 write server hello A
[peap] >>> TLS 1.0 Handshake [length 1324], 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 60 to 10.129.180.1 port 55764
	EAP-Message = 0x01a4040019c000001381160301004a0200004603014ffc4812c847086e3bf550b27dec5092792445e353e4d1a3e2db9eaa97196478205c12f92d381891c8b63481c5b06d636edd829eb656da4bb5fe754809f8f741f8000a0016030113240b00132000131d00071730820713308205fba0030201020214101b7f691cb7a76e4d2434f172207f0d6457bfd6300d06092a864886f70d01010505003081b0310b3009060355040613024553310f300d060355040813064d4144524944310f300d060355040713064d414452494431243022060355040a131b6970732043657274696669636174696f6e20417574686f7269747931183016060355040b130f
	EAP-Message = 0x43657274696669636163696f6e657331193017060355040313106970734341204c6576656c20312043413124302206092a864886f70d010901161569707363616c6576656c314069707363612e636f6d301e170d3132303132373132303731335a170d3134303230313132303731335a30818d310b3009060355040613024152311530130603550407130c4275656e6f73204169726573311f301d060355040a1316556e6976657273696461642064652050616c65726d6f31273025060355040b131e446570617274616d656e746f20646520436f6d756e69636163696f6e6573311d301b06035504031314737065637472756d2e70616c65726d6f2e
	EAP-Message = 0x65647530820122300d06092a864886f70d01010105000382010f003082010a0282010100a8efabc6c785b1314fc7a643f75a6b1e1ae645b4ad47d23a5ce062cd3fa5a998112d48b88960da17628181430eb1e6dbcd806eb3b77ad9ca5728e86d333f9a910107d90c0e2dad1951587fb6ebe0b1f99665fad04e352e1d87dbf1a1292ae3cc8bb936511b10cc25facb79db7df50a443359a5c46213253cfbee9db5a262221e23d6961c6f2e4bff6c7702fe05eeff8c97c3c4558f84d3d61a1571017d5638863ec4f341613113aca1b385ae7598065503f28f78c83c632ebb0183d6d8ffd21edd8cd4db50c1550443f12706847a053769c1c5c7f94eff9fff
	EAP-Message = 0x8c3091d3892db2f35ab46dc208d1e03f37b82fb96d7fba474b42279ecb74157fdc521c038949dd0203010001a38203443082034030090603551d1304023000301106096086480186f8420101040403020640300b0603551d0f0404030205a030130603551d25040c300a06082b06010505070301301d0603551d0e04160414d57dbba6130341755d2138e3ed9faacb3e65cdcb301f0603551d230418301680147d9e6b0efc13e1c48ba77f61ae6021cfcda0d30630090603551d1104023000305b0603551d1204543052a450304e310b300906035504061302455331243022060355040a131b6970732043657274696669636174696f6e20417574686f
	EAP-Message = 0x726974793119301706035504
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x8814deeb8ab0c7a97df52ad5b75d5386
Finished request 2.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 10.129.180.1 port 55764,
id=61, length=180
	User-Name = "yyyyy"
	NAS-Identifier = "AP-yocadorneotucadorneas-PB-SUM"
	NAS-Port = 5
	Called-Station-Id = "00-XX-XX-XX-25-D0:sarlanga-I"
	Calling-Station-Id = "A8-FF-FF-AA-E7-48"
	Framed-MTU = 1400
	NAS-Port-Type = Wireless-802.11
	Connect-Info = "CONNECT 54Mbps 802.11g"
	EAP-Message = 0x02a400061900
	State = 0x8814deeb8ab0c7a97df52ad5b75d5386
	Message-Authenticator = 0x6151ed5595147071dcca331db578ce31
# Executing section authorize from file /etc/raddb-testing/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
[auth_log] 	expand: %{Virtual-Server} ->
[auth_log] 	... expanding second conditional
[auth_log] 	expand:
/usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] /usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "yyyyy", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 164 length 6
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/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 fragment handler
[peap] eaptls_verify returned 1
[peap] eaptls_process returned 13
[peap] EAPTLS_HANDLED
++[eap] returns handled
Sending Access-Challenge of id 61 to 10.129.180.1 port 55764
	EAP-Message = 0x01a503fc19400313106970734341204c6576656c2031204341307106096086480186f842010d046416624f7267616e697a6174696f6e20496e666f726d6174696f6e204e4f542056414c4944415445442e204c4556454c3120536572766572204365727469666963617465206973737565642062792068747470733a2f2f7777772e69707363612e636f6d2f302906096086480186f8420102041c161a687474703a2f2f6c6576656c3130312e69707363612e636f6d2f303c06096086480186f8420104042f162d687474703a2f2f6c6576656c3130312e69707363612e636f6d2f63726c2f69707363616c6576656c312e63726c3043060960864801
	EAP-Message = 0x86f842010304361634687474703a2f2f6c6576656c3130312e69707363612e636f6d2f63726c2f7265766f636174696f6e4c4556454c312e68746d6c3f304006096086480186f842010704331631687474703a2f2f6c6576656c3130312e69707363612e636f6d2f63726c2f72656e6577616c4c4556454c312e68746d6c3f303e06096086480186f84201080431162f687474703a2f2f6c6576656c3130312e69707363612e636f6d2f63726c2f706f6c6963794c4556454c312e68746d6c30730603551d1f046c306a3033a031a02f862d687474703a2f2f6c6576656c3130312e69707363612e636f6d2f63726c2f69707363616c6576656c312e63
	EAP-Message = 0x726c3033a031a02f862d687474703a2f2f6c6576656c3130322e69707363612e636f6d2f63726c2f69707363616c6576656c312e63726c303e06082b0601050507010104323030302e06082b060105050730018622687474703a2f2f6f6373706c6576656c3130312e69707363612e636f6d2f6f637370300d06092a864886f70d010105050003820101004b61ec4cf7163dad3601e3de9938175ec4ec75d60f658eabf37b1e331283419e7438e584e8cfc47c087c7cba005dcd9d7d165a470bd7aaea70b69b1a18ad2a6cc136cf2ff5cb948ae2866b1c46bbf8ef999ecb510407f28a091e7aa75684d90938f85c3fcd39580112b6979f3284c581f86b
	EAP-Message = 0x998bc211c1de82e87fd57ee7a975905e9dec75020feda29cf4f29dc59dc3946ccf328f7380c6f3f4064d3f6f496fffd640ed2c813c44d648f9ee0143f894d41f4970f30e158064b305a4ae264887a62b5ee51f4f7f3241ddeebcd1153f836262d7c24c4cd7a09f2ab6bc052cd2ecf78e948f240e526538478893fb776f27d44d620c8e58641b4231958c85d758080005f2308205ee308204d6a00302010202141000000000000000000000000000000000000011300d06092a864886f70d01010505003081b2310b3009060355040613024553310f300d060355040813064d6164726964310f300d060355040713064d6164726964312f302d06035504
	EAP-Message = 0x0a13264950532043
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x8814deeb8bb1c7a97df52ad5b75d5386
Finished request 3.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 10.129.180.1 port 55764,
id=62, length=180
	User-Name = "yyyyy"
	NAS-Identifier = "AP-yocadorneotucadorneas-PB-SUM"
	NAS-Port = 5
	Called-Station-Id = "00-XX-XX-XX-25-D0:sarlanga-I"
	Calling-Station-Id = "A8-FF-FF-AA-E7-48"
	Framed-MTU = 1400
	NAS-Port-Type = Wireless-802.11
	Connect-Info = "CONNECT 54Mbps 802.11g"
	EAP-Message = 0x02a500061900
	State = 0x8814deeb8bb1c7a97df52ad5b75d5386
	Message-Authenticator = 0x67cd523cc7b68fde1645669d61b7fd97
# Executing section authorize from file /etc/raddb-testing/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
[auth_log] 	expand: %{Virtual-Server} ->
[auth_log] 	... expanding second conditional
[auth_log] 	expand:
/usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] /usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "yyyyy", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 165 length 6
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/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 fragment handler
[peap] eaptls_verify returned 1
[peap] eaptls_process returned 13
[peap] EAPTLS_HANDLED
++[eap] returns handled
Sending Access-Challenge of id 62 to 10.129.180.1 port 55764
	EAP-Message = 0x01a603fc1940657274696669636174696f6e20417574686f7269747920732e6c2e206970734341310e300c060355040b13056970734341311d301b06035504031314697073434120476c6f62616c20434120526f6f743121301f06092a864886f70d0109011612676c6f62616c30314069707363612e636f6d301e170d3039313231303131333835325a170d3239313232343131333835325a3081b0310b3009060355040613024553310f300d060355040813064d4144524944310f300d060355040713064d414452494431243022060355040a131b6970732043657274696669636174696f6e20417574686f7269747931183016060355040b130f43
	EAP-Message = 0x657274696669636163696f6e657331193017060355040313106970734341204c6576656c20312043413124302206092a864886f70d010901161569707363616c6576656c314069707363612e636f6d30820122300d06092a864886f70d01010105000382010f003082010a02820101009f4db33023c13a798527825d45ce5b64f82bafe15d6001e2940ed501863645858c0c8e76e1a0a40d935417986aeeb2840b23eabf217cc0ee49c026791954d6311890f7fb970be3a8c51640de2eb88c655b32d953f7c8ff06fb896654f8ea44420fb74fc7b0e94fd1d0bbfa968a33e346b7fa64bc65b11fb1e0e105f80cdd4af58b3097b4480a94feaf1d63f269
	EAP-Message = 0x2c6b1b446afd3bd9dae70df0c68ab6e0190160a309a2b320401b14073a8c22df33f476c185b862b849b975c196ed1108af3c3f4e59e840dc995a134747e3b2840a7e8130b71c969f2e0d1cd0e5845e5bad3cce4a6b7d684522f54d5ae79f5c19004827a5787882d3e2ffb1e680dfeb2a125bb10203010001a38201fa308201f6302a06096086480186f8420102041d161b68747470733a2f2f6c6576656c3130312e69707363612e636f6d2f302106096086480186f84201030414161265737461646f2f65737461646f2e6173703f302706096086480186f8420104041a161865737461646f4341544553542f65737461646f2e6173703f3022060960
	EAP-Message = 0x86480186f84201070415161365737461646f2f72656e756576612e6173703f302006096086480186f84201080413161165737461646f2f706f6c6963792e617370302406096086480186f842010d04171615436572746966696361646f20706f72206970734341301106096086480186f8420101040403020007300c0603551d13040530030101ff301d0603551d0e041604147d9e6b0efc13e1c48ba77f61ae6021cfcda0d306303e0603551d1f043730353033a031a02f862d687474703a2f2f6c6576656c3130312e69707363612e636f6d2f63726c2f69707363616c6576656c312e63726c303a06082b06010505070101042e302c302a06082b06
	EAP-Message = 0x010505073001861e
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x8814deeb8cb2c7a97df52ad5b75d5386
Finished request 4.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 10.129.180.1 port 55764,
id=63, length=180
	User-Name = "yyyyy"
	NAS-Identifier = "AP-yocadorneotucadorneas-PB-SUM"
	NAS-Port = 5
	Called-Station-Id = "00-XX-XX-XX-25-D0:sarlanga-I"
	Calling-Station-Id = "A8-FF-FF-AA-E7-48"
	Framed-MTU = 1400
	NAS-Port-Type = Wireless-802.11
	Connect-Info = "CONNECT 54Mbps 802.11g"
	EAP-Message = 0x02a600061900
	State = 0x8814deeb8cb2c7a97df52ad5b75d5386
	Message-Authenticator = 0xf9928a64da7f9a2233d6f45dd4ddd309
# Executing section authorize from file /etc/raddb-testing/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
[auth_log] 	expand: %{Virtual-Server} ->
[auth_log] 	... expanding second conditional
[auth_log] 	expand:
/usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] /usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "yyyyy", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 166 length 6
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/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 fragment handler
[peap] eaptls_verify returned 1
[peap] eaptls_process returned 13
[peap] EAPTLS_HANDLED
++[eap] returns handled
Sending Access-Challenge of id 63 to 10.129.180.1 port 55764
	EAP-Message = 0x01a703fc1940687474703a2f2f6f6373706c6576656c3130312e69707363612e636f6d2f300b0603551d0f04040302010630470603551d250440303e06082b0601050507030106082b0601050507030206082b0601050507030306082b0601050507030406082b06010505070308060a2b0601040182370a0304300d06092a864886f70d010105050003820101008737bf8f76d31d2eca683935839d2958fe74add252963aff06e84cb0308e3316c5972d29aebe28e77e10ab20e1ed509ecfbbd394fe1da47370d27e6239b31cd636e1d4782bace12bedc753a14dd4281f588e56e2c924f956ec0ad0df5268575ea4393bb9e46619c4cff0a7fbb7717f
	EAP-Message = 0xd7f247a501f8245587996ac61064ada017958080eb6d4bd3e9171685a951ae6e69419776e8de7f43fb891b853397030059549676d110ed837018f76ca7231a20647365211bee7e1e67f8026ad21c2e37a31e7001bb866076bc7adc7eff3475f2e33e94c60ed70ce792cc33c31c32df09e853cf3587c59453f98efa685cce193a482a52f3da46c2e6bd5d02b5471eb9954000060b30820607308204efa003020102020100300d06092a864886f70d01010505003081b2310b3009060355040613024553310f300d060355040813064d6164726964310f300d060355040713064d6164726964312f302d060355040a132649505320436572746966696361
	EAP-Message = 0x74696f6e20417574686f7269747920732e6c2e206970734341310e300c060355040b13056970734341311d301b06035504031314697073434120476c6f62616c20434120526f6f743121301f06092a864886f70d0109011612676c6f62616c30314069707363612e636f6d301e170d3039303930373134333834345a170d3239313232353134333834345a3081b2310b3009060355040613024553310f300d060355040813064d6164726964310f300d060355040713064d6164726964312f302d060355040a13264950532043657274696669636174696f6e20417574686f7269747920732e6c2e206970734341310e300c060355040b130569707343
	EAP-Message = 0x41311d301b06035504031314697073434120476c6f62616c20434120526f6f743121301f06092a864886f70d0109011612676c6f62616c30314069707363612e636f6d30820122300d06092a864886f70d01010105000382010f003082010a0282010100a7efcc8030b091244fb068f8c3ca2d1538555882e23863b0f7a3926f83b8b05eb08cac54b177d050e097b390ad8ab31f392b4556f7aae2df7cb2ec6f532f9acbd0e666cbc913e872e2b4cd31578712b593e8fa72ceea47f28cb4b063d70400b764363997e895f188f9710d03278c61cf0883964f83c54ee85cf80670f102aa1c1ea9c8aa7ee75dcd8d3c146f67d01ba923488b21283a8a4ce6
	EAP-Message = 0x1131f9212eb26766
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x8814deeb8db3c7a97df52ad5b75d5386
Finished request 5.
Going to the next request
Waking up in 4.8 seconds.
rad_recv: Access-Request packet from host 10.129.180.1 port 55764,
id=64, length=180
	User-Name = "yyyyy"
	NAS-Identifier = "AP-yocadorneotucadorneas-PB-SUM"
	NAS-Port = 5
	Called-Station-Id = "00-XX-XX-XX-25-D0:sarlanga-I"
	Calling-Station-Id = "A8-FF-FF-AA-E7-48"
	Framed-MTU = 1400
	NAS-Port-Type = Wireless-802.11
	Connect-Info = "CONNECT 54Mbps 802.11g"
	EAP-Message = 0x02a700061900
	State = 0x8814deeb8db3c7a97df52ad5b75d5386
	Message-Authenticator = 0xed341e6a8b2de2aae596dcc6c65dc1c9
# Executing section authorize from file /etc/raddb-testing/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
[auth_log] 	expand: %{Virtual-Server} ->
[auth_log] 	... expanding second conditional
[auth_log] 	expand:
/usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] /usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "yyyyy", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 167 length 6
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/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 fragment handler
[peap] eaptls_verify returned 1
[peap] eaptls_process returned 13
[peap] EAPTLS_HANDLED
++[eap] returns handled
Sending Access-Challenge of id 64 to 10.129.180.1 port 55764
	EAP-Message = 0x01a803af1900c6296e9493cf4096fcb03dbfb2b493bf5671b6a54187b058b559232849b898f9501e2d15280b4cac49d184a99b9ae77254b738d0dbc9fea973d56d10cd8e75ebfe97fd803cfcb4d848f499460b8814a4b62edb4c60f421c16c809514d5afd50203010001a382022430820220301d0603551d0e0416041415a69680b1154b31c3c29cf6e7130b4bf318cd863081df0603551d230481d73081d4801415a69680b1154b31c3c29cf6e7130b4bf318cd86a181b8a481b53081b2310b3009060355040613024553310f300d060355040813064d6164726964310f300d060355040713064d6164726964312f302d060355040a13264950532043
	EAP-Message = 0x657274696669636174696f6e20417574686f7269747920732e6c2e206970734341310e300c060355040b13056970734341311d301b06035504031314697073434120476c6f62616c20434120526f6f743121301f06092a864886f70d0109011612676c6f62616c30314069707363612e636f6d820100300c0603551d13040530030101ff300b0603551d0f04040302010630470603551d250440303e06082b0601050507030106082b0601050507030206082b0601050507030306082b0601050507030406082b06010505070308060a2b0601040182370a0304301d0603551d11041630148112676c6f62616c30314069707363612e636f6d301d0603
	EAP-Message = 0x551d12041630148112676c6f62616c30314069707363612e636f6d30410603551d1f043a30383036a034a0328630687474703a2f2f63726c676c6f62616c30312e69707363612e636f6d2f63726c2f63726c676c6f62616c30312e63726c303806082b06010505070101042c302a302806082b06010505073001861c687474703a2f2f63726c676c6f62616c30312e69707363612e636f6d300d06092a864886f70d0101050500038201010018f4aefe800f8ec1776fa25a47489f2355a1536bf95da730a524be432ff8c1d157f93e2c8025cc46a936f3495b1df67cd763b34d3e78f6a7b40277f8790d3e6acb1860b8fd00af0cdd54e3548f223df310
	EAP-Message = 0x6f110db51e7a8d27cc08b85bc3b81a5f2ba7603f001cf70f5c4266649e8712807089e0fa57280e4e1f102fd90580b6802f1c69f0f6b66534056fcad93ef8d45d3732c7b82bccff73930071e001c8aa43bda9f1cefa80f9f1431291a665e560074d47ba2b2f04f64a8529886510c9b253629c6c9b605c1a1bd3aec51d729906ff05cc862673b4d45405dd1e6b003bb789e8e391022012ebefe9fe0a29238123a300da70cc925f3723d01c7b355c037a16030100040e000000
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x8814deeb8ebcc7a97df52ad5b75d5386
Finished request 6.
Going to the next request
Waking up in 4.8 seconds.
rad_recv: Access-Request packet from host 10.129.180.1 port 55764,
id=65, length=500
	User-Name = "yyyyy"
	NAS-Identifier = "AP-yocadorneotucadorneas-PB-SUM"
	NAS-Port = 5
	Called-Station-Id = "00-XX-XX-XX-25-D0:sarlanga-I"
	Calling-Station-Id = "A8-FF-FF-AA-E7-48"
	Framed-MTU = 1400
	NAS-Port-Type = Wireless-802.11
	Connect-Info = "CONNECT 54Mbps 802.11g"
	EAP-Message = 0x02a8014419001603010106100001020100422704cd2992a92b33a0a1516a61525d92c4dfb23f82f13d7b5f56317b8668a17155e3e2db2f75abdc226796641cb7b202dbb1848b69d8dfbc9516be04e509aea5daa181ff82a299d1461247d5eaeac52c51c4e0fd970eda3675f726e29e528089f432cf1969857b47a5589ae03dedc4b2504710c7bff51ec058c224cb9d9c03e67635e9491f822d5f2b7b4cc325df76f6d7b4f95ae47a83d736e4013e02e4636b883d00cd9efd5faab5074cd2d5d2ef6200d92c35ef95a04c8943228e3d54f9c864bd52472a461a4f4a001a34004855f560759d43b008102d6ca5316311ac5d169ad14ff2ae6de1df49f05c
	EAP-Message = 0x8381e6e6b4115f6cea0ee09eb3e6d38b1358d7781403010001011603010028d07f0c5c09b511758626eb89bb222bd25ea4a04957e579211de5c394aa80202f9f928fe9b2cfef6f
	State = 0x8814deeb8ebcc7a97df52ad5b75d5386
	Message-Authenticator = 0x5e5b913676276daf39e1c9ff83c259aa
# Executing section authorize from file /etc/raddb-testing/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
[auth_log] 	expand: %{Virtual-Server} ->
[auth_log] 	... expanding second conditional
[auth_log] 	expand:
/usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] /usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "yyyyy", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 168 length 253
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/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] <<< 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
  SSL: adding session
5c12f92d381891c8b63481c5b06d636edd829eb656da4bb5fe754809f8f741f8 to
cache
[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 65 to 10.129.180.1 port 55764
	EAP-Message = 0x01a9003919001403010001011603010028d821d133aa4769658355f7b458637264e48e690e4e79bdb4b0d2d18ef509335e35e751de3bf1d351
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x8814deeb8fbdc7a97df52ad5b75d5386
Finished request 7.
Going to the next request
Waking up in 4.7 seconds.
rad_recv: Access-Request packet from host 10.129.180.1 port 55764,
id=66, length=180
	User-Name = "yyyyy"
	NAS-Identifier = "AP-yocadorneotucadorneas-PB-SUM"
	NAS-Port = 5
	Called-Station-Id = "00-XX-XX-XX-25-D0:sarlanga-I"
	Calling-Station-Id = "A8-FF-FF-AA-E7-48"
	Framed-MTU = 1400
	NAS-Port-Type = Wireless-802.11
	Connect-Info = "CONNECT 54Mbps 802.11g"
	EAP-Message = 0x02a900061900
	State = 0x8814deeb8fbdc7a97df52ad5b75d5386
	Message-Authenticator = 0xca4cc7ef534339b7d9aeaf0a2a352aa5
# Executing section authorize from file /etc/raddb-testing/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
[auth_log] 	expand: %{Virtual-Server} ->
[auth_log] 	... expanding second conditional
[auth_log] 	expand:
/usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] /usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "yyyyy", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 169 length 6
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/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 66 to 10.129.180.1 port 55764
	EAP-Message = 0x01aa002b1900170301002086365ff559740ef850f6911a41fa412f9b1630f7051310898c0fc537065e9e2e
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x8814deeb80bec7a97df52ad5b75d5386
Finished request 8.
Going to the next request
Waking up in 4.7 seconds.
rad_recv: Access-Request packet from host 10.129.180.1 port 55764,
id=67, length=246
	User-Name = "yyyyy"
	NAS-Identifier = "AP-yocadorneotucadorneas-PB-SUM"
	NAS-Port = 5
	Called-Station-Id = "00-XX-XX-XX-25-D0:sarlanga-I"
	Calling-Station-Id = "A8-FF-FF-AA-E7-48"
	Framed-MTU = 1400
	NAS-Port-Type = Wireless-802.11
	Connect-Info = "CONNECT 54Mbps 802.11g"
	EAP-Message = 0x02aa0048190017030100185c83e2174ed058a36cc14e00cc4fae5a9179da770e44ac83170301002078727b4506bc63126fa2517afc18e439fd9f2f4ffdd00d99700217f127b402dd
	State = 0x8814deeb80bec7a97df52ad5b75d5386
	Message-Authenticator = 0xecc9f19ff811e3e27e70eae209dbb6e9
# Executing section authorize from file /etc/raddb-testing/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
[auth_log] 	expand: %{Virtual-Server} ->
[auth_log] 	... expanding second conditional
[auth_log] 	expand:
/usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] /usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "yyyyy", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 170 length 72
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/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 - yyyyy
[peap] Got inner identity 'yyyyy'
[peap] Setting default EAP type for tunneled EAP session.
[peap] Got tunneled request
	EAP-Message = 0x02aa000b016d6c656e6f69
server  {
[peap] Setting User-Name to yyyyy
Sending tunneled request
	EAP-Message = 0x02aa000b016d6c656e6f69
	FreeRADIUS-Proxied-To = 127.0.0.1
	User-Name = "yyyyy"
	NAS-Identifier = "AP-yocadorneotucadorneas-PB-SUM"
	NAS-Port = 5
	Called-Station-Id = "00-XX-XX-XX-25-D0:sarlanga-I"
	Calling-Station-Id = "A8-FF-FF-AA-E7-48"
	Framed-MTU = 1400
	NAS-Port-Type = Wireless-802.11
	Connect-Info = "CONNECT 54Mbps 802.11g"
	NAS-IP-Address = 10.129.180.1
server inner-tunnel {
# Executing section authorize from file
/etc/raddb-testing/sites-enabled/inner-tunnel
+- entering group authorize {...}
[auth_log] 	expand: %{Virtual-Server} -> inner-tunnel
[auth_log] 	expand:
/usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-inner-tunnel-20120710
[auth_log] /usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-inner-tunnel-20120710
[auth_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[auth_log] returns ok
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "yyyyy", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[control] returns noop
[eap] EAP packet type response id 170 length 11
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
++[files] returns noop
[ldap] performing user authorization for yyyyy
[ldap] 	expand: (uid=%u) -> (uid=yyyyy)
[ldap] 	expand: ou=people,dc=sarlanga,dc=edu -> ou=people,dc=sarlanga,dc=edu
  [ldap] ldap_get_conn: Checking Id: 0
  [ldap] ldap_get_conn: Got Id: 0
  [ldap] attempting LDAP reconnection
  [ldap] (re)connect to ldap.sarlanga.edu:636, authentication 0
  [ldap] setting TLS mode to 1
  [ldap] setting TLS CACert File to /etc/raddb-testing/cacert.pem
  [ldap] setting TLS Require Cert to demand
  [ldap] setting TLS Key File to /dev/urandom
  [ldap] bind as
cn=freeradius,ou=applications,dc=sarlanga,dc=edu/sarlanga to
ldap.sarlanga.edu:636
  [ldap] waiting for bind result ...
  [ldap] Bind was successful
  [ldap] performing search in ou=people,dc=sarlanga,dc=edu, with
filter (uid=yyyyy)
[ldap] checking if remote access for yyyyy is allowed by radiusAllowed
[ldap] looking for check items in directory...
  [ldap] userPassword -> Password-With-Header == "{crypt}5.glupOqHA"
  [ldap] radiusPassword -> Cleartext-Password == "pulgas"
[ldap] looking for reply items in directory...
[ldap] user yyyyy authorized to use remote access
  [ldap] ldap_release_conn: Release Id: 0
++[ldap] returns ok
++[expiration] returns noop
++[logintime] returns noop
[pap] WARNING: Auth-Type already set.  Not setting to PAP
++[pap] returns noop
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/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 = 0x01ab00201a01ab001b109bf4c3c47d874e0e30116053130d22176d6c656e6f69
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xbb51c5a6bbfadfaf825abcaa22d694a6
[peap] Got tunneled reply RADIUS code 11
	EAP-Message = 0x01ab00201a01ab001b109bf4c3c47d874e0e30116053130d22176d6c656e6f69
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xbb51c5a6bbfadfaf825abcaa22d694a6
[peap] Got tunneled Access-Challenge
++[eap] returns handled
Sending Access-Challenge of id 67 to 10.129.180.1 port 55764
	EAP-Message = 0x01ab0043190017030100384becf00fe9503ff392eb0ef0d3775f51ebbbdc99abb097e7e43649e4cf5a6b045b10dd51fae88ce51dd17e63c292e1d3b6bc4d2b5e524350
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x8814deeb81bfc7a97df52ad5b75d5386
Finished request 9.
Going to the next request
Waking up in 4.7 seconds.
rad_recv: Access-Request packet from host 10.129.180.1 port 55764,
id=68, length=302
	User-Name = "yyyyy"
	NAS-Identifier = "AP-yocadorneotucadorneas-PB-SUM"
	NAS-Port = 5
	Called-Station-Id = "00-XX-XX-XX-25-D0:sarlanga-I"
	Calling-Station-Id = "A8-FF-FF-AA-E7-48"
	Framed-MTU = 1400
	NAS-Port-Type = Wireless-802.11
	Connect-Info = "CONNECT 54Mbps 802.11g"
	EAP-Message = 0x02ab008019001703010018e5c8db5aac6c066c485ed9d84349c06661d80cb88cac9bd71703010058f95b5203bf5406a11f8204e5c9ff3c031497b8c48e2c4ed596a8f9cfa51ff75e2b9bf76ac3c68495721e19293d195ed2d472041ba9539e60d5c703a1829363d37341dddc900803343450801dbcd56b5ebae15794da693e6d
	State = 0x8814deeb81bfc7a97df52ad5b75d5386
	Message-Authenticator = 0x692eac82692b4c4c30c5e03ca653c1de
# Executing section authorize from file /etc/raddb-testing/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
[auth_log] 	expand: %{Virtual-Server} ->
[auth_log] 	... expanding second conditional
[auth_log] 	expand:
/usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] /usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "yyyyy", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 171 length 128
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/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 = 0x02ab00411a02ab003c313eb695228fced3448bf448185a1b560b0000000000000000f252c6370bc3705bdfe5555ae9338a10b5ba72288de07e4a006d6c656e6f69
server  {
[peap] Setting User-Name to yyyyy
Sending tunneled request
	EAP-Message = 0x02ab00411a02ab003c313eb695228fced3448bf448185a1b560b0000000000000000f252c6370bc3705bdfe5555ae9338a10b5ba72288de07e4a006d6c656e6f69
	FreeRADIUS-Proxied-To = 127.0.0.1
	User-Name = "yyyyy"
	State = 0xbb51c5a6bbfadfaf825abcaa22d694a6
	NAS-Identifier = "AP-yocadorneotucadorneas-PB-SUM"
	NAS-Port = 5
	Called-Station-Id = "00-XX-XX-XX-25-D0:sarlanga-I"
	Calling-Station-Id = "A8-FF-FF-AA-E7-48"
	Framed-MTU = 1400
	NAS-Port-Type = Wireless-802.11
	Connect-Info = "CONNECT 54Mbps 802.11g"
	NAS-IP-Address = 10.129.180.1
server inner-tunnel {
# Executing section authorize from file
/etc/raddb-testing/sites-enabled/inner-tunnel
+- entering group authorize {...}
[auth_log] 	expand: %{Virtual-Server} -> inner-tunnel
[auth_log] 	expand:
/usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-inner-tunnel-20120710
[auth_log] /usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-inner-tunnel-20120710
[auth_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[auth_log] returns ok
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "yyyyy", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[control] returns noop
[eap] EAP packet type response id 171 length 65
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
++[files] returns noop
[ldap] performing user authorization for yyyyy
[ldap] 	expand: (uid=%u) -> (uid=yyyyy)
[ldap] 	expand: ou=people,dc=sarlanga,dc=edu -> ou=people,dc=sarlanga,dc=edu
  [ldap] ldap_get_conn: Checking Id: 0
  [ldap] ldap_get_conn: Got Id: 0
  [ldap] performing search in ou=people,dc=sarlanga,dc=edu, with
filter (uid=yyyyy)
[ldap] checking if remote access for yyyyy is allowed by radiusAllowed
[ldap] looking for check items in directory...
  [ldap] userPassword -> Password-With-Header == "{crypt}5.glupOqHA"
  [ldap] radiusPassword -> Cleartext-Password == "pulgas"
[ldap] looking for reply items in directory...
[ldap] user yyyyy authorized to use remote access
  [ldap] ldap_release_conn: Release Id: 0
++[ldap] returns ok
++[expiration] returns noop
++[logintime] returns noop
[pap] WARNING: Auth-Type already set.  Not setting to PAP
++[pap] returns noop
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/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/raddb-testing/sites-enabled/inner-tunnel
[mschapv2] +- entering group MS-CHAP {...}
[mschap] Creating challenge hash with username: yyyyy
[mschap] Told to do MS-CHAPv2 for yyyyy with NT-Password
[mschap] adding MS-CHAPv2 MPPE keys
++[mschap] returns ok
MSCHAP Success
++[eap] returns handled
} # server inner-tunnel
[peap] Got tunneled reply code 11
	EAP-Message = 0x01ac00331a03ab002e533d39413841374339354142433645443831334534384146373535453037463639433846314244443044
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xbb51c5a6bafddfaf825abcaa22d694a6
[peap] Got tunneled reply RADIUS code 11
	EAP-Message = 0x01ac00331a03ab002e533d39413841374339354142433645443831334534384146373535453037463639433846314244443044
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xbb51c5a6bafddfaf825abcaa22d694a6
[peap] Got tunneled Access-Challenge
++[eap] returns handled
Sending Access-Challenge of id 68 to 10.129.180.1 port 55764
	EAP-Message = 0x01ac005319001703010048582dc8183c53ae3d2134db71275f243f43601712a6c44627b4d1381f1c2141a19c9aaba8810d9d9de13075eec7e6a1cff66065961e308bf24cf9a473d8f5d0dc43be68270b31990c
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x8814deeb82b8c7a97df52ad5b75d5386
Finished request 10.
Going to the next request
Waking up in 4.7 seconds.
rad_recv: Access-Request packet from host 10.129.180.1 port 55764,
id=69, length=238
	User-Name = "yyyyy"
	NAS-Identifier = "AP-yocadorneotucadorneas-PB-SUM"
	NAS-Port = 5
	Called-Station-Id = "00-XX-XX-XX-25-D0:sarlanga-I"
	Calling-Station-Id = "A8-FF-FF-AA-E7-48"
	Framed-MTU = 1400
	NAS-Port-Type = Wireless-802.11
	Connect-Info = "CONNECT 54Mbps 802.11g"
	EAP-Message = 0x02ac004019001703010018b43d2d7c7f82555756d2fd3b16e87a5af6b0ac44b0b5d2a61703010018fd3842857f995c59fa42e79d67210d7146eec97a1d953fd1
	State = 0x8814deeb82b8c7a97df52ad5b75d5386
	Message-Authenticator = 0xbdc84070e732333a170a84aceec7dd0a
# Executing section authorize from file /etc/raddb-testing/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
[auth_log] 	expand: %{Virtual-Server} ->
[auth_log] 	... expanding second conditional
[auth_log] 	expand:
/usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] /usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "yyyyy", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 172 length 64
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/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 = 0x02ac00061a03
server  {
[peap] Setting User-Name to yyyyy
Sending tunneled request
	EAP-Message = 0x02ac00061a03
	FreeRADIUS-Proxied-To = 127.0.0.1
	User-Name = "yyyyy"
	State = 0xbb51c5a6bafddfaf825abcaa22d694a6
	NAS-Identifier = "AP-yocadorneotucadorneas-PB-SUM"
	NAS-Port = 5
	Called-Station-Id = "00-XX-XX-XX-25-D0:sarlanga-I"
	Calling-Station-Id = "A8-FF-FF-AA-E7-48"
	Framed-MTU = 1400
	NAS-Port-Type = Wireless-802.11
	Connect-Info = "CONNECT 54Mbps 802.11g"
	NAS-IP-Address = 10.129.180.1
server inner-tunnel {
# Executing section authorize from file
/etc/raddb-testing/sites-enabled/inner-tunnel
+- entering group authorize {...}
[auth_log] 	expand: %{Virtual-Server} -> inner-tunnel
[auth_log] 	expand:
/usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-inner-tunnel-20120710
[auth_log] /usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-inner-tunnel-20120710
[auth_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[auth_log] returns ok
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "yyyyy", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[control] returns noop
[eap] EAP packet type response id 172 length 6
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
++[files] returns noop
[ldap] performing user authorization for yyyyy
[ldap] 	expand: (uid=%u) -> (uid=yyyyy)
[ldap] 	expand: ou=people,dc=sarlanga,dc=edu -> ou=people,dc=sarlanga,dc=edu
  [ldap] ldap_get_conn: Checking Id: 0
  [ldap] ldap_get_conn: Got Id: 0
  [ldap] performing search in ou=people,dc=sarlanga,dc=edu, with
filter (uid=yyyyy)
[ldap] checking if remote access for yyyyy is allowed by radiusAllowed
[ldap] looking for check items in directory...
  [ldap] userPassword -> Password-With-Header == "{crypt}5.glupOqHA"
  [ldap] radiusPassword -> Cleartext-Password == "pulgas"
[ldap] looking for reply items in directory...
[ldap] user yyyyy authorized to use remote access
  [ldap] ldap_release_conn: Release Id: 0
++[ldap] returns ok
++[expiration] returns noop
++[logintime] returns noop
[pap] WARNING: Auth-Type already set.  Not setting to PAP
++[pap] returns noop
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/sites-enabled/inner-tunnel
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/mschapv2
[eap] processing type mschapv2
[eap] Freeing handler
++[eap] returns ok
Login OK: [yyyyy] (from client AP-yocadorneotucadorneas-PB-SUM port 5
cli A8-FF-FF-AA-E7-48 via TLS tunnel)
# Executing section post-auth from file
/etc/raddb-testing/sites-enabled/inner-tunnel
+- entering group post-auth {...}
[linelog] 	expand: /usr/local-test/var/log/radius/linelog ->
/usr/local-test/var/log/radius/linelog
[linelog] WARNING: Deprecated conditional expansion ":-".  See "man
unlang" for details
[linelog] 	expand: %{Packet-Type} %{reply:Packet-Type} %{User-Name}
%{EAP-Type:-PAP} %{NAS-IP-Address} %{NAS-Identifier} -> Access-Request
Access-Accept yyyyy MS-CHAP-V2 10.129.180.1
AP-yocadorneotucadorneas-PB-SUM
++[linelog] returns ok
[reply_log] 	expand: %{Virtual-Server} -> inner-tunnel
[reply_log] 	expand:
/usr/local-test/var/log/radius/radacct/replies/%{Client-IP-Address}/reply-detail-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/replies/10.129.180.1/reply-detail-inner-tunnel-20120710
[reply_log] /usr/local-test/var/log/radius/radacct/replies/%{Client-IP-Address}/reply-detail-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/replies/10.129.180.1/reply-detail-inner-tunnel-20120710
[reply_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[reply_log] returns ok
} # server inner-tunnel
[peap] Got tunneled reply code 2
	MS-MPPE-Encryption-Policy = 0x00000001
	MS-MPPE-Encryption-Types = 0x00000006
	MS-MPPE-Send-Key = 0x016b3d4d79555d8ffdd2999bdc4ed987
	MS-MPPE-Recv-Key = 0xb01b2bb020cf345a4141d5d2d6c82ca9
	EAP-Message = 0x03ac0004
	Message-Authenticator = 0x00000000000000000000000000000000
	User-Name = "yyyyy"
[peap] Got tunneled reply RADIUS code 2
	MS-MPPE-Encryption-Policy = 0x00000001
	MS-MPPE-Encryption-Types = 0x00000006
	MS-MPPE-Send-Key = 0x016b3d4d79555d8ffdd2999bdc4ed987
	MS-MPPE-Recv-Key = 0xb01b2bb020cf345a4141d5d2d6c82ca9
	EAP-Message = 0x03ac0004
	Message-Authenticator = 0x00000000000000000000000000000000
	User-Name = "yyyyy"
[peap] Tunneled authentication was successful.
[peap] SUCCESS
[peap] Saving tunneled attributes for later
++[eap] returns handled
Sending Access-Challenge of id 69 to 10.129.180.1 port 55764
	EAP-Message = 0x01ad002b19001703010020be08b0114c9fff08b9bec860f8bea503abcbdafac449467b0b92a56001336215
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x8814deeb83b9c7a97df52ad5b75d5386
Finished request 11.
Going to the next request
Waking up in 4.7 seconds.
rad_recv: Access-Request packet from host 10.129.180.1 port 55764,
id=70, length=246
	User-Name = "yyyyy"
	NAS-Identifier = "AP-yocadorneotucadorneas-PB-SUM"
	NAS-Port = 5
	Called-Station-Id = "00-XX-XX-XX-25-D0:sarlanga-I"
	Calling-Station-Id = "A8-FF-FF-AA-E7-48"
	Framed-MTU = 1400
	NAS-Port-Type = Wireless-802.11
	Connect-Info = "CONNECT 54Mbps 802.11g"
	EAP-Message = 0x02ad004819001703010018256b110d9946f17c6d89933febaccaf415e820c89ea931981703010020af0105fc0964b833222e642a69e790f244caea25a0a4219d56da823f8c03ed88
	State = 0x8814deeb83b9c7a97df52ad5b75d5386
	Message-Authenticator = 0x187841b23947f774d7a9ce533effc1db
# Executing section authorize from file /etc/raddb-testing/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
[auth_log] 	expand: %{Virtual-Server} ->
[auth_log] 	... expanding second conditional
[auth_log] 	expand:
/usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] /usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "yyyyy", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 173 length 72
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/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 success
[peap] Received EAP-TLV response.
[peap] Success
[peap] Using saved attributes from the original Access-Accept
	User-Name = "yyyyy"
[peap] Saving response in the cache
[eap] Freeing handler
++[eap] returns ok
Login OK: [yyyyy] (from client AP-yocadorneotucadorneas-PB-SUM port 5
cli A8-FF-FF-AA-E7-48)
# Executing section post-auth from file /etc/raddb-testing/sites-enabled/default
+- entering group post-auth {...}
[auth_log] 	expand: %{Virtual-Server} ->
[auth_log] 	... expanding second conditional
[auth_log] 	expand:
/usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] /usr/local-test/var/log/radius/radacct/requests/%{Client-IP-Address}/auth-detail-%{NAS-Identifier}-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/requests/10.129.180.1/auth-detail-AP-yocadorneotucadorneas-PB-SUM-DEFAULT-20120710
[auth_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[auth_log] returns ok
[linelog] 	expand: /usr/local-test/var/log/radius/linelog ->
/usr/local-test/var/log/radius/linelog
[linelog] WARNING: Deprecated conditional expansion ":-".  See "man
unlang" for details
[linelog] 	expand: %{Packet-Type} %{reply:Packet-Type} %{User-Name}
%{EAP-Type:-PAP} %{NAS-IP-Address} %{NAS-Identifier} -> Access-Request
Access-Accept yyyyy PEAP 10.129.180.1 AP-yocadorneotucadorneas-PB-SUM
++[linelog] returns ok
[reply_log] 	expand: %{Virtual-Server} ->
[reply_log] 	... expanding second conditional
[reply_log] 	expand:
/usr/local-test/var/log/radius/radacct/replies/%{Client-IP-Address}/reply-detail-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/replies/10.129.180.1/reply-detail-DEFAULT-20120710
[reply_log] /usr/local-test/var/log/radius/radacct/replies/%{Client-IP-Address}/reply-detail-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/replies/10.129.180.1/reply-detail-DEFAULT-20120710
[reply_log] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[reply_log] returns ok
++[exec] returns noop
Sending Access-Accept of id 70 to 10.129.180.1 port 55764
	User-Name = "yyyyy"
	MS-MPPE-Recv-Key =
0xe51a247fdc7439415a900365b5d85218ff058272cf645fdbef6c6e90b5bb1dea
	MS-MPPE-Send-Key =
0x9fc773164859d4fe14b4890fb97748a4253016cde71c265c05fd7436040a4569
	EAP-Message = 0x03ad0004
	Message-Authenticator = 0x00000000000000000000000000000000
Finished request 12.
Going to the next request
Waking up in 4.7 seconds.
rad_recv: Accounting-Request packet from host 10.129.180.1 port 48923,
id=71, length=161
	Acct-Session-Id = "4FFC46F9-00000004"
	Acct-Status-Type = Start
	Acct-Authentic = RADIUS
	User-Name = "yyyyy"
	NAS-Identifier = "AP-yocadorneotucadorneas-PB-SUM"
	NAS-Port = 5
	Called-Station-Id = "00-XX-XX-XX-25-D0:sarlanga-I"
	Calling-Station-Id = "A8-FF-FF-AA-E7-48"
	NAS-Port-Type = Wireless-802.11
	Connect-Info = "CONNECT 54Mbps 802.11g"
# Executing section preacct from file /etc/raddb-testing/sites-enabled/default
+- entering group preacct {...}
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 5,Client-IP-Address =
10.129.180.1,NAS-IP-Address = 10.129.180.1,Acct-Session-Id =
"4FFC46F9-00000004",User-Name = "yyyyy"'
[acct_unique] Acct-Unique-Session-ID = "dddf83abe2685566".
++[acct_unique] returns ok
[suffix] No '@' in User-Name = "yyyyy", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[files] returns noop
# Executing section accounting from file
/etc/raddb-testing/sites-enabled/default
+- entering group accounting {...}
[detail] 	expand:
/usr/local-test/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d
-> /usr/local-test/var/log/radius/radacct/10.129.180.1/detail-20120710
[detail] /usr/local-test/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d
expands to /usr/local-test/var/log/radius/radacct/10.129.180.1/detail-20120710
[detail] 	expand: %t -> Tue Jul 10 12:19:46 2012
++[detail] returns ok
++[unix] returns ok
[radutmp] 	expand: /usr/local-test/var/log/radius/radutmp ->
/usr/local-test/var/log/radius/radutmp
[radutmp] 	expand: %{User-Name} -> yyyyy
++[radutmp] returns ok
++[exec] returns noop
[attr_filter.accounting_response] 	expand: %{User-Name} -> yyyyy
attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
Sending Accounting-Response of id 71 to 10.129.180.1 port 48923
Finished request 13.
Cleaning up request 13 ID 71 with timestamp +2
Going to the next request
Waking up in 4.6 seconds.

Thanks in advance
-- 
--
Sergio Belkin  http://www.sergiobelkin.com
Watch More TV http://sebelk.blogspot.com
LPIC-2 Certified - http://www.lpi.org