Problems with Huntgroup

4 Jun 2012

      Hi (sorry I resend the email because in my earlier one the subject was
missing by mistake)

I'm trying to get working huntgroups but I can't do it:

I've appended something like to huntgroups file

mb NAS-IP-Address == 10.129.189.1
mb NAS-IP-Address == 10.129.84.1
mb Called-Station-Id == 00-1B-7E-DC-AB-1A:UP-PVIII-I

And in users files:

pruebita  Huntgroup-Name == "mb",Cleartext-Password := "pruebon"

But is not working user pruebita does not get an Access-Accept

Please could you help me to solve it?

Below the debug messages (I've trunked a bit for mail can be sent)

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 = yes
 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 = "testing123"
       response_window = 20
       max_outstanding = 65536
       require_message_authenticator = yes
       zombie_period = 40
       status_check = "status-server"
       ping_interval = 30
       check_interval = 30
       num_answers_to_alive = 3
       num_pings_to_alive = 3
       revive_interval = 120
       status_check_timeout = 4
 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 = "testing123"
       nastype = "other"
 }
 client spectrum {
       require_message_authenticator = no
       secret = "testing123"
       shortname = "spectrum.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.158.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-SS"
 }
 client 10.129.80.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-PB"
 }
 client 10.129.180.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-PB-SUM"
 }
 client 10.128.255.81 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-I"
 }
 client 10.129.81.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-I"
 }
 client 10.128.255.82 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-II"
 }
 client 10.129.82.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-II"
 }
 client 10.128.250.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-Bridge"
 }
 client 10.128.255.172 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-I_bis_test"
 }
 client 10.128.255.83 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-III"
 }
 client 10.129.83.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-III"
 }
 client 10.129.183.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-III"
 }
 client 10.129.84.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-IV"
 }
 client 10.129.184.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-IV-Bis"
 }
 client 10.129.194.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-IV-Bis-2"
 }
 client 10.128.255.181 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-I_bis"
 }
 client 10.129.181.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-I_bis"
 }
 client 10.129.162.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-I_bis_2"
 }
 client 10.128.255.86 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-VI"
 }
 client 10.129.86.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-VI"
 }
 client 10.128.255.87 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-VII"
 }
 client 10.129.87.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-VII"
 }
 client 10.129.187.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-VII-Derecho"
 }
 client 10.129.99.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-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-PVIII-V"
 }
 client 10.129.85.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-V"
 }
 client 10.129.189.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-IX-HD"
 }
 client 10.129.88.1 {
       require_message_authenticator = no
       secret = "sarlangalad0-Red-398952"
       shortname = "AP-PVIII-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-PVIII-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-spectrum-key.pem"
       certificate_file = "/etc/pki/tls/certs/spectrum.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-peap"
       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 = no
 }
 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: 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: 0x1ffc1270
 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: Instantiating module "post_proxy_log" from file
/etc/raddb-testing/modules/detail.log
 detail post_proxy_log {
       detailfile =
"/usr/local-test/var/log/radius/radacct/postproxy/%{Client-IP-Address}/post-proxy-detail-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d"
       header = "%t"
       detailperm = 384
       dirperm = 493
       locking = no
       log_packet_header = no
 }
 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"
 }
}
 ... adding new socket proxy address * port 54388
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 proxy address 192.168.1.5 port 1814
Ready to process requests.

rad_recv: Access-Request packet from host 10.129.89.1 port 38848,
id=74, length=170
       User-Name = "pruebita"
       NAS-Identifier = "AP-PVIII-IX-OF"
       NAS-Port = 3
       Called-Station-Id = "00:1B-7E-DC-AB-1A:sarlanga-I"
       Calling-Station-Id = "E8-3E-B6-26-C3-28"
       Framed-MTU = 1400
       NAS-Port-Type = Wireless-802.11
       Connect-Info = "CONNECT 54Mbps 802.11g"
       EAP-Message = 0x0238000d017072756562697461
       Message-Authenticator = 0x510c83d2d8d716e25fa8e00162124f28
# 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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[auth_log]      expand: %t -> Fri Jun  1 07:43:26 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "pruebita", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 56 length 13
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
++[unix] returns notfound
[files] users: Matched entry pruebita at line 434
++[files] 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/default
+- entering group authenticate {...}
[eap] EAP Identity
[eap] processing type md5
rlm_eap_md5: Issuing Challenge
++[eap] returns handled
Sending Access-Challenge of id 74 to 10.129.89.1 port 38848
       EAP-Message = 0x013900160410381a962cdd81a78cdd529de9d83f2b61
       Message-Authenticator = 0x00000000000000000000000000000000
       State = 0x51c5e19f51fce56b215acf310f5bc900
Finished request 28.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 10.129.89.1 port 38848,
id=75, length=181
       User-Name = "pruebita"
       NAS-Identifier = "AP-PVIII-IX-OF"
       NAS-Port = 3
       Called-Station-Id = "00:1B-7E-DC-AB-1A:sarlanga-I"
       Calling-Station-Id = "E8-3E-B6-26-C3-28"
       Framed-MTU = 1400
       NAS-Port-Type = Wireless-802.11
       Connect-Info = "CONNECT 54Mbps 802.11g"
       EAP-Message = 0x023900060319
       State = 0x51c5e19f51fce56b215acf310f5bc900
       Message-Authenticator = 0x9a66eb7b5a106c018d00e0b57f80f262
