Hi everyone, I already configured my freeradius with eap-ttls pap with authentication on mysql. I obtain authentication, but logs some lines: Wed Jun 20 19:46:47 2007 : Error: Trying to look up name of unknown client 127.0.0.1. Wed Jun 20 19:46:47 2007 : Auth: Login OK: [teste/secret] (from client UNKNOWN-CLIENT port 327 cli 0040.96a2.24f3) Wed Jun 20 19:46:47 2007 : Auth: Login OK: [teste/<no User-Password attribute>] (from client ap2 port 327 cli 0040.96a2.24f3) My radiusd.conf: .................... authorize { preprocess sql pap } authenticate{ Auth-Type PAP { pap } eap } ...................... My eap.conf: eap{ default_eap_type = ttls timer_expire = 60 ignore_unknown_eap_types = no cisco_accounting_username_bug = yes gtc { auth_type = PAP } tls { private_key_password = whatever private_key_file = ${raddbdir}/certs/cert-srv.pem certificate_file = ${raddbdir}/certs/cert-srv.pem CA_file = ${raddbdir}/certs/demoCA/cacert.pem dh_file = ${raddbdir}/certs/dh random_file = ${raddbdir}/certs/random } ttls { default_eap_type = gtc copy_request_to_tunnel = yes use_tunneled_reply = yes } } What it is that I need put in mysql and my configuration, for before I obtain good authentication return: Tunnel-Type, Tunnel-Medium-Type and Tunnel-Private-Group-ID for the client make a dhclient in vlan I return? Best Regards -- /emmc
What it is that I need put in mysql and my configuration, for before I obtain good authentication return: Tunnel-Type, Tunnel-Medium-Type and Tunnel-Private-Group-ID for the client make a dhclient in vlan I return?
Put the appropriate attributes for VLAN assignment into the radreply table for the user in question. Chances are that you also need to set the option use_tunneled_reply = yes in eap.conf. Greetings, Stefan Winter -- Stefan WINTER Stiftung RESTENA - Réseau Téléinformatique de l'Education Nationale et de la Recherche Ingenieur Forschung & Entwicklung 6, rue Richard Coudenhove-Kalergi L-1359 Luxembourg E-Mail: stefan.winter@restena.lu Tel.: +352 424409-1 http://www.restena.lu Fax: +352 422473
Stefan Winter wrote:
What it is that I need put in mysql and my configuration, for before I obtain good authentication return: Tunnel-Type, Tunnel-Medium-Type and Tunnel-Private-Group-ID for the client make a dhclient in vlan I return?
Put the appropriate attributes for VLAN assignment into the radreply table for the user in question. Chances are that you also need to set the option
use_tunneled_reply = yes
in eap.conf.
Greetings,
Stefan Winter
------------------------------------------------------------------------
- List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html I already put appropriate attributes for VLAN assignment into the radreply table, but I think I have a problem with authentication because log this lines:
Wed Jun 20 19:46:47 2007 : Error: Trying to look up name of unknown client 127.0.0.1. Wed Jun 20 19:46:47 2007 : Auth: Login OK: [teste/secret] (from client UNKNOWN-CLIENT port 327 cli 0040.96a2.24f3) Wed Jun 20 19:46:47 2007 : Auth: Login OK: [teste/<no User-Password attribute>] (from client ap2 port 327 cli 0040.96a2.24f3) but if I use cli with command radtest authentication is sucessefull and receive reply attributes. I'm to use a Cisco ap1100 configurated with wpa-tkip and for client use a pc with windows XP with supplicant securew2 configured with eap-ttls pap. Can help-me? -- /emmc
Hi,
Wed Jun 20 19:46:47 2007 : Error: Trying to look up name of unknown client 127.0.0.1. Wed Jun 20 19:46:47 2007 : Auth: Login OK: [teste/secret] (from client UNKNOWN-CLIENT port 327 cli 0040.96a2.24f3) Wed Jun 20 19:46:47 2007 : Auth: Login OK: [teste/<no User-Password attribute>] (from client ap2 port 327 cli 0040.96a2.24f3)
but if I use cli with command radtest authentication is sucessefull and receive reply attributes.
I'm to use a Cisco ap1100 configurated with wpa-tkip and for client use a pc with windows XP with supplicant securew2 configured with eap-ttls pap.
send us you naslist table and clients.conf alan
A.L.M.Buxey@lboro.ac.uk wrote:
Hi,
Wed Jun 20 19:46:47 2007 : Error: Trying to look up name of unknown client 127.0.0.1. Wed Jun 20 19:46:47 2007 : Auth: Login OK: [teste/secret] (from client UNKNOWN-CLIENT port 327 cli 0040.96a2.24f3) Wed Jun 20 19:46:47 2007 : Auth: Login OK: [teste/<no User-Password attribute>] (from client ap2 port 327 cli 0040.96a2.24f3)
but if I use cli with command radtest authentication is sucessefull and receive reply attributes.
I'm to use a Cisco ap1100 configurated with wpa-tkip and for client use a pc with windows XP with supplicant securew2 configured with eap-ttls pap.
send us you naslist table and clients.conf
alan - List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
See in attach naslist, clients.conf and radius -xx log. -- /emmc # # clients.conf - client configuration directives # ####################################################################### ####################################################################### # # Definition of a RADIUS client (usually a NAS). # # The information given here over rides anything given in the # 'clients' file, or in the 'naslist' file. The configuration here # contains all of the information from those two files, and allows # for more configuration items. # # The "shortname" is be used for logging. The "nastype", "login" and # "password" fields are mainly used for checkrad and are optional. # # # Defines a RADIUS client. The format is 'client [hostname|ip-address]' # # '127.0.0.1' is another name for 'localhost'. It is enabled by default, # to allow testing of the server after an initial installation. If you # are not going to be permitting RADIUS queries from localhost, we suggest # that you delete, or comment out, this entry. # client 10.10.10.4 { # # The shared secret use to "encrypt" and "sign" packets between # the NAS and FreeRADIUS. You MUST change this secret from the # default, otherwise it's not a secret any more! # # The secret can be any string, up to 31 characters in length. # secret = teste123 # # The short name is used as an alias for the fully qualified # domain name, or the IP address. # shortname = fonte # # the following three fields are optional, but may be used by # checkrad.pl for simultaneous use checks # # # The nastype tells 'checkrad.pl' which NAS-specific method to # use to query the NAS for simultaneous use. # # Permitted NAS types are: # # cisco # computone # livingston # max40xx # multitech # netserver # pathras # patton # portslave # tc # usrhiper # other # for all other types # #nastype = other # localhost isn't usually a NAS... # # The following two configurations are for future use. # The 'naspasswd' file is currently used to store the NAS # login name and password, which is used by checkrad.pl # when querying the NAS for simultaneous use. # # login = !root # password = someadminpas } #client 192.168.1.68{ # secret = xFRKWJwelf23#"4DSFFEWfdsfjweEEE # shortname = 192.168.1.68 # nastype = cisco #} client 10.10.10.5{ secret = xFRKGJwelf24#"4DSJFEWfdsfjweEEE shortname = ap2 } #client some.host.org { # secret = testing123 # shortname = localhost #} # # You can now specify one secret for a network of clients. # When a client request comes in, the BEST match is chosen. # i.e. The entry from the smallest possible network. # #client 192.168.0.0/24 { # secret = testing123-1 # shortname = private-network-1 #} # #client 192.168.0.0/16 { # secret = testing123-2 # shortname = private-network-2 #} #client 10.10.10.10 { # # secret and password are mapped through the "secrets" file. # secret = testing123 # shortname = liv1 # # the following three fields are optional, but may be used by # # checkrad.pl for simultaneous usage checks # nastype = livingston # login = !root # password = someadminpas #} # # THIS FILE IS DEPRECATED. # # You should NOT be using this file to configure the server. # It is here ONLY for backwards compatibility. # # See 'clients.conf' for the new configuration. # # # naslist This file contains a list of NASes (Network Access Servers, # also known as terminal servers) which we know. # # Description of the fields: # # * The first field is a valid hostname or IP address # for the client. # * The second field (seperated by blanks or tabs) is the # short name we use in the logfiles for this NAS. # * The third field defines what type of device it is. Valid # values are "cisco", "computone", "livingston", "max40xx", # "multitech", "netserver", "pathras", "patton", "portslave", # "tc", "usrhiper" or "other". # # This is used to find out how to detect double logins. # # NAS Name Short Name Type #---------------- ---------- ---- #portmaster1.isp.com pm1.NY livingston #portmaster2.isp.com pm1.LA livingston localhost local portslave 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 = no 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 realm realm: format = "suffix" realm: delimiter = "@" realm: ignore_default = no realm: ignore_null = no Module: Instantiated realm (suffix) 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=159 --- 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 = 0xf3a96c9310ab5ff81307e7ffbefcfd99 EAP-Message = 0x0202000d0167696c626572746f NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "263" NAS-Port = 263 NAS-IP-Address = 10.10.10.5 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 = 0x070b1f9f28ca2b353b583da51af2fc55 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=224 Thread 2 got semaphore Waking up in 31 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 = 0x1000703418cbb6ff0326772522bf4521 EAP-Message = 0x0203003c158000000032160301002d010000290301aa2822869a88176a306deccee520ff6b856d0cd75a62af06b5e293548301d5d3000002000a0100 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "263" NAS-Port = 263 State = 0x070b1f9f28ca2b353b583da51af2fc55 NAS-IP-Address = 10.10.10.5 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 = 0x0104040a15c0000006f1160301004a020000460301467ade11f9353d60155b39ea640e0277363e66addbcfe3a1684890f15e11f88a201861b6bb2f420668d6fbcc2783558fb6810050aabab55338abd03a37c56af65d000a0016030106940b00069000068d0002cd308202c930820232a003020102020102300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e74206365 EAP-Message = 0x7274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d301e170d3034303132353133323631305a170d3035303132343133323631305a30819b310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f73743119301706035504031310526f6f74206365727469666963617465311f301d06092a864886f70d0109011610726f6f74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003 EAP-Message = 0x818d0030818902818100dac525422bfedb082629a2cba44b3449c90d0ab462fb72c8434a782098863d7eb7d7e70028c2b7ad555a51cc756cf4fa1d7091615ab450d5289553ae6616aff014a55085d6b8fb4aee98638e426175cdd36c665c63cda177d34920eb30585edc8773999c2980f81ad4638bbbea1c82d054023db7ef24a3ec1c3f6241a903d7f30203010001a317301530130603551d25040c300a06082b06010505070301300d06092a864886f70d0101040500038181007a2d921b1cf13bf2982a9178ec9ede6d88edc178a2e8bd40a0a06fb6f0769957884cd7084537083496fd184165293f583c8e8240eb68e042c94b15752e4c07e80d09 EAP-Message = 0x779afa3dd55c24fa54ac292d77205d1c2477ed30d59f57caf9bd21ff2a8d16cc0911c50e4f295763fcb60efa3c3d2d0e43850f6e6fbe284902f6e83503650003ba308203b63082031fa003020102020100300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c EAP-Message = 0x652e636f6d301e170d3034303132353133323630375a Message-Authenticator = 0x00000000000000000000000000000000 State = 0xd67ba0cc7c0b93a0db85f7792589b733 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=170 Waking up in 31 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 = 0xfcec03c136b4211b289714283380d8c1 EAP-Message = 0x020400061500 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "263" NAS-Port = 263 State = 0xd67ba0cc7c0b93a0db85f7792589b733 NAS-IP-Address = 10.10.10.5 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 = 0x010502fb1580000006f1170d3036303132343133323630375a30819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003818d0030818902818100d4c5b19724f164acf1ffb189db1c8fbff4f14396ea7cb1e90f78d69451725377895dfe52cc EAP-Message = 0xb99b41e80ddeb58b127a943f4f58cbc562878192fbdc6fece9f871e7c130d35cf5188817e9b133249edd2a1c75d31043ae87553cec7a77ef26aa7d74281db9b77e17c6446c5dd9b188b43250ca0229963722a123a726b00b4027fd0203010001a381ff3081fc301d0603551d0e0416041468d36d3e1ee7bc9d5a057021c363da1365d1ade33081cc0603551d230481c43081c1801468d36d3e1ee7bc9d5a057021c363da1365d1ade3a181a5a481a230819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e EAP-Message = 0x31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d820100300c0603551d13040530030101ff300d06092a864886f70d01010405000381810033c00b66b1e579ef73a06798252dab8d5e5511fc00fd276d80d12f834777c6743fdc2743fca1507704e4bc0979e4f60ac3ad9ee83e6f347369229d1f77229ba2e982359da563024a00163dba6d6c986c0bad28af85132ff8f0d76501bf1b7c2dff658ce1e62c01997b6e64e3e8d4373354ce9912847651539063b85bbc5485c51603010004 EAP-Message = 0x0e000000 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x8bbf86428550e7fc507e0805fced3d34 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=364 Waking up in 31 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 = 0xa3b5384673056ff7c63c704679d3fc00 EAP-Message = 0x020500c81580000000be16030100861000008200807d8d43fd17312f970566b92ecf2b99e62cf8779d2644018ff3f53d4656ea759b253d32dacff8fadbf2d6763bba970d86d01788c06933514ce775f30c86c8a87a3757fe9f8284a08916e6cc88e141268d9c6b848ef8e86c1043939bec9569738a75324e31172268e98c4fb3ed44d98f89e8e93b35a72cd2b86a3d13bbfafd2cc91403010001011603010028202bdfa014e749e3bc91034f1dfb883c3172161fd6069c497ae782f3e0a55f7d3f626fd227aa1ce0 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "263" NAS-Port = 263 State = 0x8bbf86428550e7fc507e0805fced3d34 NAS-IP-Address = 10.10.10.5 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 = 0x0106003d1580000000331403010001011603010028bfe6f70abd9816ad3865d6a3cfb5aa269d6b65b5e2894e9d5b0b8e13944b59ae212d9ec769b48ddf Message-Authenticator = 0x00000000000000000000000000000000 State = 0xada45ffb8df56ab1bf358060f5d98e13 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=235 Waking up in 31 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 = 0xde4e9b0ba023cfd0834689ff0cf166cc EAP-Message = 0x0206004715800000003d1703010038d324d10de83ce53c602de5eb9035ac83399801ebfb569f139e00b4c4f7b59564a2ce2e4c2889210482d9ea28fab17911108a216a1708e677 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "263" NAS-Port = 263 State = 0xada45ffb8df56ab1bf358060f5d98e13 NAS-IP-Address = 10.10.10.5 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 Trying to look up name of unknown client 127.0.0.1. Login OK: [gilberto/teste] (from client UNKNOWN-CLIENT 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 263 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 = 0x8e4df35d44f36b5bcc98631d5ccd5a5b8d2837b1b12cf75b27463bfa64a1f637 MS-MPPE-Send-Key = 0x30f2d9b099bf780dfcce30cd91fa5d586c3791d7c38826ab8770ec08d9e40ca0 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=159 --- Walking the entire request list --- Thread 1 got semaphore Waking up in 2 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 = 0x089742cd1b4f4f8600ee958a04a775af EAP-Message = 0x0201000d0167696c626572746f NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "264" NAS-Port = 264 NAS-IP-Address = 10.10.10.5 Processing the authorize section of radiusd.conf modcall: entering group authorize for request 5 rlm_eap: EAP packet type response id 1 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 = 0x010200061520 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x4bd6c130f499a2ec58518339a504f83b 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=159 Waking up in 2 seconds... Thread 2 got semaphore Threads: total/active/spare threads = 5/0/5 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 = 0xfef325d99adbe35fcf1d6f8bd19a0d83 EAP-Message = 0x0203000d0167696c626572746f NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "264" NAS-Port = 264 NAS-IP-Address = 10.10.10.5 Processing the authorize section of radiusd.conf modcall: entering group authorize for request 6 rlm_eap: EAP packet type response id 3 length 13 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: 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 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 = 0x010400061520 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x4bb993a5275308488ff94c8951fc9df4 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=256 Waking up in 2 seconds... Thread 3 got semaphore 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 = 0xaa3e0458cb2da93f8bd91508b49fe0c3 EAP-Message = 0x0204005c158000000052160301004d010000490301e34d2bcb74e9edfb84341a547b2b05cdb2373ce8685c193c86bfd3a92f9dfbe4201861b6bb2f420668d6fbcc2783558fb6810050aabab55338abd03a37c56af65d0002000a0100 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "264" NAS-Port = 264 State = 0x4bb993a5275308488ff94c8951fc9df4 NAS-IP-Address = 10.10.10.5 Processing the authorize section of radiusd.conf modcall: entering group authorize for request 7 rlm_eap: EAP packet type response id 4 length 92 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: Length Included eaptls_verify returned 11 (other): before/accept initialization TLS_accept: before/accept initialization rlm_eap_tls: <<< TLS 1.0 Handshake [length 004d], 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 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 = 0x0105040a15c0000006f1160301004a020000460301467ade155df2954abcd56ab43900c60fa001301a26aade31b2726e76ad8663bf20956154aff114e89e9d0260f441587dfcf414b543e50a2bf7a9c78919968f58e7000a0016030106940b00069000068d0002cd308202c930820232a003020102020102300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e74206365 EAP-Message = 0x7274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d301e170d3034303132353133323631305a170d3035303132343133323631305a30819b310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f73743119301706035504031310526f6f74206365727469666963617465311f301d06092a864886f70d0109011610726f6f74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003 EAP-Message = 0x818d0030818902818100dac525422bfedb082629a2cba44b3449c90d0ab462fb72c8434a782098863d7eb7d7e70028c2b7ad555a51cc756cf4fa1d7091615ab450d5289553ae6616aff014a55085d6b8fb4aee98638e426175cdd36c665c63cda177d34920eb30585edc8773999c2980f81ad4638bbbea1c82d054023db7ef24a3ec1c3f6241a903d7f30203010001a317301530130603551d25040c300a06082b06010505070301300d06092a864886f70d0101040500038181007a2d921b1cf13bf2982a9178ec9ede6d88edc178a2e8bd40a0a06fb6f0769957884cd7084537083496fd184165293f583c8e8240eb68e042c94b15752e4c07e80d09 EAP-Message = 0x779afa3dd55c24fa54ac292d77205d1c2477ed30d59f57caf9bd21ff2a8d16cc0911c50e4f295763fcb60efa3c3d2d0e43850f6e6fbe284902f6e83503650003ba308203b63082031fa003020102020100300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c EAP-Message = 0x652e636f6d301e170d3034303132353133323630375a Message-Authenticator = 0x00000000000000000000000000000000 State = 0x2de00f68998dc8400511676ab890399d 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=170 Waking up in 2 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 = 0x121c75cb1ee9a78e79299f3213a6b6b8 EAP-Message = 0x020500061500 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "264" NAS-Port = 264 State = 0x2de00f68998dc8400511676ab890399d NAS-IP-Address = 10.10.10.5 Processing the authorize section of radiusd.conf modcall: entering group authorize for request 8 rlm_eap: EAP packet type response id 5 length 6 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: 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 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 = 0x010602fb1580000006f1170d3036303132343133323630375a30819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003818d0030818902818100d4c5b19724f164acf1ffb189db1c8fbff4f14396ea7cb1e90f78d69451725377895dfe52cc EAP-Message = 0xb99b41e80ddeb58b127a943f4f58cbc562878192fbdc6fece9f871e7c130d35cf5188817e9b133249edd2a1c75d31043ae87553cec7a77ef26aa7d74281db9b77e17c6446c5dd9b188b43250ca0229963722a123a726b00b4027fd0203010001a381ff3081fc301d0603551d0e0416041468d36d3e1ee7bc9d5a057021c363da1365d1ade33081cc0603551d230481c43081c1801468d36d3e1ee7bc9d5a057021c363da1365d1ade3a181a5a481a230819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e EAP-Message = 0x31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d820100300c0603551d13040530030101ff300d06092a864886f70d01010405000381810033c00b66b1e579ef73a06798252dab8d5e5511fc00fd276d80d12f834777c6743fdc2743fca1507704e4bc0979e4f60ac3ad9ee83e6f347369229d1f77229ba2e982359da563024a00163dba6d6c986c0bad28af85132ff8f0d76501bf1b7c2dff658ce1e62c01997b6e64e3e8d4373354ce9912847651539063b85bbc5485c51603010004 EAP-Message = 0x0e000000 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x2aefa9a82e6178895e706ab7410007fe 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=364 Waking up in 2 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 = 0xa9b65f85fee568e6ec3a7873c79e28e7 EAP-Message = 0x020600c81580000000be160301008610000082008001b69b3fa2001e372dd469320eda21f39bb055f5b7fc093b4685400a2dc248ac4c8b7039453100dab968d2e62c1f92d905800299b2287239d200c52ab5063262821a99ad41fc0b140a01de7835d7a913d3447cd745905ac2a447a19e7d66e086c50186cebe8df11ef59c4fd17adfc443fd656bb8e5acb699497c0c8a90b7cee41403010001011603010028dd5730628ccc1738a713376718cd65a41de9d8c5a627fbd0363756a87d3e29c076384f1cceefbd91 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "264" NAS-Port = 264 State = 0x2aefa9a82e6178895e706ab7410007fe NAS-IP-Address = 10.10.10.5 Processing the authorize section of radiusd.conf modcall: entering group authorize for request 9 rlm_eap: EAP packet type response id 6 length 200 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 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 9 modcall: leaving group authenticate (returns handled) for request 9 Sending Access-Challenge 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" EAP-Message = 0x0107003d1580000000331403010001011603010028d09a0d15b9f17a54edb4e07a811ae3ee573dc37bea7d6020ef40e193285ccde39f81c34e77512361 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x55dc6df6783d5f2580d0d5a0ca2b002c Finished request 9 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=11, length=235 Waking up in 2 seconds... Thread 1 got semaphore Thread 1 handling request 10, (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 = 0x0f23d28e21d0ae5d05c1ef8623e474e5 EAP-Message = 0x0207004715800000003d1703010038736c639ffc49cf8fe16d239e25fba0624211a06879f2c90bb26db208064b476b3d48319aea982d1a6b66df5fd80abd6f3d5b06440a2bc956 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "264" NAS-Port = 264 State = 0x55dc6df6783d5f2580d0d5a0ca2b002c NAS-IP-Address = 10.10.10.5 Processing the authorize section of radiusd.conf modcall: entering group authorize for request 10 rlm_eap: EAP packet type response id 7 length 71 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: 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 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: 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 10 rlm_eap: No EAP-Message, not doing EAP modcall[authorize]: module "eap" returns noop 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 modcall[authorize]: module "pap" returns updated for request 10 modcall: leaving group authorize (returns updated) for request 10 rad_check_password: Found Auth-Type pap auth: type "PAP" Processing the authenticate section of radiusd.conf modcall: entering group PAP for request 10 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 10 modcall: leaving group PAP (returns ok) for request 10 Trying to look up name of unknown client 127.0.0.1. Login OK: [gilberto/teste] (from client UNKNOWN-CLIENT 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 10 modcall: leaving group authenticate (returns ok) for request 10 Login OK: [gilberto/<no User-Password attribute>] (from client ap2 port 264 cli 0040.96a2.24f3) Sending Access-Accept 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" Tunnel-Type:0 := VLAN Tunnel-Medium-Type:0 := IEEE-802 Tunnel-Private-Group-Id:0 := "2" MS-MPPE-Recv-Key = 0x2ccf173bda6a1c3f7ca61a68e11a4ced2f8250da966128d13ddcd8b15c117d47 MS-MPPE-Send-Key = 0x5db9cbefd51f579d8e13b9b7a985cd326502a08d4f83b0aab2b977b79c9e3b0c EAP-Message = 0x03070004 Message-Authenticator = 0x00000000000000000000000000000000 User-Name = "gilberto" Finished request 10 Going to the next request Thread 1 waiting to be assigned a request --- Walking the entire request list --- Cleaning up request 0 ID 1 with timestamp 467ade11 Cleaning up request 1 ID 2 with timestamp 467ade11 Cleaning up request 2 ID 3 with timestamp 467ade11 Cleaning up request 3 ID 4 with timestamp 467ade11 Cleaning up request 4 ID 5 with timestamp 467ade11 Waking up in 4 seconds... rad_recv: Access-Request packet from host 10.10.10.5:1645, id=12, length=159 --- Walking the entire request list --- Thread 2 got semaphore Waking up in 2 seconds... Thread 2 handling request 11, (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 = 0x67f4e443d3f867d495d1539eb169b5df EAP-Message = 0x0201000d0167696c626572746f NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "265" NAS-Port = 265 NAS-IP-Address = 10.10.10.5 Processing the authorize section of radiusd.conf modcall: entering group authorize for request 11 rlm_eap: EAP packet type response id 1 length 13 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: 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 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 = 0x010200061520 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x495e97fbd83c8c8abea0ebd247bbbdf7 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=159 Waking up in 2 seconds... Thread 3 got semaphore Threads: total/active/spare threads = 5/0/5 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 = 0xf3dba6252ba148fabca275161c321a4f EAP-Message = 0x0203000d0167696c626572746f NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "265" NAS-Port = 265 NAS-IP-Address = 10.10.10.5 Processing the authorize section of radiusd.conf modcall: entering group authorize for request 12 rlm_eap: EAP packet type response id 3 length 13 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: 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 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 = 0x010400061520 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x71edece91825e4ace2ab65b7ed192fe7 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=256 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 = 0x55078379ded64b891aa47a9895e95d69 EAP-Message = 0x0204005c158000000052160301004d01000049030194f3c3f268be36088799671156803b4c2e771caf5730d084c6fffc9c5dc14bfc20956154aff114e89e9d0260f441587dfcf414b543e50a2bf7a9c78919968f58e70002000a0100 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "265" NAS-Port = 265 State = 0x71edece91825e4ace2ab65b7ed192fe7 NAS-IP-Address = 10.10.10.5 Processing the authorize section of radiusd.conf modcall: entering group authorize for request 13 rlm_eap: EAP packet type response id 4 length 92 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 (other): before/accept initialization TLS_accept: before/accept initialization rlm_eap_tls: <<< TLS 1.0 Handshake [length 004d], 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 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 = 0x0105040a15c0000006f1160301004a020000460301467ade1973f1c30a6d7c87770297168766a7bbecdac34879ac3538b1735320e3202e6ca6bb3cfbb973be7ab33fe8a36f2299e781ccbe9352b53781a8f9c3db294e000a0016030106940b00069000068d0002cd308202c930820232a003020102020102300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e74206365 EAP-Message = 0x7274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d301e170d3034303132353133323631305a170d3035303132343133323631305a30819b310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f73743119301706035504031310526f6f74206365727469666963617465311f301d06092a864886f70d0109011610726f6f74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003 EAP-Message = 0x818d0030818902818100dac525422bfedb082629a2cba44b3449c90d0ab462fb72c8434a782098863d7eb7d7e70028c2b7ad555a51cc756cf4fa1d7091615ab450d5289553ae6616aff014a55085d6b8fb4aee98638e426175cdd36c665c63cda177d34920eb30585edc8773999c2980f81ad4638bbbea1c82d054023db7ef24a3ec1c3f6241a903d7f30203010001a317301530130603551d25040c300a06082b06010505070301300d06092a864886f70d0101040500038181007a2d921b1cf13bf2982a9178ec9ede6d88edc178a2e8bd40a0a06fb6f0769957884cd7084537083496fd184165293f583c8e8240eb68e042c94b15752e4c07e80d09 EAP-Message = 0x779afa3dd55c24fa54ac292d77205d1c2477ed30d59f57caf9bd21ff2a8d16cc0911c50e4f295763fcb60efa3c3d2d0e43850f6e6fbe284902f6e83503650003ba308203b63082031fa003020102020100300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c EAP-Message = 0x652e636f6d301e170d3034303132353133323630375a Message-Authenticator = 0x00000000000000000000000000000000 State = 0x371209c8b0393addfc9574a8deb19ea3 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=170 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 = 0x94118edafb7ca6af2b7513c76a207457 EAP-Message = 0x020500061500 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "265" NAS-Port = 265 State = 0x371209c8b0393addfc9574a8deb19ea3 NAS-IP-Address = 10.10.10.5 Processing the authorize section of radiusd.conf modcall: entering group authorize for request 14 rlm_eap: EAP packet type response id 5 length 6 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: 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 14 modcall: leaving group authenticate (returns handled) for request 14 Sending Access-Challenge 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" EAP-Message = 0x010602fb1580000006f1170d3036303132343133323630375a30819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003818d0030818902818100d4c5b19724f164acf1ffb189db1c8fbff4f14396ea7cb1e90f78d69451725377895dfe52cc EAP-Message = 0xb99b41e80ddeb58b127a943f4f58cbc562878192fbdc6fece9f871e7c130d35cf5188817e9b133249edd2a1c75d31043ae87553cec7a77ef26aa7d74281db9b77e17c6446c5dd9b188b43250ca0229963722a123a726b00b4027fd0203010001a381ff3081fc301d0603551d0e0416041468d36d3e1ee7bc9d5a057021c363da1365d1ade33081cc0603551d230481c43081c1801468d36d3e1ee7bc9d5a057021c363da1365d1ade3a181a5a481a230819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e EAP-Message = 0x31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d820100300c0603551d13040530030101ff300d06092a864886f70d01010405000381810033c00b66b1e579ef73a06798252dab8d5e5511fc00fd276d80d12f834777c6743fdc2743fca1507704e4bc0979e4f60ac3ad9ee83e6f347369229d1f77229ba2e982359da563024a00163dba6d6c986c0bad28af85132ff8f0d76501bf1b7c2dff658ce1e62c01997b6e64e3e8d4373354ce9912847651539063b85bbc5485c51603010004 EAP-Message = 0x0e000000 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x006e3c1c72446aff2654cd8cecfa7fa1 Finished request 14 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=16, length=364 Waking up in 2 seconds... Thread 1 got semaphore Thread 1 handling request 15, (4 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 = 0x524fa587cf207d7c9b8c1ba136cbecfa EAP-Message = 0x020600c81580000000be160301008610000082008046ad1556ba03b82ab2373f27b54cb33821bb18f34e7037716be2652d0d64029023596463895159a1d23ed245674a37baf8ced3c2998635d2b386442b5c5fed432b7772fbbae32634f6e717ffff8e5d547024f29f72cdb28c7592e620a3466e03fa495f379a83a6aa6565ab4ae84bc799789eb2a409bf6ecde32ca410084eeba614030100010116030100285cb5ff5277464035712b49844bc7dc44e5f33e1a63dd4542d509b4eb6f1daa94f2e4e1651e83baca NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "265" NAS-Port = 265 State = 0x006e3c1c72446aff2654cd8cecfa7fa1 NAS-IP-Address = 10.10.10.5 Processing the authorize section of radiusd.conf modcall: entering group authorize for request 15 rlm_eap: EAP packet type response id 6 length 200 rlm_eap: No EAP Start, assuming it's an on-going EAP conversation modcall[authorize]: module "eap" returns updated for request 15 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 15 rlm_pap: Found existing Auth-Type, not changing it. modcall[authorize]: module "pap" returns noop for request 15 modcall: leaving group authorize (returns updated) for request 15 rad_check_password: Found Auth-Type EAP auth: type "EAP" Processing the authenticate section of radiusd.conf modcall: entering group authenticate for request 15 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 15 modcall: leaving group authenticate (returns handled) for request 15 Sending Access-Challenge of id 16 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 = 0x0107003d15800000003314030100010116030100282200a62e549cd997393e3628406e71f4b4e49761532bbd3d8da404ee17e92d354733755b2688ef97 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x601dd2da0012ca6354a7c04d5b91a21d Finished request 15 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=17, length=235 Waking up in 2 seconds... Thread 2 got semaphore Thread 2 handling request 16, (4 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 = 0x2092b71d0b47bb3ae7bbf807a348d2db EAP-Message = 0x0207004715800000003d1703010038aece30d0891a4be87d6452677e3db6f8f2eb02954b52550ddd1cb23f507c0ff3284cf92ede34eda182e55b6236ed31a7385a88d76f3a5791 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "265" NAS-Port = 265 State = 0x601dd2da0012ca6354a7c04d5b91a21d NAS-IP-Address = 10.10.10.5 Processing the authorize section of radiusd.conf modcall: entering group authorize for request 16 rlm_eap: EAP packet type response id 7 length 71 rlm_eap: No EAP Start, assuming it's an on-going EAP conversation modcall[authorize]: module "eap" returns updated for request 16 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 16 rlm_pap: Found existing Auth-Type, not changing it. modcall[authorize]: module "pap" returns noop for request 16 modcall: leaving group authorize (returns updated) for request 16 rad_check_password: Found Auth-Type EAP auth: type "EAP" Processing the authenticate section of radiusd.conf modcall: entering group authenticate for request 16 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 16 rlm_eap: No EAP-Message, not doing EAP modcall[authorize]: module "eap" returns noop for request 16 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 16 modcall[authorize]: module "pap" returns updated for request 16 modcall: leaving group authorize (returns updated) for request 16 rad_check_password: Found Auth-Type pap auth: type "PAP" Processing the authenticate section of radiusd.conf modcall: entering group PAP for request 16 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 16 modcall: leaving group PAP (returns ok) for request 16 Trying to look up name of unknown client 127.0.0.1. Login OK: [gilberto/teste] (from client UNKNOWN-CLIENT 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 16 modcall: leaving group authenticate (returns ok) for request 16 Login OK: [gilberto/<no User-Password attribute>] (from client ap2 port 265 cli 0040.96a2.24f3) Sending Access-Accept of id 17 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 = 0x3a2094f4e129d1f9884e3c37b4810d4808872c6e8fd566e8b6c8900f52b4b554 MS-MPPE-Send-Key = 0x54d7106f542b65ca06bc4c190f0e9af2549baa3c73a781eb36bd129d036e02da EAP-Message = 0x03070004 Message-Authenticator = 0x00000000000000000000000000000000 User-Name = "gilberto" Finished request 16 Going to the next request Thread 2 waiting to be assigned a request --- Walking the entire request list --- Cleaning up request 5 ID 6 with timestamp 467ade15 Cleaning up request 6 ID 7 with timestamp 467ade15 Cleaning up request 7 ID 8 with timestamp 467ade15 Cleaning up request 8 ID 9 with timestamp 467ade15 Cleaning up request 9 ID 10 with timestamp 467ade15 Cleaning up request 10 ID 11 with timestamp 467ade15 Waking up in 4 seconds... rad_recv: Access-Request packet from host 10.10.10.5:1645, id=18, length=159 --- Walking the entire request list --- Thread 3 got semaphore Waking up in 2 seconds... Thread 3 handling request 17, (4 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 = 0xf0b730c04cfa71a99363741eedebc8b6 EAP-Message = 0x0202000d0167696c626572746f NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "266" NAS-Port = 266 NAS-IP-Address = 10.10.10.5 Processing the authorize section of radiusd.conf modcall: entering group authorize for request 17 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 17 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 17 rlm_pap: Found existing Auth-Type, not changing it. modcall[authorize]: module "pap" returns noop for request 17 modcall: leaving group authorize (returns updated) for request 17 rad_check_password: Found Auth-Type EAP auth: type "EAP" Processing the authenticate section of radiusd.conf modcall: entering group authenticate for request 17 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 17 modcall: leaving group authenticate (returns handled) for request 17 Sending Access-Challenge of id 18 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 = 0xed36682d4ca76bf242a5306504c869d2 Finished request 17
A.L.M.Buxey@lboro.ac.uk wrote:
Hi,
See in attach naslist, clients.conf and radius -xx log.
you dont have 127.0.0.1 in your clients.conf
alan - List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
I already add 127.0.0.1 im my clients.conf but I continue with the problem. -- /emmc 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 realm realm: format = "suffix" realm: delimiter = "@" realm: ignore_default = no realm: ignore_null = no Module: Instantiated realm (suffix) 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=78, length=159 --- Walking the entire request list --- Waking up in 31 seconds... Threads: total/active/spare threads = 5/0/5 Thread 1 got semaphore Thread 1 handling request 0, (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 = 0xba711eb89ef6e0d1749e25c36ef4cea7 EAP-Message = 0x0202000d0167696c626572746f NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "275" NAS-Port = 275 NAS-IP-Address = 10.10.10.5 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 78 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 = 0xcc1d7090fe3c708059231d99a5367647 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=79, length=224 Waking up in 31 seconds... Thread 2 got semaphore 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 = 0x76070533b28f83f02259c23387625b72 EAP-Message = 0x0203003c158000000032160301002d010000290301e496ea74b73a164c9286c8503db24b90d7bd6e1bca3b4a308e55b03e976aa1c4000002000a0100 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "275" NAS-Port = 275 State = 0xcc1d7090fe3c708059231d99a5367647 NAS-IP-Address = 10.10.10.5 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 79 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 = 0x0104040a15c0000006f1160301004a020000460301467e56d16f04407aeb455775bd9d8573185d601b95fd9e9e76abe096067b7cdd204c38f26167f0290d1326026809db9b767ab3a9a6ec6fd6e619db94a4dc0de81c000a0016030106940b00069000068d0002cd308202c930820232a003020102020102300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e74206365 EAP-Message = 0x7274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d301e170d3034303132353133323631305a170d3035303132343133323631305a30819b310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f73743119301706035504031310526f6f74206365727469666963617465311f301d06092a864886f70d0109011610726f6f74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003 EAP-Message = 0x818d0030818902818100dac525422bfedb082629a2cba44b3449c90d0ab462fb72c8434a782098863d7eb7d7e70028c2b7ad555a51cc756cf4fa1d7091615ab450d5289553ae6616aff014a55085d6b8fb4aee98638e426175cdd36c665c63cda177d34920eb30585edc8773999c2980f81ad4638bbbea1c82d054023db7ef24a3ec1c3f6241a903d7f30203010001a317301530130603551d25040c300a06082b06010505070301300d06092a864886f70d0101040500038181007a2d921b1cf13bf2982a9178ec9ede6d88edc178a2e8bd40a0a06fb6f0769957884cd7084537083496fd184165293f583c8e8240eb68e042c94b15752e4c07e80d09 EAP-Message = 0x779afa3dd55c24fa54ac292d77205d1c2477ed30d59f57caf9bd21ff2a8d16cc0911c50e4f295763fcb60efa3c3d2d0e43850f6e6fbe284902f6e83503650003ba308203b63082031fa003020102020100300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c EAP-Message = 0x652e636f6d301e170d3034303132353133323630375a Message-Authenticator = 0x00000000000000000000000000000000 State = 0x9c375a385659eeacf9d8c92dd164eb9d 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=80, length=170 Waking up in 31 seconds... Thread 3 got semaphore 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 = 0x5570327018f5c0421ccb0f47f89370dd EAP-Message = 0x020400061500 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "275" NAS-Port = 275 State = 0x9c375a385659eeacf9d8c92dd164eb9d NAS-IP-Address = 10.10.10.5 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 80 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 = 0x010502fb1580000006f1170d3036303132343133323630375a30819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003818d0030818902818100d4c5b19724f164acf1ffb189db1c8fbff4f14396ea7cb1e90f78d69451725377895dfe52cc EAP-Message = 0xb99b41e80ddeb58b127a943f4f58cbc562878192fbdc6fece9f871e7c130d35cf5188817e9b133249edd2a1c75d31043ae87553cec7a77ef26aa7d74281db9b77e17c6446c5dd9b188b43250ca0229963722a123a726b00b4027fd0203010001a381ff3081fc301d0603551d0e0416041468d36d3e1ee7bc9d5a057021c363da1365d1ade33081cc0603551d230481c43081c1801468d36d3e1ee7bc9d5a057021c363da1365d1ade3a181a5a481a230819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e EAP-Message = 0x31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d820100300c0603551d13040530030101ff300d06092a864886f70d01010405000381810033c00b66b1e579ef73a06798252dab8d5e5511fc00fd276d80d12f834777c6743fdc2743fca1507704e4bc0979e4f60ac3ad9ee83e6f347369229d1f77229ba2e982359da563024a00163dba6d6c986c0bad28af85132ff8f0d76501bf1b7c2dff658ce1e62c01997b6e64e3e8d4373354ce9912847651539063b85bbc5485c51603010004 EAP-Message = 0x0e000000 Message-Authenticator = 0x00000000000000000000000000000000 State = 0xe47b644488e93ab668ce977ce690ffd7 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=81, length=364 Waking up in 31 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 = 0xbc07e0e9880b09550d3a5f3b48c0e725 EAP-Message = 0x020500c81580000000be16030100861000008200804e18d18e7df03f3a77f25ff3962d242b43af6dbf483ac13c4bb6fbc5beeaa1e605d0bd9bac2c6632c49c58935490c80d4cf86d4c891e233f92d4559017be7b7838d4129600fe53816b7c9cb5679c9751a5f9b7449138be2057cf64f73b1e5e6aa58a0644c007c2c2e4d85f6ded287d7711bff6417463a0798ea3cee9487574c7140301000101160301002888b33a94bd8e5a2a260fb17337a31ae3458bc12ac92df9f0122f915ebf4c26f982f6184be1cc687e NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "275" NAS-Port = 275 State = 0xe47b644488e93ab668ce977ce690ffd7 NAS-IP-Address = 10.10.10.5 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 81 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 = 0x0106003d15800000003314030100010116030100289d50e21d637c11e69b956045e2f4b5fa2a8cee318b8c598fb0e026810c019bd1841586796ab7cac3 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x58bb7dcdab904644bbca98ee4726b315 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=82, length=235 Waking up in 31 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 = 0x7a325ce2ac97beaab07f110de7edf98e EAP-Message = 0x0206004715800000003d17030100387e84ed86f521ee4cbf370fd619dc98f72547f39c88e313ff4daf6bb68afc96dbbd1254f1d1343e93f557143734d1986df01829b4efd0dc64 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "275" NAS-Port = 275 State = 0x58bb7dcdab904644bbca98ee4726b315 NAS-IP-Address = 10.10.10.5 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 275 cli 0040.96a2.24f3) Sending Access-Accept of id 82 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 = 0x823bb5e864ec96b02ce5423a0e9b8a36a842b08a4ba2bb25a5a704c26758e132 MS-MPPE-Send-Key = 0xf0c51a60f7f2b4f922fc21ba45934cb95f8cb39bbbc7ae666a71729b22db2260 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=83, length=159 --- Walking the entire request list --- Waking up in 2 seconds... Thread 1 got semaphore Thread 1 handling request 5, (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 = 0x3cf4413d6e6cbc621630e1e87e1fb382 EAP-Message = 0x0202000d0167696c626572746f NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "276" NAS-Port = 276 NAS-IP-Address = 10.10.10.5 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 83 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 = 0x7aa51b1c1d28eb35ec71a6faa41da57f 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=84, length=256 Waking up in 2 seconds... Thread 2 got semaphore 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 = 0x2760cd0c1df62b24cd4f40f801a3528d EAP-Message = 0x0203005c158000000052160301004d010000490301300d5f6e036fc02399b43761f2eb24c1b904585d5156ee890a36f9ca24ba4fb7204c38f26167f0290d1326026809db9b767ab3a9a6ec6fd6e619db94a4dc0de81c0002000a0100 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "276" NAS-Port = 276 State = 0x7aa51b1c1d28eb35ec71a6faa41da57f NAS-IP-Address = 10.10.10.5 Processing the authorize section of radiusd.conf modcall: entering group authorize for request 6 rlm_eap: EAP packet type response id 3 length 92 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 004d], 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 84 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 = 0x0104040a15c0000006f1160301004a020000460301467e56d583b10b78c4e94be3d88dc7c8e17997289f898e2027e1a417a8840b08207778f4e31b508df1a273c5eca786cd03a5f8cb243fd3a212e7e9f47deb5d93c6000a0016030106940b00069000068d0002cd308202c930820232a003020102020102300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e74206365 EAP-Message = 0x7274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d301e170d3034303132353133323631305a170d3035303132343133323631305a30819b310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f73743119301706035504031310526f6f74206365727469666963617465311f301d06092a864886f70d0109011610726f6f74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003 EAP-Message = 0x818d0030818902818100dac525422bfedb082629a2cba44b3449c90d0ab462fb72c8434a782098863d7eb7d7e70028c2b7ad555a51cc756cf4fa1d7091615ab450d5289553ae6616aff014a55085d6b8fb4aee98638e426175cdd36c665c63cda177d34920eb30585edc8773999c2980f81ad4638bbbea1c82d054023db7ef24a3ec1c3f6241a903d7f30203010001a317301530130603551d25040c300a06082b06010505070301300d06092a864886f70d0101040500038181007a2d921b1cf13bf2982a9178ec9ede6d88edc178a2e8bd40a0a06fb6f0769957884cd7084537083496fd184165293f583c8e8240eb68e042c94b15752e4c07e80d09 EAP-Message = 0x779afa3dd55c24fa54ac292d77205d1c2477ed30d59f57caf9bd21ff2a8d16cc0911c50e4f295763fcb60efa3c3d2d0e43850f6e6fbe284902f6e83503650003ba308203b63082031fa003020102020100300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c EAP-Message = 0x652e636f6d301e170d3034303132353133323630375a Message-Authenticator = 0x00000000000000000000000000000000 State = 0xd80807313dfb49b227c4e24dc697c7f4 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=85, length=170 Waking up in 2 seconds... Thread 3 got semaphore 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 = 0xe4e31be2450195ce8e6c083019086b17 EAP-Message = 0x020400061500 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "276" NAS-Port = 276 State = 0xd80807313dfb49b227c4e24dc697c7f4 NAS-IP-Address = 10.10.10.5 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 85 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 = 0x010502fb1580000006f1170d3036303132343133323630375a30819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003818d0030818902818100d4c5b19724f164acf1ffb189db1c8fbff4f14396ea7cb1e90f78d69451725377895dfe52cc EAP-Message = 0xb99b41e80ddeb58b127a943f4f58cbc562878192fbdc6fece9f871e7c130d35cf5188817e9b133249edd2a1c75d31043ae87553cec7a77ef26aa7d74281db9b77e17c6446c5dd9b188b43250ca0229963722a123a726b00b4027fd0203010001a381ff3081fc301d0603551d0e0416041468d36d3e1ee7bc9d5a057021c363da1365d1ade33081cc0603551d230481c43081c1801468d36d3e1ee7bc9d5a057021c363da1365d1ade3a181a5a481a230819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e EAP-Message = 0x31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d820100300c0603551d13040530030101ff300d06092a864886f70d01010405000381810033c00b66b1e579ef73a06798252dab8d5e5511fc00fd276d80d12f834777c6743fdc2743fca1507704e4bc0979e4f60ac3ad9ee83e6f347369229d1f77229ba2e982359da563024a00163dba6d6c986c0bad28af85132ff8f0d76501bf1b7c2dff658ce1e62c01997b6e64e3e8d4373354ce9912847651539063b85bbc5485c51603010004 EAP-Message = 0x0e000000 Message-Authenticator = 0x00000000000000000000000000000000 State = 0xe721c9b7f799b39f8a4b5b5ced491690 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=86, length=364 Waking up in 2 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 = 0xa69ab2676dd753af6977b99668f4f893 EAP-Message = 0x020500c81580000000be16030100861000008200809e17416923dc63754b665b554c4be2f2f3dfcbe18c5e382947f99083340e7f6c8b1e97b1bb2c706c42f3508d8f85b4308b61fa35b3207f4ca3a5931fe6876e3e2d47679d62ab4772b89871399c02e9621749ef4e8b5e13337f36f8fdbea5491b2b142d17bbbdcad3dd3d44eb0a110a1bf2ae43fb7a84e52de89e119fd5ae96d31403010001011603010028a18943aa64037a32be1d9d6bd4666e43288fd5783c857a139c4ae5bde27651d8548710f23f43e03d NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "276" NAS-Port = 276 State = 0xe721c9b7f799b39f8a4b5b5ced491690 NAS-IP-Address = 10.10.10.5 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 86 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 = 0x0106003d1580000000331403010001011603010028e490cb6d9c803b01e1a73c09a349c205ea08613f902226f98e5468d89696186dc6c2134ee0ea8f3c Message-Authenticator = 0x00000000000000000000000000000000 State = 0xc3554aa3001ebcffa9d77866d6cb71a8 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=87, length=235 Waking up in 2 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 = 0x380933b94cf4fe47723c316bf860a10e EAP-Message = 0x0206004715800000003d1703010038969fff5682979f2cbb1b0fcdc45ba95bfb574db30f34fed273451502bbb555d362eb337a6dc893997ba34c9b416bfe8cd29eb923fea0b743 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "276" NAS-Port = 276 State = 0xc3554aa3001ebcffa9d77866d6cb71a8 NAS-IP-Address = 10.10.10.5 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 276 cli 0040.96a2.24f3) Sending Access-Accept of id 87 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 = 0x7570b2571ef96575fa6b4f55bb63a33ebb67b729f73c2d949e4259586b794289 MS-MPPE-Send-Key = 0xe1bccc650286606e3a582292485e14f52e29f46ecdde787615f727467d4d122b 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 --- Cleaning up request 0 ID 78 with timestamp 467e56d1 Cleaning up request 1 ID 79 with timestamp 467e56d1 Cleaning up request 2 ID 80 with timestamp 467e56d1 Cleaning up request 3 ID 81 with timestamp 467e56d1 Cleaning up request 4 ID 82 with timestamp 467e56d1 Waking up in 4 seconds... rad_recv: Access-Request packet from host 10.10.10.5:1645, id=88, length=159 --- Walking the entire request list --- Waking up in 2 seconds... Thread 1 got semaphore Thread 1 handling request 10, (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 = 0x22737f8ed0e55d4ff1ab53dcb21ca996 EAP-Message = 0x0202000d0167696c626572746f NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "277" NAS-Port = 277 NAS-IP-Address = 10.10.10.5 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 88 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 = 0xfc4aa37aa67f63bb6170faef11a33420 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=89, length=256 Waking up in 2 seconds... Thread 2 got semaphore 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 = 0xc589b0a7cd5e5929dd64bbc4362a133e EAP-Message = 0x0203005c158000000052160301004d010000490301e97cc1e3b2395cab1cda917189416c7fa48585a4c9503de8cc553a93cca7774c207778f4e31b508df1a273c5eca786cd03a5f8cb243fd3a212e7e9f47deb5d93c60002000a0100 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "277" NAS-Port = 277 State = 0xfc4aa37aa67f63bb6170faef11a33420 NAS-IP-Address = 10.10.10.5 Processing the authorize section of radiusd.conf modcall: entering group authorize for request 11 rlm_eap: EAP packet type response id 3 length 92 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 004d], 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 89 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 = 0x0104040a15c0000006f1160301004a020000460301467e56d913eccdb2683fdddaf61b491b31e2bed2415c6d3db72d735a32be944820789154369971bafac376d622fc9f5565a6f69d2b05ba294c77c8ba6cd82306e1000a0016030106940b00069000068d0002cd308202c930820232a003020102020102300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e74206365 EAP-Message = 0x7274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d301e170d3034303132353133323631305a170d3035303132343133323631305a30819b310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f73743119301706035504031310526f6f74206365727469666963617465311f301d06092a864886f70d0109011610726f6f74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003 EAP-Message = 0x818d0030818902818100dac525422bfedb082629a2cba44b3449c90d0ab462fb72c8434a782098863d7eb7d7e70028c2b7ad555a51cc756cf4fa1d7091615ab450d5289553ae6616aff014a55085d6b8fb4aee98638e426175cdd36c665c63cda177d34920eb30585edc8773999c2980f81ad4638bbbea1c82d054023db7ef24a3ec1c3f6241a903d7f30203010001a317301530130603551d25040c300a06082b06010505070301300d06092a864886f70d0101040500038181007a2d921b1cf13bf2982a9178ec9ede6d88edc178a2e8bd40a0a06fb6f0769957884cd7084537083496fd184165293f583c8e8240eb68e042c94b15752e4c07e80d09 EAP-Message = 0x779afa3dd55c24fa54ac292d77205d1c2477ed30d59f57caf9bd21ff2a8d16cc0911c50e4f295763fcb60efa3c3d2d0e43850f6e6fbe284902f6e83503650003ba308203b63082031fa003020102020100300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c EAP-Message = 0x652e636f6d301e170d3034303132353133323630375a Message-Authenticator = 0x00000000000000000000000000000000 State = 0x2150ab3acc26e18f20b3b360c91eace0 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=90, length=170 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 = 0xf07a0db6016a6a19be738d33cfcc0419 EAP-Message = 0x020400061500 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "277" NAS-Port = 277 State = 0x2150ab3acc26e18f20b3b360c91eace0 NAS-IP-Address = 10.10.10.5 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 90 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 = 0x010502fb1580000006f1170d3036303132343133323630375a30819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003818d0030818902818100d4c5b19724f164acf1ffb189db1c8fbff4f14396ea7cb1e90f78d69451725377895dfe52cc EAP-Message = 0xb99b41e80ddeb58b127a943f4f58cbc562878192fbdc6fece9f871e7c130d35cf5188817e9b133249edd2a1c75d31043ae87553cec7a77ef26aa7d74281db9b77e17c6446c5dd9b188b43250ca0229963722a123a726b00b4027fd0203010001a381ff3081fc301d0603551d0e0416041468d36d3e1ee7bc9d5a057021c363da1365d1ade33081cc0603551d230481c43081c1801468d36d3e1ee7bc9d5a057021c363da1365d1ade3a181a5a481a230819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e EAP-Message = 0x31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d820100300c0603551d13040530030101ff300d06092a864886f70d01010405000381810033c00b66b1e579ef73a06798252dab8d5e5511fc00fd276d80d12f834777c6743fdc2743fca1507704e4bc0979e4f60ac3ad9ee83e6f347369229d1f77229ba2e982359da563024a00163dba6d6c986c0bad28af85132ff8f0d76501bf1b7c2dff658ce1e62c01997b6e64e3e8d4373354ce9912847651539063b85bbc5485c51603010004 EAP-Message = 0x0e000000 Message-Authenticator = 0x00000000000000000000000000000000 State = 0xe58b46f627240cd3e26a827ddb7c21db 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=91, length=364 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 = 0xa908c78488abacaea60090c2891b684e EAP-Message = 0x020500c81580000000be16030100861000008200800b8bfcbc2f6278eb38d9e22df23b87ef772dab5e4eb9bcd87aa2eba7d5bde6f71848c05a88798d159189c607e4c255e24fd69144e5618b9db64feba52e5bd18631ab43dd6582f678bdd0b5298e39d66b92ac1426f8c3847d42b8364d014af8cd891ccb6aa399ee743d56f6f21dda45ddb0cea21403f3cebcb3442802f68f04681403010001011603010028a426c7b4ccf081bb017c29de1a730ea8d8e94f9bd860a9fca7183e9076fe99826b865de4b5cd6b87 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "277" NAS-Port = 277 State = 0xe58b46f627240cd3e26a827ddb7c21db NAS-IP-Address = 10.10.10.5 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 91 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 = 0x0106003d1580000000331403010001011603010028613c65a9913595fc4e75c44027e78c10becaf89ce5fca1382f79b6604c7684a3692f1f780c530532 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x6bd9be79242c7a433d4ec12366a55989 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=92, length=235 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 = 0xebc0bab7466e03c0e7f05ee419da17db EAP-Message = 0x0206004715800000003d1703010038b5a78edab221a11a3513c5795a49f1884edb03fb96c52a091b5a8d70047b871ebe9371479bb55a581fdf4aea1e6e845f36c9bcf25e682e3d NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "277" NAS-Port = 277 State = 0x6bd9be79242c7a433d4ec12366a55989 NAS-IP-Address = 10.10.10.5 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 277 cli 0040.96a2.24f3) Sending Access-Accept of id 92 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 = 0x0189cc0257dfd66164816feffa3bd8394cb1f28618f900d5f4994cf8b4a7f934 MS-MPPE-Send-Key = 0xbdbe9df57147be5d9386d278f6aaf993e9d4c1aea9b6eced31187de35892910e 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
And the problem is? Your request gets accepted and you do return VLAN attributes. Ivan Kalik Kalik Informatika ISP Dana 24/6/2007, "emmcosta" <emmcosta@gmail.com> piše:
A.L.M.Buxey@lboro.ac.uk wrote:
Hi,
See in attach naslist, clients.conf and radius -xx log.
you dont have 127.0.0.1 in your clients.conf
alan - List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
I already add 127.0.0.1 im my clients.conf but I continue with the problem.
-- /emmc
tnt@kalik.co.yu wrote:
And the problem is? Your request gets accepted and you do return VLAN attributes.
Ivan Kalik Kalik Informatika ISP
Dana 24/6/2007, "emmcosta" <emmcosta@gmail.com> piše:
A.L.M.Buxey@lboro.ac.uk wrote:
Hi,
See in attach naslist, clients.conf and radius -xx log.
you dont have 127.0.0.1 in your clients.conf
alan - List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
I already add 127.0.0.1 im my clients.conf but I continue with the problem.
-- /emmc
- List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
I think that I know which is the problem, when I change configuration in my Cisco ap 1100 to this: .................... dot11 ssid FONTELONGA vlan 2 authentication open eap eap_methods authentication key-management wpa accounting acct_methods interface Dot11Radio0 no ip address no ip route-cache ! encryption vlan 2 mode ciphers tkip ! encryption mode ciphers tkip ! ssid FONTELONGA ........................ The authentication fail, but if I have change to this: ............ dot11 ssid FONTELONGA vlan 2 authentication open eap eap_methods accounting acct_methods .......... interface Dot11Radio0 no ip address no ip route-cache ! encryption mode wep optional ! encryption vlan 2 mode wep mandatory ! ssid FONTELONGA ............ the authentication is sucessefull. Can you help-me, my Cisco Ap 1100 IOS version is 12.3(8)JA2. -- /emmc
Can you post the radius debug from the failed attempt. Ivan Kalik Kalik Informatika ISP Dana 24/6/2007, "emmcosta" <emmcosta@gmail.com> piše:
tnt@kalik.co.yu wrote:
And the problem is? Your request gets accepted and you do return VLAN attributes.
Ivan Kalik Kalik Informatika ISP
Dana 24/6/2007, "emmcosta" <emmcosta@gmail.com> piše:
A.L.M.Buxey@lboro.ac.uk wrote:
Hi,
See in attach naslist, clients.conf and radius -xx log.
you dont have 127.0.0.1 in your clients.conf
alan - List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
I already add 127.0.0.1 im my clients.conf but I continue with the problem.
-- /emmc
- List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
I think that I know which is the problem, when I change configuration in my Cisco ap 1100 to this:
..................... dot11 ssid FONTELONGA vlan 2 authentication open eap eap_methods authentication key-management wpa accounting acct_methods
interface Dot11Radio0 no ip address no ip route-cache ! encryption vlan 2 mode ciphers tkip ! encryption mode ciphers tkip ! ssid FONTELONGA .........................
The authentication fail, but if I have change to this:
.............
dot11 ssid FONTELONGA vlan 2 authentication open eap eap_methods accounting acct_methods ...........
interface Dot11Radio0 no ip address no ip route-cache ! encryption mode wep optional ! encryption vlan 2 mode wep mandatory ! ssid FONTELONGA
............. the authentication is sucessefull.
Can you help-me, my Cisco Ap 1100 IOS version is 12.3(8)JA2.
-- /emmc
tnt@kalik.co.yu wrote:
Can you post the radius debug from the failed attempt.
Ivan Kalik Kalik Informatika ISP
Dana 24/6/2007, "emmcosta" <emmcosta@gmail.com> piše:
tnt@kalik.co.yu wrote:
And the problem is? Your request gets accepted and you do return VLAN attributes.
Ivan Kalik Kalik Informatika ISP
Dana 24/6/2007, "emmcosta" <emmcosta@gmail.com> piše:
A.L.M.Buxey@lboro.ac.uk wrote:
Hi,
See in attach naslist, clients.conf and radius -xx log.
you dont have 127.0.0.1 in your clients.conf
alan - List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
I already add 127.0.0.1 im my clients.conf but I continue with the problem.
-- /emmc
- List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
I think that I know which is the problem, when I change configuration in my Cisco ap 1100 to this:
..................... dot11 ssid FONTELONGA vlan 2 authentication open eap eap_methods authentication key-management wpa accounting acct_methods
interface Dot11Radio0 no ip address no ip route-cache ! encryption vlan 2 mode ciphers tkip ! encryption mode ciphers tkip ! ssid FONTELONGA .........................
The authentication fail, but if I have change to this:
.............
dot11 ssid FONTELONGA vlan 2 authentication open eap eap_methods accounting acct_methods ...........
interface Dot11Radio0 no ip address no ip route-cache ! encryption mode wep optional ! encryption vlan 2 mode wep mandatory ! ssid FONTELONGA
............. the authentication is sucessefull.
Can you help-me, my Cisco Ap 1100 IOS version is 12.3(8)JA2.
-- /emmc
- List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
-- /emmc 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 = 0x0104040a15c0000006f1160301004a020000460301467ec919ec5f698d97daecf82f23e45e096d0bcdcdc951e1a663a2d232873a6d20bfd203de4f9165074be8a6e0a905513d1473ee89b7d437307b90a81cf23d58f6000a0016030106940b00069000068d0002cd308202c930820232a003020102020102300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e74206365 EAP-Message = 0x7274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d301e170d3034303132353133323631305a170d3035303132343133323631305a30819b310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f73743119301706035504031310526f6f74206365727469666963617465311f301d06092a864886f70d0109011610726f6f74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003 EAP-Message = 0x818d0030818902818100dac525422bfedb082629a2cba44b3449c90d0ab462fb72c8434a782098863d7eb7d7e70028c2b7ad555a51cc756cf4fa1d7091615ab450d5289553ae6616aff014a55085d6b8fb4aee98638e426175cdd36c665c63cda177d34920eb30585edc8773999c2980f81ad4638bbbea1c82d054023db7ef24a3ec1c3f6241a903d7f30203010001a317301530130603551d25040c300a06082b06010505070301300d06092a864886f70d0101040500038181007a2d921b1cf13bf2982a9178ec9ede6d88edc178a2e8bd40a0a06fb6f0769957884cd7084537083496fd184165293f583c8e8240eb68e042c94b15752e4c07e80d09 EAP-Message = 0x779afa3dd55c24fa54ac292d77205d1c2477ed30d59f57caf9bd21ff2a8d16cc0911c50e4f295763fcb60efa3c3d2d0e43850f6e6fbe284902f6e83503650003ba308203b63082031fa003020102020100300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c 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 = 0x010502fb1580000006f1170d3036303132343133323630375a30819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003818d0030818902818100d4c5b19724f164acf1ffb189db1c8fbff4f14396ea7cb1e90f78d69451725377895dfe52cc EAP-Message = 0xb99b41e80ddeb58b127a943f4f58cbc562878192fbdc6fece9f871e7c130d35cf5188817e9b133249edd2a1c75d31043ae87553cec7a77ef26aa7d74281db9b77e17c6446c5dd9b188b43250ca0229963722a123a726b00b4027fd0203010001a381ff3081fc301d0603551d0e0416041468d36d3e1ee7bc9d5a057021c363da1365d1ade33081cc0603551d230481c43081c1801468d36d3e1ee7bc9d5a057021c363da1365d1ade3a181a5a481a230819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e EAP-Message = 0x31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d820100300c0603551d13040530030101ff300d06092a864886f70d01010405000381810033c00b66b1e579ef73a06798252dab8d5e5511fc00fd276d80d12f834777c6743fdc2743fca1507704e4bc0979e4f60ac3ad9ee83e6f347369229d1f77229ba2e982359da563024a00163dba6d6c986c0bad28af85132ff8f0d76501bf1b7c2dff658ce1e62c01997b6e64e3e8d4373354ce9912847651539063b85bbc5485c51603010004 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 = 0x020500c81580000000be1603010086100000820080c44abb4d3f569f6bb8337561776918270adc19c64367cc1de20cb5ab2227d8c9f34f7577865d41d6e65545031b2ce31b3454b9c42b7485df71baac8ddc61b7d545d53599429d7c809661ace9e3c89f2b0780ac6f9404e2c6cc66b0f595ff3f7285699a367d8595101bfa9a601a4e17dc74ebcb8ef6cb9aa114cb3222837adbe714030100010116030100284948a9b700ea3af45c94bc3827d68ab7f0056270c08a7611fb62baeca41e641b9aa1b411463c5dab 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 = 0x0104040a15c0000006f1160301004a020000460301467ec91d502f6c800b9f80cc6e7523ae9ecad076fac1d7b56b1d452b98c23ab52056ff414ca9f31be5b2f7675d35da28f228947350aeeb6ecdae16be2b40c2f0cf000a0016030106940b00069000068d0002cd308202c930820232a003020102020102300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e74206365 EAP-Message = 0x7274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d301e170d3034303132353133323631305a170d3035303132343133323631305a30819b310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f73743119301706035504031310526f6f74206365727469666963617465311f301d06092a864886f70d0109011610726f6f74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003 EAP-Message = 0x818d0030818902818100dac525422bfedb082629a2cba44b3449c90d0ab462fb72c8434a782098863d7eb7d7e70028c2b7ad555a51cc756cf4fa1d7091615ab450d5289553ae6616aff014a55085d6b8fb4aee98638e426175cdd36c665c63cda177d34920eb30585edc8773999c2980f81ad4638bbbea1c82d054023db7ef24a3ec1c3f6241a903d7f30203010001a317301530130603551d25040c300a06082b06010505070301300d06092a864886f70d0101040500038181007a2d921b1cf13bf2982a9178ec9ede6d88edc178a2e8bd40a0a06fb6f0769957884cd7084537083496fd184165293f583c8e8240eb68e042c94b15752e4c07e80d09 EAP-Message = 0x779afa3dd55c24fa54ac292d77205d1c2477ed30d59f57caf9bd21ff2a8d16cc0911c50e4f295763fcb60efa3c3d2d0e43850f6e6fbe284902f6e83503650003ba308203b63082031fa003020102020100300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c 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 = 0x010502fb1580000006f1170d3036303132343133323630375a30819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003818d0030818902818100d4c5b19724f164acf1ffb189db1c8fbff4f14396ea7cb1e90f78d69451725377895dfe52cc EAP-Message = 0xb99b41e80ddeb58b127a943f4f58cbc562878192fbdc6fece9f871e7c130d35cf5188817e9b133249edd2a1c75d31043ae87553cec7a77ef26aa7d74281db9b77e17c6446c5dd9b188b43250ca0229963722a123a726b00b4027fd0203010001a381ff3081fc301d0603551d0e0416041468d36d3e1ee7bc9d5a057021c363da1365d1ade33081cc0603551d230481c43081c1801468d36d3e1ee7bc9d5a057021c363da1365d1ade3a181a5a481a230819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e EAP-Message = 0x31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d820100300c0603551d13040530030101ff300d06092a864886f70d01010405000381810033c00b66b1e579ef73a06798252dab8d5e5511fc00fd276d80d12f834777c6743fdc2743fca1507704e4bc0979e4f60ac3ad9ee83e6f347369229d1f77229ba2e982359da563024a00163dba6d6c986c0bad28af85132ff8f0d76501bf1b7c2dff658ce1e62c01997b6e64e3e8d4373354ce9912847651539063b85bbc5485c51603010004 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 = 0x020500c81580000000be160301008610000082008025d8ff97795c40f1402eaad884cd50f3142ea6b00528380fa2239463511eb870435260988ab01c1ea8599e4a862d3493a602817f2ca0a1b8ffb63b544128664cfa0bc43d0bf686007c8f3cbb33e1b605c8a2bceb42dbfc1762ce7241daafda657c26f7f2bf5373ea3d5753cf7725ebef2ca578cd9769bac9147f3daabbca8b0a1403010001011603010028c859cb67ec272608df850aac8a355fb6ff1a8dffddf9cd8e8f5e15a83a140fa772bf7eded173e3a1 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 = 0x0104040a15c0000006f1160301004a020000460301467ec921606131e4013e959b975e294da8981767e2fe9d3e1877947bae6362aa2051ce335f05d736702d7d0a1fc704a25ffa57120d840193d8b4ef7917c66fac32000a0016030106940b00069000068d0002cd308202c930820232a003020102020102300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e74206365 EAP-Message = 0x7274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d301e170d3034303132353133323631305a170d3035303132343133323631305a30819b310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f73743119301706035504031310526f6f74206365727469666963617465311f301d06092a864886f70d0109011610726f6f74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003 EAP-Message = 0x818d0030818902818100dac525422bfedb082629a2cba44b3449c90d0ab462fb72c8434a782098863d7eb7d7e70028c2b7ad555a51cc756cf4fa1d7091615ab450d5289553ae6616aff014a55085d6b8fb4aee98638e426175cdd36c665c63cda177d34920eb30585edc8773999c2980f81ad4638bbbea1c82d054023db7ef24a3ec1c3f6241a903d7f30203010001a317301530130603551d25040c300a06082b06010505070301300d06092a864886f70d0101040500038181007a2d921b1cf13bf2982a9178ec9ede6d88edc178a2e8bd40a0a06fb6f0769957884cd7084537083496fd184165293f583c8e8240eb68e042c94b15752e4c07e80d09 EAP-Message = 0x779afa3dd55c24fa54ac292d77205d1c2477ed30d59f57caf9bd21ff2a8d16cc0911c50e4f295763fcb60efa3c3d2d0e43850f6e6fbe284902f6e83503650003ba308203b63082031fa003020102020100300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c 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 = 0x010502fb1580000006f1170d3036303132343133323630375a30819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003818d0030818902818100d4c5b19724f164acf1ffb189db1c8fbff4f14396ea7cb1e90f78d69451725377895dfe52cc EAP-Message = 0xb99b41e80ddeb58b127a943f4f58cbc562878192fbdc6fece9f871e7c130d35cf5188817e9b133249edd2a1c75d31043ae87553cec7a77ef26aa7d74281db9b77e17c6446c5dd9b188b43250ca0229963722a123a726b00b4027fd0203010001a381ff3081fc301d0603551d0e0416041468d36d3e1ee7bc9d5a057021c363da1365d1ade33081cc0603551d230481c43081c1801468d36d3e1ee7bc9d5a057021c363da1365d1ade3a181a5a481a230819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e EAP-Message = 0x31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d820100300c0603551d13040530030101ff300d06092a864886f70d01010405000381810033c00b66b1e579ef73a06798252dab8d5e5511fc00fd276d80d12f834777c6743fdc2743fca1507704e4bc0979e4f60ac3ad9ee83e6f347369229d1f77229ba2e982359da563024a00163dba6d6c986c0bad28af85132ff8f0d76501bf1b7c2dff658ce1e62c01997b6e64e3e8d4373354ce9912847651539063b85bbc5485c51603010004 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 = 0x020500c81580000000be16030100861000008200805c81e485b7c270681997ccce49e65a504184a438f3f673cc80556010b3575e0ff1e941095c52c07b41800a3b49af3add305728d1818f2a642788070cb9a92b6287a3b6fc3c873413d7f0d606221ee7b0a2573000535ec56c72663d82796c712329ca0341ec19ce5f70ef4e7c23e9de7dcadcbbf55a61296e474bf7f4ecc4176d1403010001011603010028974b91d454193939149159ffd00fed1e06f78d854515b9ca7718158609bbb65fbaa92174b711ec6f 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...
In what way is this not OK? RADIUS works. Do debug on your NAS to see why is connection not established. Ivan Kalik Kalik Informatika ISP Dana 24/6/2007, "emmcosta" <emmcosta@gmail.com> piše:
tnt@kalik.co.yu wrote:
Can you post the radius debug from the failed attempt.
Ivan Kalik Kalik Informatika ISP
Dana 24/6/2007, "emmcosta" <emmcosta@gmail.com> piše:
tnt@kalik.co.yu wrote:
And the problem is? Your request gets accepted and you do return VLAN attributes.
Ivan Kalik Kalik Informatika ISP
Dana 24/6/2007, "emmcosta" <emmcosta@gmail.com> piše:
A.L.M.Buxey@lboro.ac.uk wrote:
Hi,
> See in attach naslist, clients.conf and radius -xx log. > > > you dont have 127.0.0.1 in your clients.conf
alan - List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
I already add 127.0.0.1 im my clients.conf but I continue with the problem.
-- /emmc
- List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
I think that I know which is the problem, when I change configuration in my Cisco ap 1100 to this:
..................... dot11 ssid FONTELONGA vlan 2 authentication open eap eap_methods authentication key-management wpa accounting acct_methods
interface Dot11Radio0 no ip address no ip route-cache ! encryption vlan 2 mode ciphers tkip ! encryption mode ciphers tkip ! ssid FONTELONGA .........................
The authentication fail, but if I have change to this:
.............
dot11 ssid FONTELONGA vlan 2 authentication open eap eap_methods accounting acct_methods ...........
interface Dot11Radio0 no ip address no ip route-cache ! encryption mode wep optional ! encryption vlan 2 mode wep mandatory ! ssid FONTELONGA
............. the authentication is sucessefull.
Can you help-me, my Cisco Ap 1100 IOS version is 12.3(8)JA2.
-- /emmc
- List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
-- /emmc
You need to post the debug (radiusd -X) output. Whole thing. Ivan Kalik Kalik Informatika ISP -----Original Message----- From: freeradius-users-bounces+tnt=kalik.co.yu@lists.freeradius.org [mailto:freeradius-users-bounces+tnt=kalik.co.yu@lists.freeradius.org] On Behalf Of emmcosta Sent: 21 June 2007 20:22 To: FreeRadius users mailing list Subject: Re: EAP-TTLS PAP Mysql problems Stefan Winter wrote: What it is that I need put in mysql and my configuration, for before I obtain good authentication return: Tunnel-Type, Tunnel-Medium-Type and Tunnel-Private-Group-ID for the client make a dhclient in vlan I return? Put the appropriate attributes for VLAN assignment into the radreply table for the user in question. Chances are that you also need to set the option use_tunneled_reply = yes in eap.conf. Greetings, Stefan Winter _____ - List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html I already put appropriate attributes for VLAN assignment into the radreply table, but I think I have a problem with authentication because log this lines: Wed Jun 20 19:46:47 2007 : Error: Trying to look up name of unknown client 127.0.0.1. Wed Jun 20 19:46:47 2007 : Auth: Login OK: [teste/secret] (from client UNKNOWN-CLIENT port 327 cli 0040.96a2.24f3) Wed Jun 20 19:46:47 2007 : Auth: Login OK: [teste/<no User-Password attribute>] (from client ap2 port 327 cli 0040.96a2.24f3) but if I use cli with command radtest authentication is sucessefull and receive reply attributes. I'm to use a Cisco ap1100 configurated with wpa-tkip and for client use a pc with windows XP with supplicant securew2 configured with eap-ttls pap. Can help-me? -- /emmc
participants (5)
-
A.L.M.Buxey＠lboro.ac.uk -
emmcosta -
Ivan Kalik -
Stefan Winter -
tnt＠kalik.co.yu