Starting - reading configuration files ...
reread_config:  reading radiusd.conf
Config:   including file: /usr/local/etc/raddb/clients.conf
Config:   including file: /usr/local/etc/raddb/eap.conf
Config:   including file: /usr/local/etc/raddb/sql.conf
 main: prefix = "/usr/local"
 main: localstatedir = "/usr/local/var"
 main: logdir = "/usr/local/var/log/radius"
 main: libdir = "/usr/local/lib"
 main: radacctdir = "/usr/local/var/log/radius/radacct"
 main: hostname_lookups = no
 main: max_request_time = 30
 main: cleanup_delay = 5
 main: max_requests = 1024
 main: delete_blocked_requests = 0
 main: port = 0
 main: allow_core_dumps = no
 main: log_stripped_names = no
 main: log_file = "/usr/local/var/log/radius/radius.log"
 main: log_auth = yes
 main: log_auth_badpass = yes
 main: log_auth_goodpass = yes
 main: pidfile = "/usr/local/var/run/radiusd/radiusd.pid"
 main: user = "(null)"
 main: group = "(null)"
 main: usercollide = yes
 main: lower_user = "no"
 main: lower_pass = "no"
 main: nospace_user = "no"
 main: nospace_pass = "no"
 main: checkrad = "/usr/local/sbin/checkrad"
 main: proxy_requests = no
 security: max_attributes = 200
 security: reject_delay = 1
 security: status_server = no
 main: debug_level = 0
read_config_files:  reading dictionary
read_config_files:  reading naslist
Using deprecated naslist file.  Support for this will go away soon.
read_config_files:  reading clients
read_config_files:  reading realms
 listen: ipaddr = 10.10.10.4 IP address [10.10.10.4]
 listen: port = 1812
 listen: type = "auth"
 listen: ipaddr = 10.10.10.4 IP address [10.10.10.4]
 listen: port = 1813
 listen: type = "acct"
radiusd:  entering modules setup
Module: Library search path is /usr/local/lib
Module: Loaded expr 
Module: Instantiated expr (expr) 
Module: Loaded PAP 
 pap: encryption_scheme = "crypt"
 pap: auto_header = no
Module: Instantiated pap (pap) 
Module: Loaded eap 
 eap: default_eap_type = "ttls"
 eap: timer_expire = 60
 eap: ignore_unknown_eap_types = yes
 eap: cisco_accounting_username_bug = yes
 gtc: challenge = "Password: "
 gtc: auth_type = "PAP"
rlm_eap: Loaded and initialized type gtc
 tls: rsa_key_exchange = no
 tls: dh_key_exchange = yes
 tls: rsa_key_length = 512
 tls: dh_key_length = 512
 tls: verify_depth = 0
 tls: CA_path = "(null)"
 tls: pem_file_type = yes
 tls: private_key_file = "/usr/local/etc/raddb/certs/cert-srv.pem"
 tls: certificate_file = "/usr/local/etc/raddb/certs/cert-srv.pem"
 tls: CA_file = "/usr/local/etc/raddb/certs/demoCA/cacert.pem"
 tls: private_key_password = "whatever"
 tls: dh_file = "/usr/local/etc/raddb/certs/dh"
 tls: random_file = "/usr/local/etc/raddb/certs/random"
 tls: fragment_size = 1024
 tls: include_length = yes
 tls: check_crl = no
 tls: check_cert_cn = "(null)"
 tls: cipher_list = "(null)"
 tls: check_cert_issuer = "(null)"
rlm_eap_tls: Loading the certificate file as a chain
rlm_eap: Loaded and initialized type tls
 ttls: default_eap_type = "gtc"
 ttls: copy_request_to_tunnel = no
 ttls: use_tunneled_reply = yes
rlm_eap: Loaded and initialized type ttls
Module: Instantiated eap (eap) 
Module: Loaded SQL 
 sql: driver = "rlm_sql_mysql"
 sql: server = "localhost"
 sql: port = ""
 sql: login = "radius"
 sql: password = "teste"
 sql: radius_db = "radius"
 sql: nas_table = "nas"
 sql: sqltrace = no
 sql: sqltracefile = "/usr/local/var/log/radius/sqltrace.sql"
 sql: readclients = no
 sql: deletestalesessions = yes
 sql: num_sql_socks = 5
 sql: sql_user_name = "%{User-Name}"
 sql: default_user_profile = ""
 sql: query_on_not_found = no
 sql: authorize_check_query = "SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = '%{SQL-User-Name}'          ORDER BY id"
 sql: authorize_reply_query = "SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = '%{SQL-User-Name}'           ORDER BY id"
 sql: authorize_group_check_query = ""
 sql: authorize_group_reply_query = ""
 sql: accounting_onoff_query = "UPDATE radacct SET AcctStopTime='%S', AcctSessionTime=unix_timestamp('%S') - unix_timestamp(AcctStartTime), AcctTerminateCause='%{Acct-Terminate-Cause}', AcctStopDelay = '%{Acct-Delay-Time}' WHERE AcctSessionTime=0 AND AcctStopTime=0 AND NASIPAddress= '%{NAS-IP-Address}' AND AcctStartTime <= '%S'"
 sql: accounting_update_query = "UPDATE radacct           SET FramedIPAddress = '%{Framed-IP-Address}',           AcctSessionTime = '%{Acct-Session-Time}',           AcctInputOctets = '%{Acct-Input-Octets}',           AcctOutputOctets = '%{Acct-Output-Octets}'           WHERE AcctSessionId = '%{Acct-Session-Id}'           AND UserName = '%{SQL-User-Name}'           AND NASIPAddress= '%{NAS-IP-Address}'"
 sql: accounting_update_query_alt = "INSERT into radacct (AcctSessionId, AcctUniqueId, UserName, Realm, NASIPAddress, NASPortId, NASPortType, AcctStartTime, AcctSessionTime, AcctAuthentic, ConnectInfo_start, AcctInputOctets, AcctOutputOctets, CalledStationId, CallingStationId, ServiceType, FramedProtocol, FramedIPAddress, AcctStartDelay) values('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}', '%{SQL-User-Name}', '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}', '%{NAS-Port-Type}', DATE_SUB('%S',INTERVAL (%{Acct-Session-Time:-0} + %{Acct-Delay-Time:-0}) SECOND), '%{Acct-Session-Time}', '%{Acct-Authentic}', '', '%{Acct-Input-Octets}', '%{Acct-Output-Octets}', '%{Called-Station-Id}', '%{Calling-Station-Id}', '%{Service-Type}', '%{Framed-Protocol}', '%{Framed-IP-Address}', '0')"
 sql: accounting_start_query = "INSERT into radacct (AcctSessionId, AcctUniqueId, UserName, Realm, NASIPAddress, NASPortId, NASPortType, AcctStartTime, AcctStopTime, AcctSessionTime, AcctAuthentic, ConnectInfo_start, ConnectInfo_stop, AcctInputOctets, AcctOutputOctets, CalledStationId, CallingStationId, AcctTerminateCause, ServiceType, FramedProtocol, FramedIPAddress, AcctStartDelay, AcctStopDelay) values('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}', '%{SQL-User-Name}', '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}', '%{NAS-Port-Type}', '%S', '0', '0', '%{Acct-Authentic}', '%{Connect-Info}', '', '0', '0', '%{Called-Station-Id}', '%{Calling-Station-Id}', '', '%{Service-Type}', '%{Framed-Protocol}', '%{Framed-IP-Address}', '%{Acct-Delay-Time}', '0')"
 sql: accounting_start_query_alt = "UPDATE radacct SET AcctStartTime = '%S', AcctStartDelay = '%{Acct-Delay-Time}', ConnectInfo_start = '%{Connect-Info}' WHERE AcctSessionId = '%{Acct-Session-Id}' AND UserName = '%{SQL-User-Name}' AND NASIPAddress = '%{NAS-IP-Address}'"
 sql: accounting_stop_query = "UPDATE radacct SET AcctStopTime = '%S', AcctSessionTime = '%{Acct-Session-Time}', AcctInputOctets = '%{Acct-Input-Octets}', AcctOutputOctets = '%{Acct-Output-Octets}', AcctTerminateCause = '%{Acct-Terminate-Cause}', AcctStopDelay = '%{Acct-Delay-Time}', ConnectInfo_stop = '%{Connect-Info}' WHERE AcctSessionId = '%{Acct-Session-Id}' AND UserName = '%{SQL-User-Name}' AND NASIPAddress = '%{NAS-IP-Address}'"
 sql: accounting_stop_query_alt = "INSERT into radacct (AcctSessionId, AcctUniqueId, UserName, Realm, NASIPAddress, NASPortId, NASPortType, AcctStartTime, AcctStopTime, AcctSessionTime, AcctAuthentic, ConnectInfo_start, ConnectInfo_stop, AcctInputOctets, AcctOutputOctets, CalledStationId, CallingStationId, AcctTerminateCause, ServiceType, FramedProtocol, FramedIPAddress, AcctStartDelay, AcctStopDelay) values('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}', '%{SQL-User-Name}', '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}', '%{NAS-Port-Type}', DATE_SUB('%S', INTERVAL (%{Acct-Session-Time:-0} + %{Acct-Delay-Time:-0}) SECOND), '%S', '%{Acct-Session-Time}', '%{Acct-Authentic}', '', '%{Connect-Info}', '%{Acct-Input-Octets}', '%{Acct-Output-Octets}', '%{Called-Station-Id}', '%{Calling-Station-Id}', '%{Acct-Terminate-Cause}', '%{Service-Type}', '%{Framed-Protocol}', '%{Framed-IP-Address}', '0', '%{Acct-Delay-Time}')"
 sql: group_membership_query = "SELECT GroupName FROM usergroup WHERE UserName='%{SQL-User-Name}'"
 sql: connect_failure_retry_delay = 60
 sql: simul_count_query = "SELECT COUNT(*) FROM radacct WHERE UserName='%{SQL-User-Name}' AND AcctStopTime = 0"
 sql: simul_verify_query = "SELECT RadAcctId, AcctSessionId, UserName, NASIPAddress, NASPortId, FramedIPAddress, CallingStationId, FramedProtocol FROM radacct WHERE UserName='%{SQL-User-Name}' AND AcctStopTime = 0"
 sql: postauth_query = "INSERT into radpostauth (id, user, pass, reply, date) values ('', '%{User-Name}', '%{User-Password:-Chap-Password}', '%{reply:Packet-Type}', NOW())"
 sql: safe-characters = "@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /"