# 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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[auth_log]      expand: %t -> Fri Jun  1 07:43:26 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "pruebita", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 57 length 6
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
++[unix] returns notfound
[files] users: Matched entry pruebita at line 434
++[files] 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/default
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP NAK
[eap] EAP-NAK asked for EAP-Type/peap
[eap] processing type tls
[tls] Initiate
[tls] Start returned 1
++[eap] returns handled
Sending Access-Challenge of id 75 to 10.129.89.1 port 38848
       EAP-Message = 0x013a00061920
       Message-Authenticator = 0x00000000000000000000000000000000
       State = 0x51c5e19f50fff86b215acf310f5bc900
Finished request 29.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 10.129.89.1 port 38848,
id=76, length=241
       User-Name = "pruebita"
       NAS-Identifier = "AP-PVIII-IX-OF"
       NAS-Port = 3
       Called-Station-Id = "00:1B-7E-DC-AB-1A:sarlanga-I"
       Calling-Station-Id = "E8-3E-B6-26-C3-28"
       Framed-MTU = 1400
       NAS-Port-Type = Wireless-802.11
       Connect-Info = "CONNECT 54Mbps 802.11g"
       EAP-Message =
0x023a0042190016030100370100003303014fc89ccd9ef24a5acabf352713ed67aad4acfb2a2f0d4c5ad746a761a24f976800000c0013000a0033002f000500040100
       State = 0x51c5e19f50fff86b215acf310f5bc900
       Message-Authenticator = 0xb1b6655fab2a4f02abdddc4928d46870
# 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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[auth_log]      expand: %t -> Fri Jun  1 07:43:26 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "pruebita", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 58 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 76 to 10.129.89.1 port 38848
       EAP-Message =
0x013b040019c000001381160301004a0200004603014fc89cce0c471ae8314022655c37dcbb1fe882ed8735b93cfda7f5029188744220189eb3cfe1bd6b3b6b87fa215018f64ad2b414cda3740799b6d6c814cb3f7700000a0016030113240b00132000131d00071730820713308205fba0030201020214101b7f691cb7a76e4d2434f172207f0d6457bfd6300d06092a864886f70d01010505003081b0310b3009060355040613024553310f300d060355040813064d4144524944310f300d060355040713064d414452494431243022060355040a131b6970732043657274696669636174696f6e20417574686f7269747931183016060355040b130f
       EAP-Message =
0x43657274696669636163696f6e657331193017060355040313106970734341204c6576656c20312043413124302206092a864886f70d010901161569707363616c6576656c314069707363612e636f6d301e170d3132303132373132303731335a170d3134303230313132303731335a30818d310b3009060355040613024152311530130603550407130c4275656e6f73204169726573311f301d060355040a1316556e6976657273696461642064652050616c65726d6f31273025060355040b131e446570617274616d656e746f20646520436f6d756e69636163696f6e6573311d301b06035504031314737065637472756d2e70616c65726d6f2e
       EAP-Message =
0x65647530820122300d06092a864886f70d01010105000382010f003082010a0282010100a8efabc6c785b1314fc7a643f75a6b1e1ae645b4ad47d23a5ce062cd3fa5a998112d48b88960da17628181430eb1e6dbcd806eb3b77ad9ca5728e86d333f9a910107d90c0e2dad1951587fb6ebe0b1f99665fad04e352e1d87dbf1a1292ae3cc8bb936511b10cc25facb79db7df50a443359a5c46213253cfbee9db5a262221e23d6961c6f2e4bff6c7702fe05eeff8c97c3c4558f84d3d61a1571017d5638863ec4f341613113aca1b385ae7598065503f28f78c83c632ebb0183d6d8ffd21edd8cd4db50c1550443f12706847a053769c1c5c7f94eff9fff
       EAP-Message =
0x8c3091d3892db2f35ab46dc208d1e03f37b82fb96d7fba474b42279ecb74157fdc521c038949dd0203010001a38203443082034030090603551d1304023000301106096086480186f8420101040403020640300b0603551d0f0404030205a030130603551d25040c300a06082b06010505070301301d0603551d0e04160414d57dbba6130341755d2138e3ed9faacb3e65cdcb301f0603551d230418301680147d9e6b0efc13e1c48ba77f61ae6021cfcda0d30630090603551d1104023000305b0603551d1204543052a450304e310b300906035504061302455331243022060355040a131b6970732043657274696669636174696f6e20417574686f
       EAP-Message = 0x726974793119301706035504
       Message-Authenticator = 0x00000000000000000000000000000000
       State = 0x51c5e19f53fef86b215acf310f5bc900
Finished request 30.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 10.129.89.1 port 38848,
id=77, length=181
       User-Name = "pruebita"
       NAS-Identifier = "AP-PVIII-IX-OF"
       NAS-Port = 3
       Called-Station-Id = "00:1B-7E-DC-AB-1A:sarlanga-I"
       Calling-Station-Id = "E8-3E-B6-26-C3-28"
       Framed-MTU = 1400
       NAS-Port-Type = Wireless-802.11
       Connect-Info = "CONNECT 54Mbps 802.11g"
       EAP-Message = 0x023b00061900
       State = 0x51c5e19f53fef86b215acf310f5bc900
       Message-Authenticator = 0xa4a26c588cef259c66f231f46e99c8da
# 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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[auth_log]      expand: %t -> Fri Jun  1 07:43:26 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "pruebita", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 59 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 77 to 10.129.89.1 port 38848
       EAP-Message =
