iPad PEAP MSCHAPv2

Óscar Remírez de Ganuza Satrústegui oscarrdg at unav.es
Thu Nov 19 16:54:40 CET 2015


Sorry, it got clipped...
I send again requests #10 and #11 (the last one):

(10) Received Access-Request Id 92 from xxxxxx:45903 to xxx12.225:1812
length 264
(10)   User-Name = "oscarrdg at 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 at 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 at unav.es
(10) eap_peap: Got inner identity 'oscarrdg at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at unav.es
(11)             &User-Name = oscarrdg at 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 at 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 at 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 at 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 at 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 at 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 xxx12.225: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/

On Thu, Nov 19, 2015 at 4:49 PM, Óscar Remírez de Ganuza Satrústegui <
oscarrdg at unav.es> wrote:

> 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at unav.es
> (10) eap_peap: Got inner identity 'oscarrdg at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at unav.es
> (11)             &User-Name = oscarrdg at 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 at 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 at 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 at 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 at 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 at 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/
>


More information about the Freeradius-Users mailing list