rlm_sql (sql): Driver rlm_sql_mysql (module rlm_sql_mysql) loaded and linked
rlm_sql (sql): Attempting to connect to root@localhost:/radius
rlm_sql (sql): starting 0
rlm_sql (sql): Attempting to connect rlm_sql_mysql #0
rlm_sql_mysql: Starting connect to MySQL server for #0
rlm_sql (sql): Connected new DB handle, #0
rlm_sql (sql): starting 1
rlm_sql (sql): Attempting to connect rlm_sql_mysql #1
rlm_sql_mysql: Starting connect to MySQL server for #1
rlm_sql (sql): Connected new DB handle, #1
rlm_sql (sql): starting 2
rlm_sql (sql): Attempting to connect rlm_sql_mysql #2
rlm_sql_mysql: Starting connect to MySQL server for #2
rlm_sql (sql): Connected new DB handle, #2
rlm_sql (sql): starting 3
rlm_sql (sql): Attempting to connect rlm_sql_mysql #3
rlm_sql_mysql: Starting connect to MySQL server for #3
rlm_sql (sql): Connected new DB handle, #3
rlm_sql (sql): starting 4
rlm_sql (sql): Attempting to connect rlm_sql_mysql #4
rlm_sql_mysql: Starting connect to MySQL server for #4
rlm_sql (sql): Connected new DB handle, #4
Module: Instantiated sql (sql) 
Module: Loaded preprocess 
 preprocess: huntgroups = "/usr/local/etc/raddb/huntgroups"
 preprocess: hints = "/usr/local/etc/raddb/hints"
 preprocess: with_ascend_hack = no
 preprocess: ascend_channels_per_line = 23
 preprocess: with_ntdomain_hack = no
 preprocess: with_specialix_jetstream_hack = no
 preprocess: with_cisco_vsa_hack = no
 preprocess: with_alvarion_vsa_hack = no
Module: Instantiated preprocess (preprocess) 
Module: Loaded detail 
 detail: detailfile = "/usr/local/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d"
 detail: detailperm = 384
 detail: dirperm = 493
 detail: locking = no
Module: Instantiated detail (detail) 
Module: Loaded System 
 unix: cache = no
 unix: passwd = "(null)"
 unix: shadow = "(null)"
 unix: group = "(null)"
 unix: radwtmp = "/usr/local/var/log/radius/radwtmp"
 unix: usegroup = no
 unix: cache_reload = 600
Module: Instantiated unix (unix) 
Module: Loaded radutmp 
 radutmp: filename = "/usr/local/var/log/radius/radutmp"
 radutmp: username = "%{User-Name}"
 radutmp: case_sensitive = yes
 radutmp: check_with_nas = yes
 radutmp: perm = 384
 radutmp: callerid = yes
Module: Instantiated radutmp (radutmp) 
Module: Loaded sql_log 
 sql_log: path = "/usr/local/var/log/radius/radacct/sql-relay"
 sql_log: Post-Auth = "INSERT INTO radpostauth                   ?? (user, pass, reply, date) VALUES                            ?? ('%{User-Name}', '%{User-Password:-Chap-Password}',         ?? '%{reply:Packet-Type}', '%S');"
 sql_log: sql_user_name = ""
 sql_log: safe-characters = "@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /"
Module: Instantiated sql_log (sql_log) 
Initializing the thread pool...
 thread: start_servers = 5
 thread: max_servers = 32
 thread: min_spare_servers = 3
 thread: max_spare_servers = 10
 thread: max_requests_per_server = 0
 thread: cleanup_delay = 5
Thread spawned new child 1. Total threads in pool: 1
Thread spawned new child 2. Total threads in pool: 2
Thread spawned new child 3. Total threads in pool: 3
Thread spawned new child 4. Total threads in pool: 4
Thread spawned new child 5. Total threads in pool: 5
Thread pool initialized
Listening on authentication 10.10.10.4:1812
Listening on accounting 10.10.10.4:1813
Ready to process requests.
Thread 1 waiting to be assigned a request
Thread 2 waiting to be assigned a request
Thread 3 waiting to be assigned a request
Thread 4 waiting to be assigned a request
Thread 5 waiting to be assigned a request
rad_recv: Access-Request packet from host 10.10.10.5:1645, id=1, length=164
--- Walking the entire request list ---
Thread 1 got semaphore
Waking up in 31 seconds...
Thread 1 handling request 0, (1 handled so far)
Threads: total/active/spare threads = 5/1/4
	User-Name = "gilberto"
	Framed-MTU = 1400
	Called-Station-Id = "001b.5303.b550"
	Calling-Station-Id = "0040.96a2.24f3"
	Cisco-AVPair = "ssid=FONTELONGA"
	Service-Type = Login-User
	Message-Authenticator = 0xd851cbf6dde5769b03a7f02adf8e073c
	EAP-Message = 0x0202000d0167696c626572746f
	NAS-Port-Type = Wireless-802.11
	Cisco-NAS-Port = "257"
	NAS-Port = 257
	NAS-IP-Address = 10.10.10.5
	NAS-Identifier = "ap2"
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 0
  rlm_eap: EAP packet type response id 2 length 13
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 0
radius_xlat:  'gilberto'
rlm_sql (sql): sql_set_user escaped user --> 'gilberto'
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = 'gilberto'          ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 4
radius_xlat:  ''
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = 'gilberto'           ORDER BY id'
radius_xlat:  ''
rlm_sql (sql): Released sql socket id: 4
  modcall[authorize]: module "sql" returns ok for request 0