0x013c03fc19400313106970734341204c6576656c2031204341307106096086480186f842010d046416624f7267616e697a6174696f6e20496e666f726d6174696f6e204e4f542056414c4944415445442e204c4556454c3120536572766572204365727469666963617465206973737565642062792068747470733a2f2f7777772e69707363612e636f6d2f302906096086480186f8420102041c161a687474703a2f2f6c6576656c3130312e69707363612e636f6d2f303c06096086480186f8420104042f162d687474703a2f2f6c6576656c3130312e69707363612e636f6d2f63726c2f69707363616c6576656c312e63726c3043060960864801
       EAP-Message =
0x86f842010304361634687474703a2f2f6c6576656c3130312e69707363612e636f6d2f63726c2f7265766f636174696f6e4c4556454c312e68746d6c3f304006096086480186f842010704331631687474703a2f2f6c6576656c3130312e69707363612e636f6d2f63726c2f72656e6577616c4c4556454c312e68746d6c3f303e06096086480186f84201080431162f687474703a2f2f6c6576656c3130312e69707363612e636f6d2f63726c2f706f6c6963794c4556454c312e68746d6c30730603551d1f046c306a3033a031a02f862d687474703a2f2f6c6576656c3130312e69707363612e636f6d2f63726c2f69707363616c6576656c312e63
       EAP-Message =
0x726c3033a031a02f862d687474703a2f2f6c6576656c3130322e69707363612e636f6d2f63726c2f69707363616c6576656c312e63726c303e06082b0601050507010104323030302e06082b060105050730018622687474703a2f2f6f6373706c6576656c3130312e69707363612e636f6d2f6f637370300d06092a864886f70d010105050003820101004b61ec4cf7163dad3601e3de9938175ec4ec75d60f658eabf37b1e331283419e7438e584e8cfc47c087c7cba005dcd9d7d165a470bd7aaea70b69b1a18ad2a6cc136cf2ff5cb948ae2866b1c46bbf8ef999ecb510407f28a091e7aa75684d90938f85c3fcd39580112b6979f3284c581f86b
       EAP-Message =
0x998bc211c1de82e87fd57ee7a975905e9dec75020feda29cf4f29dc59dc3946ccf328f7380c6f3f4064d3f6f496fffd640ed2c813c44d648f9ee0143f894d41f4970f30e158064b305a4ae264887a62b5ee51f4f7f3241ddeebcd1153f836262d7c24c4cd7a09f2ab6bc052cd2ecf78e948f240e526538478893fb776f27d44d620c8e58641b4231958c85d758080005f2308205ee308204d6a00302010202141000000000000000000000000000000000000011300d06092a864886f70d01010505003081b2310b3009060355040613024553310f300d060355040813064d6164726964310f300d060355040713064d6164726964312f302d06035504
       EAP-Message = 0x0a13264950532043
       Message-Authenticator = 0x00000000000000000000000000000000
       State = 0x51c5e19f52f9f86b215acf310f5bc900
Finished request 31.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 10.129.89.1 port 38848,
id=78, length=181
       User-Name = "pruebita"
       NAS-Identifier = "AP-PVIII-IX-OF"
       NAS-Port = 3
       Called-Station-Id = "00:1B-7E-DC-AB-1A:sarlanga-I"
       Calling-Station-Id = "E8-3E-B6-26-C3-28"
       Framed-MTU = 1400
       NAS-Port-Type = Wireless-802.11
       Connect-Info = "CONNECT 54Mbps 802.11g"
       EAP-Message = 0x023c00061900
       State = 0x51c5e19f52f9f86b215acf310f5bc900
       Message-Authenticator = 0xf15626a2f70fb625cfbdee8873d99f4f
# 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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[auth_log]      expand: %t -> Fri Jun  1 07:43:26 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "pruebita", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 60 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 78 to 10.129.89.1 port 38848
       EAP-Message =
0x013d03fc1940657274696669636174696f6e20417574686f7269747920732e6c2e206970734341310e300c060355040b13056970734341311d301b06035504031314697073434120476c6f62616c20434120526f6f743121301f06092a864886f70d0109011612676c6f62616c30314069707363612e636f6d301e170d3039313231303131333835325a170d3239313232343131333835325a3081b0310b3009060355040613024553310f300d060355040813064d4144524944310f300d060355040713064d414452494431243022060355040a131b6970732043657274696669636174696f6e20417574686f7269747931183016060355040b130f43
       EAP-Message =
0x657274696669636163696f6e657331193017060355040313106970734341204c6576656c20312043413124302206092a864886f70d010901161569707363616c6576656c314069707363612e636f6d30820122300d06092a864886f70d01010105000382010f003082010a02820101009f4db33023c13a798527825d45ce5b64f82bafe15d6001e2940ed501863645858c0c8e76e1a0a40d935417986aeeb2840b23eabf217cc0ee49c026791954d6311890f7fb970be3a8c51640de2eb88c655b32d953f7c8ff06fb896654f8ea44420fb74fc7b0e94fd1d0bbfa968a33e346b7fa64bc65b11fb1e0e105f80cdd4af58b3097b4480a94feaf1d63f269
       EAP-Message =
0x2c6b1b446afd3bd9dae70df0c68ab6e0190160a309a2b320401b14073a8c22df33f476c185b862b849b975c196ed1108af3c3f4e59e840dc995a134747e3b2840a7e8130b71c969f2e0d1cd0e5845e5bad3cce4a6b7d684522f54d5ae79f5c19004827a5787882d3e2ffb1e680dfeb2a125bb10203010001a38201fa308201f6302a06096086480186f8420102041d161b68747470733a2f2f6c6576656c3130312e69707363612e636f6d2f302106096086480186f84201030414161265737461646f2f65737461646f2e6173703f302706096086480186f8420104041a161865737461646f4341544553542f65737461646f2e6173703f3022060960
       EAP-Message =
