FreeRADIUS Version 3.0.12
Copyright (C) 1999-2016 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 ...
Ignoring old-style configuration entry "secret" in realm "~(.*\.)*rnpteste\.br$"
Debugger not attached
Creating attribute Unix-Group
Creating attribute LDAP-Group
tls: Using cached TLS configuration from previous invocation
tls: Using cached TLS configuration from previous invocation
[/etc/freeradius/mods-config/attr_filter/access_reject]:11 Check item "FreeRADIUS-Response-Delay" 	found in filter list for realm "DEFAULT". 
[/etc/freeradius/mods-config/attr_filter/access_reject]:11 Check item "FreeRADIUS-Response-Delay-USec" 	found in filter list for realm "DEFAULT". 
rlm_detail (auth_log): 'User-Password' suppressed, will not appear in detail output
rlm_cache (cache_eap): Driver rlm_cache_rbtree (module rlm_cache_rbtree) loaded and linked
rlm_mschap (mschap): using internal authentication
rlm_ldap: libldap vendor: OpenLDAP, version: 20442
rlm_ldap (ldap): Initialising connection pool
rlm_ldap (ldap): Opening additional connection (0), 1 of 32 pending slots used
rlm_ldap (ldap): Connecting to ldap://localhost:389
rlm_ldap (ldap): Bind successful
rlm_ldap (ldap): Opening additional connection (1), 1 of 31 pending slots used
rlm_ldap (ldap): Connecting to ldap://localhost:389
rlm_ldap (ldap): Bind successful
rlm_ldap (ldap): Opening additional connection (2), 1 of 30 pending slots used
rlm_ldap (ldap): Connecting to ldap://localhost:389
rlm_ldap (ldap): Bind successful
rlm_ldap (ldap): Opening additional connection (3), 1 of 29 pending slots used
rlm_ldap (ldap): Connecting to ldap://localhost:389
rlm_ldap (ldap): Bind successful
rlm_ldap (ldap): Opening additional connection (4), 1 of 28 pending slots used
rlm_ldap (ldap): Connecting to ldap://localhost:389
rlm_ldap (ldap): Bind successful
Ignoring "sql" (see raddb/mods-available/README.rst)
radiusd: #### Opening IP addresses and Ports ####
Listening on auth proto tcp address * port 2083 (TLS) bound to server default
Listening on auth proto tcp address :: port 2083 (TLS)
Listening on auth address 127.0.0.1 port 4000
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 * port 18120 bound to server inner-tunnel
Listening on proxy address * port 36116
Listening on proxy address :: port 46813
Ready to process requests
Listening on auth from client (200.130.15.23, 33001) -> (*, 2083, virtual-server=default)
Waking up in 0.1 seconds.
Waking up in 0.1 seconds.
Waking up in 0.1 seconds.
Waking up in 29.4 seconds.
(0) Application data status 7
(0) tls_recv: Access-Request packet from host 200.130.15.23 port 33001, id=0, length=161
(0) Received Access-Request Id 0 from 200.130.15.23:33001 to 0.0.0.0:2083 length 161
(0)   User-Name = "eduroam@rnpteste.br"
(0)   NAS-IP-Address = 127.0.0.1
(0)   Calling-Station-Id = "02-00-00-00-00-01"
(0)   Framed-MTU = 1400
(0)   NAS-Port-Type = Wireless-802.11
(0)   Service-Type = Framed-User
(0)   Connect-Info = "CONNECT 11Mbps 802.11b"
(0)   EAP-Message = 0x0200001801656475726f616d40726e7074657374652e6272
(0)   Message-Authenticator = 0x3eae28718e7454d14f37816adabd4878
(0)   Event-Timestamp = "Jul 13 2017 17:23:36 BRT"
(0)   Proxy-State = 0x30
(0) # Executing section authorize from file /etc/freeradius/sites-enabled/default
(0) # Executing group from file /etc/freeradius/sites-enabled/default
(0) eap: EAP session adding &reply:State = 0x161759b416165db0
(0) # Executing group from file /etc/freeradius/sites-enabled/default
(0) Sent Access-Challenge Id 0 from 0.0.0.0:2083 to 200.130.15.23:33001 length 0
(0)   EAP-Message = 0x010100160410104ff67bbf7ef4852235f519ee2b3e17
(0)   Message-Authenticator = 0x00000000000000000000000000000000
(0)   State = 0x161759b416165db0f3b4952e04b41a4d
(0)   Proxy-State = 0x30
Waking up in 0.3 seconds.
(0) Application data status 7
(0) tls_recv: Access-Request packet from host 200.130.15.23 port 33001, id=1, length=161
(1) Received Access-Request Id 1 from 200.130.15.23:33001 to 0.0.0.0:2083 length 161
(1)   User-Name = "eduroam@rnpteste.br"
(1)   NAS-IP-Address = 127.0.0.1
(1)   Calling-Station-Id = "02-00-00-00-00-01"
(1)   Framed-MTU = 1400
(1)   NAS-Port-Type = Wireless-802.11
(1)   Service-Type = Framed-User
(1)   Connect-Info = "CONNECT 11Mbps 802.11b"
(1)   EAP-Message = 0x020100060315
(1)   State = 0x161759b416165db0f3b4952e04b41a4d
(1)   Message-Authenticator = 0x03c7cdfd254a26072eaf6d48c8bdc3a6
(1)   Event-Timestamp = "Jul 13 2017 17:23:36 BRT"
(1)   Proxy-State = 0x31
(1) # Executing section authorize from file /etc/freeradius/sites-enabled/default
rlm_ldap (ldap): Reserved connection (0)
(1) ldap: Performing search in "dc=eduroam,dc=across" with filter "(uid=eduroam@rnpteste.br)", scope "sub"
(1) ldap: Waiting for search result...
Waking up in 0.3 seconds.
(1) ldap: Search returned no results
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://localhost:389
rlm_ldap (ldap): Bind successful
(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) # Executing group from file /etc/freeradius/sites-enabled/default
(1) eap: Expiring EAP session with state 0x161759b416165db0
(1) eap: Finished EAP session with state 0x161759b416165db0
(1) eap: Previous EAP request found for state 0x161759b416165db0, released from the list
(1) eap: Found mutually acceptable type TTLS (21)
(1) eap: EAP session adding &reply:State = 0x161759b417154cb0
(1) # Executing group from file /etc/freeradius/sites-enabled/default
(1) Sent Access-Challenge Id 1 from 0.0.0.0:2083 to 200.130.15.23:33001 length 0
(1)   EAP-Message = 0x010200061520
(1)   Message-Authenticator = 0x00000000000000000000000000000000
(1)   State = 0x161759b417154cb0f3b4952e04b41a4d
(1)   Proxy-State = 0x31
(0) Application data status 7
(0) tls_recv: Access-Request packet from host 200.130.15.23 port 33001, id=2, length=464
(2) Received Access-Request Id 2 from 200.130.15.23:33001 to 0.0.0.0:2083 length 464
(2)   User-Name = "eduroam@rnpteste.br"
(2)   NAS-IP-Address = 127.0.0.1
(2)   Calling-Station-Id = "02-00-00-00-00-01"
(2)   Framed-MTU = 1400
(2)   NAS-Port-Type = Wireless-802.11
(2)   Service-Type = Framed-User
(2)   Connect-Info = "CONNECT 11Mbps 802.11b"
(2)   EAP-Message = 0x0202013315001603010128010001240303d2f5eea4fdbe3d97827f69579ce66256228d2985dafcc71333fe57a0d62fe0610000aac030c02cc028c024c014c00a00a500a300a1009f006b006a0069006800390038003700360088008700860085c032c02ec02ac026c00fc005009d003d00350084c02fc0
(2)   State = 0x161759b417154cb0f3b4952e04b41a4d
(2)   Message-Authenticator = 0x6b32fe02a4f59cfaed271d8228aef6fa
(2)   Event-Timestamp = "Jul 13 2017 17:23:37 BRT"
(2)   Proxy-State = 0x32
(2) # Executing section authorize from file /etc/freeradius/sites-enabled/default
(2) # Executing group from file /etc/freeradius/sites-enabled/default
(2) eap: Expiring EAP session with state 0x161759b417154cb0
(2) eap: Finished EAP session with state 0x161759b417154cb0
(2) eap: Previous EAP request found for state 0x161759b417154cb0, released from the list
(2) eap: EAP session adding &reply:State = 0x161759b414144cb0
(2) # Executing group from file /etc/freeradius/sites-enabled/default
(2) Sent Access-Challenge Id 2 from 0.0.0.0:2083 to 200.130.15.23:33001 length 0
(2)   EAP-Message = 0x010303ec15c000000a96160303005e0200005a03035abb37296a95058a948ba3bddc38590e887f725d4ac8bd7bff479322ca926c85205b51bfdcc1a455f10894c0146589ba2cd9b3ea9dd4e663291e53621985c79168c030000012ff01000100000b000403000102000f00010116030308d30b0008cf00
(2)   Message-Authenticator = 0x00000000000000000000000000000000
(2)   State = 0x161759b414144cb0f3b4952e04b41a4d
(2)   Proxy-State = 0x32
Waking up in 0.2 seconds.
Waking up in 4.6 seconds.
(0) Application data status 7
(0) tls_recv: Access-Request packet from host 200.130.15.23 port 33001, id=3, length=464
(3) Received Access-Request Id 3 from 200.130.15.23:33001 to 0.0.0.0:2083 length 464
(3)   User-Name = "eduroam@rnpteste.br"
(3)   NAS-IP-Address = 127.0.0.1
(3)   Calling-Station-Id = "02-00-00-00-00-01"
(3)   Framed-MTU = 1400
(3)   NAS-Port-Type = Wireless-802.11
(3)   Service-Type = Framed-User
(3)   Connect-Info = "CONNECT 11Mbps 802.11b"
(3)   EAP-Message = 0x0202013315001603010128010001240303d2f5eea4fdbe3d97827f69579ce66256228d2985dafcc71333fe57a0d62fe0610000aac030c02cc028c024c014c00a00a500a300a1009f006b006a0069006800390038003700360088008700860085c032c02ec02ac026c00fc005009d003d00350084c02fc0
(3)   State = 0x161759b417154cb0f3b4952e04b41a4d
(3)   Message-Authenticator = 0x384c2741f7321ca2d8e8f78b9c0864b3
(3)   Event-Timestamp = "Jul 13 2017 17:23:37 BRT"
(3)   Proxy-State = 0x32
(3) # Executing section authorize from file /etc/freeradius/sites-enabled/default
(3) # Executing group from file /etc/freeradius/sites-enabled/default
(3) eap: Expiring EAP session with state 0x161759b414144cb0
rlm_eap (EAP): No EAP session matching state 0x161759b417154cb0
(3) eap: Either EAP-request timed out OR EAP-response to an unknown EAP-request
(3) # Executing group from file /etc/freeradius/sites-enabled/default
(3) eap: Expiring EAP session with state 0x161759b414144cb0
rlm_eap (EAP): No EAP session matching state 0x161759b417154cb0
(3) eap: Either EAP-request timed out OR EAP-response to an unknown EAP-request
Waking up in 0.3 seconds.
Waking up in 0.6 seconds.
(3) Sent Access-Reject Id 3 from 0.0.0.0:2083 to 200.130.15.23:33001 length 23
(3)   Proxy-State = 0x32
Waking up in 0.8 seconds.
Waking up in 3.0 seconds.
Waking up in 0.3 seconds.
Waking up in 24.9 seconds.