rlm_pap: Found existing Auth-Type, not changing it.
  modcall[authorize]: module "pap" returns noop for request 0
modcall: leaving group authorize (returns updated) for request 0
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 0
  rlm_eap: EAP Identity
  rlm_eap: processing type tls
  rlm_eap_tls: Initiate
  rlm_eap_tls: Start returned 1
  modcall[authenticate]: module "eap" returns handled for request 0
modcall: leaving group authenticate (returns handled) for request 0
Sending Access-Challenge of id 1 to 10.10.10.5 port 1645
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
	EAP-Message = 0x010300061520
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x2640822bfb1a99bc7558d40d28abd230
Finished request 0
Going to the next request
Thread 1 waiting to be assigned a request
rad_recv: Access-Request packet from host 10.10.10.5:1645, id=2, length=229
--- Walking the entire request list ---
Thread 2 got semaphore
Waking up in 5 seconds...
Thread 2 handling request 1, (1 handled so far)
	User-Name = "gilberto"
	Framed-MTU = 1400
	Called-Station-Id = "001b.5303.b550"
	Calling-Station-Id = "0040.96a2.24f3"
	Cisco-AVPair = "ssid=FONTELONGA"
	Service-Type = Login-User
	Message-Authenticator = 0x3a8443a856532cbdb56a0acb1a134e7c
	EAP-Message = 0x0203003c158000000032160301002d010000290301ab105cfd8a61041af72dfc7175802147bc1c5dcf85589d13dd010a9878bc0220000002000a0100
	NAS-Port-Type = Wireless-802.11
	Cisco-NAS-Port = "257"
	NAS-Port = 257
	State = 0x2640822bfb1a99bc7558d40d28abd230
	NAS-IP-Address = 10.10.10.5
	NAS-Identifier = "ap2"
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 1
  rlm_eap: EAP packet type response id 3 length 60
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 1
radius_xlat:  'gilberto'
rlm_sql (sql): sql_set_user escaped user --> 'gilberto'
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = 'gilberto'          ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 3
radius_xlat:  ''
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = 'gilberto'           ORDER BY id'
radius_xlat:  ''
rlm_sql (sql): Released sql socket id: 3
  modcall[authorize]: module "sql" returns ok for request 1
rlm_pap: Found existing Auth-Type, not changing it.
  modcall[authorize]: module "pap" returns noop for request 1
modcall: leaving group authorize (returns updated) for request 1
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 1
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/ttls
  rlm_eap: processing type ttls
  rlm_eap_ttls: Authenticate
  rlm_eap_tls: processing TLS
rlm_eap_tls:  Length Included
  eaptls_verify returned 11 
    (other): before/accept initialization 
    TLS_accept: before/accept initialization 
  rlm_eap_tls: <<< TLS 1.0 Handshake [length 002d], ClientHello  
    TLS_accept: SSLv3 read client hello A 
  rlm_eap_tls: >>> TLS 1.0 Handshake [length 004a], ServerHello  
    TLS_accept: SSLv3 write server hello A 
  rlm_eap_tls: >>> TLS 1.0 Handshake [length 0694], Certificate  
    TLS_accept: SSLv3 write certificate A 
  rlm_eap_tls: >>> TLS 1.0 Handshake [length 0004], ServerHelloDone  
    TLS_accept: SSLv3 write server done A 
    TLS_accept: SSLv3 flush data 
    TLS_accept: Need to read more data: SSLv3 read client certificate A
In SSL Handshake Phase 
In SSL Accept mode  
  eaptls_process returned 13 
  modcall[authenticate]: module "eap" returns handled for request 1
modcall: leaving group authenticate (returns handled) for request 1
Sending Access-Challenge of id 2 to 10.10.10.5 port 1645
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = 0x652e636f6d301e170d3034303132353133323630375a
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x3f02967e5311f3941365ed57ed49ed0a
Finished request 1
Going to the next request
Thread 2 waiting to be assigned a request
rad_recv: Access-Request packet from host 10.10.10.5:1645, id=3, length=175
Waking up in 5 seconds...
Thread 3 got semaphore
Threads: total/active/spare threads = 5/0/5
Thread 3 handling request 2, (1 handled so far)
	User-Name = "gilberto"
	Framed-MTU = 1400
	Called-Station-Id = "001b.5303.b550"
	Calling-Station-Id = "0040.96a2.24f3"
	Cisco-AVPair = "ssid=FONTELONGA"
	Service-Type = Login-User
	Message-Authenticator = 0xfd9a70c135c3f7ea5184249dd0f2d0d1
	EAP-Message = 0x020400061500
	NAS-Port-Type = Wireless-802.11
	Cisco-NAS-Port = "257"
	NAS-Port = 257
	State = 0x3f02967e5311f3941365ed57ed49ed0a
	NAS-IP-Address = 10.10.10.5
	NAS-Identifier = "ap2"
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 2
  rlm_eap: EAP packet type response id 4 length 6
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 2
radius_xlat:  'gilberto'
rlm_sql (sql): sql_set_user escaped user --> 'gilberto'
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = 'gilberto'          ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 2
radius_xlat:  ''
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = 'gilberto'           ORDER BY id'
radius_xlat:  ''
rlm_sql (sql): Released sql socket id: 2
  modcall[authorize]: module "sql" returns ok for request 2
rlm_pap: Found existing Auth-Type, not changing it.
  modcall[authorize]: module "pap" returns noop for request 2
modcall: leaving group authorize (returns updated) for request 2
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 2
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/ttls
  rlm_eap: processing type ttls
  rlm_eap_ttls: Authenticate
  rlm_eap_tls: processing TLS
rlm_eap_tls: Received EAP-TLS ACK message
  rlm_eap_tls: ack handshake fragment handler
  eaptls_verify returned 1 
  eaptls_process returned 13 
  modcall[authenticate]: module "eap" returns handled for request 2
modcall: leaving group authenticate (returns handled) for request 2
Sending Access-Challenge of id 3 to 10.10.10.5 port 1645
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = 0x0e000000
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x2eb817848bcbe259afca070697a8478f
Finished request 2
Going to the next request
Thread 3 waiting to be assigned a request
rad_recv: Access-Request packet from host 10.10.10.5:1645, id=4, length=369
Waking up in 5 seconds...
Thread 4 got semaphore
Thread 4 handling request 3, (1 handled so far)
	User-Name = "gilberto"
	Framed-MTU = 1400
	Called-Station-Id = "001b.5303.b550"
	Calling-Station-Id = "0040.96a2.24f3"
	Cisco-AVPair = "ssid=FONTELONGA"
	Service-Type = Login-User
	Message-Authenticator = 0x2c6017c5b727c3d32fd1c0545d517374
	EAP-Message = [payload removed: 402 characters]
	NAS-Port-Type = Wireless-802.11
	Cisco-NAS-Port = "257"
	NAS-Port = 257
	State = 0x2eb817848bcbe259afca070697a8478f
	NAS-IP-Address = 10.10.10.5
	NAS-Identifier = "ap2"
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 3
  rlm_eap: EAP packet type response id 5 length 200
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 3
radius_xlat:  'gilberto'
rlm_sql (sql): sql_set_user escaped user --> 'gilberto'
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = 'gilberto'          ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 1
radius_xlat:  ''
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = 'gilberto'           ORDER BY id'
radius_xlat:  ''
rlm_sql (sql): Released sql socket id: 1
  modcall[authorize]: module "sql" returns ok for request 3