0x86480186f84201070415161365737461646f2f72656e756576612e6173703f302006096086480186f84201080413161165737461646f2f706f6c6963792e617370302406096086480186f842010d04171615436572746966696361646f20706f72206970734341301106096086480186f8420101040403020007300c0603551d13040530030101ff301d0603551d0e041604147d9e6b0efc13e1c48ba77f61ae6021cfcda0d306303e0603551d1f043730353033a031a02f862d687474703a2f2f6c6576656c3130312e69707363612e636f6d2f63726c2f69707363616c6576656c312e63726c303a06082b06010505070101042e302c302a06082b06
       EAP-Message = 0x010505073001861e
       Message-Authenticator = 0x00000000000000000000000000000000
       State = 0x51c5e19f55f8f86b215acf310f5bc900
Finished request 32.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 10.129.89.1 port 38848,
id=79, length=181
       User-Name = "pruebita"
       NAS-Identifier = "AP-PVIII-IX-OF"
       NAS-Port = 3
       Called-Station-Id = "00:1B-7E-DC-AB-1A:sarlanga-I"
       Calling-Station-Id = "E8-3E-B6-26-C3-28"
       Framed-MTU = 1400
       NAS-Port-Type = Wireless-802.11
       Connect-Info = "CONNECT 54Mbps 802.11g"
       EAP-Message = 0x023d00061900
       State = 0x51c5e19f55f8f86b215acf310f5bc900
       Message-Authenticator = 0x4ce902814c105901520df2f8036166c3
# 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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[auth_log]      expand: %t -> Fri Jun  1 07:43:26 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "pruebita", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 61 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 79 to 10.129.89.1 port 38848
       EAP-Message =
0x013e03fc1940687474703a2f2f6f6373706c6576656c3130312e69707363612e636f6d2f300b0603551d0f04040302010630470603551d250440303e06082b0601050507030106082b0601050507030206082b0601050507030306082b0601050507030406082b06010505070308060a2b0601040182370a0304300d06092a864886f70d010105050003820101008737bf8f76d31d2eca683935839d2958fe74add252963aff06e84cb0308e3316c5972d29aebe28e77e10ab20e1ed509ecfbbd394fe1da47370d27e6239b31cd636e1d4782bace12bedc753a14dd4281f588e56e2c924f956ec0ad0df5268575ea4393bb9e46619c4cff0a7fbb7717f
       EAP-Message =
0xd7f247a501f8245587996ac61064ada017958080eb6d4bd3e9171685a951ae6e69419776e8de7f43fb891b853397030059549676d110ed837018f76ca7231a20647365211bee7e1e67f8026ad21c2e37a31e7001bb866076bc7adc7eff3475f2e33e94c60ed70ce792cc33c31c32df09e853cf3587c59453f98efa685cce193a482a52f3da46c2e6bd5d02b5471eb9954000060b30820607308204efa003020102020100300d06092a864886f70d01010505003081b2310b3009060355040613024553310f300d060355040813064d6164726964310f300d060355040713064d6164726964312f302d060355040a132649505320436572746966696361
       EAP-Message =
0x74696f6e20417574686f7269747920732e6c2e206970734341310e300c060355040b13056970734341311d301b06035504031314697073434120476c6f62616c20434120526f6f743121301f06092a864886f70d0109011612676c6f62616c30314069707363612e636f6d301e170d3039303930373134333834345a170d3239313232353134333834345a3081b2310b3009060355040613024553310f300d060355040813064d6164726964310f300d060355040713064d6164726964312f302d060355040a13264950532043657274696669636174696f6e20417574686f7269747920732e6c2e206970734341310e300c060355040b130569707343
       EAP-Message =
0x41311d301b06035504031314697073434120476c6f62616c20434120526f6f743121301f06092a864886f70d0109011612676c6f62616c30314069707363612e636f6d30820122300d06092a864886f70d01010105000382010f003082010a0282010100a7efcc8030b091244fb068f8c3ca2d1538555882e23863b0f7a3926f83b8b05eb08cac54b177d050e097b390ad8ab31f392b4556f7aae2df7cb2ec6f532f9acbd0e666cbc913e872e2b4cd31578712b593e8fa72ceea47f28cb4b063d70400b764363997e895f188f9710d03278c61cf0883964f83c54ee85cf80670f102aa1c1ea9c8aa7ee75dcd8d3c146f67d01ba923488b21283a8a4ce6
       EAP-Message = 0x1131f9212eb26766
       Message-Authenticator = 0x00000000000000000000000000000000
       State = 0x51c5e19f54fbf86b215acf310f5bc900
Finished request 33.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 10.129.89.1 port 38848,
id=80, length=181
       User-Name = "pruebita"
       NAS-Identifier = "AP-PVIII-IX-OF"
       NAS-Port = 3
       Called-Station-Id = "00:1B-7E-DC-AB-1A:sarlanga-I"
       Calling-Station-Id = "E8-3E-B6-26-C3-28"
       Framed-MTU = 1400
       NAS-Port-Type = Wireless-802.11
       Connect-Info = "CONNECT 54Mbps 802.11g"
       EAP-Message = 0x023e00061900
       State = 0x51c5e19f54fbf86b215acf310f5bc900
       Message-Authenticator = 0x419616a5e5f69204aaca365aa417d934
