Hi,
Subject: Re: errors when check with huntgroup
hi,
you've edited a whole lot of stuff out of your debug log...including the stuff which actually matters where the failure actually occurs (you just kept the part where the end result was recorded).
alan
Below the full output (radiusd -X) when user access is rejected. I compared with the output when successed and it differs from the one below with "++[files] returns noop " I put the words " <<<=== FIRST DIFFERENCE" to find it easily. users file contains : bp3 Cleartext-Password := "test" , Calling-Station-Id == "844b.f5b8.d423" , Cisco-AVPair == "ssid=ipl_dsi" , Huntgroup-Name == "wifi" with : , Huntgroup-Name == "wifi" when the reject occurs. huntgroup file contains : wifi NAS-IP-Address == 172.20.100.53 Thanks for any help. Bertrand. radiusd -X FreeRADIUS Version 2.2.0, for host i686-pc-linux-gnu, built on Mar 11 2013 at 13:51:19 Copyright (C) 1999-2012 The FreeRADIUS server project and contributors. There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. You may redistribute copies of FreeRADIUS under the terms of the GNU General Public License v2. Starting - reading configuration files ... including configuration file /usr/local/etc/raddb/radiusd.conf including configuration file /usr/local/etc/raddb/proxy.conf including configuration file /usr/local/etc/raddb/clients.conf including files in directory /usr/local/etc/raddb/modules/ including configuration file /usr/local/etc/raddb/modules/mac2ip including configuration file /usr/local/etc/raddb/modules/smbpasswd including configuration file /usr/local/etc/raddb/modules/preprocess including configuration file /usr/local/etc/raddb/modules/mschap including configuration file /usr/local/etc/raddb/modules/dhcp_sqlippool including configuration file /usr/local/etc/raddb/sql/mysql/ippool-dhcp.conf including configuration file /usr/local/etc/raddb/modules/always including configuration file /usr/local/etc/raddb/modules/passwd including configuration file /usr/local/etc/raddb/modules/ntlm_auth including configuration file /usr/local/etc/raddb/modules/cache including configuration file /usr/local/etc/raddb/modules/detail.example.com including configuration file /usr/local/etc/raddb/modules/pam including configuration file /usr/local/etc/raddb/modules/ippool including configuration file /usr/local/etc/raddb/modules/expr including configuration file /usr/local/etc/raddb/modules/realm including configuration file /usr/local/etc/raddb/modules/files including configuration file /usr/local/etc/raddb/modules/radrelay including configuration file /usr/local/etc/raddb/modules/radutmp including configuration file /usr/local/etc/raddb/modules/mac2vlan including configuration file /usr/local/etc/raddb/modules/attr_filter including configuration file /usr/local/etc/raddb/modules/detail.log including configuration file /usr/local/etc/raddb/modules/sql_log including configuration file /usr/local/etc/raddb/modules/perl including configuration file /usr/local/etc/raddb/modules/replicate including configuration file /usr/local/etc/raddb/modules/wimax including configuration file /usr/local/etc/raddb/modules/linelog including configuration file /usr/local/etc/raddb/modules/sqlcounter_expire_on_login including configuration file /usr/local/etc/raddb/modules/expiration including configuration file /usr/local/etc/raddb/modules/checkval including configuration file /usr/local/etc/raddb/modules/otp including configuration file /usr/local/etc/raddb/modules/inner-eap including configuration file /usr/local/etc/raddb/modules/sradutmp including configuration file /usr/local/etc/raddb/modules/etc_group including configuration file /usr/local/etc/raddb/modules/digest including configuration file /usr/local/etc/raddb/modules/counter including configuration file /usr/local/etc/raddb/modules/attr_rewrite including configuration file /usr/local/etc/raddb/modules/ldap including configuration file /usr/local/etc/raddb/modules/chap including configuration file /usr/local/etc/raddb/modules/acct_unique including configuration file /usr/local/etc/raddb/modules/redis including configuration file /usr/local/etc/raddb/modules/unix including configuration file /usr/local/etc/raddb/modules/echo including configuration file /usr/local/etc/raddb/modules/exec including configuration file /usr/local/etc/raddb/modules/dynamic_clients including configuration file /usr/local/etc/raddb/modules/soh including configuration file /usr/local/etc/raddb/modules/pap including configuration file /usr/local/etc/raddb/modules/krb5 including configuration file /usr/local/etc/raddb/modules/rediswho including configuration file /usr/local/etc/raddb/modules/logintime including configuration file /usr/local/etc/raddb/modules/policy including configuration file /usr/local/etc/raddb/modules/cui including configuration file /usr/local/etc/raddb/modules/opendirectory including configuration file /usr/local/etc/raddb/modules/detail including configuration file /usr/local/etc/raddb/modules/smsotp including configuration file /usr/local/etc/raddb/eap.conf including configuration file /usr/local/etc/raddb/policy.conf including files in directory /usr/local/etc/raddb/sites-enabled/ including configuration file /usr/local/etc/raddb/sites-enabled/control-socket including configuration file /usr/local/etc/raddb/sites-enabled/inner-tunnel including configuration file /usr/local/etc/raddb/sites-enabled/default main { allow_core_dumps = no } including dictionary file /usr/local/etc/raddb/dictionary main { name = "radiusd" prefix = "/usr/local" localstatedir = "/usr/local/var" sbindir = "/usr/local/sbin" logdir = "/usr/local/var/log/radius" run_dir = "/usr/local/var/run/radiusd" libdir = "/usr/local/lib" radacctdir = "/usr/local/var/log/radius/radacct" hostname_lookups = no max_request_time = 30 cleanup_delay = 5 max_requests = 1024 pidfile = "/usr/local/var/run/radiusd/radiusd.pid" checkrad = "/usr/local/sbin/checkrad" debug_level = 0 proxy_requests = yes log { stripped_names = no auth = no auth_badpass = no auth_goodpass = no } security { max_attributes = 200 reject_delay = 1 status_server = yes } } radiusd: #### Loading Realms and Home Servers #### proxy server { retry_delay = 5 retry_count = 3 default_fallback = no dead_time = 120 wake_all_if_all_dead = no } home_server localhost { ipaddr = 127.0.0.1 port = 1812 type = "auth" secret = "testing123" response_window = 20 max_outstanding = 65536 require_message_authenticator = yes zombie_period = 40 status_check = "status-server" ping_interval = 30 check_interval = 30 num_answers_to_alive = 3 num_pings_to_alive = 3 revive_interval = 120 status_check_timeout = 4 coa { irt = 2 mrt = 16 mrc = 5 mrd = 30 } } home_server_pool my_auth_failover { type = fail-over home_server = localhost } realm example.com { auth_pool = my_auth_failover } realm LOCAL { } radiusd: #### Loading Clients #### client localhost { ipaddr = 127.0.0.1 require_message_authenticator = no secret = "secret" nastype = "other" } client 172.20.100.53 { require_message_authenticator = no secret = "iplradioshared53" shortname = "net-ap-A1-1-53" nastype = "other" } radiusd: #### Instantiating modules #### instantiate { Module: Linked to module rlm_exec Module: Instantiating module "exec" from file /usr/local/etc/raddb/modules/exec exec { wait = no input_pairs = "request" shell_escape = yes } Module: Linked to module rlm_expr Module: Instantiating module "expr" from file /usr/local/etc/raddb/modules/expr Module: Linked to module rlm_expiration Module: Instantiating module "expiration" from file /usr/local/etc/raddb/modules/expiration expiration { reply-message = "Password Has Expired " } Module: Linked to module rlm_logintime Module: Instantiating module "logintime" from file /usr/local/etc/raddb/modules/logintime logintime { reply-message = "You are calling outside your allowed timespan " minimum-timeout = 60 } } radiusd: #### Loading Virtual Servers #### server { # from file /usr/local/etc/raddb/radiusd.conf modules { Module: Creating Auth-Type = digest Module: Creating Post-Auth-Type = REJECT Module: Checking authenticate {...} for more modules to load Module: Linked to module rlm_pap Module: Instantiating module "pap" from file /usr/local/etc/raddb/modules/pap pap { encryption_scheme = "auto" auto_header = no } Module: Linked to module rlm_chap Module: Instantiating module "chap" from file /usr/local/etc/raddb/modules/chap Module: Linked to module rlm_mschap Module: Instantiating module "mschap" from file /usr/local/etc/raddb/modules/mschap mschap { use_mppe = yes require_encryption = no require_strong = no with_ntdomain_hack = no allow_retry = yes } Module: Linked to module rlm_digest Module: Instantiating module "digest" from file /usr/local/etc/raddb/modules/digest Module: Linked to module rlm_unix Module: Instantiating module "unix" from file /usr/local/etc/raddb/modules/unix unix { radwtmp = "/usr/local/var/log/radius/radwtmp" } Module: Linked to module rlm_eap Module: Instantiating module "eap" from file /usr/local/etc/raddb/eap.conf eap { default_eap_type = "peap" timer_expire = 60 ignore_unknown_eap_types = no cisco_accounting_username_bug = no max_sessions = 4096 } Module: Linked to sub-module rlm_eap_md5 Module: Instantiating eap-md5 Module: Linked to sub-module rlm_eap_leap Module: Instantiating eap-leap Module: Linked to sub-module rlm_eap_gtc Module: Instantiating eap-gtc gtc { challenge = "Password: " auth_type = "PAP" } Module: Linked to sub-module rlm_eap_tls Module: Instantiating eap-tls tls { rsa_key_exchange = no dh_key_exchange = yes rsa_key_length = 512 dh_key_length = 512 verify_depth = 0 CA_path = "/usr/local/etc/raddb/certs" pem_file_type = yes private_key_file = "/usr/local/etc/raddb/certs/cert-srv.pem" certificate_file = "/usr/local/etc/raddb/certs/cert-srv.pem" CA_file = "/usr/local/etc/raddb/certs/demoCA/cacert.pem" private_key_password = "donnezmoiunlevier" dh_file = "/usr/local/etc/raddb/certs/dh" random_file = "/usr/local/etc/raddb/certs/random" fragment_size = 1024 include_length = yes check_crl = no cipher_list = "DEFAULT" ecdh_curve = "prime256v1" cache { enable = no lifetime = 24 max_entries = 255 } verify { } ocsp { enable = no override_cert_url = yes url = "http://127.0.0.1/ocsp/" use_nonce = yes timeout = 0 softfail = no } } Module: Linked to sub-module rlm_eap_ttls Module: Instantiating eap-ttls ttls { default_eap_type = "md5" copy_request_to_tunnel = no use_tunneled_reply = no virtual_server = "inner-tunnel" include_length = yes } Module: Linked to sub-module rlm_eap_peap Module: Instantiating eap-peap peap { default_eap_type = "mschapv2" copy_request_to_tunnel = yes use_tunneled_reply = no proxy_tunneled_request_as_eap = yes virtual_server = "inner-tunnel" soh = no } Module: Linked to sub-module rlm_eap_mschapv2 Module: Instantiating eap-mschapv2 mschapv2 { with_ntdomain_hack = no send_error = no } Module: Checking authorize {...} for more modules to load Module: Linked to module rlm_preprocess Module: Instantiating module "preprocess" from file /usr/local/etc/raddb/modules/preprocess preprocess { huntgroups = "/usr/local/etc/raddb/huntgroups" hints = "/usr/local/etc/raddb/hints" with_ascend_hack = no ascend_channels_per_line = 23 with_ntdomain_hack = no with_specialix_jetstream_hack = no with_cisco_vsa_hack = no with_alvarion_vsa_hack = no } reading pairlist file /usr/local/etc/raddb/huntgroups reading pairlist file /usr/local/etc/raddb/hints Module: Linked to module rlm_realm Module: Instantiating module "suffix" from file /usr/local/etc/raddb/modules/realm realm suffix { format = "suffix" delimiter = "@" ignore_default = no ignore_null = no } Module: Linked to module rlm_files Module: Instantiating module "files" from file /usr/local/etc/raddb/modules/files files { usersfile = "/usr/local/etc/raddb/users" acctusersfile = "/usr/local/etc/raddb/acct_users" preproxy_usersfile = "/usr/local/etc/raddb/preproxy_users" compat = "no" } reading pairlist file /usr/local/etc/raddb/users reading pairlist file /usr/local/etc/raddb/acct_users reading pairlist file /usr/local/etc/raddb/preproxy_users Module: Checking preacct {...} for more modules to load Module: Linked to module rlm_acct_unique Module: Instantiating module "acct_unique" from file /usr/local/etc/raddb/modules/acct_unique acct_unique { key = "User-Name, Acct-Session-Id, NAS-IP-Address, NAS-Identifier, NAS-Port" } Module: Checking accounting {...} for more modules to load Module: Linked to module rlm_detail Module: Instantiating module "detail" from file /usr/local/etc/raddb/modules/detail detail { detailfile = "/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d" header = "%t" detailperm = 384 dirperm = 493 locking = no log_packet_header = no } Module: Linked to module rlm_attr_filter Module: Instantiating module "attr_filter.accounting_response" from file /usr/local/etc/raddb/modules/attr_filter attr_filter attr_filter.accounting_response { attrsfile = "/usr/local/etc/raddb/attrs.accounting_response" key = "%{User-Name}" relaxed = no } reading pairlist file /usr/local/etc/raddb/attrs.accounting_response Module: Checking session {...} for more modules to load Module: Linked to module rlm_radutmp Module: Instantiating module "radutmp" from file /usr/local/etc/raddb/modules/radutmp radutmp { filename = "/usr/local/var/log/radius/radutmp" username = "%{User-Name}" case_sensitive = yes check_with_nas = yes perm = 384 callerid = yes } Module: Checking post-proxy {...} for more modules to load Module: Checking post-auth {...} for more modules to load Module: Instantiating module "attr_filter.access_reject" from file /usr/local/etc/raddb/modules/attr_filter attr_filter attr_filter.access_reject { attrsfile = "/usr/local/etc/raddb/attrs.access_reject" key = "%{User-Name}" relaxed = no } reading pairlist file /usr/local/etc/raddb/attrs.access_reject } # modules } # server server inner-tunnel { # from file /usr/local/etc/raddb/sites-enabled/inner-tunnel modules { Module: Checking authenticate {...} for more modules to load Module: Checking authorize {...} for more modules to load Module: Checking session {...} for more modules to load Module: Checking post-proxy {...} for more modules to load Module: Checking post-auth {...} for more modules to load } # modules } # server radiusd: #### Opening IP addresses and Ports #### listen { type = "auth" ipaddr = * port = 0 } listen { type = "acct" ipaddr = * port = 0 } listen { type = "control" listen { socket = "/usr/local/var/run/radiusd/radiusd.sock" } } listen { type = "auth" ipaddr = 127.0.0.1 port = 18120 } ... adding new socket proxy address * port 36347 Listening on authentication address * port 1812 Listening on accounting address * port 1813 Listening on command file /usr/local/var/run/radiusd/radiusd.sock Listening on authentication address 127.0.0.1 port 18120 as server inner-tunnel Listening on proxy address * port 1814 Ready to process requests. rad_recv: Access-Request packet from host 172.20.100.53 port 1645, id=52, length=162 User-Name = "bp3" Framed-MTU = 1400 Called-Station-Id = "0014.1bb6.4be0" Calling-Station-Id = "844b.f5b8.d423" Cisco-AVPair = "ssid=ipl_dsi" Service-Type = Login-User Message-Authenticator = 0xe401af4dc9f01c6140abfcf4a0c4adf8 EAP-Message = 0x0202000801627033 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "766" NAS-Port = 766 NAS-IP-Address = 172.20.100.53 NAS-Identifier = "net-ap-A1-1-53" # Executing section authorize from file /usr/local/etc/raddb/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok ++[chap] returns noop ++[mschap] returns noop ++[digest] returns noop [suffix] No '@' in User-Name = "bp3", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop [eap] EAP packet type response id 2 length 8 [eap] No EAP Start, assuming it's an on-going EAP conversation ++[eap] returns updated [files] users: Matched entry bp3 at line 212 ++[files] returns ok ++[expiration] returns noop ++[logintime] returns noop [pap] WARNING: Auth-Type already set. Not setting to PAP ++[pap] returns noop Found Auth-Type = EAP # Executing group from file /usr/local/etc/raddb/sites-enabled/default +- entering group authenticate {...} [eap] EAP Identity [eap] processing type tls [tls] Initiate [tls] Start returned 1 ++[eap] returns handled Sending Access-Challenge of id 52 to 172.20.100.53 port 1645 EAP-Message = 0x010300061920 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x5d0df6005d0eefd992b197fe0a3305b3 Finished request 0. Going to the next request Waking up in 4.9 seconds. rad_recv: Access-Request packet from host 172.20.100.53 port 1645, id=53, length=277 User-Name = "bp3" Framed-MTU = 1400 Called-Station-Id = "0014.1bb6.4be0" Calling-Station-Id = "844b.f5b8.d423" Cisco-AVPair = "ssid=ipl_dsi" Service-Type = Login-User Message-Authenticator = 0x51b97c04d114b931a490a5ec503d43be EAP-Message = 0x0203006919800000005f160301005a0100005603015142d5a914bebec7e6d72f2d81dcaa5b163d71838d4a545e8f6eb7f4c8013a29000018002f00350005000ac013c014c009c00a003200380013000401000015ff01000100000a0006000400170018000b00020100 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "766" NAS-Port = 766 State = 0x5d0df6005d0eefd992b197fe0a3305b3 NAS-IP-Address = 172.20.100.53 NAS-Identifier = "net-ap-A1-1-53" # Executing section authorize from file /usr/local/etc/raddb/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok ++[chap] returns noop ++[mschap] returns noop ++[digest] returns noop [suffix] No '@' in User-Name = "bp3", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop [eap] EAP packet type response id 3 length 105 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /usr/local/etc/raddb/sites-enabled/default +- entering group authenticate {...} [eap] Request found, released from the list [eap] EAP/peap [eap] processing type peap [peap] processing EAP-TLS TLS Length 95 [peap] Length Included [peap] eaptls_verify returned 11 [peap] (other): before/accept initialization [peap] TLS_accept: before/accept initialization [peap] <<< TLS 1.0 Handshake [length 005a], ClientHello [peap] TLS_accept: SSLv3 read client hello A [peap] >>> TLS 1.0 Handshake [length 0031], ServerHello [peap] TLS_accept: SSLv3 write server hello A [peap] >>> TLS 1.0 Handshake [length 06ff], Certificate [peap] TLS_accept: SSLv3 write certificate A [peap] >>> TLS 1.0 Handshake [length 0004], ServerHelloDone [peap] TLS_accept: SSLv3 write server done A [peap] TLS_accept: SSLv3 flush data [peap] TLS_accept: Need to read more data: SSLv3 read client certificate A In SSL Handshake Phase In SSL Accept mode [peap] eaptls_process returned 13 [peap] EAPTLS_HANDLED ++[eap] returns handled Sending Access-Challenge of id 53 to 172.20.100.53 port 1645 EAP-Message = 0x0104040019c00000074316030100310200002d03015142d57bdf4bb8967ba80763ed4634e972c7391703143e4499c6b7cf46e8d03d00002f000005ff0100010016030106ff0b0006fb0006f80002fa308202f63082025fa003020102020102300d06092a864886f70d01010405003081b3310b3009060355040613024652310d300b060355040813044e6f7264310e300c060355040713054c696c6c6531223020060355040a1419496e7374697475745f506173746575725f64655f4c696c6c653121301f060355040b13186d617877656c6c2e706173746575722d6c696c6c652e6672311630140603550403130d506173746575722d6c696c6c6531 EAP-Message = 0x26302406092a864886f70d0109011617706f756c657440706173746575722d6c696c6c652e6672301e170d3037303531383136323530375a170d3038303531373136323530375a3081b4310b3009060355040613024652310d300b060355040813044e6f7264310e300c060355040713054c696c6c6531223020060355040a1419496e7374697475745f506173746575725f64655f4c696c6c653121301f060355040b13186d617877656c6c2e706173746575722d6c696c6c652e66723119301706035504031310526f6f742063657274696669636174653124302206092a864886f70d0109011615726f6f7440706173746575722d6c696c6c652e66 EAP-Message = 0x7230819f300d06092a864886f70d010101050003818d0030818902818100c516c8478dfbcb452b98cfc105f321088a9b52820a5ca11f6d107d01c125fb516af5b453b83262c7288d1a1662480674b0d7da18e04019baf4083700bb8356c20ef277813397fa581fdcf439c2d4b146510a0ad815dcb6dc3dec279a123ab1c18d23b826e97d9907d0f9ab5019699a8e8c81f3638113c632095b60be89d80e650203010001a317301530130603551d25040c300a06082b06010505070301300d06092a864886f70d0101040500038181002f420fe5e57c5ca79af4145d4ed4fa3a255d57b42cfa753f26d9e00513e5411730d0f2e6ed9b901f711c37763c72 EAP-Message = 0xf406f4c5e54942223cd857ddf60046b87ab62842a6e90c556cf57ac6c9ad3368f94f91066de015c1c92b8d709af2c4031ac8b706253734b10d5d67fb10ba457ed72211e16249d1a5ee4487a915ceab475c100003f8308203f43082035da003020102020100300d06092a864886f70d01010405003081b3310b3009060355040613024652310d300b060355040813044e6f7264310e300c060355040713054c696c6c6531223020060355040a1419496e7374697475745f506173746575725f64655f4c696c6c653121301f060355040b13186d617877656c6c2e706173746575722d6c696c6c652e6672311630140603550403130d506173746575722d EAP-Message = 0x6c696c6c653126302406092a Message-Authenticator = 0x00000000000000000000000000000000 State = 0x5d0df6005c09efd992b197fe0a3305b3 Finished request 1. Going to the next request Waking up in 4.9 seconds. rad_recv: Access-Request packet from host 172.20.100.53 port 1645, id=54, length=178 User-Name = "bp3" Framed-MTU = 1400 Called-Station-Id = "0014.1bb6.4be0" Calling-Station-Id = "844b.f5b8.d423" Cisco-AVPair = "ssid=ipl_dsi" Service-Type = Login-User Message-Authenticator = 0xe4da8ee89142ba06f191d43d67b6d5d6 EAP-Message = 0x020400061900 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "766" NAS-Port = 766 State = 0x5d0df6005c09efd992b197fe0a3305b3 NAS-IP-Address = 172.20.100.53 NAS-Identifier = "net-ap-A1-1-53" # Executing section authorize from file /usr/local/etc/raddb/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok ++[chap] returns noop ++[mschap] returns noop ++[digest] returns noop [suffix] No '@' in User-Name = "bp3", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop [eap] EAP packet type response id 4 length 6 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /usr/local/etc/raddb/sites-enabled/default +- entering group authenticate {...} [eap] Request found, released from the list [eap] EAP/peap [eap] processing type peap [peap] processing EAP-TLS [peap] Received TLS ACK [peap] ACK handshake fragment handler [peap] eaptls_verify returned 1 [peap] eaptls_process returned 13 [peap] EAPTLS_HANDLED ++[eap] returns handled Sending Access-Challenge of id 54 to 172.20.100.53 port 1645 EAP-Message = 0x010503531900864886f70d0109011617706f756c657440706173746575722d6c696c6c652e6672301e170d3037303531383136323530355a170d3039303531373136323530355a3081b3310b3009060355040613024652310d300b060355040813044e6f7264310e300c060355040713054c696c6c6531223020060355040a1419496e7374697475745f506173746575725f64655f4c696c6c653121301f060355040b13186d617877656c6c2e706173746575722d6c696c6c652e6672311630140603550403130d506173746575722d6c696c6c653126302406092a864886f70d0109011617706f756c657440706173746575722d6c696c6c652e6672 EAP-Message = 0x30819f300d06092a864886f70d010101050003818d0030818902818100acec13f623bd1fef319440b6bae4d5ab83555797e2a452677db05c007d9cf9a05a6cb934d22d8c78a7abad11005b24bed0b046103bf95951a9598ad19d9a3bf76ab24e49661b06d62d4972086aa864a72174d6e647e7ff55b7296acb8ceecc0d0525630edbbf7283fa73812d2e2bf84396835004a50db665cc286c32cfe8f0710203010001a382011430820110301d0603551d0e04160414a644d091476bf920160deaefd598519ca037f7b43081e00603551d230481d83081d58014a644d091476bf920160deaefd598519ca037f7b4a181b9a481b63081b3310b3009060355 EAP-Message = 0x040613024652310d300b060355040813044e6f7264310e300c060355040713054c696c6c6531223020060355040a1419496e7374697475745f506173746575725f64655f4c696c6c653121301f060355040b13186d617877656c6c2e706173746575722d6c696c6c652e6672311630140603550403130d506173746575722d6c696c6c653126302406092a864886f70d0109011617706f756c657440706173746575722d6c696c6c652e6672820100300c0603551d13040530030101ff300d06092a864886f70d01010405000381810035284dce9ba4ce6dafaf397fd6360ec8f0977bfa6ca69c3a047e773f1e34a1f4be74c2d84c0739c656dcfa908f EAP-Message = 0xb6339e7db4bf29e726e62c6a1248d4499676238d797fa0221dac93cc3a0bb16137dd3464cd8efd3e8cc02013755a3f1d01c6108c1609ed3fd80eed2ad3cdaccb074f796a37d3396cf68aafd08a8cdd446ea81c16030100040e000000 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x5d0df6005f08efd992b197fe0a3305b3 Finished request 2. Going to the next request Waking up in 4.9 seconds. rad_recv: Access-Request packet from host 172.20.100.53 port 1645, id=55, length=380 User-Name = "bp3" Framed-MTU = 1400 Called-Station-Id = "0014.1bb6.4be0" Calling-Station-Id = "844b.f5b8.d423" Cisco-AVPair = "ssid=ipl_dsi" Service-Type = Login-User Message-Authenticator = 0x363c88d81d36200aee279a85cd29a0f7 EAP-Message = 0x020500d01980000000c616030100861000008200802bb710cd435bb244e719571aad73ae7dc9adb196f3091764ed9b8c831d431af3fa52fc33d4edbf43fd0a6afb624bfc185da52157ab13896520b28584acf052e656f557d2e1320f3d478a638af1f266a0019241cbf963073efeaa44889ee04b47de9234fb2fb11fa450f7a7064a3f369df717e22102cc96368bf78a248b34301514030100010116030100302ac5d38fa9168176ea2934166b5eb747063e89de89f47f56957bbab13d96c6c7409e6bc78675e7b6daa778b35391d57a NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "766" NAS-Port = 766 State = 0x5d0df6005f08efd992b197fe0a3305b3 NAS-IP-Address = 172.20.100.53 NAS-Identifier = "net-ap-A1-1-53" # Executing section authorize from file /usr/local/etc/raddb/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok ++[chap] returns noop ++[mschap] returns noop ++[digest] returns noop [suffix] No '@' in User-Name = "bp3", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop [eap] EAP packet type response id 5 length 208 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /usr/local/etc/raddb/sites-enabled/default +- entering group authenticate {...} [eap] Request found, released from the list [eap] EAP/peap [eap] processing type peap [peap] processing EAP-TLS TLS Length 198 [peap] Length Included [peap] eaptls_verify returned 11 [peap] <<< TLS 1.0 Handshake [length 0086], ClientKeyExchange [peap] TLS_accept: SSLv3 read client key exchange A [peap] <<< TLS 1.0 ChangeCipherSpec [length 0001] [peap] <<< TLS 1.0 Handshake [length 0010], Finished [peap] TLS_accept: SSLv3 read finished A [peap] >>> TLS 1.0 ChangeCipherSpec [length 0001] [peap] TLS_accept: SSLv3 write change cipher spec A [peap] >>> TLS 1.0 Handshake [length 0010], Finished [peap] TLS_accept: SSLv3 write finished A [peap] TLS_accept: SSLv3 flush data [peap] (other): SSL negotiation finished successfully SSL Connection Established [peap] eaptls_process returned 13 [peap] EAPTLS_HANDLED ++[eap] returns handled Sending Access-Challenge of id 55 to 172.20.100.53 port 1645 EAP-Message = 0x01060041190014030100010116030100304ea7885a78c4a44e77c49aa00f0cf0c1bd8c9433a85e049ce363d0739a3c31246b0964a2d3c14fe878ebfbc601c69899 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x5d0df6005e0befd992b197fe0a3305b3 Finished request 3. Going to the next request Waking up in 4.9 seconds. rad_recv: Access-Request packet from host 172.20.100.53 port 1645, id=56, length=178 User-Name = "bp3" Framed-MTU = 1400 Called-Station-Id = "0014.1bb6.4be0" Calling-Station-Id = "844b.f5b8.d423" Cisco-AVPair = "ssid=ipl_dsi" Service-Type = Login-User Message-Authenticator = 0xd176f3c2fcf37c2b129fbf72ac15f4e3 EAP-Message = 0x020600061900 NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "766" NAS-Port = 766 State = 0x5d0df6005e0befd992b197fe0a3305b3 NAS-IP-Address = 172.20.100.53 NAS-Identifier = "net-ap-A1-1-53" # Executing section authorize from file /usr/local/etc/raddb/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok ++[chap] returns noop ++[mschap] returns noop ++[digest] returns noop [suffix] No '@' in User-Name = "bp3", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop [eap] EAP packet type response id 6 length 6 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /usr/local/etc/raddb/sites-enabled/default +- entering group authenticate {...} [eap] Request found, released from the list [eap] EAP/peap [eap] processing type peap [peap] processing EAP-TLS [peap] Received TLS ACK [peap] ACK handshake is finished [peap] eaptls_verify returned 3 [peap] eaptls_process returned 3 [peap] EAPTLS_SUCCESS [peap] Session established. Decoding tunneled attributes. [peap] Peap state TUNNEL ESTABLISHED ++[eap] returns handled Sending Access-Challenge of id 56 to 172.20.100.53 port 1645 EAP-Message = 0x0107002b19001703010020d73ee234564312f0aac58cb5d36bf9f2ce8bf7b0e67f47481a76b66ba323f79c Message-Authenticator = 0x00000000000000000000000000000000 State = 0x5d0df600590aefd992b197fe0a3305b3 Finished request 4. Going to the next request Waking up in 4.9 seconds. rad_recv: Access-Request packet from host 172.20.100.53 port 1645, id=57, length=215 User-Name = "bp3" Framed-MTU = 1400 Called-Station-Id = "0014.1bb6.4be0" Calling-Station-Id = "844b.f5b8.d423" Cisco-AVPair = "ssid=ipl_dsi" Service-Type = Login-User Message-Authenticator = 0x63a3b90b0132832b0dfa47177f22bffc EAP-Message = 0x0207002b19001703010020d060e0b23e11b4cb2312d853ec239ceb35ce3c520ec432457e48de8547a54f4b NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "766" NAS-Port = 766 State = 0x5d0df600590aefd992b197fe0a3305b3 NAS-IP-Address = 172.20.100.53 NAS-Identifier = "net-ap-A1-1-53" # Executing section authorize from file /usr/local/etc/raddb/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok ++[chap] returns noop ++[mschap] returns noop ++[digest] returns noop [suffix] No '@' in User-Name = "bp3", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop [eap] EAP packet type response id 7 length 43 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /usr/local/etc/raddb/sites-enabled/default +- entering group authenticate {...} [eap] Request found, released from the list [eap] EAP/peap [eap] processing type peap [peap] processing EAP-TLS [peap] eaptls_verify returned 7 [peap] Done initial handshake [peap] eaptls_process returned 7 [peap] EAPTLS_OK [peap] Session established. Decoding tunneled attributes. [peap] Peap state WAITING FOR INNER IDENTITY [peap] Identity - bp3 [peap] Got inner identity 'bp3' [peap] Setting default EAP type for tunneled EAP session. [peap] Got tunneled request EAP-Message = 0x0207000801627033 server { [peap] Setting User-Name to bp3 Sending tunneled request EAP-Message = 0x0207000801627033 FreeRADIUS-Proxied-To = 127.0.0.1 User-Name = "bp3" Framed-MTU = 1400 Called-Station-Id = "0014.1bb6.4be0" Calling-Station-Id = "844b.f5b8.d423" Cisco-AVPair = "ssid=ipl_dsi" Service-Type = Login-User NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "766" NAS-Port = 766 NAS-IP-Address = 172.20.100.53 NAS-Identifier = "net-ap-A1-1-53" server inner-tunnel { # Executing section authorize from file /usr/local/etc/raddb/sites-enabled/inner-tunnel +- entering group authorize {...} ++[chap] returns noop ++[mschap] returns noop [suffix] No '@' in User-Name = "bp3", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop ++[control] returns noop [eap] EAP packet type response id 7 length 8 [eap] No EAP Start, assuming it's an on-going EAP conversation ++[eap] returns updated ++[files] returns noop <<<=== FIRST DIFFERENCE ++[expiration] returns noop ++[logintime] returns noop ++[pap] returns noop Found Auth-Type = EAP # Executing group from file /usr/local/etc/raddb/sites-enabled/inner-tunnel +- entering group authenticate {...} [eap] EAP Identity [eap] processing type mschapv2 rlm_eap_mschapv2: Issuing Challenge ++[eap] returns handled } # server inner-tunnel [peap] Got tunneled reply code 11 EAP-Message = 0x0108001d1a0108001810d2e75f8c502dc884626f434900ca627d627033 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x7eccccc17ec4d6654d9cd951e2161559 [peap] Got tunneled reply RADIUS code 11 EAP-Message = 0x0108001d1a0108001810d2e75f8c502dc884626f434900ca627d627033 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x7eccccc17ec4d6654d9cd951e2161559 [peap] Got tunneled Access-Challenge ++[eap] returns handled Sending Access-Challenge of id 57 to 172.20.100.53 port 1645 EAP-Message = 0x0108003b190017030100304632ad3a92925ae22d91713494cf597ae8c285803f64e9836162622c1448dc06806127143b63496eb4949e1d09c3bc4d Message-Authenticator = 0x00000000000000000000000000000000 State = 0x5d0df6005805efd992b197fe0a3305b3 Finished request 5. Going to the next request Waking up in 4.9 seconds. rad_recv: Access-Request packet from host 172.20.100.53 port 1645, id=58, length=263 User-Name = "bp3" Framed-MTU = 1400 Called-Station-Id = "0014.1bb6.4be0" Calling-Station-Id = "844b.f5b8.d423" Cisco-AVPair = "ssid=ipl_dsi" Service-Type = Login-User Message-Authenticator = 0xf68e507bb1d7ba85220eea1efd3b8860 EAP-Message = 0x0208005b190017030100509143a1dc15faf890dbaad0062fe688eb49c5e68a662a512030cef139c6a9df2cecfbacfba0e8055cf05b332e4fe29a603547c3f079b70a5f7e3312201dce872cab36dca8a39681feec4e099f1b77b8ab NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "766" NAS-Port = 766 State = 0x5d0df6005805efd992b197fe0a3305b3 NAS-IP-Address = 172.20.100.53 NAS-Identifier = "net-ap-A1-1-53" # Executing section authorize from file /usr/local/etc/raddb/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok ++[chap] returns noop ++[mschap] returns noop ++[digest] returns noop [suffix] No '@' in User-Name = "bp3", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop [eap] EAP packet type response id 8 length 91 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /usr/local/etc/raddb/sites-enabled/default +- entering group authenticate {...} [eap] Request found, released from the list [eap] EAP/peap [eap] processing type peap [peap] processing EAP-TLS [peap] eaptls_verify returned 7 [peap] Done initial handshake [peap] eaptls_process returned 7 [peap] EAPTLS_OK [peap] Session established. Decoding tunneled attributes. [peap] Peap state phase2 [peap] EAP type mschapv2 [peap] Got tunneled request EAP-Message = 0x0208003e1a020800393144f9bd9926ec2d6fe43c53ef12569056000000000000000067640068a77a256b9fa458ea1e5164714e53b657b522e39d00627033 server { [peap] Setting User-Name to bp3 Sending tunneled request EAP-Message = 0x0208003e1a020800393144f9bd9926ec2d6fe43c53ef12569056000000000000000067640068a77a256b9fa458ea1e5164714e53b657b522e39d00627033 FreeRADIUS-Proxied-To = 127.0.0.1 User-Name = "bp3" State = 0x7eccccc17ec4d6654d9cd951e2161559 Framed-MTU = 1400 Called-Station-Id = "0014.1bb6.4be0" Calling-Station-Id = "844b.f5b8.d423" Cisco-AVPair = "ssid=ipl_dsi" Service-Type = Login-User NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "766" NAS-Port = 766 NAS-IP-Address = 172.20.100.53 NAS-Identifier = "net-ap-A1-1-53" server inner-tunnel { # Executing section authorize from file /usr/local/etc/raddb/sites-enabled/inner-tunnel +- entering group authorize {...} ++[chap] returns noop ++[mschap] returns noop [suffix] No '@' in User-Name = "bp3", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop ++[control] returns noop [eap] EAP packet type response id 8 length 62 [eap] No EAP Start, assuming it's an on-going EAP conversation ++[eap] returns updated ++[files] returns noop ++[expiration] returns noop ++[logintime] returns noop ++[pap] returns noop Found Auth-Type = EAP # Executing group from file /usr/local/etc/raddb/sites-enabled/inner-tunnel +- entering group authenticate {...} [eap] Request found, released from the list [eap] EAP/mschapv2 [eap] processing type mschapv2 [mschapv2] # Executing group from file /usr/local/etc/raddb/sites-enabled/inner-tunnel [mschapv2] +- entering group MS-CHAP {...} [mschap] No Cleartext-Password configured. Cannot create LM-Password. [mschap] No Cleartext-Password configured. Cannot create NT-Password. [mschap] Creating challenge hash with username: bp3 [mschap] Client is using MS-CHAPv2 for bp3, we need NT-Password [mschap] FAILED: No NT/LM-Password. Cannot perform authentication. [mschap] FAILED: MS-CHAP2-Response is incorrect ++[mschap] returns reject [eap] Freeing handler ++[eap] returns reject Failed to authenticate the user. } # server inner-tunnel [peap] Got tunneled reply code 3 MS-CHAP-Error = "\010E=691 R=1" EAP-Message = 0x04080004 Message-Authenticator = 0x00000000000000000000000000000000 [peap] Got tunneled reply RADIUS code 3 MS-CHAP-Error = "\010E=691 R=1" EAP-Message = 0x04080004 Message-Authenticator = 0x00000000000000000000000000000000 [peap] Tunneled authentication was rejected. [peap] FAILURE ++[eap] returns handled Sending Access-Challenge of id 58 to 172.20.100.53 port 1645 EAP-Message = 0x0109002b190017030100208ae32fccfa52f34b8f7ac8e930fddff5e17154492963a564bf2de64107eaed35 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x5d0df6005b04efd992b197fe0a3305b3 Finished request 6. Going to the next request Waking up in 4.9 seconds. rad_recv: Access-Request packet from host 172.20.100.53 port 1645, id=59, length=215 User-Name = "bp3" Framed-MTU = 1400 Called-Station-Id = "0014.1bb6.4be0" Calling-Station-Id = "844b.f5b8.d423" Cisco-AVPair = "ssid=ipl_dsi" Service-Type = Login-User Message-Authenticator = 0x926a420b295742cbecd275130c10953b EAP-Message = 0x0209002b1900170301002081e0b9e52fc870ee671da2029ee88b22106c1aa6e6200820a858b01e7fb2130b NAS-Port-Type = Wireless-802.11 Cisco-NAS-Port = "766" NAS-Port = 766 State = 0x5d0df6005b04efd992b197fe0a3305b3 NAS-IP-Address = 172.20.100.53 NAS-Identifier = "net-ap-A1-1-53" # Executing section authorize from file /usr/local/etc/raddb/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok ++[chap] returns noop ++[mschap] returns noop ++[digest] returns noop [suffix] No '@' in User-Name = "bp3", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop [eap] EAP packet type response id 9 length 43 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /usr/local/etc/raddb/sites-enabled/default +- entering group authenticate {...} [eap] Request found, released from the list [eap] EAP/peap [eap] processing type peap [peap] processing EAP-TLS [peap] eaptls_verify returned 7 [peap] Done initial handshake [peap] eaptls_process returned 7 [peap] EAPTLS_OK [peap] Session established. Decoding tunneled attributes. [peap] Peap state send tlv failure [peap] Received EAP-TLV response. [peap] The users session was previously rejected: returning reject (again.) [peap] *** This means you need to read the PREVIOUS messages in the debug output [peap] *** to find out the reason why the user was rejected. [peap] *** Look for "reject" or "fail". Those earlier messages will tell you. [peap] *** what went wrong, and how to fix the problem. [eap] Handler failed in EAP/peap [eap] Failed in EAP select ++[eap] returns invalid Failed to authenticate the user. Using Post-Auth-Type REJECT # Executing group from file /usr/local/etc/raddb/sites-enabled/default +- entering group REJECT {...} [attr_filter.access_reject] expand: %{User-Name} -> bp3 attr_filter: Matched entry DEFAULT at line 11 ++[attr_filter.access_reject] returns updated Delaying reject of request 7 for 1 seconds Going to the next request Waking up in 0.9 seconds. Sending delayed reject for request 7 Sending Access-Reject of id 59 to 172.20.100.53 port 1645 EAP-Message = 0x04090004 Message-Authenticator = 0x00000000000000000000000000000000 Waking up in 3.9 seconds. Cleaning up request 0 ID 52 with timestamp +19 Cleaning up request 1 ID 53 with timestamp +19 Cleaning up request 2 ID 54 with timestamp +19 Cleaning up request 3 ID 55 with timestamp +19 Cleaning up request 4 ID 56 with timestamp +19 Cleaning up request 5 ID 57 with timestamp +19 Cleaning up request 6 ID 58 with timestamp +19 Waking up in 1.0 seconds. Cleaning up request 7 ID 59 with timestamp +19 Ready to process requests.