rlm_pap: Found existing Auth-Type, not changing it.
  modcall[authorize]: module "pap" returns noop for request 3
modcall: leaving group authorize (returns updated) for request 3
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 3
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/ttls
  rlm_eap: processing type ttls
  rlm_eap_ttls: Authenticate
  rlm_eap_tls: processing TLS
rlm_eap_tls:  Length Included
  eaptls_verify returned 11 
  rlm_eap_tls: <<< TLS 1.0 Handshake [length 0086], ClientKeyExchange  
    TLS_accept: SSLv3 read client key exchange A 
  rlm_eap_tls: <<< TLS 1.0 ChangeCipherSpec [length 0001]  
  rlm_eap_tls: <<< TLS 1.0 Handshake [length 0010], Finished  
    TLS_accept: SSLv3 read finished A 
  rlm_eap_tls: >>> TLS 1.0 ChangeCipherSpec [length 0001]  
    TLS_accept: SSLv3 write change cipher spec A 
  rlm_eap_tls: >>> TLS 1.0 Handshake [length 0010], Finished  
    TLS_accept: SSLv3 write finished A 
    TLS_accept: SSLv3 flush data 
    (other): SSL negotiation finished successfully 
SSL Connection Established 
  eaptls_process returned 13 
  modcall[authenticate]: module "eap" returns handled for request 3
modcall: leaving group authenticate (returns handled) for request 3
Sending Access-Challenge of id 4 to 10.10.10.5 port 1645
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
	EAP-Message = 0x0106003d1580000000331403010001011603010028cacebed246a55da732d23a1ad0f5124c46cdcfed950ceeac1c8148d3b834a9938cfd332a52244a4f
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x9d0b70005d052d3eeb65e301b9fb67fb
Finished request 3
Going to the next request
Thread 4 waiting to be assigned a request
rad_recv: Access-Request packet from host 10.10.10.5:1645, id=5, length=240
Waking up in 5 seconds...
Thread 5 got semaphore
Thread 5 handling request 4, (1 handled so far)
	User-Name = "gilberto"
	Framed-MTU = 1400
	Called-Station-Id = "001b.5303.b550"
	Calling-Station-Id = "0040.96a2.24f3"
	Cisco-AVPair = "ssid=FONTELONGA"
	Service-Type = Login-User
	Message-Authenticator = 0x1e273634b577ce22ab8e0bc234bb120e
	EAP-Message = 0x0206004715800000003d1703010038a971abc5e5c91700396cb557d5a2616281a92de5fd18f98263e1081de2f9a26ebc758e765ea215164bbc1b39754edbd88eb54e9594fad09d
	NAS-Port-Type = Wireless-802.11
	Cisco-NAS-Port = "257"
	NAS-Port = 257
	State = 0x9d0b70005d052d3eeb65e301b9fb67fb
	NAS-IP-Address = 10.10.10.5
	NAS-Identifier = "ap2"
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 4
  rlm_eap: EAP packet type response id 6 length 71
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 4
radius_xlat:  'gilberto'
rlm_sql (sql): sql_set_user escaped user --> 'gilberto'
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = 'gilberto'          ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 0
radius_xlat:  ''
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = 'gilberto'           ORDER BY id'
radius_xlat:  ''
rlm_sql (sql): Released sql socket id: 0
  modcall[authorize]: module "sql" returns ok for request 4
rlm_pap: Found existing Auth-Type, not changing it.
  modcall[authorize]: module "pap" returns noop for request 4
modcall: leaving group authorize (returns updated) for request 4
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 4
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/ttls
  rlm_eap: processing type ttls
  rlm_eap_ttls: Authenticate
  rlm_eap_tls: processing TLS
rlm_eap_tls:  Length Included
  eaptls_verify returned 11 
  eaptls_process returned 7 
  rlm_eap_ttls: Session established.  Proceeding to decode tunneled attributes.
  TTLS: Got tunneled request
	User-Name = "gilberto"
	User-Password = "teste"
	FreeRADIUS-Proxied-To = 127.0.0.1
  TTLS: Sending tunneled request
	User-Name = "gilberto"
	User-Password = "teste"
	FreeRADIUS-Proxied-To = 127.0.0.1
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 4
  rlm_eap: No EAP-Message, not doing EAP
  modcall[authorize]: module "eap" returns noop for request 4
radius_xlat:  'gilberto'
rlm_sql (sql): sql_set_user escaped user --> 'gilberto'
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = 'gilberto'          ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 4
radius_xlat:  ''
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = 'gilberto'           ORDER BY id'
radius_xlat:  ''
rlm_sql (sql): Released sql socket id: 4
  modcall[authorize]: module "sql" returns ok for request 4
  modcall[authorize]: module "pap" returns updated for request 4
modcall: leaving group authorize (returns updated) for request 4
  rad_check_password:  Found Auth-Type pap
auth: type "PAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group PAP for request 4
rlm_pap: login attempt with password teste
rlm_pap: Using CRYPT encryption.
rlm_pap: User authenticated successfully
  modcall[authenticate]: module "pap" returns ok for request 4
modcall: leaving group PAP (returns ok) for request 4
Login OK: [gilberto/teste] (from client localhost port 0)
  TTLS: Got tunneled reply RADIUS code 2
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
  TTLS: Got tunneled Access-Accept
  rlm_eap: Freeing handler
  modcall[authenticate]: module "eap" returns ok for request 4
modcall: leaving group authenticate (returns ok) for request 4
Login OK: [gilberto/<no User-Password attribute>] (from client ap2 port 257 cli 0040.96a2.24f3)
Sending Access-Accept of id 5 to 10.10.10.5 port 1645
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
	MS-MPPE-Recv-Key = 0xa5b20de88cb44044f7692dfb23a14d91b733ef3b7716f1b116617f2475e3bf45
	MS-MPPE-Send-Key = 0x31730415a672eaec6510d3dbb0010352297ec09b1b521721c1c6367f082c884c
	EAP-Message = 0x03060004
	Message-Authenticator = 0x00000000000000000000000000000000
	User-Name = "gilberto"
Finished request 4
Going to the next request
Thread 5 waiting to be assigned a request
rad_recv: Access-Request packet from host 10.10.10.5:1645, id=6, length=164
--- Walking the entire request list ---
Thread 1 got semaphore
Waking up in 1 seconds...
Thread 1 handling request 5, (2 handled so far)
Threads: total/active/spare threads = 5/1/4
	User-Name = "gilberto"
	Framed-MTU = 1400
	Called-Station-Id = "001b.5303.b550"
	Calling-Station-Id = "0040.96a2.24f3"
	Cisco-AVPair = "ssid=FONTELONGA"
	Service-Type = Login-User
	Message-Authenticator = 0xb98300fca2a495c550f123b041f9f5c1
	EAP-Message = 0x0202000d0167696c626572746f
	NAS-Port-Type = Wireless-802.11
	Cisco-NAS-Port = "258"
	NAS-Port = 258
	NAS-IP-Address = 10.10.10.5
	NAS-Identifier = "ap2"
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 5
  rlm_eap: EAP packet type response id 2 length 13
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 5
radius_xlat:  'gilberto'
rlm_sql (sql): sql_set_user escaped user --> 'gilberto'
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = 'gilberto'          ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 3
radius_xlat:  ''
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = 'gilberto'           ORDER BY id'
radius_xlat:  ''
rlm_sql (sql): Released sql socket id: 3
  modcall[authorize]: module "sql" returns ok for request 5