# 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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[auth_log]      expand: %t -> Fri Jun  1 07:43:26 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "pruebita", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 62 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 80 to 10.129.89.1 port 38848
       EAP-Message =
0x013f03af1900c6296e9493cf4096fcb03dbfb2b493bf5671b6a54187b058b559232849b898f9501e2d15280b4cac49d184a99b9ae77254b738d0dbc9fea973d56d10cd8e75ebfe97fd803cfcb4d848f499460b8814a4b62edb4c60f421c16c809514d5afd50203010001a382022430820220301d0603551d0e0416041415a69680b1154b31c3c29cf6e7130b4bf318cd863081df0603551d230481d73081d4801415a69680b1154b31c3c29cf6e7130b4bf318cd86a181b8a481b53081b2310b3009060355040613024553310f300d060355040813064d6164726964310f300d060355040713064d6164726964312f302d060355040a13264950532043
       EAP-Message =
0x657274696669636174696f6e20417574686f7269747920732e6c2e206970734341310e300c060355040b13056970734341311d301b06035504031314697073434120476c6f62616c20434120526f6f743121301f06092a864886f70d0109011612676c6f62616c30314069707363612e636f6d820100300c0603551d13040530030101ff300b0603551d0f04040302010630470603551d250440303e06082b0601050507030106082b0601050507030206082b0601050507030306082b0601050507030406082b06010505070308060a2b0601040182370a0304301d0603551d11041630148112676c6f62616c30314069707363612e636f6d301d0603
       EAP-Message =
0x551d12041630148112676c6f62616c30314069707363612e636f6d30410603551d1f043a30383036a034a0328630687474703a2f2f63726c676c6f62616c30312e69707363612e636f6d2f63726c2f63726c676c6f62616c30312e63726c303806082b06010505070101042c302a302806082b06010505073001861c687474703a2f2f63726c676c6f62616c30312e69707363612e636f6d300d06092a864886f70d0101050500038201010018f4aefe800f8ec1776fa25a47489f2355a1536bf95da730a524be432ff8c1d157f93e2c8025cc46a936f3495b1df67cd763b34d3e78f6a7b40277f8790d3e6acb1860b8fd00af0cdd54e3548f223df310
       EAP-Message =
0x6f110db51e7a8d27cc08b85bc3b81a5f2ba7603f001cf70f5c4266649e8712807089e0fa57280e4e1f102fd90580b6802f1c69f0f6b66534056fcad93ef8d45d3732c7b82bccff73930071e001c8aa43bda9f1cefa80f9f1431291a665e560074d47ba2b2f04f64a8529886510c9b253629c6c9b605c1a1bd3aec51d729906ff05cc862673b4d45405dd1e6b003bb789e8e391022012ebefe9fe0a29238123a300da70cc925f3723d01c7b355c037a16030100040e000000
       Message-Authenticator = 0x00000000000000000000000000000000
       State = 0x51c5e19f57faf86b215acf310f5bc900
Finished request 34.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 10.129.89.1 port 38848,
id=81, length=501
       User-Name = "pruebita"
       NAS-Identifier = "AP-PVIII-IX-OF"
       NAS-Port = 3
       Called-Station-Id = "00:1B-7E-DC-AB-1A:sarlanga-I"
       Calling-Station-Id = "E8-3E-B6-26-C3-28"
       Framed-MTU = 1400
       NAS-Port-Type = Wireless-802.11
       Connect-Info = "CONNECT 54Mbps 802.11g"
       EAP-Message =
0x023f014419001603010106100001020100615e16737ca27664e029e9464b6d38d0e96d10e9fa36dcc345d6c2bebc5b46b7f6f57dee94e3445d5addd0c341d0330731effdda2d1300f6163f8f9e639e7f29d6d5e01f75cc38ae18ea18c5db3b29b96255f6928c2a420882655e8cb81ea6c6df4fe2f5d37b0de031b7c81556d94022fb272852e078572cba44e3e8151e9660e6816d10fdba3a51b36e43119417ce6ca34e1d37d659a2f8b1f8bb4248ed807392bf7f5f453cf26f00fbcdfc6ec84f1798737710ade7141b2ecd78d77e6ba428ba468a36405c92a816c5a4868fa9ebb0b5f8a726d4b1ce9892fa2fe554e645d16522c263f3c5f86cd03171d0
       EAP-Message =
0x8053a3c19d3fe2901eac4ee34cc1a51f2d9068191403010001011603010028dc45b3ecdbce94b5a878b480e812ee79a9c17150a92671fa177236cf97bf02bffc1a52c026dbbb2a
       State = 0x51c5e19f57faf86b215acf310f5bc900
       Message-Authenticator = 0xf86876fb7612ab7ff8a644817293b53b
# 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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[auth_log]      expand: %t -> Fri Jun  1 07:43:26 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "pruebita", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 63 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
189eb3cfe1bd6b3b6b87fa215018f64ad2b414cda3740799b6d6c814cb3f7700 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 81 to 10.129.89.1 port 38848
       EAP-Message =
0x0140003919001403010001011603010028120ba7581f67afee3fe93d71202a3b8eef45d44ed59366641e4e901d1d30295d67644c20f0636c95
       Message-Authenticator = 0x00000000000000000000000000000000
       State = 0x51c5e19f5685f86b215acf310f5bc900
