iPad PEAP MSCHAPv2

19 Nov 2015

      Good afternoon,

As I told on a previous email, we are migrating previous radius (2.1.9)
authentication to a new instance of freeradius (3.0.10).

I am experiencing some problems authenticating some devices.

I have previously tested with eapol_test that the new freeradius is working
ok with both:
- EAP TTLS - PAP
- PEAP - MSCHAPv2

But when testing PEAP on some devices, it is not working properly.

It looks I am getting authorized properly, and even authenticated:
(11)     [mschap] = ok
(11)   } # Auth-Type MS-CHAP = ok
(11) MSCHAP Success

But I am not getting a final Access-Accept (nor an Access-Reject), and it
the authentication fails....

Any idea where could be the problem?

Thanks a lot!

Copyright (C) 1999-2015 The FreeRADIUS server project and contributors
There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A
PARTICULAR PURPOSE
You may redistribute copies of FreeRADIUS under the terms of the
GNU General Public License
For more information about these matters, see the file named COPYRIGHT
Starting - reading configuration files ...
including dictionary file
/usr/local/etc2/freeradius/share/freeradius/dictionary
including dictionary file
/usr/local/etc2/freeradius/share/freeradius/dictionary.dhcp
including dictionary file
/usr/local/etc2/freeradius/share/freeradius/dictionary.vqp
including dictionary file /usr/local/etc2/freeradius/etc/raddb/dictionary
including configuration file
/usr/local/etc2/freeradius/etc/raddb/radiusd.conf
including configuration file /usr/local/etc2/freeradius/etc/raddb/proxy.conf
including configuration file
/usr/local/etc2/freeradius/etc/raddb/clients.conf
including files in directory
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/attr_filter
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/cache_eap
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/chap
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/detail
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/detail.log
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/digest
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/dhcp
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/dynamic_clients
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/eap
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/echo
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/exec
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/expiration
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/expr
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/files
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/linelog
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/logintime
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/mschap
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/ntlm_auth
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/pap
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/passwd
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/preprocess
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/radutmp
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/realm
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/replicate
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/soh
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/sradutmp
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/unix
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/unpack
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/utf8
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/ldap
including configuration file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/ippool
including files in directory /usr/local/etc2/freeradius/etc/raddb/policy.d/
including configuration file
/usr/local/etc2/freeradius/etc/raddb/policy.d/abfab-tr
including configuration file
/usr/local/etc2/freeradius/etc/raddb/policy.d/accounting
including configuration file
/usr/local/etc2/freeradius/etc/raddb/policy.d/canonicalization
including configuration file
/usr/local/etc2/freeradius/etc/raddb/policy.d/control
including configuration file
/usr/local/etc2/freeradius/etc/raddb/policy.d/cui
including configuration file
/usr/local/etc2/freeradius/etc/raddb/policy.d/debug
including configuration file
/usr/local/etc2/freeradius/etc/raddb/policy.d/dhcp
including configuration file
/usr/local/etc2/freeradius/etc/raddb/policy.d/eap
including configuration file
/usr/local/etc2/freeradius/etc/raddb/policy.d/filter
including configuration file
/usr/local/etc2/freeradius/etc/raddb/policy.d/operator-name
including configuration file
/usr/local/etc2/freeradius/etc/raddb/policy.d/unav.policies
including files in directory
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/
including configuration file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
including configuration file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/inner-tunnel
including configuration file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/status
main {
 security {
     user = "radius"
     group = "radius"
     allow_core_dumps = no
 }
    name = "radiusd"
    prefix = "/usr/local/etc2/freeradius"
    localstatedir = "/usr/local/etc2/freeradius/var"
    logdir = "/usr/local/etc2/freeradius/var/log/radius"
    run_dir = "/usr/local/etc2/freeradius/var/run/radiusd"
}
main {
    name = "radiusd"
    prefix = "/usr/local/etc2/freeradius"
    localstatedir = "/usr/local/etc2/freeradius/var"
    sbindir = "/usr/local/etc2/freeradius/sbin"
    logdir = "/usr/local/etc2/freeradius/var/log/radius"
    run_dir = "/usr/local/etc2/freeradius/var/run/radiusd"
    libdir = "/usr/local/etc2/freeradius/lib"
    radacctdir = "/usr/local/etc2/freeradius/var/log/radius/radacct"
    hostname_lookups = no
    max_request_time = 30
    cleanup_delay = 5
    max_requests = 16384
    pidfile = "/usr/local/etc2/freeradius/var/run/radiusd/radiusd.pid"
    checkrad = "/usr/local/etc2/freeradius/sbin/checkrad"
    debug_level = 0
    proxy_requests = yes
 log {
     stripped_names = no
     auth = yes
     auth_badpass = no
     auth_goodpass = no
     colourise = yes
     msg_denied = "You are already logged in - access denied"
 }
 resources {
 }
 security {
     max_attributes = 200
     reject_delay = 1.000000
     status_server = yes
     allow_vulnerable_openssl = "CVE-2014-0160"
 }
}
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 = <<< secret >>>
     response_window = 20.000000
     response_timeouts = 1
     max_outstanding = 65536
     zombie_period = 40
     status_check = "status-server"
     ping_interval = 30
     check_interval = 30
     check_timeout = 4
     num_answers_to_alive = 3
     revive_interval = 120
  limit {
      max_connections = 16
      max_requests = 0
      lifetime = 0
      idle_timeout = 0
  }
  coa {
      irt = 2
      mrt = 16
      mrc = 5
      mrd = 30
  }
 }
 home_server radiusproxy1 {
     ipaddr = 159.23xxxx
     port = 1812
     type = "auth+acct"
     secret = <<< secret >>>
     response_window = 20.000000
     response_timeouts = 1
     max_outstanding = 65536
     zombie_period = 40
     status_check = "status-server"
     ping_interval = 30
     check_interval = 30
     check_timeout = 4
     num_answers_to_alive = 3
     revive_interval = 60
  limit {
      max_connections = 16
      max_requests = 0
      lifetime = 0
      idle_timeout = 0
  }
  coa {
      irt = 2
      mrt = 16
      mrc = 5
      mrd = 30
  }
 }
 home_server radiusproxy2 {
     ipaddr = 159.237xxx
     port = 1812
     type = "auth+acct"
     secret = <<< secret >>>
     response_window = 20.000000
     response_timeouts = 1
     max_outstanding = 65536
     zombie_period = 40
     status_check = "status-server"
     ping_interval = 30
     check_interval = 30
     check_timeout = 4
     num_answers_to_alive = 3
     revive_interval = 60
  limit {
      max_connections = 16
      max_requests = 0
      lifetime = 0
      idle_timeout = 0
  }
  coa {
      irt = 2
      mrt = 16
      mrc = 5
      mrd = 30
  }
 }
 realm ~^(.*\.)?unav\.es$ {
 }
 realm UNAV-PDC-01 {
 }
 realm LOCAL {
 }
 realm NULL {
 }
 home_server_pool radiusproxy_pool {
    type = fail-over
    home_server = xxx
    home_server = xxx
 }
 realm ~.+$ {
    pool = radiusproxy_pool
    nostrip
 }
 home_server_pool my_auth_failover {
    type = fail-over
    home_server = localhost
 }
radiusd: #### Loading Clients ####
 client localhost {
     ipaddr = 127.0.0.1
     require_message_authenticator = no
     secret = <<< secret >>>
     nas_type = "other"
     proto = "*"
  limit {
      max_connections = 16
      lifetime = 0
      idle_timeout = 30
  }
 }
 client localhost_ipv6 {
     ipv6addr = ::1
     require_message_authenticator = no
     secret = <<< secret >>>
  limit {
      max_connections = 16
      lifetime = 0
      idle_timeout = 30
  }
 }
 client WirelessSW_Dere {
     ipaddr = xxx
     netmask = 32
     require_message_authenticator = no
     secret = <<< secret >>>
     shortname = "WirelessSW"
  limit {
      max_connections = 16
      lifetime = 0
      idle_timeout = 30
  }
 }
 client WirelessSW_PRE {
     ipaddr = xxx
     netmask = 32
     require_message_authenticator = no
     secret = <<< secret >>>
     shortname = "WirelessSW"
  limit {
      max_connections = 16
      lifetime = 0
      idle_timeout = 30
  }
 }

Debugger not attached
 # Creating Auth-Type = digest
 # Creating Autz-Type = Status-Server
radiusd: #### Instantiating modules ####
  # Loaded module rlm_always
  # Loading module "reject" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
  always reject {
      rcode = "reject"
      simulcount = 0
      mpp = no
  }
  # Loading module "fail" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
  always fail {
      rcode = "fail"
      simulcount = 0
      mpp = no
  }
  # Loading module "ok" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
  always ok {
      rcode = "ok"
      simulcount = 0
      mpp = no
  }
  # Loading module "handled" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
  always handled {
      rcode = "handled"
      simulcount = 0
      mpp = no
  }
  # Loading module "invalid" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
  always invalid {
      rcode = "invalid"
      simulcount = 0
      mpp = no
  }
  # Loading module "userlock" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
  always userlock {
      rcode = "userlock"
      simulcount = 0
      mpp = no
  }
  # Loading module "notfound" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
  always notfound {
      rcode = "notfound"
      simulcount = 0
      mpp = no
  }
  # Loading module "noop" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
  always noop {
      rcode = "noop"
      simulcount = 0
      mpp = no
  }
  # Loading module "updated" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
  always updated {
      rcode = "updated"
      simulcount = 0
      mpp = no
  }
  # Loaded module rlm_attr_filter
  # Loading module "attr_filter.post-proxy" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/attr_filter
  attr_filter attr_filter.post-proxy {
      filename =
"/usr/local/etc2/freeradius/etc/raddb/mods-config/attr_filter/post-proxy"
      key = "%{Realm}"
      relaxed = no
  }
  # Loading module "attr_filter.pre-proxy" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/attr_filter
  attr_filter attr_filter.pre-proxy {
      filename =
"/usr/local/etc2/freeradius/etc/raddb/mods-config/attr_filter/pre-proxy"
      key = "%{Realm}"
      relaxed = no
  }
  # Loading module "attr_filter.access_reject" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/attr_filter
  attr_filter attr_filter.access_reject {
      filename =
"/usr/local/etc2/freeradius/etc/raddb/mods-config/attr_filter/access_reject"
      key = "%{User-Name}"
      relaxed = no
  }
  # Loading module "attr_filter.access_challenge" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/attr_filter
  attr_filter attr_filter.access_challenge {
      filename =
"/usr/local/etc2/freeradius/etc/raddb/mods-config/attr_filter/access_challenge"
      key = "%{User-Name}"
      relaxed = no
  }
  # Loading module "attr_filter.accounting_response" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/attr_filter
  attr_filter attr_filter.accounting_response {
      filename =
"/usr/local/etc2/freeradius/etc/raddb/mods-config/attr_filter/accounting_response"
      key = "%{User-Name}"
      relaxed = no
  }
  # Loaded module rlm_cache
  # Loading module "cache_eap" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/cache_eap
  cache cache_eap {
      driver = "rlm_cache_rbtree"
      key = "%{%{control:State}:-%{%{reply:State}:-%{State}}}"
      ttl = 15
      max_entries = 0
      epoch = 0
      add_stats = no
  }
  # Loaded module rlm_chap
  # Loading module "chap" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/chap
  # Loaded module rlm_detail
  # Loading module "detail" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/detail
  detail {
      filename =
"/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d"
      header = "%t"
      permissions = 384
      locking = no
      escape_filenames = no
      log_packet_header = no
  }
  # Loading module "auth_log" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/detail.log
  detail auth_log {
      filename =
"/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d"
      header = "%t"
      permissions = 384
      locking = no
      escape_filenames = no
      log_packet_header = no
  }
  # Loading module "reply_log" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/detail.log
  detail reply_log {
      filename =
"/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/reply-detail-%Y%m%d"
      header = "%t"
      permissions = 384
      locking = no
      escape_filenames = no
      log_packet_header = no
  }
  # Loading module "pre_proxy_log" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/detail.log
  detail pre_proxy_log {
      filename =
"/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/pre-proxy-detail-%Y%m%d"
      header = "%t"
      permissions = 384
      locking = no
      escape_filenames = no
      log_packet_header = no
  }
  # Loading module "post_proxy_log" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/detail.log
  detail post_proxy_log {
      filename =
"/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/post-proxy-detail-%Y%m%d"
      header = "%t"
      permissions = 384
      locking = no
      escape_filenames = no
      log_packet_header = no
  }
  # Loaded module rlm_digest
  # Loading module "digest" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/digest
  # Loaded module rlm_dhcp
  # Loading module "dhcp" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/dhcp
  # Loaded module rlm_dynamic_clients
  # Loading module "dynamic_clients" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/dynamic_clients
  # Loaded module rlm_eap
  # Loading module "eap" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/eap
  eap {
      default_eap_type = "md5"
      timer_expire = 60
      ignore_unknown_eap_types = no
      cisco_accounting_username_bug = no
      max_sessions = 16384
  }
  # Loaded module rlm_exec
  # Loading module "echo" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/echo
  exec echo {
      wait = yes
      program = "/bin/echo %{User-Name}"
      input_pairs = "request"
      output_pairs = "reply"
      shell_escape = yes
  }
  # Loading module "exec" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/exec
  exec {
      wait = no
      input_pairs = "request"
      shell_escape = yes
      timeout = 10
  }
  # Loaded module rlm_expiration
  # Loading module "expiration" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/expiration
  # Loaded module rlm_expr
  # Loading module "expr" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/expr
  expr {
      safe_characters =
"@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_:
/äéöüàâæçèéêëîïôœùûüaÿÄÉÖÜßÀÂÆÇÈÉÊËÎÏÔŒÙÛÜŸ"
  }
  # Loaded module rlm_files
  # Loading module "files" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/files
  files {
      filename =
"/usr/local/etc2/freeradius/etc/raddb/mods-config/files/authorize"
      acctusersfile =
"/usr/local/etc2/freeradius/etc/raddb/mods-config/files/accounting"
      preproxy_usersfile =
"/usr/local/etc2/freeradius/etc/raddb/mods-config/files/pre-proxy"
  }
  # Loaded module rlm_linelog
  # Loading module "linelog" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/linelog
  linelog {
      filename = "/usr/local/etc2/freeradius/var/log/radius/linelog"
      escape_filenames = no
      syslog_severity = "info"
      permissions = 384
      format = "This is a log message for %{User-Name}"
      reference = "messages.%{%{reply:Packet-Type}:-default}"
  }
  # Loading module "log_accounting" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/linelog
  linelog log_accounting {
      filename =
"/usr/local/etc2/freeradius/var/log/radius/linelog-accounting"
      escape_filenames = no
      syslog_severity = "info"
      permissions = 384
      format = ""
      reference = "Accounting-Request.%{%{Acct-Status-Type}:-unknown}"
  }
  # Loaded module rlm_logintime
  # Loading module "logintime" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/logintime
  logintime {
      minimum_timeout = 60
  }
  # Loaded module rlm_mschap
  # Loading module "mschap" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/mschap
  mschap {
      use_mppe = yes
      require_encryption = no
      require_strong = no
      with_ntdomain_hack = yes
      ntlm_auth = "/usr/bin/ntlm_auth --request-nt-key
--username=%{%{Stripped-User-Name}:-%{%{User-Name}:-None}}
--challenge=%{%{mschap:Challenge}:-00}
--nt-response=%{%{mschap:NT-Response}:-00}"
   passchange {
   }
      allow_retry = yes
  }
  # Loading module "ntlm_auth" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/ntlm_auth
  exec ntlm_auth {
      wait = yes
      program = "/path/to/ntlm_auth --request-nt-key --domain=MYDOMAIN
--username=%{mschap:User-Name} --password=%{User-Password}"
      shell_escape = yes
  }
  # Loaded module rlm_pap
  # Loading module "pap" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/pap
  pap {
      normalise = yes
  }
  # Loaded module rlm_passwd
  # Loading module "etc_passwd" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/passwd
  passwd etc_passwd {
      filename = "/etc/passwd"
      format = "*User-Name:Crypt-Password:"
      delimiter = ":"
      ignore_nislike = no
      ignore_empty = yes
      allow_multiple_keys = no
      hash_size = 100
  }
  # Loaded module rlm_preprocess
  # Loading module "preprocess" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/preprocess
  preprocess {
      huntgroups =
"/usr/local/etc2/freeradius/etc/raddb/mods-config/preprocess/huntgroups"
      hints =
"/usr/local/etc2/freeradius/etc/raddb/mods-config/preprocess/hints"
      with_ascend_hack = no
      ascend_channels_per_line = 23
      with_ntdomain_hack = no
      with_specialix_jetstream_hack = no
      with_cisco_vsa_hack = no
      with_alvarion_vsa_hack = no
  }
  # Loaded module rlm_radutmp
  # Loading module "radutmp" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/radutmp
  radutmp {
      filename = "/usr/local/etc2/freeradius/var/log/radius/radutmp"
      username = "%{User-Name}"
      case_sensitive = yes
      check_with_nas = yes
      permissions = 384
      caller_id = yes
  }
  # Loaded module rlm_realm
  # Loading module "IPASS" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/realm
  realm IPASS {
      format = "prefix"
      delimiter = "/"
      ignore_default = no
      ignore_null = no
  }
  # Loading module "suffix" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/realm
  realm suffix {
      format = "suffix"
      delimiter = "@"
      ignore_default = no
      ignore_null = yes
  }
  # Loading module "realmpercent" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/realm
  realm realmpercent {
      format = "suffix"
      delimiter = "%"
      ignore_default = no
      ignore_null = no
  }
  # Loading module "ntdomain" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/realm
  realm ntdomain {
      format = "prefix"
      delimiter = "\\"
      ignore_default = no
      ignore_null = no
  }
  # Loaded module rlm_replicate
  # Loading module "replicate" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/replicate
  # Loaded module rlm_soh
  # Loading module "soh" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/soh
  soh {
      dhcp = yes
  }
  # Loading module "sradutmp" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/sradutmp
  radutmp sradutmp {
      filename = "/usr/local/etc2/freeradius/var/log/radius/sradutmp"
      username = "%{User-Name}"
      case_sensitive = yes
      check_with_nas = yes
      permissions = 420
      caller_id = no
  }
  # Loaded module rlm_unix
  # Loading module "unix" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/unix
  unix {
      radwtmp = "/usr/local/etc2/freeradius/var/log/radius/radwtmp"
  }