rlm_pap: Found existing Auth-Type, not changing it.
  modcall[authorize]: module "pap" returns noop for request 5
modcall: leaving group authorize (returns updated) for request 5
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 5
  rlm_eap: EAP Identity
  rlm_eap: processing type tls
  rlm_eap_tls: Initiate
  rlm_eap_tls: Start returned 1
  modcall[authenticate]: module "eap" returns handled for request 5
modcall: leaving group authenticate (returns handled) for request 5
Sending Access-Challenge of id 6 to 10.10.10.5 port 1645
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
	EAP-Message = 0x010300061520
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xdf6f06023f9b69bc1204628a499f9da3
Finished request 5
Going to the next request
Thread 1 waiting to be assigned a request
rad_recv: Access-Request packet from host 10.10.10.5:1645, id=7, length=229
Cleaning up request 0 ID 1 with timestamp 467ec918
Thread 2 got semaphore
Waking up in 1 seconds...
Thread 2 handling request 6, (2 handled so far)
	User-Name = "gilberto"
	Framed-MTU = 1400
	Called-Station-Id = "001b.5303.b550"
	Calling-Station-Id = "0040.96a2.24f3"
	Cisco-AVPair = "ssid=FONTELONGA"
	Service-Type = Login-User
	Message-Authenticator = 0xe8f2e544703a219df2d35463a2f70ae0
	EAP-Message = 0x0203003c158000000032160301002d010000290301b7851237e4e3998647d46f94097f710a0fbb389e5af4c761db6bf3e65c58de17000002000a0100
	NAS-Port-Type = Wireless-802.11
	Cisco-NAS-Port = "258"
	NAS-Port = 258
	State = 0xdf6f06023f9b69bc1204628a499f9da3
	NAS-IP-Address = 10.10.10.5
	NAS-Identifier = "ap2"
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 6
  rlm_eap: EAP packet type response id 3 length 60
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 6
radius_xlat:  'gilberto'
rlm_sql (sql): sql_set_user escaped user --> 'gilberto'
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = 'gilberto'          ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 2
radius_xlat:  ''
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = 'gilberto'           ORDER BY id'
radius_xlat:  ''
rlm_sql (sql): Released sql socket id: 2
  modcall[authorize]: module "sql" returns ok for request 6
rlm_pap: Found existing Auth-Type, not changing it.
  modcall[authorize]: module "pap" returns noop for request 6
modcall: leaving group authorize (returns updated) for request 6
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 6
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/ttls
  rlm_eap: processing type ttls
  rlm_eap_ttls: Authenticate
  rlm_eap_tls: processing TLS
rlm_eap_tls:  Length Included
  eaptls_verify returned 11 
    (other): before/accept initialization 
    TLS_accept: before/accept initialization 
  rlm_eap_tls: <<< TLS 1.0 Handshake [length 002d], ClientHello  
    TLS_accept: SSLv3 read client hello A 
  rlm_eap_tls: >>> TLS 1.0 Handshake [length 004a], ServerHello  
    TLS_accept: SSLv3 write server hello A 
  rlm_eap_tls: >>> TLS 1.0 Handshake [length 0694], Certificate  
    TLS_accept: SSLv3 write certificate A 
  rlm_eap_tls: >>> TLS 1.0 Handshake [length 0004], ServerHelloDone  
    TLS_accept: SSLv3 write server done A 
    TLS_accept: SSLv3 flush data 
    TLS_accept: Need to read more data: SSLv3 read client certificate A
In SSL Handshake Phase 
In SSL Accept mode  
  eaptls_process returned 13 
  modcall[authenticate]: module "eap" returns handled for request 6
modcall: leaving group authenticate (returns handled) for request 6
Sending Access-Challenge of id 7 to 10.10.10.5 port 1645
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = 0x652e636f6d301e170d3034303132353133323630375a
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xd4c80d866de2a7797059021a127ad6cb
Finished request 6
Going to the next request
Thread 2 waiting to be assigned a request
rad_recv: Access-Request packet from host 10.10.10.5:1645, id=8, length=175
Waking up in 1 seconds...
Thread 3 got semaphore
Threads: total/active/spare threads = 5/0/5
Thread 3 handling request 7, (2 handled so far)
	User-Name = "gilberto"
	Framed-MTU = 1400
	Called-Station-Id = "001b.5303.b550"
	Calling-Station-Id = "0040.96a2.24f3"
	Cisco-AVPair = "ssid=FONTELONGA"
	Service-Type = Login-User
	Message-Authenticator = 0xf7dead81843694d5b7a726080698fcff
	EAP-Message = 0x020400061500
	NAS-Port-Type = Wireless-802.11
	Cisco-NAS-Port = "258"
	NAS-Port = 258
	State = 0xd4c80d866de2a7797059021a127ad6cb
	NAS-IP-Address = 10.10.10.5
	NAS-Identifier = "ap2"
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 7
  rlm_eap: EAP packet type response id 4 length 6
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 7
radius_xlat:  'gilberto'
rlm_sql (sql): sql_set_user escaped user --> 'gilberto'
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = 'gilberto'          ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 1
radius_xlat:  ''
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = 'gilberto'           ORDER BY id'
radius_xlat:  ''
rlm_sql (sql): Released sql socket id: 1
  modcall[authorize]: module "sql" returns ok for request 7
rlm_pap: Found existing Auth-Type, not changing it.
  modcall[authorize]: module "pap" returns noop for request 7
modcall: leaving group authorize (returns updated) for request 7
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 7
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/ttls
  rlm_eap: processing type ttls
  rlm_eap_ttls: Authenticate
  rlm_eap_tls: processing TLS
rlm_eap_tls: Received EAP-TLS ACK message
  rlm_eap_tls: ack handshake fragment handler
  eaptls_verify returned 1 
  eaptls_process returned 13 
  modcall[authenticate]: module "eap" returns handled for request 7
modcall: leaving group authenticate (returns handled) for request 7
Sending Access-Challenge of id 8 to 10.10.10.5 port 1645
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = 0x0e000000
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xbd339a26eca100a5198c7dc5647b7eb7
Finished request 7
Going to the next request
Thread 3 waiting to be assigned a request
rad_recv: Access-Request packet from host 10.10.10.5:1645, id=9, length=369
Waking up in 1 seconds...
Thread 4 got semaphore
Thread 4 handling request 8, (2 handled so far)
	User-Name = "gilberto"
	Framed-MTU = 1400
	Called-Station-Id = "001b.5303.b550"
	Calling-Station-Id = "0040.96a2.24f3"
	Cisco-AVPair = "ssid=FONTELONGA"
	Service-Type = Login-User
	Message-Authenticator = 0xaea91f97fcfef704f03731678b3ef7b9
	EAP-Message = [payload removed: 402 characters]
	NAS-Port-Type = Wireless-802.11
	Cisco-NAS-Port = "258"
	NAS-Port = 258
	State = 0xbd339a26eca100a5198c7dc5647b7eb7
	NAS-IP-Address = 10.10.10.5
	NAS-Identifier = "ap2"
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 8
  rlm_eap: EAP packet type response id 5 length 200
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 8
radius_xlat:  'gilberto'
rlm_sql (sql): sql_set_user escaped user --> 'gilberto'
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = 'gilberto'          ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 0
radius_xlat:  ''
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = 'gilberto'           ORDER BY id'
radius_xlat:  ''
rlm_sql (sql): Released sql socket id: 0
  modcall[authorize]: module "sql" returns ok for request 8