Finished request 35.
Going to the next request
Waking up in 4.8 seconds.
rad_recv: Access-Request packet from host 10.129.89.1 port 38848,
id=82, length=181
       User-Name = "pruebita"
       NAS-Identifier = "AP-PVIII-IX-OF"
       NAS-Port = 3
       Called-Station-Id = "00:1B-7E-DC-AB-1A:sarlanga-I"
       Calling-Station-Id = "E8-3E-B6-26-C3-28"
       Framed-MTU = 1400
       NAS-Port-Type = Wireless-802.11
       Connect-Info = "CONNECT 54Mbps 802.11g"
       EAP-Message = 0x024000061900
       State = 0x51c5e19f5685f86b215acf310f5bc900
       Message-Authenticator = 0x9876211434267235d34177c6baada0a0
# 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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[auth_log]      expand: %t -> Fri Jun  1 07:43:26 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "pruebita", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 64 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 82 to 10.129.89.1 port 38848
       EAP-Message =
0x0141002b19001703010020a7bad7bd5b08f24e8165859c7202d96fbb32d601cf63a81969b692422bbce7f0
       Message-Authenticator = 0x00000000000000000000000000000000
       State = 0x51c5e19f5984f86b215acf310f5bc900
Finished request 36.
Going to the next request
Waking up in 4.8 seconds.
rad_recv: Access-Request packet from host 10.129.89.1 port 38848,
id=83, length=247
       User-Name = "pruebita"
       NAS-Identifier = "AP-PVIII-IX-OF"
       NAS-Port = 3
       Called-Station-Id = "00:1B-7E-DC-AB-1A:sarlanga-I"
       Calling-Station-Id = "E8-3E-B6-26-C3-28"
       Framed-MTU = 1400
       NAS-Port-Type = Wireless-802.11
       Connect-Info = "CONNECT 54Mbps 802.11g"
       EAP-Message =
0x02410048190017030100182c80dd585307c00c58051a0ea4faa27e4a5df95720469a7a170301002095ab5282b093ae854c63cc7d3ba67d506c23bddff057c2a98511a4bf854240c9
       State = 0x51c5e19f5984f86b215acf310f5bc900
       Message-Authenticator = 0x39c120e0dca7d430448165505dc8dd6c
# 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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[auth_log]      expand: %t -> Fri Jun  1 07:43:26 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "pruebita", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 65 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 - pruebita
[peap] Got inner identity 'pruebita'
[peap] Setting default EAP type for tunneled EAP session.
[peap] Got tunneled request
       EAP-Message = 0x0241000d017072756562697461