Creating attribute Unix-Group
  # Loaded module rlm_unpack
  # Loading module "unpack" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/unpack
  # Loaded module rlm_utf8
  # Loading module "utf8" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/utf8
  # Loaded module rlm_ldap
  # Loading module "ldap" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/ldap
  ldap {
      server = "ldap.unav.es"
      identity = "cn=radiusOp,dc=unav,dc=es"
      password = <<< secret >>>
   sasl {
   }
   user {
       scope = "sub"
       access_positive = yes
    sasl {
    }
   }
   group {
       filter = "(objectClass=posixGroup)"
       scope = "sub"
       name_attribute = "cn"
       membership_attribute = "member"
       cacheable_name = no
       cacheable_dn = no
   }
   client {
       filter = "(objectClass=radiusClient)"
       scope = "sub"
       base_dn = "ou=radius,o=accounts,dc=unav,dc=es"
   }
   profile {
   }
   options {
       ldap_debug = 40
       chase_referrals = yes
       rebind = yes
       net_timeout = 1
       res_timeout = 10
       srv_timelimit = 3
       idle = 60
       probes = 3
       interval = 3
   }
   tls {
       start_tls = yes
   }
  }
Creating attribute LDAP-Group
  # Loaded module rlm_ippool
  # Loading module "VPN_IPSEC_CUN" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/ippool
  ippool VPN_IPSEC_CUN {
      filename =
"/usr/local/etc2/freeradius/etc/raddb/db.ippool.VPN.IPSEC.CUN"
      ip_index =
"/usr/local/etc2/freeradius/etc/raddb/db.ipindex.VPN.IPSEC.CUN"
      key = "%{Acct-Session-Id}"
      range_start = xxx
      range_stop = xxx
      netmask = 255.255.255.0
      cache_size = 255
      override = no
      maximum_timeout = 0
  }
 instantiate {
 }
 modules {
  # Instantiating module "reject" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
  # Instantiating module "fail" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
  # Instantiating module "ok" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
  # Instantiating module "handled" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
  # Instantiating module "invalid" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
  # Instantiating module "userlock" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
  # Instantiating module "notfound" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
  # Instantiating module "noop" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
  # Instantiating module "updated" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/always
  # Instantiating module "attr_filter.post-proxy" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/attr_filter
reading pairlist file
/usr/local/etc2/freeradius/etc/raddb/mods-config/attr_filter/post-proxy
  # Instantiating module "attr_filter.pre-proxy" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/attr_filter
reading pairlist file
/usr/local/etc2/freeradius/etc/raddb/mods-config/attr_filter/pre-proxy
  # Instantiating module "attr_filter.access_reject" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/attr_filter
reading pairlist file
/usr/local/etc2/freeradius/etc/raddb/mods-config/attr_filter/access_reject
  # Instantiating module "attr_filter.access_challenge" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/attr_filter
reading pairlist file
/usr/local/etc2/freeradius/etc/raddb/mods-config/attr_filter/access_challenge
  # Instantiating module "attr_filter.accounting_response" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/attr_filter
reading pairlist file
/usr/local/etc2/freeradius/etc/raddb/mods-config/attr_filter/accounting_response
  # Instantiating module "cache_eap" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/cache_eap
rlm_cache (cache_eap): Driver rlm_cache_rbtree (module rlm_cache_rbtree)
loaded and linked
  # Instantiating module "detail" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/detail
  # Instantiating module "auth_log" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/detail.log
rlm_detail (auth_log): 'User-Password' suppressed, will not appear in
detail output
  # Instantiating module "reply_log" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/detail.log
  # Instantiating module "pre_proxy_log" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/detail.log
  # Instantiating module "post_proxy_log" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/detail.log
  # Instantiating module "eap" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/eap
   # Linked to sub-module rlm_eap_md5
   # Linked to sub-module rlm_eap_leap
   # Linked to sub-module rlm_eap_gtc
   gtc {
       challenge = "Password: "
       auth_type = "PAP"
   }
   # Linked to sub-module rlm_eap_tls
   tls {
       tls = "tls-common"
   }
   tls-config tls-common {
       rsa_key_exchange = no
       dh_key_exchange = yes
       rsa_key_length = 512
       dh_key_length = 512
       verify_depth = 0
       ca_path = "/usr/local/etc2/freeradius/etc/raddb/certs"
       pem_file_type = yes
       private_key_file =
"/usr/local/etc2/freeradius/etc/raddb/certs/radius.unav.es_privatekey.pem"
       certificate_file =
"/usr/local/etc2/freeradius/etc/raddb/certs/radius.unav.es_cert.pem"
       ca_file =
"/usr/local/etc2/freeradius/etc/raddb/certs/TERENASSL_PATH.pem"
       dh_file = "/usr/local/etc2/freeradius/etc/raddb/certs/dh"
       fragment_size = 1024
       include_length = yes
       check_crl = no
       check_all_crl = no
       cipher_list = "DEFAULT"
       ecdh_curve = "prime256v1"
    cache {
        enable = yes
        lifetime = 24
        max_entries = 255
    }
    verify {
    }
    ocsp {
        enable = no
        override_cert_url = yes
        url = "http://127.0.0.1/ocsp/"
        use_nonce = yes
        timeout = 0
        softfail = no
    }
   }
   # Linked to sub-module rlm_eap_ttls
   ttls {
       tls = "tls-common"
       default_eap_type = "md5"
       copy_request_to_tunnel = yes
       use_tunneled_reply = no
       virtual_server = "inner-tunnel"
       include_length = yes
       require_client_cert = no
   }
tls: Using cached TLS configuration from previous invocation
   # Linked to sub-module rlm_eap_peap
   peap {
       tls = "tls-common"
       default_eap_type = "mschapv2"
       copy_request_to_tunnel = yes
       use_tunneled_reply = no
       proxy_tunneled_request_as_eap = yes
       virtual_server = "inner-tunnel"
       soh = no
       require_client_cert = no
   }
tls: Using cached TLS configuration from previous invocation
   # Linked to sub-module rlm_eap_mschapv2
   mschapv2 {
       with_ntdomain_hack = no
       send_error = no
   }
  # Instantiating module "expiration" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/expiration
  # Instantiating module "files" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/files
reading pairlist file
/usr/local/etc2/freeradius/etc/raddb/mods-config/files/authorize
reading pairlist file
/usr/local/etc2/freeradius/etc/raddb/mods-config/files/users.vpn
reading pairlist file
/usr/local/etc2/freeradius/etc/raddb/mods-config/files/accounting
reading pairlist file
/usr/local/etc2/freeradius/etc/raddb/mods-config/files/pre-proxy
  # Instantiating module "linelog" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/linelog
  # Instantiating module "log_accounting" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/linelog
  # Instantiating module "logintime" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/logintime
  # Instantiating module "mschap" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/mschap
rlm_mschap (mschap): authenticating by calling 'ntlm_auth'
  # Instantiating module "pap" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/pap
  # Instantiating module "etc_passwd" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/passwd
rlm_passwd: nfields: 3 keyfield 0(User-Name) listable: no
  # Instantiating module "preprocess" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/preprocess
reading pairlist file
/usr/local/etc2/freeradius/etc/raddb/mods-config/preprocess/huntgroups
reading pairlist file
/usr/local/etc2/freeradius/etc/raddb/mods-config/preprocess/hints
  # Instantiating module "IPASS" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/realm
  # Instantiating module "suffix" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/realm
  # Instantiating module "realmpercent" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/realm
  # Instantiating module "ntdomain" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/realm
  # Instantiating module "ldap" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/ldap
rlm_ldap: libldap vendor: OpenLDAP, version: 20439
   accounting {
       reference = "%{tolower:type.%{Acct-Status-Type}}"
   }
   post-auth {
       reference = "."
   }
rlm_ldap (ldap): Initialising connection pool
   pool {
       start = 5
       min = 3
       max = 32
       spare = 10
       uses = 0
       lifetime = 0
       cleanup_interval = 30
       idle_timeout = 60
       retry_delay = 30
       spread = no
   }
rlm_ldap (ldap): Opening additional connection (0), 1 of 32 pending slots
used
rlm_ldap (ldap): Connecting to ldap://ldap.unav.es:389
rlm_ldap (ldap): Waiting for bind result...
rlm_ldap (ldap): Bind successful
rlm_ldap (ldap): Opening additional connection (1), 1 of 31 pending slots
used
rlm_ldap (ldap): Connecting to ldap://ldap.unav.es:389
rlm_ldap (ldap): Waiting for bind result...
rlm_ldap (ldap): Bind successful
rlm_ldap (ldap): Opening additional connection (2), 1 of 30 pending slots
used
rlm_ldap (ldap): Connecting to ldap://ldap.unav.es:389
rlm_ldap (ldap): Waiting for bind result...
rlm_ldap (ldap): Bind successful
rlm_ldap (ldap): Opening additional connection (3), 1 of 29 pending slots
used
rlm_ldap (ldap): Connecting to ldap://ldap.unav.es:389
rlm_ldap (ldap): Waiting for bind result...
rlm_ldap (ldap): Bind successful
rlm_ldap (ldap): Opening additional connection (4), 1 of 28 pending slots
used
rlm_ldap (ldap): Connecting to ldap://ldap.unav.es:389
rlm_ldap (ldap): Waiting for bind result...
rlm_ldap (ldap): Bind successful
  # Instantiating module "VPN_IPSEC_CUN" from file
/usr/local/etc2/freeradius/etc/raddb/mods-enabled/ippool
 } # modules