rlm_pap: Found existing Auth-Type, not changing it.
  modcall[authorize]: module "pap" returns noop for request 8
modcall: leaving group authorize (returns updated) for request 8
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 8
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/ttls
  rlm_eap: processing type ttls
  rlm_eap_ttls: Authenticate
  rlm_eap_tls: processing TLS
rlm_eap_tls:  Length Included
  eaptls_verify returned 11 
  rlm_eap_tls: <<< TLS 1.0 Handshake [length 0086], ClientKeyExchange  
    TLS_accept: SSLv3 read client key exchange A 
  rlm_eap_tls: <<< TLS 1.0 ChangeCipherSpec [length 0001]  
  rlm_eap_tls: <<< TLS 1.0 Handshake [length 0010], Finished  
    TLS_accept: SSLv3 read finished A 
  rlm_eap_tls: >>> TLS 1.0 ChangeCipherSpec [length 0001]  
    TLS_accept: SSLv3 write change cipher spec A 
  rlm_eap_tls: >>> TLS 1.0 Handshake [length 0010], Finished  
    TLS_accept: SSLv3 write finished A 
    TLS_accept: SSLv3 flush data 
    (other): SSL negotiation finished successfully 
SSL Connection Established 
  eaptls_process returned 13 
  modcall[authenticate]: module "eap" returns handled for request 8
modcall: leaving group authenticate (returns handled) for request 8
Sending Access-Challenge of id 9 to 10.10.10.5 port 1645
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
	EAP-Message = 0x0106003d1580000000331403010001011603010028d8fde742707cddfb3551dc4b0d7e5a4a7e3bbcb05203ac4c9c45fd0146cdd0cf9878eb36348ad7d8
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xcb2098b5cdb1598620a239e8355c54c4
Finished request 8
Going to the next request
Thread 4 waiting to be assigned a request
rad_recv: Access-Request packet from host 10.10.10.5:1645, id=10, length=240
Waking up in 1 seconds...
Thread 5 got semaphore
Thread 5 handling request 9, (2 handled so far)
	User-Name = "gilberto"
	Framed-MTU = 1400
	Called-Station-Id = "001b.5303.b550"
	Calling-Station-Id = "0040.96a2.24f3"
	Cisco-AVPair = "ssid=FONTELONGA"
	Service-Type = Login-User
	Message-Authenticator = 0x2ce374eaa492398711e52c4210692362
	EAP-Message = 0x0206004715800000003d17030100387dc3189d241442350eb05ced7c33c99b038c9e415c91ece79a6015eb0631f573b6a6bb4d8be715fa8c9fe5c7f363a10aafe3a8b549f1fdf2
	NAS-Port-Type = Wireless-802.11
	Cisco-NAS-Port = "258"
	NAS-Port = 258
	State = 0xcb2098b5cdb1598620a239e8355c54c4
	NAS-IP-Address = 10.10.10.5
	NAS-Identifier = "ap2"
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 9
  rlm_eap: EAP packet type response id 6 length 71
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 9
radius_xlat:  'gilberto'
rlm_sql (sql): sql_set_user escaped user --> 'gilberto'
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = 'gilberto'          ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 4
radius_xlat:  ''
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = 'gilberto'           ORDER BY id'
radius_xlat:  ''
rlm_sql (sql): Released sql socket id: 4
  modcall[authorize]: module "sql" returns ok for request 9
rlm_pap: Found existing Auth-Type, not changing it.
  modcall[authorize]: module "pap" returns noop for request 9
modcall: leaving group authorize (returns updated) for request 9
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 9
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/ttls
  rlm_eap: processing type ttls
  rlm_eap_ttls: Authenticate
  rlm_eap_tls: processing TLS
rlm_eap_tls:  Length Included
  eaptls_verify returned 11 
  eaptls_process returned 7 
  rlm_eap_ttls: Session established.  Proceeding to decode tunneled attributes.
  TTLS: Got tunneled request
	User-Name = "gilberto"
	User-Password = "teste"
	FreeRADIUS-Proxied-To = 127.0.0.1
  TTLS: Sending tunneled request
	User-Name = "gilberto"
	User-Password = "teste"
	FreeRADIUS-Proxied-To = 127.0.0.1
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 9
  rlm_eap: No EAP-Message, not doing EAP
  modcall[authorize]: module "eap" returns noop for request 9
radius_xlat:  'gilberto'
rlm_sql (sql): sql_set_user escaped user --> 'gilberto'
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = 'gilberto'          ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 3
radius_xlat:  ''
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = 'gilberto'           ORDER BY id'
radius_xlat:  ''
rlm_sql (sql): Released sql socket id: 3
  modcall[authorize]: module "sql" returns ok for request 9
  modcall[authorize]: module "pap" returns updated for request 9
modcall: leaving group authorize (returns updated) for request 9
  rad_check_password:  Found Auth-Type pap
auth: type "PAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group PAP for request 9
rlm_pap: login attempt with password teste
rlm_pap: Using CRYPT encryption.
rlm_pap: User authenticated successfully
  modcall[authenticate]: module "pap" returns ok for request 9
modcall: leaving group PAP (returns ok) for request 9
Login OK: [gilberto/teste] (from client localhost port 0)
  TTLS: Got tunneled reply RADIUS code 2
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
  TTLS: Got tunneled Access-Accept
  rlm_eap: Freeing handler
  modcall[authenticate]: module "eap" returns ok for request 9
modcall: leaving group authenticate (returns ok) for request 9
Login OK: [gilberto/<no User-Password attribute>] (from client ap2 port 258 cli 0040.96a2.24f3)
Sending Access-Accept of id 10 to 10.10.10.5 port 1645
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
	MS-MPPE-Recv-Key = 0x53c38322b4617e8ed73705008a9459847b963fe971b59d1020f56846353df34f
	MS-MPPE-Send-Key = 0x408c5e583c3adc925a59d8f72da4b19dcb0308893390c3a97b4d740f4222dd86
	EAP-Message = 0x03060004
	Message-Authenticator = 0x00000000000000000000000000000000
	User-Name = "gilberto"
Finished request 9
Going to the next request
Thread 5 waiting to be assigned a request
--- Walking the entire request list ---
Waking up in 1 seconds...
--- Walking the entire request list ---
Cleaning up request 1 ID 2 with timestamp 467ec919
Cleaning up request 2 ID 3 with timestamp 467ec919
Cleaning up request 3 ID 4 with timestamp 467ec919
Cleaning up request 4 ID 5 with timestamp 467ec919
Waking up in 4 seconds...
rad_recv: Access-Request packet from host 10.10.10.5:1645, id=11, length=164
--- Walking the entire request list ---
Thread 1 got semaphore
Waking up in 2 seconds...
Thread 1 handling request 10, (3 handled so far)
Threads: total/active/spare threads = 5/1/4
	User-Name = "gilberto"
	Framed-MTU = 1400
	Called-Station-Id = "001b.5303.b550"
	Calling-Station-Id = "0040.96a2.24f3"
	Cisco-AVPair = "ssid=FONTELONGA"
	Service-Type = Login-User
	Message-Authenticator = 0xd4b559accdf3a05efe843f2c2fd67933
	EAP-Message = 0x0202000d0167696c626572746f
	NAS-Port-Type = Wireless-802.11
	Cisco-NAS-Port = "259"
	NAS-Port = 259
	NAS-IP-Address = 10.10.10.5
	NAS-Identifier = "ap2"
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 10
  rlm_eap: EAP packet type response id 2 length 13
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 10
radius_xlat:  'gilberto'
rlm_sql (sql): sql_set_user escaped user --> 'gilberto'
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = 'gilberto'          ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 2
radius_xlat:  ''
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = 'gilberto'           ORDER BY id'
radius_xlat:  ''
rlm_sql (sql): Released sql socket id: 2
  modcall[authorize]: module "sql" returns ok for request 10