server  {
[peap] Setting User-Name to pruebita
Sending tunneled request
       EAP-Message = 0x0241000d017072756562697461
       FreeRADIUS-Proxied-To = 127.0.0.1
       User-Name = "pruebita"
       NAS-Identifier = "AP-PVIII-IX-OF"
       NAS-Port = 3
       Called-Station-Id = "00:1B-7E-DC-AB-1A:sarlanga-I"
       Calling-Station-Id = "E8-3E-B6-26-C3-28"
       Framed-MTU = 1400
       NAS-Port-Type = Wireless-802.11
       Connect-Info = "CONNECT 54Mbps 802.11g"
       NAS-IP-Address = 10.129.89.1
server inner-tunnel-peap {
# Executing section authorize from file
/etc/raddb-testing/sites-enabled/inner-tunnel-peap
+- entering group authorize {...}
++[chap] returns noop
++[mschap] returns noop
++[unix] returns notfound
[suffix] No '@' in User-Name = "pruebita", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[control] returns noop
[eap] EAP packet type response id 65 length 13
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
++[files] returns noop
[ldap] performing user authorization for pruebita
[ldap]  expand: (uid=%u) -> (uid=pruebita)
[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=pruebita)
 [ldap] object not found
[ldap] search failed
 [ldap] ldap_release_conn: Release Id: 0
++[ldap] returns notfound
++[expiration] returns noop
++[logintime] returns noop
++[pap] returns noop
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/sites-enabled/inner-tunnel-peap
+- entering group authenticate {...}
[eap] EAP Identity
[eap] processing type mschapv2
rlm_eap_mschapv2: Issuing Challenge
++[eap] returns handled
} # server inner-tunnel-peap
[peap] Got tunneled reply code 11
       EAP-Message =
0x014200221a0142001d1044c4caf16d9a7e43d6e3fbad805501237072756562697461
       Message-Authenticator = 0x00000000000000000000000000000000
       State = 0x63fdd7b563bfcd4b0f3934ebfe7a85b9
[peap] Got tunneled reply RADIUS code 11
       EAP-Message =
0x014200221a0142001d1044c4caf16d9a7e43d6e3fbad805501237072756562697461
       Message-Authenticator = 0x00000000000000000000000000000000
       State = 0x63fdd7b563bfcd4b0f3934ebfe7a85b9
[peap] Got tunneled Access-Challenge
++[eap] returns handled
Sending Access-Challenge of id 83 to 10.129.89.1 port 38848
       EAP-Message =
0x0142004319001703010038359eaf0224c732f9e83935d0da09d2f6b169503605cb8311ba6d9e3485d51cb6a25ddfc336d6bae781180ce0d413562af3bdcfde5180c80f
       Message-Authenticator = 0x00000000000000000000000000000000
       State = 0x51c5e19f5887f86b215acf310f5bc900
Finished request 37.
Going to the next request
Waking up in 4.8 seconds.
rad_recv: Access-Request packet from host 10.129.89.1 port 38848,
id=84, length=303
       User-Name = "pruebita"
       NAS-Identifier = "AP-PVIII-IX-OF"
       NAS-Port = 3
       Called-Station-Id = "00:1B-7E-DC-AB-1A:sarlanga-I"
       Calling-Station-Id = "E8-3E-B6-26-C3-28"
       Framed-MTU = 1400
       NAS-Port-Type = Wireless-802.11
       Connect-Info = "CONNECT 54Mbps 802.11g"
       EAP-Message =
0x0242008019001703010018f3e980063f7cfb058900cf00b2760b697de7aba67e199b4f17030100588febd03280df614d10106a127d9ce496c4fccaad9681a12dd4f8d4641a2e337486c608e136ed1cbeac8af33318136b2dfa9b9a952cb7b61837ad54a90d1330537628ee52fde4e6af685b3d573bbbf04388bbaeecb4ea8e17
       State = 0x51c5e19f5887f86b215acf310f5bc900
       Message-Authenticator = 0x0c1ae63b5d7e03e33800ad87364eed83
# 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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[auth_log]      expand: %t -> Fri Jun  1 07:43:26 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "pruebita", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 66 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 =
0x024200431a0242003e314a6d7e7fe98db69bbc4fc8cceb9dfb8d0000000000000000cedcbce414bab2e5f0f7952223f1b126947ce80dd6929378007072756562697461
server  {
[peap] Setting User-Name to pruebita
Sending tunneled request
       EAP-Message =
0x024200431a0242003e314a6d7e7fe98db69bbc4fc8cceb9dfb8d0000000000000000cedcbce414bab2e5f0f7952223f1b126947ce80dd6929378007072756562697461
       FreeRADIUS-Proxied-To = 127.0.0.1
       User-Name = "pruebita"
       State = 0x63fdd7b563bfcd4b0f3934ebfe7a85b9
       NAS-Identifier = "AP-PVIII-IX-OF"
       NAS-Port = 3
       Called-Station-Id = "00:1B-7E-DC-AB-1A:sarlanga-I"
       Calling-Station-Id = "E8-3E-B6-26-C3-28"
       Framed-MTU = 1400
       NAS-Port-Type = Wireless-802.11
       Connect-Info = "CONNECT 54Mbps 802.11g"
       NAS-IP-Address = 10.129.89.1
server inner-tunnel-peap {
# Executing section authorize from file
/etc/raddb-testing/sites-enabled/inner-tunnel-peap
+- entering group authorize {...}
++[chap] returns noop
++[mschap] returns noop
++[unix] returns notfound
[suffix] No '@' in User-Name = "pruebita", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[control] returns noop
[eap] EAP packet type response id 66 length 67
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
++[files] returns noop
[ldap] performing user authorization for pruebita
[ldap]  expand: (uid=%u) -> (uid=pruebita)
[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=pruebita)
 [ldap] object not found
[ldap] search failed
 [ldap] ldap_release_conn: Release Id: 0
++[ldap] returns notfound
++[expiration] returns noop
++[logintime] returns noop
++[pap] returns noop
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/sites-enabled/inner-tunnel-peap
+- 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-peap
[mschapv2] +- entering group MS-CHAP {...}
[mschap] No Cleartext-Password configured.  Cannot create LM-Password.
[mschap] No Cleartext-Password configured.  Cannot create NT-Password.
[mschap] Creating challenge hash with username: pruebita
[mschap] Told to do MS-CHAPv2 for pruebita with NT-Password
[mschap] FAILED: No NT/LM-Password.  Cannot perform authentication.
[mschap] FAILED: MS-CHAP2-Response is incorrect
++[mschap] returns reject
MSCHAP Failure
++[eap] returns handled
} # server inner-tunnel-peap
[peap] Got tunneled reply code 11
       EAP-Message = 0x014300121a0442000d453d36393120523d31
       Message-Authenticator = 0x00000000000000000000000000000000
       State = 0x63fdd7b562becd4b0f3934ebfe7a85b9
[peap] Got tunneled reply RADIUS code 11
       EAP-Message = 0x014300121a0442000d453d36393120523d31
       Message-Authenticator = 0x00000000000000000000000000000000
       State = 0x63fdd7b562becd4b0f3934ebfe7a85b9
[peap] Got tunneled Access-Challenge
++[eap] returns handled
Sending Access-Challenge of id 84 to 10.129.89.1 port 38848
       EAP-Message =
0x014300331900170301002888636a6445053d18018a230fd21f9959466fd90802b7210f8b788a09a4494fc64d1f7b6ae050f021
       Message-Authenticator = 0x00000000000000000000000000000000
       State = 0x51c5e19f5b86f86b215acf310f5bc900
Finished request 38.
Going to the next request
Waking up in 4.8 seconds.
rad_recv: Access-Request packet from host 10.129.89.1 port 38848,
id=85, length=239
       User-Name = "pruebita"
       NAS-Identifier = "AP-PVIII-IX-OF"
       NAS-Port = 3
       Called-Station-Id = "00:1B-7E-DC-AB-1A:sarlanga-I"
       Calling-Station-Id = "E8-3E-B6-26-C3-28"
       Framed-MTU = 1400
       NAS-Port-Type = Wireless-802.11
       Connect-Info = "CONNECT 54Mbps 802.11g"
       EAP-Message =
0x0243004019001703010018e16554d32843bdba9b707d3e41f21023c12b34e85e7f7df517030100186d29c7588683f31175ad338f971a6426cac9ffe6b6eaa912
       State = 0x51c5e19f5b86f86b215acf310f5bc900
       Message-Authenticator = 0x10df06a435d2fc959d36eb7ecb637292
# 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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[auth_log]      expand: %t -> Fri Jun  1 07:43:26 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "pruebita", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 67 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 = 0x024300061a04
server  {
[peap] Setting User-Name to pruebita
Sending tunneled request
       EAP-Message = 0x024300061a04
       FreeRADIUS-Proxied-To = 127.0.0.1
       User-Name = "pruebita"
       State = 0x63fdd7b562becd4b0f3934ebfe7a85b9
       NAS-Identifier = "AP-PVIII-IX-OF"
       NAS-Port = 3
       Called-Station-Id = "00:1B-7E-DC-AB-1A:sarlanga-I"
       Calling-Station-Id = "E8-3E-B6-26-C3-28"
       Framed-MTU = 1400
       NAS-Port-Type = Wireless-802.11
       Connect-Info = "CONNECT 54Mbps 802.11g"
       NAS-IP-Address = 10.129.89.1
server inner-tunnel-peap {
# Executing section authorize from file
/etc/raddb-testing/sites-enabled/inner-tunnel-peap
+- entering group authorize {...}
++[chap] returns noop
++[mschap] returns noop
++[unix] returns notfound
[suffix] No '@' in User-Name = "pruebita", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[control] returns noop
[eap] EAP packet type response id 67 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 pruebita
[ldap]  expand: (uid=%u) -> (uid=pruebita)
[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=pruebita)
 [ldap] object not found
[ldap] search failed
 [ldap] ldap_release_conn: Release Id: 0
++[ldap] returns notfound
++[expiration] returns noop
++[logintime] returns noop
++[pap] returns noop
Found Auth-Type = EAP
# Executing group from file /etc/raddb-testing/sites-enabled/inner-tunnel-peap
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/mschapv2
[eap] processing type mschapv2
[eap] Freeing handler
++[eap] returns reject
Failed to authenticate the user.
Login incorrect (  [ldap] User not found): [pruebita] (from client
oficina-test port 3 cli E8-3E-B6-26-C3-28 via TLS tunnel)
} # server inner-tunnel-peap
[peap] Got tunneled reply code 3
       EAP-Message = 0x04430004
       Message-Authenticator = 0x00000000000000000000000000000000
[peap] Got tunneled reply RADIUS code 3
       EAP-Message = 0x04430004
       Message-Authenticator = 0x00000000000000000000000000000000
[peap] Tunneled authentication was rejected.
[peap] FAILURE
++[eap] returns handled
Sending Access-Challenge of id 85 to 10.129.89.1 port 38848
       EAP-Message =
0x0144002b1900170301002083387441294d575a9779c1b19e136da15935afcd9f001a1d2e4b5b87e236d164
       Message-Authenticator = 0x00000000000000000000000000000000
       State = 0x51c5e19f5a81f86b215acf310f5bc900
Finished request 39.
Going to the next request
Waking up in 4.8 seconds.
rad_recv: Access-Request packet from host 10.129.89.1 port 38848,
id=86, length=247
       User-Name = "pruebita"
       NAS-Identifier = "AP-PVIII-IX-OF"
       NAS-Port = 3
       Called-Station-Id = "00:1B-7E-DC-AB-1A:sarlanga-I"
       Calling-Station-Id = "E8-3E-B6-26-C3-28"
       Framed-MTU = 1400
       NAS-Port-Type = Wireless-802.11
       Connect-Info = "CONNECT 54Mbps 802.11g"
       EAP-Message =
0x024400481900170301001854ac58595af316bc6d8484be6039a67508204d0ecaea270a1703010020b983c87ab4edfd3bc018184ba6271d50216bc96f1f430e19808d9e1a4ec9180a
       State = 0x51c5e19f5a81f86b215acf310f5bc900
       Message-Authenticator = 0xae08495c38c1461cb67d946cdf22ceae
# 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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[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.89.1/auth-detail-AP-PVIII-IX-OF-DEFAULT-20120601
[auth_log]      expand: %t -> Fri Jun  1 07:43:26 2012
++[auth_log] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "pruebita", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] EAP packet type response id 68 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 failure
[peap] Received EAP-TLV response.
[peap]  The users session was previously rejected: returning reject (again.)
[peap]  *** This means you need to read the PREVIOUS messages in the
debug output
[peap]  *** to find out the reason why the user was rejected.
[peap]  *** Look for "reject" or "fail".  Those earlier messages will tell you.
[peap]  *** what went wrong, and how to fix the problem.
 SSL: Removing session
189eb3cfe1bd6b3b6b87fa215018f64ad2b414cda3740799b6d6c814cb3f7700 from
the cache
[eap] Handler failed in EAP/peap
[eap] Failed in EAP select
++[eap] returns invalid
Failed to authenticate the user.
Login incorrect: [pruebita] (from client oficina-test port 3 cli
E8-3E-B6-26-C3-28)
Using Post-Auth-Type Reject
# Executing group from file /etc/raddb-testing/sites-enabled/default
+- entering group REJECT {...}
[attr_filter.access_reject]     expand: %{User-Name} -> pruebita
attr_filter: Matched entry DEFAULT at line 11
++[attr_filter.access_reject] returns updated
Delaying reject of request 40 for 1 seconds
Going to the next request
Waking up in 0.9 seconds.

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

Sergio Belkin

Alan DeKok

Sergio Belkin

Alan DeKok

Sergio Belkin

Alan DeKok

Matthew Newton

Sergio Belkin

Matthew Newton

Sergio Belkin

Alan DeKok

Sergio Belkin

Matthew Newton

Sergio Belkin

Matthew Newton

tags

participants (3)