radiusd: #### Loading Virtual Servers ####
server { # from file /usr/local/etc2/freeradius/etc/raddb/radiusd.conf
} # server
server default { # from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
 # Loading authenticate {...}
 # Loading authorize {...}
 # Loading preacct {...}
 # Loading accounting {...}
 # Loading session {...}
 # Loading pre-proxy {...}
 # Loading post-proxy {...}
 # Loading post-auth {...}
} # server default
server inner-tunnel { # from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/inner-tunnel
 # Loading authenticate {...}
 # Loading authorize {...}
 # Loading session {...}
 # Loading post-proxy {...}
 # Loading post-auth {...}
} # server inner-tunnel
server status { # from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/status
 # Loading authorize {...}
} # server status
radiusd: #### Opening IP addresses and Ports ####
listen {
      type = "auth"
      ipaddr = *
      port = 0
   limit {
       max_connections = 16
       lifetime = 0
       idle_timeout = 30
   }
}
listen {
      type = "acct"
      ipaddr = *
      port = 0
   limit {
       max_connections = 16
       lifetime = 0
       idle_timeout = 30
   }
}
listen {
      type = "auth"
      ipv6addr = ::
      port = 0
   limit {
       max_connections = 16
       lifetime = 0
       idle_timeout = 30
   }
}
listen {
      type = "acct"
      ipv6addr = ::
      port = 0
   limit {
       max_connections = 16
       lifetime = 0
       idle_timeout = 30
   }
}
listen {
      type = "auth"
      ipaddr = 127.0.0.1
      port = 18120
}
listen {
      type = "status"
      ipaddr = 127.0.0.1
      port = 18121
  client admin {
      ipaddr = 127.0.0.1
      require_message_authenticator = no
      secret = <<< secret >>>
   limit {
       max_connections = 16
       lifetime = 0
       idle_timeout = 30
   }
  }
}
Listening on auth address * port 1812 bound to server default
Listening on acct address * port 1813 bound to server default
Listening on auth address :: port 1812 bound to server default
Listening on acct address :: port 1813 bound to server default
Listening on auth address 127.0.0.1 port 18120 bound to server inner-tunnel
Listening on status address 127.0.0.1 port 18121 bound to server status
Listening on proxy address * port 48633
Listening on proxy address :: port 36571
Ready to process requests
(0) Received Access-Request Id 32 from xxxxxx:45903 to xxx12.225:1812
length 215
(0)   User-Name = "oscarrdg@unav.es"
(0)   NAS-IP-Address = xxx
(0)   NAS-Port = 0
(0)   NAS-Identifier = "xxx
(0)   NAS-Port-Type = Wireless-802.11
(0)   Calling-Station-Id = "344DF73C9C98"
(0)   Called-Station-Id = "001A1E003250"
(0)   Service-Type = Framed-User
(0)   Framed-MTU = 1100
(0)   EAP-Message = 0x02010015016f7363617272646740756e61762e6573
(0)   Aruba-Essid-Name = "eduroam-pre"
(0)   Aruba-Location-Id = "10.9.1"
(0)   Aruba-AP-Group = "Amigos"
(0)   Aruba-Device-Type = "Android"
(0)   Message-Authenticator = 0xaf370dcdcf5551f50dbe03c00a1b1a67
(0) # Executing section authorize from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(0)   authorize {
(0)     policy filter_username {
(0)       if (!&User-Name) {
(0)       if (!&User-Name)  -> FALSE
(0)       if (&User-Name =~ / /) {
(0)       if (&User-Name =~ / /)  -> FALSE
(0)       if (&User-Name =~ /@.*@/ ) {
(0)       if (&User-Name =~ /@.*@/ )  -> FALSE
(0)       if (&User-Name =~ /\.\./ ) {
(0)       if (&User-Name =~ /\.\./ )  -> FALSE
(0)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))  {
(0)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))   ->
FALSE
(0)       if (&User-Name =~ /\.$/)  {
(0)       if (&User-Name =~ /\.$/)   -> FALSE
(0)       if (&User-Name =~ /@\./)  {
(0)       if (&User-Name =~ /@\./)   -> FALSE
(0)     } # policy filter_username = notfound
(0)     [preprocess] = ok
(0) auth_log: EXPAND
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
(0) auth_log:    -->
/usr/local/etc2/freeradius/var/log/radius/radacct/xxx/auth-detail-20151119
(0) auth_log:
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
expands to
/usr/local/etc2/freeradius/var/log/radius/radacct/xxx/auth-detail-20151119
(0) auth_log: EXPAND %t
(0) auth_log:    --> Thu Nov 19 16:13:11 2015
(0)     [auth_log] = ok
(0)     [chap] = noop
(0)     [mschap] = noop
(0)     [digest] = noop
(0) suffix: Checking for suffix after "@"
(0) suffix: Looking up realm "unav.es" for User-Name = "oscarrdg@unav.es"
(0) suffix: Found realm "~^(.*\.)?unav\.es$"
(0) suffix: Adding Stripped-User-Name = "oscarrdg"
(0) suffix: Adding Realm = "unav.es"
(0) suffix: Authentication realm is LOCAL
(0)     [suffix] = ok
(0) ntdomain: Request already has destination realm set.  Ignoring
(0)     [ntdomain] = noop
(0) eap: Peer sent EAP Response (code 2) ID 1 length 21
(0) eap: EAP-Identity reply, returning 'ok' so we can short-circuit the
rest of authorize
(0)     [eap] = ok
(0)   } # authorize = ok
(0) Found Auth-Type = EAP
(0) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(0)   authenticate {
(0) eap: Peer sent packet with method EAP Identity (1)
(0) eap: Calling submodule eap_md5 to process data
(0) eap_md5: Issuing MD5 Challenge
(0) eap: Sending EAP Request (code 1) ID 2 length 22
(0) eap: EAP session adding &reply:State = 0x4a7c50d94a7e547d
(0)     [eap] = handled
(0)   } # authenticate = handled
(0) Using Post-Auth-Type Challenge
(0) Post-Auth-Type sub-section not found.  Ignoring.
(0) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(0) Sent Access-Challenge Id 32 from xxx:1812 to xxx:45903 length 0
(0)   EAP-Message = 0x0102001604106a56e58dbbd858bd44f49a49b03bb1d7
(0)   Message-Authenticator = 0x00000000000000000000000000000000
(0)   State = 0x4a7c50d94a7e547df1395bb8ee5037b2
(0) Finished request
Waking up in 4.9 seconds.
(1) Received Access-Request Id 237 from xxxxxx:45903 to xx.225:1812 length
218
(1)   User-Name = "oscarrdg@unav.es"
(1)   NAS-IP-Address = xxxxxx
(1)   NAS-Port = 0
(1)   NAS-Identifier = "xxxxxx"
(1)   NAS-Port-Type = Wireless-802.11
(1)   Calling-Station-Id = "344DF73C9C98"
(1)   Called-Station-Id = "001A1E003250"
(1)   Service-Type = Framed-User
(1)   Framed-MTU = 1100
(1)   EAP-Message = 0x020200060319
(1)   State = 0x4a7c50d94a7e547df1395bb8ee5037b2
(1)   Aruba-Essid-Name = "eduroam-pre"
(1)   Aruba-Location-Id = "10.9.1"
(1)   Aruba-AP-Group = "Amigos"
(1)   Aruba-Device-Type = "Android"
(1)   Message-Authenticator = 0x84aefd176e8b40d09dbe4a527366c8b7
(1) session-state: No cached attributes
(1) # Executing section authorize from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(1)   authorize {
(1)     policy filter_username {
(1)       if (!&User-Name) {
(1)       if (!&User-Name)  -> FALSE
(1)       if (&User-Name =~ / /) {
(1)       if (&User-Name =~ / /)  -> FALSE
(1)       if (&User-Name =~ /@.*@/ ) {
(1)       if (&User-Name =~ /@.*@/ )  -> FALSE
(1)       if (&User-Name =~ /\.\./ ) {
(1)       if (&User-Name =~ /\.\./ )  -> FALSE
(1)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))  {
(1)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))   ->
FALSE
(1)       if (&User-Name =~ /\.$/)  {
(1)       if (&User-Name =~ /\.$/)   -> FALSE
(1)       if (&User-Name =~ /@\./)  {
(1)       if (&User-Name =~ /@\./)   -> FALSE
(1)     } # policy filter_username = notfound
(1)     [preprocess] = ok
(1) auth_log: EXPAND
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
(1) auth_log:    -->
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(1) auth_log:
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
expands to
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(1) auth_log: EXPAND %t
(1) auth_log:    --> Thu Nov 19 16:13:11 2015
(1)     [auth_log] = ok
(1)     [chap] = noop
(1)     [mschap] = noop
(1)     [digest] = noop
(1) suffix: Checking for suffix after "@"
(1) suffix: Looking up realm "unav.es" for User-Name = "oscarrdg@unav.es"
(1) suffix: Found realm "~^(.*\.)?unav\.es$"
(1) suffix: Adding Stripped-User-Name = "oscarrdg"
(1) suffix: Adding Realm = "unav.es"
(1) suffix: Authentication realm is LOCAL
(1)     [suffix] = ok
(1) ntdomain: Request already has destination realm set.  Ignoring
(1)     [ntdomain] = noop
(1) eap: Peer sent EAP Response (code 2) ID 2 length 6
(1) eap: No EAP Start, assuming it's an on-going EAP conversation
(1)     [eap] = updated
(1)     policy unav.authorize.default {
(1)       if ( "%{client:group}" == "wireless" ) {
(1)       EXPAND %{client:group}
(1)          --> wireless
(1)       if ( "%{client:group}" == "wireless" )  -> TRUE
(1)       if ( "%{client:group}" == "wireless" )  {
(1)         if ( Aruba-Essid-Name == "eduroam" || Aruba-Essid-Name ==
"eduroam-pre" ) {
(1)         if ( Aruba-Essid-Name == "eduroam" || Aruba-Essid-Name ==
"eduroam-pre" )  -> TRUE
(1)         if ( Aruba-Essid-Name == "eduroam" || Aruba-Essid-Name ==
"eduroam-pre" )  {
(1)           policy unav.reject.eduroam.baddomain {
(1)             if ( Realm =~ /myabc\.com$/i ) {
(1)             if ( Realm =~ /myabc\.com$/i )  -> FALSE
(1)             if ( Realm =~
/wlan\.[[:alnum:]]+\.[[:alnum:]]+\.3gppnetwork\.org$/i ) {
(1)             if ( Realm =~
/wlan\.[[:alnum:]]+\.[[:alnum:]]+\.3gppnetwork\.org$/i )  -> FALSE
(1)             if ( Realm =~ /gmail\.co(m|\.[[:alnum:]][[:alnum:]])$/i ) {
(1)             if ( Realm =~ /gmail\.co(m|\.[[:alnum:]][[:alnum:]])$/i )
-> FALSE
(1)             if ( Realm =~ /yahoo\.co(m|\.[[:alnum:]][[:alnum:]])$/i ) {
(1)             if ( Realm =~ /yahoo\.co(m|\.[[:alnum:]][[:alnum:]])$/i )
-> FALSE
(1)             if ( Realm =~ /hotmail\.co(m|\.[[:alnum:]][[:alnum:]])$/i )
{
(1)             if ( Realm =~ /hotmail\.co(m|\.[[:alnum:]][[:alnum:]])$/i
)  -> FALSE
(1)           } # policy unav.reject.eduroam.baddomain = updated
(1)           if ( Service-Type == "Login-User" ) {
(1)           if ( Service-Type == "Login-User" )  -> FALSE
(1)         } # if ( Aruba-Essid-Name == "eduroam" || Aruba-Essid-Name ==
"eduroam-pre" )  = updated
(1)         ... skipping elsif for request 1: Preceding "if" was taken
(1)         ... skipping else for request 1: Preceding "if" was taken
(1)       } # if ( "%{client:group}" == "wireless" )  = updated
(1)       ... skipping elsif for request 1: Preceding "if" was taken
(1)       ... skipping elsif for request 1: Preceding "if" was taken
(1)       ... skipping elsif for request 1: Preceding "if" was taken
(1)       ... skipping elsif for request 1: Preceding "if" was taken
(1)       ... skipping elsif for request 1: Preceding "if" was taken
(1)       ... skipping else for request 1: Preceding "if" was taken
(1)     } # policy unav.authorize.default = updated
(1)     [expiration] = noop
(1)     [logintime] = noop
(1) pap: WARNING: No "known good" password found for the user.  Not setting
Auth-Type
(1) pap: WARNING: Authentication will fail unless a "known good" password
is available
(1)     [pap] = noop
(1)     if (control:Cleartext-Password) {
(1)     if (control:Cleartext-Password)  -> FALSE
(1)   } # authorize = updated
(1) Found Auth-Type = EAP
(1) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(1)   authenticate {
(1) eap: Expiring EAP session with state 0x4a7c50d94a7e547d
(1) eap: Finished EAP session with state 0x4a7c50d94a7e547d
(1) eap: Previous EAP request found for state 0x4a7c50d94a7e547d, released
from the list
(1) eap: Peer sent packet with method EAP NAK (3)
(1) eap: Found mutually acceptable type PEAP (25)
(1) eap: Calling submodule eap_peap to process data
(1) eap_peap: Initiating new EAP-TLS session
(1) eap_peap: Flushing SSL sessions (of #0)
(1) eap_peap: [eaptls start] = request
(1) eap: Sending EAP Request (code 1) ID 3 length 6
(1) eap: EAP session adding &reply:State = 0x4a7c50d94b7f497d
(1)     [eap] = handled
(1)   } # authenticate = handled
(1) Using Post-Auth-Type Challenge
(1) Post-Auth-Type sub-section not found.  Ignoring.
(1) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(1) Sent Access-Challenge Id 237 from xxx12.225:1812 to xxxxxx:45903 length
0
(1)   EAP-Message = 0x010300061920
(1)   Message-Authenticator = 0x00000000000000000000000000000000
(1)   State = 0x4a7c50d94b7f497df1395bb8ee5037b2
(1) Finished request
Waking up in 4.9 seconds.
(2) Received Access-Request Id 126 from xxxxxx:45903 to xxx12.225:1812
length 380
(2)   User-Name = "oscarrdg@unav.es"
(2)   NAS-IP-Address = xxxxxx
(2)   NAS-Port = 0
(2)   NAS-Identifier = "xxxxxx"
(2)   NAS-Port-Type = Wireless-802.11
(2)   Calling-Station-Id = "344DF73C9C98"
(2)   Called-Station-Id = "001A1E003250"
(2)   Service-Type = Framed-User
(2)   Framed-MTU = 1100
(2)   EAP-Message =
0x020300a819800000009e160301009901000095030389bc40fcda2b3a01d0bd76bc2cd77cc2a31e6d59aa10c82aba3cd714a628ceca00003cc02cc030009fc02bc02f009ec00ac024c014c0280039006bc009c023c013c02700330067c007c011009d009c0035003d002f003c00050004000a00ff010000
(2)   State = 0x4a7c50d94b7f497df1395bb8ee5037b2
(2)   Aruba-Essid-Name = "eduroam-pre"
(2)   Aruba-Location-Id = "10.9.1"
(2)   Aruba-AP-Group = "Amigos"
(2)   Aruba-Device-Type = "Android"
(2)   Message-Authenticator = 0x5a70fd8f3ee58367fba8226efb8fca04
(2) session-state: No cached attributes
(2) # Executing section authorize from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(2)   authorize {
(2)     policy filter_username {
(2)       if (!&User-Name) {
(2)       if (!&User-Name)  -> FALSE
(2)       if (&User-Name =~ / /) {
(2)       if (&User-Name =~ / /)  -> FALSE
(2)       if (&User-Name =~ /@.*@/ ) {
(2)       if (&User-Name =~ /@.*@/ )  -> FALSE
(2)       if (&User-Name =~ /\.\./ ) {
(2)       if (&User-Name =~ /\.\./ )  -> FALSE
(2)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))  {
(2)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))   ->
FALSE
(2)       if (&User-Name =~ /\.$/)  {
(2)       if (&User-Name =~ /\.$/)   -> FALSE
(2)       if (&User-Name =~ /@\./)  {
(2)       if (&User-Name =~ /@\./)   -> FALSE
(2)     } # policy filter_username = notfound
(2)     [preprocess] = ok
(2) auth_log: EXPAND
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
(2) auth_log:    -->
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(2) auth_log:
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
expands to
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(2) auth_log: EXPAND %t
(2) auth_log:    --> Thu Nov 19 16:13:11 2015
(2)     [auth_log] = ok
(2)     [chap] = noop
(2)     [mschap] = noop
(2)     [digest] = noop
(2) suffix: Checking for suffix after "@"
(2) suffix: Looking up realm "unav.es" for User-Name = "oscarrdg@unav.es"
(2) suffix: Found realm "~^(.*\.)?unav\.es$"
(2) suffix: Adding Stripped-User-Name = "oscarrdg"
(2) suffix: Adding Realm = "unav.es"
(2) suffix: Authentication realm is LOCAL
(2)     [suffix] = ok
(2) ntdomain: Request already has destination realm set.  Ignoring
(2)     [ntdomain] = noop
(2) eap: Peer sent EAP Response (code 2) ID 3 length 168
(2) eap: Continuing tunnel setup
(2)     [eap] = ok
(2)   } # authorize = ok
(2) Found Auth-Type = EAP
(2) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(2)   authenticate {
(2) eap: Expiring EAP session with state 0x4a7c50d94b7f497d
(2) eap: Finished EAP session with state 0x4a7c50d94b7f497d
(2) eap: Previous EAP request found for state 0x4a7c50d94b7f497d, released
from the list
(2) eap: Peer sent packet with method EAP PEAP (25)
(2) eap: Calling submodule eap_peap to process data
(2) eap_peap: Continuing EAP-TLS
(2) eap_peap: Peer indicated complete TLS record size will be 158 bytes
(2) eap_peap: Got complete TLS record (158 bytes)
(2) eap_peap: [eaptls verify] = length included
(2) eap_peap: (other): before/accept initialization
(2) eap_peap: TLS_accept: before/accept initialization
(2) eap_peap: <<< TLS 1.2  [length 0099]
(2) eap_peap: TLS_accept: SSLv3 read client hello A
(2) eap_peap: >>> TLS 1.2  [length 0059]
(2) eap_peap: TLS_accept: SSLv3 write server hello A
(2) eap_peap: >>> TLS 1.2  [length 11ce]
(2) eap_peap: TLS_accept: SSLv3 write certificate A
(2) eap_peap: >>> TLS 1.2  [length 014d]
(2) eap_peap: TLS_accept: SSLv3 write key exchange A
(2) eap_peap: >>> TLS 1.2  [length 0004]
(2) eap_peap: TLS_accept: SSLv3 write server done A
(2) eap_peap: TLS_accept: SSLv3 flush data
(2) eap_peap: TLS_accept: Need to read more data: SSLv3 read client
certificate A
(2) eap_peap: TLS_accept: Need to read more data: SSLv3 read client
certificate A
(2) eap_peap: In SSL Handshake Phase
(2) eap_peap: In SSL Accept mode
(2) eap_peap: [eaptls process] = handled
(2) eap: Sending EAP Request (code 1) ID 4 length 1004
(2) eap: EAP session adding &reply:State = 0x4a7c50d94878497d
(2)     [eap] = handled
(2)   } # authenticate = handled
(2) Using Post-Auth-Type Challenge
(2) Post-Auth-Type sub-section not found.  Ignoring.
(2) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(2) Sent Access-Challenge Id 126 from xxx12.225:1812 to xxxxxx:45903 length
0
(2)   EAP-Message =
0x010403ec19c00000138c1603030059020000550303564de7077094b72f12c56237fbc9affbe100f088a0685fec0f9239b56ae77d0620e7b0567766cb019801cfbfed0facaa4f74a4e4e1bf48c6e2807b179ab3eab127c03000000dff01000100000b00040300010216030311ce0b0011ca0011c70004a5
(2)   Message-Authenticator = 0x00000000000000000000000000000000
(2)   State = 0x4a7c50d94878497df1395bb8ee5037b2
(2) Finished request
Waking up in 4.9 seconds.
(3) Received Access-Request Id 64 from xxxxxx:45903 to xxx12.225:1812
length 218
(3)   User-Name = "oscarrdg@unav.es"
(3)   NAS-IP-Address = xxxxxx
(3)   NAS-Port = 0
(3)   NAS-Identifier = "xxxxxx"
(3)   NAS-Port-Type = Wireless-802.11
(3)   Calling-Station-Id = "344DF73C9C98"
(3)   Called-Station-Id = "001A1E003250"
(3)   Service-Type = Framed-User
(3)   Framed-MTU = 1100
(3)   EAP-Message = 0x020400061900
(3)   State = 0x4a7c50d94878497df1395bb8ee5037b2
(3)   Aruba-Essid-Name = "eduroam-pre"
(3)   Aruba-Location-Id = "10.9.1"
(3)   Aruba-AP-Group = "Amigos"
(3)   Aruba-Device-Type = "Android"
(3)   Message-Authenticator = 0x3aec9ff6dcbdf02f48d43f32e48157e4
(3) session-state: No cached attributes
(3) # Executing section authorize from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(3)   authorize {
(3)     policy filter_username {
(3)       if (!&User-Name) {
(3)       if (!&User-Name)  -> FALSE
(3)       if (&User-Name =~ / /) {
(3)       if (&User-Name =~ / /)  -> FALSE
(3)       if (&User-Name =~ /@.*@/ ) {
(3)       if (&User-Name =~ /@.*@/ )  -> FALSE
(3)       if (&User-Name =~ /\.\./ ) {
(3)       if (&User-Name =~ /\.\./ )  -> FALSE
(3)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))  {
(3)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))   ->
FALSE
(3)       if (&User-Name =~ /\.$/)  {
(3)       if (&User-Name =~ /\.$/)   -> FALSE
(3)       if (&User-Name =~ /@\./)  {
(3)       if (&User-Name =~ /@\./)   -> FALSE
(3)     } # policy filter_username = notfound
(3)     [preprocess] = ok
(3) auth_log: EXPAND
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
(3) auth_log:    -->
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(3) auth_log:
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
expands to
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(3) auth_log: EXPAND %t
(3) auth_log:    --> Thu Nov 19 16:13:11 2015
(3)     [auth_log] = ok
(3)     [chap] = noop
(3)     [mschap] = noop
(3)     [digest] = noop
(3) suffix: Checking for suffix after "@"
(3) suffix: Looking up realm "unav.es" for User-Name = "oscarrdg@unav.es"
(3) suffix: Found realm "~^(.*\.)?unav\.es$"
(3) suffix: Adding Stripped-User-Name = "oscarrdg"
(3) suffix: Adding Realm = "unav.es"
(3) suffix: Authentication realm is LOCAL
(3)     [suffix] = ok
(3) ntdomain: Request already has destination realm set.  Ignoring
(3)     [ntdomain] = noop
(3) eap: Peer sent EAP Response (code 2) ID 4 length 6
(3) eap: Continuing tunnel setup
(3)     [eap] = ok
(3)   } # authorize = ok
(3) Found Auth-Type = EAP
(3) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(3)   authenticate {
(3) eap: Expiring EAP session with state 0x4a7c50d94878497d
(3) eap: Finished EAP session with state 0x4a7c50d94878497d
(3) eap: Previous EAP request found for state 0x4a7c50d94878497d, released
from the list
(3) eap: Peer sent packet with method EAP PEAP (25)
(3) eap: Calling submodule eap_peap to process data
(3) eap_peap: Continuing EAP-TLS
(3) eap_peap: Peer ACKed our handshake fragment
(3) eap_peap: [eaptls verify] = request
(3) eap_peap: [eaptls process] = handled
(3) eap: Sending EAP Request (code 1) ID 5 length 1000
(3) eap: EAP session adding &reply:State = 0x4a7c50d94979497d
(3)     [eap] = handled
(3)   } # authenticate = handled
(3) Using Post-Auth-Type Challenge
(3) Post-Auth-Type sub-section not found.  Ignoring.
(3) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(3) Sent Access-Challenge Id 64 from xxx12.225:1812 to xxxxxx:45903 length 0
(3)   EAP-Message =
0x010503e819402e73692e756e61762e6573820f7261646975732e756e61762e656475300d06092a864886f70d010105050003820101008d5a00339dca417d4b5b7c98c5d05763925d58ea76ff6431c64313da556a5d759f867846b256881352385407721c3b81539755438573a805ecb0acdf0a94a9f874
(3)   Message-Authenticator = 0x00000000000000000000000000000000
(3)   State = 0x4a7c50d94979497df1395bb8ee5037b2
(3) Finished request
Waking up in 4.9 seconds.
(4) Received Access-Request Id 234 from xxxxxx:45903 to xxx12.225:1812
length 218
(4)   User-Name = "oscarrdg@unav.es"
(4)   NAS-IP-Address = xxxxxx
(4)   NAS-Port = 0
(4)   NAS-Identifier = "xxxxxx"
(4)   NAS-Port-Type = Wireless-802.11
(4)   Calling-Station-Id = "344DF73C9C98"
(4)   Called-Station-Id = "001A1E003250"
(4)   Service-Type = Framed-User
(4)   Framed-MTU = 1100
(4)   EAP-Message = 0x020500061900
(4)   State = 0x4a7c50d94979497df1395bb8ee5037b2
(4)   Aruba-Essid-Name = "eduroam-pre"
(4)   Aruba-Location-Id = "10.9.1"
(4)   Aruba-AP-Group = "Amigos"
(4)   Aruba-Device-Type = "Android"
(4)   Message-Authenticator = 0x8a034f3911b75ff877975ad4bec02ff4
(4) session-state: No cached attributes
(4) # Executing section authorize from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(4)   authorize {
(4)     policy filter_username {
(4)       if (!&User-Name) {
(4)       if (!&User-Name)  -> FALSE
(4)       if (&User-Name =~ / /) {
(4)       if (&User-Name =~ / /)  -> FALSE
(4)       if (&User-Name =~ /@.*@/ ) {
(4)       if (&User-Name =~ /@.*@/ )  -> FALSE
(4)       if (&User-Name =~ /\.\./ ) {
(4)       if (&User-Name =~ /\.\./ )  -> FALSE
(4)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))  {
(4)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))   ->
FALSE
(4)       if (&User-Name =~ /\.$/)  {
(4)       if (&User-Name =~ /\.$/)   -> FALSE
(4)       if (&User-Name =~ /@\./)  {
(4)       if (&User-Name =~ /@\./)   -> FALSE
(4)     } # policy filter_username = notfound
(4)     [preprocess] = ok
(4) auth_log: EXPAND
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
(4) auth_log:    -->
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(4) auth_log:
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
expands to
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(4) auth_log: EXPAND %t
(4) auth_log:    --> Thu Nov 19 16:13:11 2015
(4)     [auth_log] = ok
(4)     [chap] = noop
(4)     [mschap] = noop
(4)     [digest] = noop
(4) suffix: Checking for suffix after "@"
(4) suffix: Looking up realm "unav.es" for User-Name = "oscarrdg@unav.es"
(4) suffix: Found realm "~^(.*\.)?unav\.es$"
(4) suffix: Adding Stripped-User-Name = "oscarrdg"
(4) suffix: Adding Realm = "unav.es"
(4) suffix: Authentication realm is LOCAL
(4)     [suffix] = ok
(4) ntdomain: Request already has destination realm set.  Ignoring
(4)     [ntdomain] = noop
(4) eap: Peer sent EAP Response (code 2) ID 5 length 6
(4) eap: Continuing tunnel setup
(4)     [eap] = ok
(4)   } # authorize = ok
(4) Found Auth-Type = EAP
(4) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(4)   authenticate {
(4) eap: Expiring EAP session with state 0x4a7c50d94979497d
(4) eap: Finished EAP session with state 0x4a7c50d94979497d
(4) eap: Previous EAP request found for state 0x4a7c50d94979497d, released
from the list
(4) eap: Peer sent packet with method EAP PEAP (25)
(4) eap: Calling submodule eap_peap to process data
(4) eap_peap: Continuing EAP-TLS
(4) eap_peap: Peer ACKed our handshake fragment
(4) eap_peap: [eaptls verify] = request
(4) eap_peap: [eaptls process] = handled
(4) eap: Sending EAP Request (code 1) ID 6 length 1000
(4) eap: EAP session adding &reply:State = 0x4a7c50d94e7a497d
(4)     [eap] = handled
(4)   } # authenticate = handled
(4) Using Post-Auth-Type Challenge
(4) Post-Auth-Type sub-section not found.  Ignoring.
(4) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(4) Sent Access-Challenge Id 234 from xxx12.225:1812 to xxxxxx:45903 length
0
(4)   EAP-Message =
0x010603e8194002010030180603551d200411300f300d060b2b06010401b2310102021d30440603551d1f043d303b3039a037a0358633687474703a2f2f63726c2e7573657274727573742e636f6d2f55544e2d5553455246697273742d48617264776172652e63726c307406082b060105050701010468
(4)   Message-Authenticator = 0x00000000000000000000000000000000
(4)   State = 0x4a7c50d94e7a497df1395bb8ee5037b2
(4) Finished request
Waking up in 4.9 seconds.
(5) Received Access-Request Id 230 from xxxxxx:45903 to xxx12.225:1812
length 218
(5)   User-Name = "oscarrdg@unav.es"
(5)   NAS-IP-Address = xxxxxx
(5)   NAS-Port = 0
(5)   NAS-Identifier = "xxxxxx"
(5)   NAS-Port-Type = Wireless-802.11
(5)   Calling-Station-Id = "344DF73C9C98"
(5)   Called-Station-Id = "001A1E003250"
(5)   Service-Type = Framed-User
(5)   Framed-MTU = 1100
(5)   EAP-Message = 0x020600061900
(5)   State = 0x4a7c50d94e7a497df1395bb8ee5037b2
(5)   Aruba-Essid-Name = "eduroam-pre"
(5)   Aruba-Location-Id = "10.9.1"
(5)   Aruba-AP-Group = "Amigos"
(5)   Aruba-Device-Type = "Android"
(5)   Message-Authenticator = 0x27a324ae82db56460d95f8e34748e3a8
(5) session-state: No cached attributes
(5) # Executing section authorize from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(5)   authorize {
(5)     policy filter_username {
(5)       if (!&User-Name) {
(5)       if (!&User-Name)  -> FALSE
(5)       if (&User-Name =~ / /) {
(5)       if (&User-Name =~ / /)  -> FALSE
(5)       if (&User-Name =~ /@.*@/ ) {
(5)       if (&User-Name =~ /@.*@/ )  -> FALSE
(5)       if (&User-Name =~ /\.\./ ) {
(5)       if (&User-Name =~ /\.\./ )  -> FALSE
(5)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))  {
(5)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))   ->
FALSE
(5)       if (&User-Name =~ /\.$/)  {
(5)       if (&User-Name =~ /\.$/)   -> FALSE
(5)       if (&User-Name =~ /@\./)  {
(5)       if (&User-Name =~ /@\./)   -> FALSE
(5)     } # policy filter_username = notfound
(5)     [preprocess] = ok
(5) auth_log: EXPAND
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
(5) auth_log:    -->
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(5) auth_log:
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
expands to
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(5) auth_log: EXPAND %t
(5) auth_log:    --> Thu Nov 19 16:13:11 2015
(5)     [auth_log] = ok
(5)     [chap] = noop
(5)     [mschap] = noop
(5)     [digest] = noop
(5) suffix: Checking for suffix after "@"
(5) suffix: Looking up realm "unav.es" for User-Name = "oscarrdg@unav.es"
(5) suffix: Found realm "~^(.*\.)?unav\.es$"
(5) suffix: Adding Stripped-User-Name = "oscarrdg"
(5) suffix: Adding Realm = "unav.es"
(5) suffix: Authentication realm is LOCAL
(5)     [suffix] = ok
(5) ntdomain: Request already has destination realm set.  Ignoring
(5)     [ntdomain] = noop
(5) eap: Peer sent EAP Response (code 2) ID 6 length 6
(5) eap: Continuing tunnel setup
(5)     [eap] = ok
(5)   } # authorize = ok
(5) Found Auth-Type = EAP
(5) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(5)   authenticate {
(5) eap: Expiring EAP session with state 0x4a7c50d94e7a497d
(5) eap: Finished EAP session with state 0x4a7c50d94e7a497d
(5) eap: Previous EAP request found for state 0x4a7c50d94e7a497d, released
from the list
(5) eap: Peer sent packet with method EAP PEAP (25)
(5) eap: Calling submodule eap_peap to process data
(5) eap_peap: Continuing EAP-TLS
(5) eap_peap: Peer ACKed our handshake fragment
(5) eap_peap: [eaptls verify] = request
(5) eap_peap: [eaptls process] = handled
(5) eap: Sending EAP Request (code 1) ID 7 length 1000
(5) eap: EAP session adding &reply:State = 0x4a7c50d94f7b497d
(5)     [eap] = handled
(5)   } # authenticate = handled
(5) Using Post-Auth-Type Challenge
(5) Post-Auth-Type sub-section not found.  Ignoring.
(5) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(5) Sent Access-Challenge Id 230 from xxx12.225:1812 to xxxxxx:45903 length
0
(5)   EAP-Message =
0x010703e8194007386ec3a55e23bc459b7b50c1c9308fdbe52b7ad35bfb33401ea0d59817bc8b87c389d35da08eb2aaaaf68e698806c5fa8921f3089d692e09339b290d460f8ccc4934b06951bdf906cd68ad664cbc3eac61bd0a880ec8df3dee7c044c9d0a5e6b91d6eec7ed288dab4d878973d06ea4d0
(5)   Message-Authenticator = 0x00000000000000000000000000000000
(5)   State = 0x4a7c50d94f7b497df1395bb8ee5037b2
(5) Finished request
Waking up in 4.9 seconds.
(6) Received Access-Request Id 168 from xxxxxx:45903 to xxx12.225:1812
length 218
(6)   User-Name = "oscarrdg@unav.es"
(6)   NAS-IP-Address = xxxxxx
(6)   NAS-Port = 0
(6)   NAS-Identifier = "xxxxxx"
(6)   NAS-Port-Type = Wireless-802.11
(6)   Calling-Station-Id = "344DF73C9C98"
(6)   Called-Station-Id = "001A1E003250"
(6)   Service-Type = Framed-User
(6)   Framed-MTU = 1100
(6)   EAP-Message = 0x020700061900
(6)   State = 0x4a7c50d94f7b497df1395bb8ee5037b2
(6)   Aruba-Essid-Name = "eduroam-pre"
(6)   Aruba-Location-Id = "10.9.1"
(6)   Aruba-AP-Group = "Amigos"
(6)   Aruba-Device-Type = "Android"
(6)   Message-Authenticator = 0x4cf19771edbbc663364c4e61d84ea4ca
(6) session-state: No cached attributes
(6) # Executing section authorize from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(6)   authorize {
(6)     policy filter_username {
(6)       if (!&User-Name) {
(6)       if (!&User-Name)  -> FALSE
(6)       if (&User-Name =~ / /) {
(6)       if (&User-Name =~ / /)  -> FALSE
(6)       if (&User-Name =~ /@.*@/ ) {
(6)       if (&User-Name =~ /@.*@/ )  -> FALSE
(6)       if (&User-Name =~ /\.\./ ) {
(6)       if (&User-Name =~ /\.\./ )  -> FALSE
(6)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))  {
(6)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))   ->
FALSE
(6)       if (&User-Name =~ /\.$/)  {
(6)       if (&User-Name =~ /\.$/)   -> FALSE
(6)       if (&User-Name =~ /@\./)  {
(6)       if (&User-Name =~ /@\./)   -> FALSE
(6)     } # policy filter_username = notfound
(6)     [preprocess] = ok
(6) auth_log: EXPAND
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
(6) auth_log:    -->
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(6) auth_log:
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
expands to
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(6) auth_log: EXPAND %t
(6) auth_log:    --> Thu Nov 19 16:13:11 2015
(6)     [auth_log] = ok
(6)     [chap] = noop
(6)     [mschap] = noop
(6)     [digest] = noop
(6) suffix: Checking for suffix after "@"
(6) suffix: Looking up realm "unav.es" for User-Name = "oscarrdg@unav.es"
(6) suffix: Found realm "~^(.*\.)?unav\.es$"
(6) suffix: Adding Stripped-User-Name = "oscarrdg"
(6) suffix: Adding Realm = "unav.es"
(6) suffix: Authentication realm is LOCAL
(6)     [suffix] = ok
(6) ntdomain: Request already has destination realm set.  Ignoring
(6)     [ntdomain] = noop
(6) eap: Peer sent EAP Response (code 2) ID 7 length 6
(6) eap: Continuing tunnel setup
(6)     [eap] = ok
(6)   } # authorize = ok
(6) Found Auth-Type = EAP
(6) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(6)   authenticate {
(6) eap: Expiring EAP session with state 0x4a7c50d94f7b497d
(6) eap: Finished EAP session with state 0x4a7c50d94f7b497d
(6) eap: Previous EAP request found for state 0x4a7c50d94f7b497d, released
from the list
(6) eap: Peer sent packet with method EAP PEAP (25)
(6) eap: Calling submodule eap_peap to process data
(6) eap_peap: Continuing EAP-TLS
(6) eap_peap: Peer ACKed our handshake fragment
(6) eap_peap: [eaptls verify] = request
(6) eap_peap: [eaptls process] = handled
(6) eap: Sending EAP Request (code 1) ID 8 length 1000
(6) eap: EAP session adding &reply:State = 0x4a7c50d94c74497d
(6)     [eap] = handled
(6)   } # authenticate = handled
(6) Using Post-Auth-Type Challenge
(6) Post-Auth-Type sub-section not found.  Ignoring.
(6) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(6) Sent Access-Challenge Id 168 from xxx12.225:1812 to xxxxxx:45903 length
0
(6)   EAP-Message =
0x010803e81940e8329e74cac89054e9c65f0f789d9a403c0eac61aa5e148f9e87a16a50dcd79a4eaf05b3a671949c71b350600ac7139d38078602a8e9a869261890ab4cb04f23ab3a4f84d8dfce9fe1696fbbd742d76b44e4c7adee6d415f725a710837b37965a459a09437f7002f0dc29272dad03872db
(6)   Message-Authenticator = 0x00000000000000000000000000000000
(6)   State = 0x4a7c50d94c74497df1395bb8ee5037b2
(6) Finished request
Waking up in 4.8 seconds.
(7) Received Access-Request Id 112 from xxxxxx:45903 to xxx12.225:1812
length 218
(7)   User-Name = "oscarrdg@unav.es"
(7)   NAS-IP-Address = xxxxxx
(7)   NAS-Port = 0
(7)   NAS-Identifier = "xxxxxx"
(7)   NAS-Port-Type = Wireless-802.11
(7)   Calling-Station-Id = "344DF73C9C98"
(7)   Called-Station-Id = "001A1E003250"
(7)   Service-Type = Framed-User
(7)   Framed-MTU = 1100
(7)   EAP-Message = 0x020800061900
(7)   State = 0x4a7c50d94c74497df1395bb8ee5037b2
(7)   Aruba-Essid-Name = "eduroam-pre"
(7)   Aruba-Location-Id = "10.9.1"
(7)   Aruba-AP-Group = "Amigos"
(7)   Aruba-Device-Type = "Android"
(7)   Message-Authenticator = 0x15bf70b018be20dac76d5bdc4532dc08
(7) session-state: No cached attributes
(7) # Executing section authorize from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(7)   authorize {
(7)     policy filter_username {
(7)       if (!&User-Name) {
(7)       if (!&User-Name)  -> FALSE
(7)       if (&User-Name =~ / /) {
(7)       if (&User-Name =~ / /)  -> FALSE
(7)       if (&User-Name =~ /@.*@/ ) {
(7)       if (&User-Name =~ /@.*@/ )  -> FALSE
(7)       if (&User-Name =~ /\.\./ ) {
(7)       if (&User-Name =~ /\.\./ )  -> FALSE
(7)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))  {
(7)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))   ->
FALSE
(7)       if (&User-Name =~ /\.$/)  {
(7)       if (&User-Name =~ /\.$/)   -> FALSE
(7)       if (&User-Name =~ /@\./)  {
(7)       if (&User-Name =~ /@\./)   -> FALSE
(7)     } # policy filter_username = notfound
(7)     [preprocess] = ok
(7) auth_log: EXPAND
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
(7) auth_log:    -->
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(7) auth_log:
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
expands to
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(7) auth_log: EXPAND %t
(7) auth_log:    --> Thu Nov 19 16:13:11 2015
(7)     [auth_log] = ok
(7)     [chap] = noop
(7)     [mschap] = noop
(7)     [digest] = noop
(7) suffix: Checking for suffix after "@"
(7) suffix: Looking up realm "unav.es" for User-Name = "oscarrdg@unav.es"
(7) suffix: Found realm "~^(.*\.)?unav\.es$"
(7) suffix: Adding Stripped-User-Name = "oscarrdg"
(7) suffix: Adding Realm = "unav.es"
(7) suffix: Authentication realm is LOCAL
(7)     [suffix] = ok
(7) ntdomain: Request already has destination realm set.  Ignoring
(7)     [ntdomain] = noop
(7) eap: Peer sent EAP Response (code 2) ID 8 length 6
(7) eap: Continuing tunnel setup
(7)     [eap] = ok
(7)   } # authorize = ok
(7) Found Auth-Type = EAP
(7) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(7)   authenticate {
(7) eap: Expiring EAP session with state 0x4a7c50d94c74497d
(7) eap: Finished EAP session with state 0x4a7c50d94c74497d
(7) eap: Previous EAP request found for state 0x4a7c50d94c74497d, released
from the list
(7) eap: Peer sent packet with method EAP PEAP (25)
(7) eap: Calling submodule eap_peap to process data
(7) eap_peap: Continuing EAP-TLS
(7) eap_peap: Peer ACKed our handshake fragment
(7) eap_peap: [eaptls verify] = request
(7) eap_peap: [eaptls process] = handled
(7) eap: Sending EAP Request (code 1) ID 9 length 40
(7) eap: EAP session adding &reply:State = 0x4a7c50d94d75497d
(7)     [eap] = handled
(7)   } # authenticate = handled
(7) Using Post-Auth-Type Challenge
(7) Post-Auth-Type sub-section not found.  Ignoring.
(7) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(7) Sent Access-Challenge Id 112 from xxx12.225:1812 to xxxxxx:45903 length
0
(7)   EAP-Message =
0x0109002819007b286bccc4f4c141f4ea5ef8a1b16dd15183d01b5b81d5a4c616030300040e000000
(7)   Message-Authenticator = 0x00000000000000000000000000000000
(7)   State = 0x4a7c50d94d75497df1395bb8ee5037b2
(7) Finished request
Waking up in 4.8 seconds.
(8) Received Access-Request Id 251 from xxxxxx:45903 to xxx12.225:1812
length 348
(8)   User-Name = "oscarrdg@unav.es"
(8)   NAS-IP-Address = xxxxxx
(8)   NAS-Port = 0
(8)   NAS-Identifier = "xxxxxx"
(8)   NAS-Port-Type = Wireless-802.11
(8)   Calling-Station-Id = "344DF73C9C98"
(8)   Called-Station-Id = "001A1E003250"
(8)   Service-Type = Framed-User
(8)   Framed-MTU = 1100
(8)   EAP-Message =
0x0209008819800000007e160303004610000042410497d6d49495961db556fda8b469384d890ed741e0f137449e864c9a78bac1d3300980cf8556274e7270cfd6d411d7dee9cd216a49a794311cb559d577fdbeb832140303000101160303002800000000000000001e3ac64ea847ecf6941ba645884a54
(8)   State = 0x4a7c50d94d75497df1395bb8ee5037b2
(8)   Aruba-Essid-Name = "eduroam-pre"
(8)   Aruba-Location-Id = "10.9.1"
(8)   Aruba-AP-Group = "Amigos"
(8)   Aruba-Device-Type = "Android"
(8)   Message-Authenticator = 0x863b805103353c3f87d71a7ead7cb041
(8) session-state: No cached attributes
(8) # Executing section authorize from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(8)   authorize {
(8)     policy filter_username {
(8)       if (!&User-Name) {
(8)       if (!&User-Name)  -> FALSE
(8)       if (&User-Name =~ / /) {
(8)       if (&User-Name =~ / /)  -> FALSE
(8)       if (&User-Name =~ /@.*@/ ) {
(8)       if (&User-Name =~ /@.*@/ )  -> FALSE
(8)       if (&User-Name =~ /\.\./ ) {
(8)       if (&User-Name =~ /\.\./ )  -> FALSE
(8)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))  {
(8)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))   ->
FALSE
(8)       if (&User-Name =~ /\.$/)  {
(8)       if (&User-Name =~ /\.$/)   -> FALSE
(8)       if (&User-Name =~ /@\./)  {
(8)       if (&User-Name =~ /@\./)   -> FALSE
(8)     } # policy filter_username = notfound
(8)     [preprocess] = ok
(8) auth_log: EXPAND
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
(8) auth_log:    -->
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(8) auth_log:
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
expands to
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(8) auth_log: EXPAND %t
(8) auth_log:    --> Thu Nov 19 16:13:11 2015
(8)     [auth_log] = ok
(8)     [chap] = noop
(8)     [mschap] = noop
(8)     [digest] = noop
(8) suffix: Checking for suffix after "@"
(8) suffix: Looking up realm "unav.es" for User-Name = "oscarrdg@unav.es"
(8) suffix: Found realm "~^(.*\.)?unav\.es$"
(8) suffix: Adding Stripped-User-Name = "oscarrdg"
(8) suffix: Adding Realm = "unav.es"
(8) suffix: Authentication realm is LOCAL
(8)     [suffix] = ok
(8) ntdomain: Request already has destination realm set.  Ignoring
(8)     [ntdomain] = noop
(8) eap: Peer sent EAP Response (code 2) ID 9 length 136
(8) eap: Continuing tunnel setup
(8)     [eap] = ok
(8)   } # authorize = ok
(8) Found Auth-Type = EAP
(8) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(8)   authenticate {
(8) eap: Expiring EAP session with state 0x4a7c50d94d75497d
(8) eap: Finished EAP session with state 0x4a7c50d94d75497d
(8) eap: Previous EAP request found for state 0x4a7c50d94d75497d, released
from the list
(8) eap: Peer sent packet with method EAP PEAP (25)
(8) eap: Calling submodule eap_peap to process data
(8) eap_peap: Continuing EAP-TLS
(8) eap_peap: Peer indicated complete TLS record size will be 126 bytes
(8) eap_peap: Got complete TLS record (126 bytes)
(8) eap_peap: [eaptls verify] = length included
(8) eap_peap: <<< TLS 1.2  [length 0046]
(8) eap_peap: TLS_accept: SSLv3 read client key exchange A
(8) eap_peap: <<< TLS 1.2  [length 0001]
(8) eap_peap: <<< TLS 1.2  [length 0010]
(8) eap_peap: TLS_accept: SSLv3 read finished A
(8) eap_peap: >>> TLS 1.2  [length 0001]
(8) eap_peap: TLS_accept: SSLv3 write change cipher spec A
(8) eap_peap: >>> TLS 1.2  [length 0010]
(8) eap_peap: TLS_accept: SSLv3 write finished A
(8) eap_peap: TLS_accept: SSLv3 flush data
(8) eap_peap: (other): SSL negotiation finished successfully
(8) eap_peap: SSL Connection Established
(8) eap_peap: [eaptls process] = handled
(8) eap: Sending EAP Request (code 1) ID 10 length 57
(8) eap: EAP session adding &reply:State = 0x4a7c50d94276497d
(8)     [eap] = handled
(8)   } # authenticate = handled
(8) Using Post-Auth-Type Challenge
(8) Post-Auth-Type sub-section not found.  Ignoring.
(8) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(8) Sent Access-Challenge Id 251 from xxx12.225:1812 to xxxxxx:45903 length
0
(8)   EAP-Message =
0x010a0039190014030300010116030300286a2a84ab3ae80e62498198297caa9815c5334160580029e175eff17a7ecb2046472a0c0a01428bea
(8)   Message-Authenticator = 0x00000000000000000000000000000000
(8)   State = 0x4a7c50d94276497df1395bb8ee5037b2
(8) Finished request
Waking up in 4.8 seconds.
(9) Received Access-Request Id 182 from xxxxxx:45903 to xxx12.225:1812
length 218
(9)   User-Name = "oscarrdg@unav.es"
(9)   NAS-IP-Address = xxxxxx
(9)   NAS-Port = 0
(9)   NAS-Identifier = "xxxxxx"
(9)   NAS-Port-Type = Wireless-802.11
(9)   Calling-Station-Id = "344DF73C9C98"
(9)   Called-Station-Id = "001A1E003250"
(9)   Service-Type = Framed-User
(9)   Framed-MTU = 1100
(9)   EAP-Message = 0x020a00061900
(9)   State = 0x4a7c50d94276497df1395bb8ee5037b2
(9)   Aruba-Essid-Name = "eduroam-pre"
(9)   Aruba-Location-Id = "10.9.1"
(9)   Aruba-AP-Group = "Amigos"
(9)   Aruba-Device-Type = "Android"
(9)   Message-Authenticator = 0x87e40768b0a6aedf20a4315cfe75d952
(9) session-state: No cached attributes
(9) # Executing section authorize from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(9)   authorize {
(9)     policy filter_username {
(9)       if (!&User-Name) {
(9)       if (!&User-Name)  -> FALSE
(9)       if (&User-Name =~ / /) {
(9)       if (&User-Name =~ / /)  -> FALSE
(9)       if (&User-Name =~ /@.*@/ ) {
(9)       if (&User-Name =~ /@.*@/ )  -> FALSE
(9)       if (&User-Name =~ /\.\./ ) {
(9)       if (&User-Name =~ /\.\./ )  -> FALSE
(9)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))  {
(9)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))   ->
FALSE
(9)       if (&User-Name =~ /\.$/)  {
(9)       if (&User-Name =~ /\.$/)   -> FALSE
(9)       if (&User-Name =~ /@\./)  {
(9)       if (&User-Name =~ /@\./)   -> FALSE
(9)     } # policy filter_username = notfound
(9)     [preprocess] = ok
(9) auth_log: EXPAND
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
(9) auth_log:    -->
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(9) auth_log:
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
expands to
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(9) auth_log: EXPAND %t
(9) auth_log:    --> Thu Nov 19 16:13:11 2015
(9)     [auth_log] = ok
(9)     [chap] = noop
(9)     [mschap] = noop
(9)     [digest] = noop
(9) suffix: Checking for suffix after "@"
(9) suffix: Looking up realm "unav.es" for User-Name = "oscarrdg@unav.es"
(9) suffix: Found realm "~^(.*\.)?unav\.es$"
(9) suffix: Adding Stripped-User-Name = "oscarrdg"
(9) suffix: Adding Realm = "unav.es"
(9) suffix: Authentication realm is LOCAL
(9)     [suffix] = ok
(9) ntdomain: Request already has destination realm set.  Ignoring
(9)     [ntdomain] = noop
(9) eap: Peer sent EAP Response (code 2) ID 10 length 6
(9) eap: Continuing tunnel setup
(9)     [eap] = ok
(9)   } # authorize = ok
(9) Found Auth-Type = EAP
(9) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(9)   authenticate {
(9) eap: Expiring EAP session with state 0x4a7c50d94276497d
(9) eap: Finished EAP session with state 0x4a7c50d94276497d
(9) eap: Previous EAP request found for state 0x4a7c50d94276497d, released
from the list
(9) eap: Peer sent packet with method EAP PEAP (25)
(9) eap: Calling submodule eap_peap to process data
(9) eap_peap: Continuing EAP-TLS
(9) eap_peap: Peer ACKed our handshake fragment.  handshake is finished
(9) eap_peap: [eaptls verify] = success
(9) eap_peap: [eaptls process] = success
(9) eap_peap: Session established.  Decoding tunneled attributes
(9) eap_peap: PEAP state TUNNEL ESTABLISHED
(9) eap: Sending EAP Request (code 1) ID 11 length 40
(9) eap: EAP session adding &reply:State = 0x4a7c50d94377497d
(9)     [eap] = handled
(9)   } # authenticate = handled
(9) Using Post-Auth-Type Challenge
(9) Post-Auth-Type sub-section not found.  Ignoring.
(9) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(9) Sent Access-Challenge Id 182 from xxx12.225:1812 to xxxxxx:45903 length
0
(9)   EAP-Message =
0x010b00281900170303001d6a2a84ab3ae80e6307de0ca3236f93adbf36e1a25d41545a9a71de0be3
(9)   Message-Authenticator = 0x00000000000000000000000000000000
(9)   State = 0x4a7c50d94377497df1395bb8ee5037b2
(9) Finished request
Waking up in 4.8 seconds.
(10) Received Access-Request Id 92 from xxxxxx:45903 to xxx12.225:1812
length 264
(10)   User-Name = "oscarrdg@unav.es"
(10)   NAS-IP-Address = xxxxxx
(10)   NAS-Port = 0
(10)   NAS-Identifier = "xxxxxx"
(10)   NAS-Port-Type = Wireless-802.11
(10)   Calling-Station-Id = "344DF73C9C98"
(10)   Called-Station-Id = "001A1E003250"
(10)   Service-Type = Framed-User
(10)   Framed-MTU = 1100
(10)   EAP-Message =
0x020b00341900170303002900000000000000019b4ab8036a75b9c14b9fbef72722cf152e7eae150e66cd2df891b0db7b49041513
(10)   State = 0x4a7c50d94377497df1395bb8ee5037b2
(10)   Aruba-Essid-Name = "eduroam-pre"
(10)   Aruba-Location-Id = "10.9.1"
(10)   Aruba-AP-Group = "Amigos"
(10)   Aruba-Device-Type = "Android"
(10)   Message-Authenticator = 0x3ed798db1779514513dd6cf8a43755bc
(10) session-state: No cached attributes
(10) # Executing section authorize from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(10)   authorize {
(10)     policy filter_username {
(10)       if (!&User-Name) {
(10)       if (!&User-Name)  -> FALSE
(10)       if (&User-Name =~ / /) {
(10)       if (&User-Name =~ / /)  -> FALSE
(10)       if (&User-Name =~ /@.*@/ ) {
(10)       if (&User-Name =~ /@.*@/ )  -> FALSE
(10)       if (&User-Name =~ /\.\./ ) {
(10)       if (&User-Name =~ /\.\./ )  -> FALSE
(10)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))  {
(10)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))   ->
FALSE
(10)       if (&User-Name =~ /\.$/)  {
(10)       if (&User-Name =~ /\.$/)   -> FALSE
(10)       if (&User-Name =~ /@\./)  {
(10)       if (&User-Name =~ /@\./)   -> FALSE
(10)     } # policy filter_username = notfound
(10)     [preprocess] = ok
(10) auth_log: EXPAND
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
(10) auth_log:    -->
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(10) auth_log:
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
expands to
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(10) auth_log: EXPAND %t
(10) auth_log:    --> Thu Nov 19 16:13:11 2015
(10)     [auth_log] = ok
(10)     [chap] = noop
(10)     [mschap] = noop
(10)     [digest] = noop
(10) suffix: Checking for suffix after "@"
(10) suffix: Looking up realm "unav.es" for User-Name = "oscarrdg@unav.es"
(10) suffix: Found realm "~^(.*\.)?unav\.es$"
(10) suffix: Adding Stripped-User-Name = "oscarrdg"
(10) suffix: Adding Realm = "unav.es"
(10) suffix: Authentication realm is LOCAL
(10)     [suffix] = ok
(10) ntdomain: Request already has destination realm set.  Ignoring
(10)     [ntdomain] = noop
(10) eap: Peer sent EAP Response (code 2) ID 11 length 52
(10) eap: Continuing tunnel setup
(10)     [eap] = ok
(10)   } # authorize = ok
(10) Found Auth-Type = EAP
(10) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(10)   authenticate {
(10) eap: Expiring EAP session with state 0x4a7c50d94377497d
(10) eap: Finished EAP session with state 0x4a7c50d94377497d
(10) eap: Previous EAP request found for state 0x4a7c50d94377497d, released
from the list
(10) eap: Peer sent packet with method EAP PEAP (25)
(10) eap: Calling submodule eap_peap to process data
(10) eap_peap: Continuing EAP-TLS
(10) eap_peap: [eaptls verify] = ok
(10) eap_peap: Done initial handshake
(10) eap_peap: [eaptls process] = ok
(10) eap_peap: Session established.  Decoding tunneled attributes
(10) eap_peap: PEAP state WAITING FOR INNER IDENTITY
(10) eap_peap: Identity - oscarrdg@unav.es
(10) eap_peap: Got inner identity 'oscarrdg@unav.es'
(10) eap_peap: Setting default EAP type for tunneled EAP session
(10) eap_peap: Got tunneled request
(10) eap_peap:   EAP-Message = 0x020b0015016f7363617272646740756e61762e6573
(10) eap_peap: Setting User-Name to oscarrdg@unav.es
(10) eap_peap: Sending tunneled request to inner-tunnel
(10) eap_peap:   EAP-Message = 0x020b0015016f7363617272646740756e61762e6573
(10) eap_peap:   FreeRADIUS-Proxied-To = 127.0.0.1
(10) eap_peap:   User-Name = "oscarrdg@unav.es"
(10) eap_peap:   NAS-IP-Address = xxxxxx
(10) eap_peap:   NAS-Port = 0
(10) eap_peap:   NAS-Identifier = "xxxxxx"
(10) eap_peap:   NAS-Port-Type = Wireless-802.11
(10) eap_peap:   Calling-Station-Id = "344DF73C9C98"
(10) eap_peap:   Called-Station-Id = "001A1E003250"
(10) eap_peap:   Service-Type = Framed-User
(10) eap_peap:   Framed-MTU = 1100
(10) eap_peap:   Aruba-Essid-Name = "eduroam-pre"
(10) eap_peap:   Aruba-Location-Id = "10.9.1"
(10) eap_peap:   Aruba-AP-Group = "Amigos"
(10) eap_peap:   Aruba-Device-Type = "Android"
(10) eap_peap:   Event-Timestamp = "Nov 19 2015 16:13:11 CET"
(10) Virtual server inner-tunnel received request
(10)   EAP-Message = 0x020b0015016f7363617272646740756e61762e6573
(10)   FreeRADIUS-Proxied-To = 127.0.0.1
(10)   User-Name = "oscarrdg@unav.es"
(10)   NAS-IP-Address = xxxxxx
(10)   NAS-Port = 0
(10)   NAS-Identifier = "xxxxxx"
(10)   NAS-Port-Type = Wireless-802.11
(10)   Calling-Station-Id = "344DF73C9C98"
(10)   Called-Station-Id = "001A1E003250"
(10)   Service-Type = Framed-User
(10)   Framed-MTU = 1100
(10)   Aruba-Essid-Name = "eduroam-pre"
(10)   Aruba-Location-Id = "10.9.1"
(10)   Aruba-AP-Group = "Amigos"
(10)   Aruba-Device-Type = "Android"
(10)   Event-Timestamp = "Nov 19 2015 16:13:11 CET"
(10) server inner-tunnel {
(10)   # Executing section authorize from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/inner-tunnel
(10)     authorize {
(10)       [chap] = noop
(10)       [mschap] = noop
(10) suffix: Checking for suffix after "@"
(10) suffix: Looking up realm "unav.es" for User-Name = "oscarrdg@unav.es"
(10) suffix: Found realm "~^(.*\.)?unav\.es$"
(10) suffix: Adding Stripped-User-Name = "oscarrdg"
(10) suffix: Adding Realm = "unav.es"
(10) suffix: Authentication realm is LOCAL
(10)       [suffix] = ok
(10) ntdomain: Request already has destination realm set.  Ignoring
(10)       [ntdomain] = noop
(10)       update control {
(10)         &Proxy-To-Realm := LOCAL
(10)       } # update control = noop
(10) eap: Peer sent EAP Response (code 2) ID 11 length 21
(10) eap: EAP-Identity reply, returning 'ok' so we can short-circuit the
rest of authorize
(10)       [eap] = ok
(10)     } # authorize = ok
(10)   Found Auth-Type = EAP
(10)   # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/inner-tunnel
(10)     authenticate {
(10) eap: Peer sent packet with method EAP Identity (1)
(10) eap: Calling submodule eap_mschapv2 to process data
(10) eap_mschapv2: Issuing Challenge
(10) eap: Sending EAP Request (code 1) ID 12 length 43
(10) eap: EAP session adding &reply:State = 0x739669f1739a7348
(10)       [eap] = handled
(10)     } # authenticate = handled
(10) } # server inner-tunnel
(10) Virtual server sending reply
(10)   EAP-Message =
0x010c002b1a010c0026109be6bd2966f7837a669e8c6ec412fec8667265657261646975732d332e302e3130
(10)   Message-Authenticator = 0x00000000000000000000000000000000
(10)   State = 0x739669f1739a7348a02455fb687f2caa
(10) eap_peap: Got tunneled reply code 11
(10) eap_peap:   EAP-Message =
0x010c002b1a010c0026109be6bd2966f7837a669e8c6ec412fec8667265657261646975732d332e302e3130
(10) eap_peap:   Message-Authenticator = 0x00000000000000000000000000000000
(10) eap_peap:   State = 0x739669f1739a7348a02455fb687f2caa
(10) eap_peap: Got tunneled reply RADIUS code 11
(10) eap_peap:   EAP-Message =
0x010c002b1a010c0026109be6bd2966f7837a669e8c6ec412fec8667265657261646975732d332e302e3130
(10) eap_peap:   Message-Authenticator = 0x00000000000000000000000000000000
(10) eap_peap:   State = 0x739669f1739a7348a02455fb687f2caa
(10) eap_peap: Got tunneled Access-Challenge
(10) eap: Sending EAP Request (code 1) ID 12 length 74
(10) eap: EAP session adding &reply:State = 0x4a7c50d94070497d
(10)     [eap] = handled
(10)   } # authenticate = handled
(10) Using Post-Auth-Type Challenge
(10) Post-Auth-Type sub-section not found.  Ignoring.
(10) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(10) Sent Access-Challenge Id 92 from xxx12.225:1812 to xxxxxx:45903 length
0
(10)   EAP-Message =
0x010c004a1900170303003f6a2a84ab3ae80e6445376747acac3a67092800521bd121ed59a9f1d8a3e4558a30aea29eae313c5e6b4befec0d80e2fbdd0f7c8ce5d3d791f671f36e56facd
(10)   Message-Authenticator = 0x00000000000000000000000000000000
(10)   State = 0x4a7c50d94070497df1395bb8ee5037b2
(10) Finished request
Waking up in 4.8 seconds.
(11) Received Access-Request Id 240 from xxxxxx:45903 to xxx12.225:1812
length 318
(11)   User-Name = "oscarrdg@unav.es"
(11)   NAS-IP-Address = xxxxxx
(11)   NAS-Port = 0
(11)   NAS-Identifier = "xxxxxx"
(11)   NAS-Port-Type = Wireless-802.11
(11)   Calling-Station-Id = "344DF73C9C98"
(11)   Called-Station-Id = "001A1E003250"
(11)   Service-Type = Framed-User
(11)   Framed-MTU = 1100
(11)   EAP-Message =
0x020c006a1900170303005f000000000000000253f426d0e803b2f101d786b3751058056be43652b9dfc3ef648ed4fa31005509bd18cfeea0fae8d7c1e20a69507c7355e0f78b6d034f70f812ce1d73e78815d519b082725844b86344c1ae97ccd9d6232d64a6c67831e3
(11)   State = 0x4a7c50d94070497df1395bb8ee5037b2
(11)   Aruba-Essid-Name = "eduroam-pre"
(11)   Aruba-Location-Id = "10.9.1"
(11)   Aruba-AP-Group = "Amigos"
(11)   Aruba-Device-Type = "Android"
(11)   Message-Authenticator = 0xfc041f435db6453937530e6ad7ad9998
(11) session-state: No cached attributes
(11) # Executing section authorize from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(11)   authorize {
(11)     policy filter_username {
(11)       if (!&User-Name) {
(11)       if (!&User-Name)  -> FALSE
(11)       if (&User-Name =~ / /) {
(11)       if (&User-Name =~ / /)  -> FALSE
(11)       if (&User-Name =~ /@.*@/ ) {
(11)       if (&User-Name =~ /@.*@/ )  -> FALSE
(11)       if (&User-Name =~ /\.\./ ) {
(11)       if (&User-Name =~ /\.\./ )  -> FALSE
(11)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))  {
(11)       if ((&User-Name =~ /@/) && (&User-Name !~ /@(.+)\.(.+)$/))   ->
FALSE
(11)       if (&User-Name =~ /\.$/)  {
(11)       if (&User-Name =~ /\.$/)   -> FALSE
(11)       if (&User-Name =~ /@\./)  {
(11)       if (&User-Name =~ /@\./)   -> FALSE
(11)     } # policy filter_username = notfound
(11)     [preprocess] = ok
(11) auth_log: EXPAND
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
(11) auth_log:    -->
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(11) auth_log:
/usr/local/etc2/freeradius/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d
expands to
/usr/local/etc2/freeradius/var/log/radius/radacct/xxxxxx/auth-detail-20151119
(11) auth_log: EXPAND %t
(11) auth_log:    --> Thu Nov 19 16:13:11 2015
(11)     [auth_log] = ok
(11)     [chap] = noop
(11)     [mschap] = noop
(11)     [digest] = noop
(11) suffix: Checking for suffix after "@"
(11) suffix: Looking up realm "unav.es" for User-Name = "oscarrdg@unav.es"
(11) suffix: Found realm "~^(.*\.)?unav\.es$"
(11) suffix: Adding Stripped-User-Name = "oscarrdg"
(11) suffix: Adding Realm = "unav.es"
(11) suffix: Authentication realm is LOCAL
(11)     [suffix] = ok
(11) ntdomain: Request already has destination realm set.  Ignoring
(11)     [ntdomain] = noop
(11) eap: Peer sent EAP Response (code 2) ID 12 length 106
(11) eap: Continuing tunnel setup
(11)     [eap] = ok
(11)   } # authorize = ok
(11) Found Auth-Type = EAP
(11) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(11)   authenticate {
(11) eap: Expiring EAP session with state 0x739669f1739a7348
(11) eap: Finished EAP session with state 0x4a7c50d94070497d
(11) eap: Previous EAP request found for state 0x4a7c50d94070497d, released
from the list
(11) eap: Peer sent packet with method EAP PEAP (25)
(11) eap: Calling submodule eap_peap to process data
(11) eap_peap: Continuing EAP-TLS
(11) eap_peap: [eaptls verify] = ok
(11) eap_peap: Done initial handshake
(11) eap_peap: [eaptls process] = ok
(11) eap_peap: Session established.  Decoding tunneled attributes
(11) eap_peap: PEAP state phase2
(11) eap_peap: EAP method MSCHAPv2 (26)
(11) eap_peap: Got tunneled request
(11) eap_peap:   EAP-Message =
0x020c004b1a020c00463116ba4ea3b0d512edfb61cc1111d4f2310000000000000000d4ced6b15b809861069531ec2cb89e3001db98c94f9018e8006f7363617272646740756e61762e6573
(11) eap_peap: Setting User-Name to oscarrdg@unav.es
(11) eap_peap: Sending tunneled request to inner-tunnel
(11) eap_peap:   EAP-Message =
0x020c004b1a020c00463116ba4ea3b0d512edfb61cc1111d4f2310000000000000000d4ced6b15b809861069531ec2cb89e3001db98c94f9018e8006f7363617272646740756e61762e6573
(11) eap_peap:   FreeRADIUS-Proxied-To = 127.0.0.1
(11) eap_peap:   User-Name = "oscarrdg@unav.es"
(11) eap_peap:   State = 0x739669f1739a7348a02455fb687f2caa
(11) eap_peap:   NAS-IP-Address = xxxxxx
(11) eap_peap:   NAS-Port = 0
(11) eap_peap:   NAS-Identifier = "xxxxxx"
(11) eap_peap:   NAS-Port-Type = Wireless-802.11
(11) eap_peap:   Calling-Station-Id = "344DF73C9C98"
(11) eap_peap:   Called-Station-Id = "001A1E003250"
(11) eap_peap:   Service-Type = Framed-User
(11) eap_peap:   Framed-MTU = 1100
(11) eap_peap:   Aruba-Essid-Name = "eduroam-pre"
(11) eap_peap:   Aruba-Location-Id = "10.9.1"
(11) eap_peap:   Aruba-AP-Group = "Amigos"
(11) eap_peap:   Aruba-Device-Type = "Android"
(11) eap_peap:   Event-Timestamp = "Nov 19 2015 16:13:11 CET"
(11) Virtual server inner-tunnel received request
(11)   EAP-Message =
0x020c004b1a020c00463116ba4ea3b0d512edfb61cc1111d4f2310000000000000000d4ced6b15b809861069531ec2cb89e3001db98c94f9018e8006f7363617272646740756e61762e6573
(11)   FreeRADIUS-Proxied-To = 127.0.0.1
(11)   User-Name = "oscarrdg@unav.es"
(11)   State = 0x739669f1739a7348a02455fb687f2caa
(11)   NAS-IP-Address = xxxxxx
(11)   NAS-Port = 0
(11)   NAS-Identifier = "xxxxxx"
(11)   NAS-Port-Type = Wireless-802.11
(11)   Calling-Station-Id = "344DF73C9C98"
(11)   Called-Station-Id = "001A1E003250"
(11)   Service-Type = Framed-User
(11)   Framed-MTU = 1100
(11)   Aruba-Essid-Name = "eduroam-pre"
(11)   Aruba-Location-Id = "10.9.1"
(11)   Aruba-AP-Group = "Amigos"
(11)   Aruba-Device-Type = "Android"
(11)   Event-Timestamp = "Nov 19 2015 16:13:11 CET"
(11) server inner-tunnel {
(11)   session-state: No cached attributes
(11)   # Executing section authorize from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/inner-tunnel
(11)     authorize {
(11)       [chap] = noop
(11)       [mschap] = noop
(11) suffix: Checking for suffix after "@"
(11) suffix: Looking up realm "unav.es" for User-Name = "oscarrdg@unav.es"
(11) suffix: Found realm "~^(.*\.)?unav\.es$"
(11) suffix: Adding Stripped-User-Name = "oscarrdg"
(11) suffix: Adding Realm = "unav.es"
(11) suffix: Authentication realm is LOCAL
(11)       [suffix] = ok
(11) ntdomain: Request already has destination realm set.  Ignoring
(11)       [ntdomain] = noop
(11)       update control {
(11)         &Proxy-To-Realm := LOCAL
(11)       } # update control = noop
(11) eap: Peer sent EAP Response (code 2) ID 12 length 75
(11) eap: No EAP Start, assuming it's an on-going EAP conversation
(11)       [eap] = updated
(11)       policy unav.authorize.inner {
(11)         if ( "%{client:group}" == 'wireless'  ) {
(11)         EXPAND %{client:group}
(11)            --> wireless
(11)         if ( "%{client:group}" == 'wireless'  )  -> TRUE
(11)         if ( "%{client:group}" == 'wireless'  )  {
(11)           policy unav.reject.otherdomain {
(11)             if ( Realm == "NULL" || Realm =~ /^(.*\.)?unav\.es$/ ||
Realm == "UNAV-PDC-01" ) {
(11)             if ( Realm == "NULL" || Realm =~ /^(.*\.)?unav\.es$/ ||
Realm == "UNAV-PDC-01" )  -> TRUE
(11)             ( Realm == "NULL" || Realm =~ /^(.*\.)?unav\.es$/ || Realm
== "UNAV-PDC-01" )  { ... } # empty sub-section is ignored
(11)             ... skipping else for request 11: Preceding "if" was taken
(11)           } # policy unav.reject.otherdomain = updated
(11)           policy unav.reject.bloqueado {
(11)             if ( Ldap-Group == "unav.radius.bloqueado" ) {
(11)             Searching for user in group "unav.radius.bloqueado"
rlm_ldap (ldap): Reserved connection (0)
(11)             EXPAND (uid=%{%{Stripped-User-Name}:-%{User-Name}})
(11)                --> (uid=oscarrdg)
(11)             Performing search in "ou=radius,o=accounts,dc=unav,dc=es"
with filter "(uid=oscarrdg)", scope "sub"
(11)             Waiting for search result...
(11)             User object found at DN
"uid=oscarrdg,ou=radius,o=accounts,dc=unav,dc=es"
(11)             Checking user object's member attributes
(11)               Performing unfiltered search in
"uid=oscarrdg,ou=radius,o=accounts,dc=unav,dc=es", scope "base"
(11)               Waiting for search result...
(11)             Processing member value
"uid=unav.radius.vpn.ssl,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" as a
DN
(11)               Resolving group DN
"uid=unav.radius.vpn.ssl,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" to
group name
(11)               Performing unfiltered search in
"uid=unav.radius.vpn.ssl,ou=groups,ou=radius,o=accounts,dc=unav,dc=es",
scope "base"
(11)               Waiting for search result...
(11)               Group DN
"uid=unav.radius.vpn.ssl,ou=groups,ou=radius,o=accounts,dc=unav,dc=es"
resolves to name "unav.radius.vpn.ssl"
(11)             Processing member value
"uid=unav.radius.eapol.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" as
a DN
(11)               Resolving group DN
"uid=unav.radius.eapol.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" to
group name
(11)               Performing unfiltered search in
"uid=unav.radius.eapol.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es",
scope "base"
(11)               Waiting for search result...
(11)               Group DN
"uid=unav.radius.eapol.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es"
resolves to name "unav.radius.eapol.n1"
(11)             Processing member value
"uid=unav.radius.wifi.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" as a
DN
(11)               Resolving group DN
"uid=unav.radius.wifi.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" to
group name
(11)               Performing unfiltered search in
"uid=unav.radius.wifi.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es",
scope "base"
(11)               Waiting for search result...
(11)               Group DN
"uid=unav.radius.wifi.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es"
resolves to name "unav.radius.wifi.n1"
(11)             Processing member value
"uid=unav.radius.vpn.ssl.si,ou=groups,ou=radius,o=accounts,dc=unav,dc=es"
as a DN
(11)               Resolving group DN
"uid=unav.radius.vpn.ssl.si,ou=groups,ou=radius,o=accounts,dc=unav,dc=es"
to group name
(11)               Performing unfiltered search in "uid=
unav.radius.vpn.ssl.si,ou=groups,ou=radius,o=accounts,dc=unav,dc=es", scope
"base"
(11)               Waiting for search result...
(11)               Group DN
"uid=unav.radius.vpn.ssl.si,ou=groups,ou=radius,o=accounts,dc=unav,dc=es"
resolves to name "unav.radius.vpn.ssl.si"
(11)             Processing member value
"uid=unav.radius.vpn.ipsec,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" as
a DN
(11)               Resolving group DN
"uid=unav.radius.vpn.ipsec,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" to
group name
(11)               Performing unfiltered search in
"uid=unav.radius.vpn.ipsec,ou=groups,ou=radius,o=accounts,dc=unav,dc=es",
scope "base"
(11)               Waiting for search result...
(11)               Group DN
"uid=unav.radius.vpn.ipsec,ou=groups,ou=radius,o=accounts,dc=unav,dc=es"
resolves to name "unav.radius.vpn.ipsec"
rlm_ldap (ldap): Released connection (0)
rlm_ldap (ldap): Need 5 more connections to reach 10 spares
rlm_ldap (ldap): Opening additional connection (5), 1 of 27 pending slots
used
rlm_ldap (ldap): Connecting to ldap://ldap.unav.es:389
TLS: certificate [CN=AddTrust External CA Root,OU=AddTrust External TTP
Network,O=AddTrust AB,C=SE] is not valid - error -8172:Peer's certificate
issuer has been marked as not trusted by the user..
rlm_ldap (ldap): Waiting for bind result...
rlm_ldap (ldap): Bind successful
(11)             User is not a member of "unav.radius.bloqueado"
(11)             if ( Ldap-Group == "unav.radius.bloqueado" )  -> FALSE
(11)           } # policy unav.reject.bloqueado = updated
rlm_ldap (ldap): Reserved connection (1)
(11) ldap: EXPAND (uid=%{%{Stripped-User-Name}:-%{User-Name}})
(11) ldap:    --> (uid=oscarrdg)
(11) ldap: Performing search in "ou=radius,o=accounts,dc=unav,dc=es" with
filter "(uid=oscarrdg)", scope "sub"
(11) ldap: Waiting for search result...
(11) ldap: User object found at DN
"uid=oscarrdg,ou=radius,o=accounts,dc=unav,dc=es"
(11) ldap: Processing user attributes
(11) ldap: control:Password-With-Header +=
'{sha}0sURRyN1nxp/BMx+Q5nBqwR7CB0='
rlm_ldap (ldap): Released connection (1)
(11)           [ldap] = updated
(11)           update reply {
(11)             EXPAND %{request:User-Name}
(11)                --> oscarrdg@unav.es
(11)             &User-Name = oscarrdg@unav.es
(11)           } # update reply = noop
(11)           if ( Ldap-Group == "unav.radius.wifi.all" ) {
(11)           Searching for user in group "unav.radius.wifi.all"
rlm_ldap (ldap): Reserved connection (2)
(11)           Using user DN from request
"uid=oscarrdg,ou=radius,o=accounts,dc=unav,dc=es"
(11)           Checking user object's member attributes
(11)             Performing unfiltered search in
"uid=oscarrdg,ou=radius,o=accounts,dc=unav,dc=es", scope "base"
(11)             Waiting for search result...
(11)           Processing member value
"uid=unav.radius.vpn.ssl,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" as a
DN
(11)             Resolving group DN
"uid=unav.radius.vpn.ssl,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" to
group name
(11)             Performing unfiltered search in
"uid=unav.radius.vpn.ssl,ou=groups,ou=radius,o=accounts,dc=unav,dc=es",
scope "base"
(11)             Waiting for search result...
(11)             Group DN
"uid=unav.radius.vpn.ssl,ou=groups,ou=radius,o=accounts,dc=unav,dc=es"
resolves to name "unav.radius.vpn.ssl"
(11)           Processing member value
"uid=unav.radius.eapol.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" as
a DN
(11)             Resolving group DN
"uid=unav.radius.eapol.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" to
group name
(11)             Performing unfiltered search in
"uid=unav.radius.eapol.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es",
scope "base"
(11)             Waiting for search result...
(11)             Group DN
"uid=unav.radius.eapol.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es"
resolves to name "unav.radius.eapol.n1"
(11)           Processing member value
"uid=unav.radius.wifi.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" as a
DN
(11)             Resolving group DN
"uid=unav.radius.wifi.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" to
group name
(11)             Performing unfiltered search in
"uid=unav.radius.wifi.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es",
scope "base"
(11)             Waiting for search result...
(11)             Group DN
"uid=unav.radius.wifi.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es"
resolves to name "unav.radius.wifi.n1"
(11)           Processing member value
"uid=unav.radius.vpn.ssl.si,ou=groups,ou=radius,o=accounts,dc=unav,dc=es"
as a DN
(11)             Resolving group DN
"uid=unav.radius.vpn.ssl.si,ou=groups,ou=radius,o=accounts,dc=unav,dc=es"
to group name
(11)             Performing unfiltered search in
"uid=unav.radius.vpn.ssl.si,ou=groups,ou=radius,o=accounts,dc=unav,dc=es",
scope "base"
(11)             Waiting for search result...
(11)             Group DN
"uid=unav.radius.vpn.ssl.si,ou=groups,ou=radius,o=accounts,dc=unav,dc=es"
resolves to name "unav.radius.vpn.ssl.si"
(11)           Processing member value
"uid=unav.radius.vpn.ipsec,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" as
a DN
(11)             Resolving group DN
"uid=unav.radius.vpn.ipsec,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" to
group name
(11)             Performing unfiltered search in
"uid=unav.radius.vpn.ipsec,ou=groups,ou=radius,o=accounts,dc=unav,dc=es",
scope "base"
(11)             Waiting for search result...
(11)             Group DN
"uid=unav.radius.vpn.ipsec,ou=groups,ou=radius,o=accounts,dc=unav,dc=es"
resolves to name "unav.radius.vpn.ipsec"
rlm_ldap (ldap): Released connection (2)
(11)           User is not a member of "unav.radius.wifi.all"
(11)           if ( Ldap-Group == "unav.radius.wifi.all" )  -> FALSE
(11)           elsif ( Ldap-Group == "unav.radius.wifi.n1" ) {
(11)           Searching for user in group "unav.radius.wifi.n1"
rlm_ldap (ldap): Reserved connection (3)
(11)           Using user DN from request
"uid=oscarrdg,ou=radius,o=accounts,dc=unav,dc=es"
(11)           Checking user object's member attributes
(11)             Performing unfiltered search in
"uid=oscarrdg,ou=radius,o=accounts,dc=unav,dc=es", scope "base"
(11)             Waiting for search result...
(11)           Processing member value
"uid=unav.radius.vpn.ssl,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" as a
DN
(11)             Resolving group DN
"uid=unav.radius.vpn.ssl,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" to
group name
(11)             Performing unfiltered search in
"uid=unav.radius.vpn.ssl,ou=groups,ou=radius,o=accounts,dc=unav,dc=es",
scope "base"
(11)             Waiting for search result...
(11)             Group DN
"uid=unav.radius.vpn.ssl,ou=groups,ou=radius,o=accounts,dc=unav,dc=es"
resolves to name "unav.radius.vpn.ssl"
(11)           Processing member value
"uid=unav.radius.eapol.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" as
a DN
(11)             Resolving group DN
"uid=unav.radius.eapol.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" to
group name
(11)             Performing unfiltered search in
"uid=unav.radius.eapol.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es",
scope "base"
(11)             Waiting for search result...
(11)             Group DN
"uid=unav.radius.eapol.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es"
resolves to name "unav.radius.eapol.n1"
(11)           Processing member value
"uid=unav.radius.wifi.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" as a
DN
(11)             Resolving group DN
"uid=unav.radius.wifi.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es" to
group name
(11)             Performing unfiltered search in
"uid=unav.radius.wifi.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es",
scope "base"
(11)             Waiting for search result...
(11)             Group DN
"uid=unav.radius.wifi.n1,ou=groups,ou=radius,o=accounts,dc=unav,dc=es"
resolves to name "unav.radius.wifi.n1"
(11)           User found in group "unav.radius.wifi.n1". Comparison
between membership: name (resolved from DN
"uid=unav.radius.wifi.n1,ou=groups,ou=radius,o=accounts,dc=unav,d?"),
check: name
rlm_ldap (ldap): Released connection (3)
(11)           elsif ( Ldap-Group == "unav.radius.wifi.n1" )  -> TRUE
(11)           elsif ( Ldap-Group == "unav.radius.wifi.n1" )  {
(11)             update reply {
(11)               &Reply-Message += 'role.n1'
(11)               &Aruba-User-Role := 'role.n1'
(11)             } # update reply = noop
(11)           } # elsif ( Ldap-Group == "unav.radius.wifi.n1" )  = noop
(11)           ... skipping elsif for request 11: Preceding "if" was taken
(11)           ... skipping elsif for request 11: Preceding "if" was taken
(11)           ... skipping elsif for request 11: Preceding "if" was taken
(11)           ... skipping elsif for request 11: Preceding "if" was taken
(11)           ... skipping elsif for request 11: Preceding "if" was taken
(11)           ... skipping elsif for request 11: Preceding "if" was taken
(11)           ... skipping elsif for request 11: Preceding "if" was taken
(11)           ... skipping else for request 11: Preceding "if" was taken
(11)         } # if ( "%{client:group}" == 'wireless'  )  = updated
(11)         ... skipping elsif for request 11: Preceding "if" was taken
(11)         ... skipping elsif for request 11: Preceding "if" was taken
(11)         ... skipping else for request 11: Preceding "if" was taken
(11)       } # policy unav.authorize.inner = updated
(11)       [expiration] = noop
(11)       [logintime] = noop
(11) pap: Converted: Password-With-Header -> SHA1-Password
(11) pap: Removing &control:Password-With-Header
(11) pap: Normalizing SHA1-Password from base64 encoding, 28 bytes -> 20
bytes
(11) pap: WARNING: Auth-Type already set.  Not setting to PAP
(11)       [pap] = noop
(11)       if (control:Cleartext-Password) {
(11)       if (control:Cleartext-Password)  -> FALSE
(11)     } # authorize = updated
(11)   Found Auth-Type = EAP
(11)   # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/inner-tunnel
(11)     authenticate {
(11) eap: Expiring EAP session with state 0x739669f1739a7348
(11) eap: Finished EAP session with state 0x739669f1739a7348
(11) eap: Previous EAP request found for state 0x739669f1739a7348, released
from the list
(11) eap: Peer sent packet with method EAP MSCHAPv2 (26)
(11) eap: Calling submodule eap_mschapv2 to process data
(11) eap_mschapv2: # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/inner-tunnel
(11) eap_mschapv2:   Auth-Type MS-CHAP {
(11) mschap: Creating challenge hash with username: oscarrdg@unav.es
(11) mschap: Client is using MS-CHAPv2
(11) mschap: Executing: /usr/bin/ntlm_auth --request-nt-key
--username=%{%{Stripped-User-Name}:-%{%{User-Name}:-None}}
--challenge=%{%{mschap:Challenge}:-00}
--nt-response=%{%{mschap:NT-Response}:-00}:
(11) mschap: EXPAND
--username=%{%{Stripped-User-Name}:-%{%{User-Name}:-None}}
(11) mschap:    --> --username=oscarrdg
(11) mschap: Creating challenge hash with username: oscarrdg@unav.es
(11) mschap: EXPAND --challenge=%{%{mschap:Challenge}:-00}
(11) mschap:    --> --challenge=c159f634076ecce6
(11) mschap: EXPAND --nt-response=%{%{mschap:NT-Response}:-00}
(11) mschap:    -->
--nt-response=d4ced6b15b809861069531ec2cb89e3001db98c94f9018e8
(11) mschap: Program returned code (0) and output 'NT_KEY:
751E91FBC94E37990A2A5B973C29E4D3'
(11) mschap: Adding MS-CHAPv2 MPPE keys
(11)     [mschap] = ok
(11)   } # Auth-Type MS-CHAP = ok
(11) MSCHAP Success
(11) eap: Sending EAP Request (code 1) ID 13 length 51
(11) eap: EAP session adding &reply:State = 0x739669f1729b7348
(11)       [eap] = handled
(11)     } # authenticate = handled
(11) } # server inner-tunnel
(11) Virtual server sending reply
(11)   User-Name = "oscarrdg@unav.es"
(11)   Reply-Message += "role.n1"
(11)   Aruba-User-Role := "role.n1"
(11)   EAP-Message =
0x010d00331a030c002e533d38443639364430423633413735314239363137343538304636313145354531323335434636443942
(11)   Message-Authenticator = 0x00000000000000000000000000000000
(11)   State = 0x739669f1729b7348a02455fb687f2caa
(11) eap_peap: Got tunneled reply code 11
(11) eap_peap:   User-Name = "oscarrdg@unav.es"
(11) eap_peap:   Reply-Message += "role.n1"
(11) eap_peap:   Aruba-User-Role := "role.n1"
(11) eap_peap:   EAP-Message =
0x010d00331a030c002e533d38443639364430423633413735314239363137343538304636313145354531323335434636443942
(11) eap_peap:   Message-Authenticator = 0x00000000000000000000000000000000
(11) eap_peap:   State = 0x739669f1729b7348a02455fb687f2caa
(11) eap_peap: Got tunneled reply RADIUS code 11
(11) eap_peap:   User-Name = "oscarrdg@unav.es"
(11) eap_peap:   Reply-Message += "role.n1"
(11) eap_peap:   Aruba-User-Role := "role.n1"
(11) eap_peap:   EAP-Message =
0x010d00331a030c002e533d38443639364430423633413735314239363137343538304636313145354531323335434636443942
(11) eap_peap:   Message-Authenticator = 0x00000000000000000000000000000000
(11) eap_peap:   State = 0x739669f1729b7348a02455fb687f2caa
(11) eap_peap: Got tunneled Access-Challenge
(11) eap: Sending EAP Request (code 1) ID 13 length 82
(11) eap: EAP session adding &reply:State = 0x4a7c50d94171497d
(11)     [eap] = handled
(11)   } # authenticate = handled
(11) Using Post-Auth-Type Challenge
(11) Post-Auth-Type sub-section not found.  Ignoring.
(11) # Executing group from file
/usr/local/etc2/freeradius/etc/raddb/sites-enabled/default
(11) Sent Access-Challenge Id 240 from xxx:1812 to xxxxxx:45903 length 0
(11)   EAP-Message =
0x010d0052190017030300476a2a84ab3ae80e65df331667562db833be49b2c3281f498c160a1da57850e4c4db22bfebfcec126265d589bbfe90d31b5ded9e845dd71e0e30a7911af58f1d022b04703a397886
(11)   Message-Authenticator = 0x00000000000000000000000000000000
(11)   State = 0x4a7c50d94171497df1395bb8ee5037b2
(11) Finished request
Waking up in 4.7 seconds.

*Oscar Remírez de Ganuza Satrústegui*
IT Services
Universidad de Navarra
Tel. +34 948425600 x803130
http://www.unav.edu/web/it/

iPad PEAP MSCHAPv2

Óscar Remírez de Ganuza Satrústegui