rlm_pap: Found existing Auth-Type, not changing it.
  modcall[authorize]: module "pap" returns noop for request 10
modcall: leaving group authorize (returns updated) for request 10
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 10
  rlm_eap: EAP Identity
  rlm_eap: processing type tls
  rlm_eap_tls: Initiate
  rlm_eap_tls: Start returned 1
  modcall[authenticate]: module "eap" returns handled for request 10
modcall: leaving group authenticate (returns handled) for request 10
Sending Access-Challenge of id 11 to 10.10.10.5 port 1645
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
	EAP-Message = 0x010300061520
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xb53be327f569a572ff913ee268f978b4
Finished request 10
Going to the next request
Thread 1 waiting to be assigned a request
rad_recv: Access-Request packet from host 10.10.10.5:1645, id=12, length=229
Waking up in 2 seconds...
Thread 2 got semaphore
Threads: total/active/spare threads = 5/0/5
Thread 2 handling request 11, (3 handled so far)
	User-Name = "gilberto"
	Framed-MTU = 1400
	Called-Station-Id = "001b.5303.b550"
	Calling-Station-Id = "0040.96a2.24f3"
	Cisco-AVPair = "ssid=FONTELONGA"
	Service-Type = Login-User
	Message-Authenticator = 0x5d0e52e1d2cb2949c11dd8c581771941
	EAP-Message = 0x0203003c158000000032160301002d0100002903019a422a0a9a5630cbd83a28cec983d656b16dfcb63575008db8ae57d73ec73724000002000a0100
	NAS-Port-Type = Wireless-802.11
	Cisco-NAS-Port = "259"
	NAS-Port = 259
	State = 0xb53be327f569a572ff913ee268f978b4
	NAS-IP-Address = 10.10.10.5
	NAS-Identifier = "ap2"
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 11
  rlm_eap: EAP packet type response id 3 length 60
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 11
radius_xlat:  'gilberto'
rlm_sql (sql): sql_set_user escaped user --> 'gilberto'
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = 'gilberto'          ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 1
radius_xlat:  ''
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = 'gilberto'           ORDER BY id'
radius_xlat:  ''
rlm_sql (sql): Released sql socket id: 1
  modcall[authorize]: module "sql" returns ok for request 11
rlm_pap: Found existing Auth-Type, not changing it.
  modcall[authorize]: module "pap" returns noop for request 11
modcall: leaving group authorize (returns updated) for request 11
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 11
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/ttls
  rlm_eap: processing type ttls
  rlm_eap_ttls: Authenticate
  rlm_eap_tls: processing TLS
rlm_eap_tls:  Length Included
  eaptls_verify returned 11 
    (other): before/accept initialization 
    TLS_accept: before/accept initialization 
  rlm_eap_tls: <<< TLS 1.0 Handshake [length 002d], ClientHello  
    TLS_accept: SSLv3 read client hello A 
  rlm_eap_tls: >>> TLS 1.0 Handshake [length 004a], ServerHello  
    TLS_accept: SSLv3 write server hello A 
  rlm_eap_tls: >>> TLS 1.0 Handshake [length 0694], Certificate  
    TLS_accept: SSLv3 write certificate A 
  rlm_eap_tls: >>> TLS 1.0 Handshake [length 0004], ServerHelloDone  
    TLS_accept: SSLv3 write server done A 
    TLS_accept: SSLv3 flush data 
    TLS_accept: Need to read more data: SSLv3 read client certificate A
In SSL Handshake Phase 
In SSL Accept mode  
  eaptls_process returned 13 
  modcall[authenticate]: module "eap" returns handled for request 11
modcall: leaving group authenticate (returns handled) for request 11
Sending Access-Challenge of id 12 to 10.10.10.5 port 1645
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = 0x652e636f6d301e170d3034303132353133323630375a
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xb07dad7100589516ccfb1cf2d790a1d6
Finished request 11
Going to the next request
Thread 2 waiting to be assigned a request
rad_recv: Access-Request packet from host 10.10.10.5:1645, id=13, length=175
Waking up in 2 seconds...
Thread 3 got semaphore
Thread 3 handling request 12, (3 handled so far)
	User-Name = "gilberto"
	Framed-MTU = 1400
	Called-Station-Id = "001b.5303.b550"
	Calling-Station-Id = "0040.96a2.24f3"
	Cisco-AVPair = "ssid=FONTELONGA"
	Service-Type = Login-User
	Message-Authenticator = 0xfd189b3e1969c3a4512d81f1f2a4bfcd
	EAP-Message = 0x020400061500
	NAS-Port-Type = Wireless-802.11
	Cisco-NAS-Port = "259"
	NAS-Port = 259
	State = 0xb07dad7100589516ccfb1cf2d790a1d6
	NAS-IP-Address = 10.10.10.5
	NAS-Identifier = "ap2"
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 12
  rlm_eap: EAP packet type response id 4 length 6
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 12
radius_xlat:  'gilberto'
rlm_sql (sql): sql_set_user escaped user --> 'gilberto'
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = 'gilberto'          ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 0
radius_xlat:  ''
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = 'gilberto'           ORDER BY id'
radius_xlat:  ''
rlm_sql (sql): Released sql socket id: 0
  modcall[authorize]: module "sql" returns ok for request 12
rlm_pap: Found existing Auth-Type, not changing it.
  modcall[authorize]: module "pap" returns noop for request 12
modcall: leaving group authorize (returns updated) for request 12
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 12
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/ttls
  rlm_eap: processing type ttls
  rlm_eap_ttls: Authenticate
  rlm_eap_tls: processing TLS
rlm_eap_tls: Received EAP-TLS ACK message
  rlm_eap_tls: ack handshake fragment handler
  eaptls_verify returned 1 
  eaptls_process returned 13 
  modcall[authenticate]: module "eap" returns handled for request 12
modcall: leaving group authenticate (returns handled) for request 12
Sending Access-Challenge of id 13 to 10.10.10.5 port 1645
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = 0x0e000000
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x01bd09371938dfb5c975c129ebdd4eb6
Finished request 12
Going to the next request
Thread 3 waiting to be assigned a request
rad_recv: Access-Request packet from host 10.10.10.5:1645, id=14, length=369
Waking up in 2 seconds...
Thread 4 got semaphore
Thread 4 handling request 13, (3 handled so far)
	User-Name = "gilberto"
	Framed-MTU = 1400
	Called-Station-Id = "001b.5303.b550"
	Calling-Station-Id = "0040.96a2.24f3"
	Cisco-AVPair = "ssid=FONTELONGA"
	Service-Type = Login-User
	Message-Authenticator = 0xaa85c075d1f0926e3c1eb0e22bbb328b
	EAP-Message = [payload removed: 402 characters]
	NAS-Port-Type = Wireless-802.11
	Cisco-NAS-Port = "259"
	NAS-Port = 259
	State = 0x01bd09371938dfb5c975c129ebdd4eb6
	NAS-IP-Address = 10.10.10.5
	NAS-Identifier = "ap2"
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 13
  rlm_eap: EAP packet type response id 5 length 200
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 13
radius_xlat:  'gilberto'
rlm_sql (sql): sql_set_user escaped user --> 'gilberto'
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = 'gilberto'          ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 4
radius_xlat:  ''
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = 'gilberto'           ORDER BY id'
radius_xlat:  ''
rlm_sql (sql): Released sql socket id: 4
  modcall[authorize]: module "sql" returns ok for request 13
rlm_pap: Found existing Auth-Type, not changing it.
  modcall[authorize]: module "pap" returns noop for request 13
modcall: leaving group authorize (returns updated) for request 13
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 13
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/ttls
  rlm_eap: processing type ttls
  rlm_eap_ttls: Authenticate
  rlm_eap_tls: processing TLS
rlm_eap_tls:  Length Included
  eaptls_verify returned 11 
  rlm_eap_tls: <<< TLS 1.0 Handshake [length 0086], ClientKeyExchange  
    TLS_accept: SSLv3 read client key exchange A 
  rlm_eap_tls: <<< TLS 1.0 ChangeCipherSpec [length 0001]  
  rlm_eap_tls: <<< TLS 1.0 Handshake [length 0010], Finished  
    TLS_accept: SSLv3 read finished A 
  rlm_eap_tls: >>> TLS 1.0 ChangeCipherSpec [length 0001]  
    TLS_accept: SSLv3 write change cipher spec A 
  rlm_eap_tls: >>> TLS 1.0 Handshake [length 0010], Finished  
    TLS_accept: SSLv3 write finished A 
    TLS_accept: SSLv3 flush data 
    (other): SSL negotiation finished successfully 
SSL Connection Established 
  eaptls_process returned 13 
  modcall[authenticate]: module "eap" returns handled for request 13
modcall: leaving group authenticate (returns handled) for request 13
Sending Access-Challenge of id 14 to 10.10.10.5 port 1645
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
	EAP-Message = 0x0106003d1580000000331403010001011603010028d27376b81b448dab7b19389073528c3e6eeeace1abd1b01fa6ab7efe1846c126787561c4250634ca
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xbf0e7c7ef13fad22397e12b95a1eedac
Finished request 13
Going to the next request
Thread 4 waiting to be assigned a request
rad_recv: Access-Request packet from host 10.10.10.5:1645, id=15, length=240
Waking up in 2 seconds...
Thread 5 got semaphore
Thread 5 handling request 14, (3 handled so far)
	User-Name = "gilberto"
	Framed-MTU = 1400
	Called-Station-Id = "001b.5303.b550"
	Calling-Station-Id = "0040.96a2.24f3"
	Cisco-AVPair = "ssid=FONTELONGA"
	Service-Type = Login-User
	Message-Authenticator = 0x743db4180c6f9bf06575586c55b788a4
	EAP-Message = 0x0206004715800000003d1703010038db37bf2ee489ac7b8eb82fddbf94b1a751d599520af04510f6ffffc02fd5d61cdb65277d18c668707e1b9e4731b8e456dc80eb11e56e89c3
	NAS-Port-Type = Wireless-802.11
	Cisco-NAS-Port = "259"
	NAS-Port = 259
	State = 0xbf0e7c7ef13fad22397e12b95a1eedac
	NAS-IP-Address = 10.10.10.5
	NAS-Identifier = "ap2"
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 14
  rlm_eap: EAP packet type response id 6 length 71
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 14
radius_xlat:  'gilberto'
rlm_sql (sql): sql_set_user escaped user --> 'gilberto'
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = 'gilberto'          ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 3
radius_xlat:  ''
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = 'gilberto'           ORDER BY id'
radius_xlat:  ''
rlm_sql (sql): Released sql socket id: 3
  modcall[authorize]: module "sql" returns ok for request 14
rlm_pap: Found existing Auth-Type, not changing it.
  modcall[authorize]: module "pap" returns noop for request 14
modcall: leaving group authorize (returns updated) for request 14
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 14
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/ttls
  rlm_eap: processing type ttls
  rlm_eap_ttls: Authenticate
  rlm_eap_tls: processing TLS
rlm_eap_tls:  Length Included
  eaptls_verify returned 11 
  eaptls_process returned 7 
  rlm_eap_ttls: Session established.  Proceeding to decode tunneled attributes.
  TTLS: Got tunneled request
	User-Name = "gilberto"
	User-Password = "teste"
	FreeRADIUS-Proxied-To = 127.0.0.1
  TTLS: Sending tunneled request
	User-Name = "gilberto"
	User-Password = "teste"
	FreeRADIUS-Proxied-To = 127.0.0.1
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 14
  rlm_eap: No EAP-Message, not doing EAP
  modcall[authorize]: module "eap" returns noop for request 14
radius_xlat:  'gilberto'
rlm_sql (sql): sql_set_user escaped user --> 'gilberto'
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radcheck           WHERE Username = 'gilberto'          ORDER BY id'
rlm_sql (sql): Reserving sql socket id: 2
radius_xlat:  ''
radius_xlat:  'SELECT id, UserName, Attribute, Value, op           FROM radreply           WHERE Username = 'gilberto'           ORDER BY id'
radius_xlat:  ''
rlm_sql (sql): Released sql socket id: 2
  modcall[authorize]: module "sql" returns ok for request 14
  modcall[authorize]: module "pap" returns updated for request 14
modcall: leaving group authorize (returns updated) for request 14
  rad_check_password:  Found Auth-Type pap
auth: type "PAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group PAP for request 14
rlm_pap: login attempt with password teste
rlm_pap: Using CRYPT encryption.
rlm_pap: User authenticated successfully
  modcall[authenticate]: module "pap" returns ok for request 14
modcall: leaving group PAP (returns ok) for request 14
Login OK: [gilberto/teste] (from client localhost port 0)
  TTLS: Got tunneled reply RADIUS code 2
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
  TTLS: Got tunneled Access-Accept
  rlm_eap: Freeing handler
  modcall[authenticate]: module "eap" returns ok for request 14
modcall: leaving group authenticate (returns ok) for request 14
Login OK: [gilberto/<no User-Password attribute>] (from client ap2 port 259 cli 0040.96a2.24f3)
Sending Access-Accept of id 15 to 10.10.10.5 port 1645
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
	Tunnel-Type:0 := VLAN
	Tunnel-Medium-Type:0 := IEEE-802
	Tunnel-Private-Group-Id:0 := "2"
	MS-MPPE-Recv-Key = 0xb8affdd6894ac2abde926a8c5831571e4f724c4db627ac4608bb42c30a87ccbb
	MS-MPPE-Send-Key = 0x595bdbd1e86f634709623b7b54a46e2b2659d515c2d687948c08cabdc1aab6ec
	EAP-Message = 0x03060004
	Message-Authenticator = 0x00000000000000000000000000000000
	User-Name = "gilberto"
Finished request 14
Going to the next request
Thread 5 waiting to be assigned a request
--- Walking the entire request list ---
Cleaning up request 5 ID 6 with timestamp 467ec91d
Cleaning up request 6 ID 7 with timestamp 467ec91d
Cleaning up request 7 ID 8 with timestamp 467ec91d
Cleaning up request 8 ID 9 with timestamp 467ec91d
Cleaning up request 9 ID 10 with timestamp 467ec91d
Waking up in 4 seconds...
