FreeRADIUS Version 2.1.12, for host x86_64-pc-linux-gnu, built on Feb 24 2014 at 14:57:57 Copyright (C) 1999-2009 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 /etc/freeradius/radiusd.conf including configuration file /etc/freeradius/proxy.conf including configuration file /etc/freeradius/clients.conf including files in directory /etc/freeradius/modules/ including configuration file /etc/freeradius/modules/smsotp including configuration file /etc/freeradius/modules/replicate including configuration file /etc/freeradius/modules/radutmp including configuration file /etc/freeradius/modules/attr_filter including configuration file /etc/freeradius/modules/echo including configuration file /etc/freeradius/modules/mschap including configuration file /etc/freeradius/modules/soh including configuration file /etc/freeradius/modules/detail including configuration file /etc/freeradius/modules/checkval including configuration file /etc/freeradius/modules/ldap including configuration file /etc/freeradius/modules/cui including configuration file /etc/freeradius/modules/always including configuration file /etc/freeradius/modules/etc_group including configuration file /etc/freeradius/modules/expiration including configuration file /etc/freeradius/modules/realm including configuration file /etc/freeradius/modules/perl including configuration file /etc/freeradius/modules/expr including configuration file /etc/freeradius/modules/sql_log including configuration file /etc/freeradius/modules/sradutmp including configuration file /etc/freeradius/modules/ippool including configuration file /etc/freeradius/modules/inner-eap including configuration file /etc/freeradius/modules/krb5 including configuration file /etc/freeradius/modules/smbpasswd including configuration file /etc/freeradius/modules/pap including configuration file /etc/freeradius/modules/counter including configuration file /etc/freeradius/modules/wimax including configuration file /etc/freeradius/modules/detail.example.com including configuration file /etc/freeradius/modules/files including configuration file /etc/freeradius/modules/redis including configuration file /etc/freeradius/modules/acct_unique including configuration file /etc/freeradius/modules/digest including configuration file /etc/freeradius/modules/passwd including configuration file /etc/freeradius/modules/exec including configuration file /etc/freeradius/modules/linelog including configuration file /etc/freeradius/modules/logintime including configuration file /etc/freeradius/modules/detail.log including configuration file /etc/freeradius/modules/rediswho including configuration file /etc/freeradius/modules/dynamic_clients including configuration file /etc/freeradius/modules/mac2vlan including configuration file /etc/freeradius/modules/mac2ip including configuration file /etc/freeradius/modules/ntlm_auth including configuration file /etc/freeradius/modules/chap including configuration file /etc/freeradius/modules/policy including configuration file /etc/freeradius/modules/otp including configuration file /etc/freeradius/modules/attr_rewrite including configuration file /etc/freeradius/modules/opendirectory including configuration file /etc/freeradius/modules/sqlcounter_expire_on_login including configuration file /etc/freeradius/modules/preprocess including configuration file /etc/freeradius/modules/pam including configuration file /etc/freeradius/modules/unix including configuration file /etc/freeradius/eap.conf including configuration file /etc/freeradius/policy.conf including files in directory /etc/freeradius/sites-enabled/ including configuration file /etc/freeradius/sites-enabled/default including configuration file /etc/freeradius/sites-enabled/inner-tunnel main { user = "freerad" group = "freerad" allow_core_dumps = no } including dictionary file /etc/freeradius/dictionary main { name = "freeradius" prefix = "/usr" localstatedir = "/var" sbindir = "/usr/sbin" logdir = "/var/log/freeradius" run_dir = "/var/run/freeradius" libdir = "/usr/lib/freeradius" radacctdir = "/var/log/freeradius/radacct" hostname_lookups = no max_request_time = 30 cleanup_delay = 5 max_requests = 1024 pidfile = "/var/run/freeradius/freeradius.pid" checkrad = "/usr/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 = "WLAN4zyxel" nastype = "other" } client 10.160.199.11 { require_message_authenticator = no secret = "WLAN4zyxel" } client 10.160.4.5 { require_message_authenticator = no secret = "testing123" } radiusd: #### Instantiating modules #### instantiate { Module: Linked to module rlm_exec Module: Instantiating module "exec" from file /etc/freeradius/modules/exec exec { wait = no input_pairs = "request" shell_escape = yes } Module: Linked to module rlm_expr Module: Instantiating module "expr" from file /etc/freeradius/modules/expr Module: Linked to module rlm_expiration Module: Instantiating module "expiration" from file /etc/freeradius/modules/expiration expiration { reply-message = "Password Has Expired " } Module: Linked to module rlm_logintime Module: Instantiating module "logintime" from file /etc/freeradius/modules/logintime logintime { reply-message = "You are calling outside your allowed timespan " minimum-timeout = 60 } } radiusd: #### Loading Virtual Servers #### server { # from file /etc/freeradius/radiusd.conf modules { Module: Creating Auth-Type = Perl Module: Creating Auth-Type = digest Module: Creating Post-Auth-Type = Perl Module: Creating Post-Auth-Type = REJECT Module: Checking authenticate {...} for more modules to load Module: Linked to module rlm_perl Module: Instantiating module "perl" from file /etc/freeradius/modules/perl perl { module = "/etc/freeradius/tiri_rlm_perl.pl" func_authorize = "authorize" func_authenticate = "authenticate" func_accounting = "accounting" func_preacct = "preacct" func_checksimul = "checksimul" func_detach = "detach" func_xlat = "xlat" func_pre_proxy = "pre_proxy" func_post_proxy = "post_proxy" func_post_auth = "post_auth" func_recv_coa = "recv_coa" func_send_coa = "send_coa" } Module: Linked to module rlm_pap Module: Instantiating module "pap" from file /etc/freeradius/modules/pap pap { encryption_scheme = "auto" auto_header = yes } Module: Linked to module rlm_chap Module: Instantiating module "chap" from file /etc/freeradius/modules/chap Module: Linked to module rlm_mschap Module: Instantiating module "mschap" from file /etc/freeradius/modules/mschap mschap { use_mppe = yes require_encryption = no require_strong = no with_ntdomain_hack = no ntlm_auth = "/usr/bin/ntlm_auth --request-nt-key --username=%{%{Stripped-User-Name}:-%{User-Name:-None}} --domain=%{%{mschap:NT-Domain}:-SWBT} --challenge=%{mschap:Challenge:-00} --nt-response=%{mschap:NT-Response:-00}" allow_retry = yes } Module: Linked to module rlm_digest Module: Instantiating module "digest" from file /etc/freeradius/modules/digest Module: Linked to module rlm_unix Module: Instantiating module "unix" from file /etc/freeradius/modules/unix unix { radwtmp = "/var/log/freeradius/radwtmp" } Module: Linked to module rlm_eap Module: Instantiating module "eap" from file /etc/freeradius/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 = "/etc/freeradius/certs" pem_file_type = yes private_key_file = "/etc/freeradius/certs/server.key" certificate_file = "/etc/freeradius/certs/server.pem" CA_file = "/etc/freeradius/certs/ca.pem" private_key_password = "WLAN4hotspot" dh_file = "/etc/freeradius/certs/dh" random_file = "/dev/urandom" fragment_size = 1024 include_length = yes check_crl = no cipher_list = "DEFAULT" make_cert_command = "/etc/freeradius/certs/bootstrap" ecdh_curve = "prime256v1" cache { enable = no lifetime = 24 max_entries = 255 } verify { tmpdir = "/tmp/freeradius" client = "/usr/bin/openssl verify -CAfile /etc/freeradius/certs/ca.pem -CApath /etc/freeradius/certs %{TLS-Client-Cert-Filename}" } ocsp { enable = no override_cert_url = yes url = "http://127.0.0.1/ocsp/" } } Module: Linked to sub-module rlm_eap_ttls Module: Instantiating eap-ttls ttls { default_eap_type = "md5" copy_request_to_tunnel = yes 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 /etc/freeradius/modules/preprocess preprocess { huntgroups = "/etc/freeradius/huntgroups" hints = "/etc/freeradius/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 } Module: Linked to module rlm_detail Module: Instantiating module "auth_log" from file /etc/freeradius/modules/detail.log detail auth_log { detailfile = "/var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d" header = "%t" detailperm = 384 dirperm = 493 locking = no log_packet_header = no } Module: Linked to module rlm_realm Module: Instantiating module "suffix" from file /etc/freeradius/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 /etc/freeradius/modules/files files { usersfile = "/etc/freeradius/users" acctusersfile = "/etc/freeradius/acct_users" preproxy_usersfile = "/etc/freeradius/preproxy_users" compat = "no" } Module: Checking preacct {...} for more modules to load Module: Linked to module rlm_acct_unique Module: Instantiating module "acct_unique" from file /etc/freeradius/modules/acct_unique acct_unique { key = "User-Name, Acct-Session-Id, NAS-IP-Address, Client-IP-Address, NAS-Port" } Module: Checking accounting {...} for more modules to load Module: Instantiating module "detail" from file /etc/freeradius/modules/detail detail { detailfile = "/var/log/freeradius/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_radutmp Module: Instantiating module "radutmp" from file /etc/freeradius/modules/radutmp radutmp { filename = "/var/log/freeradius/radutmp" username = "%{User-Name}" case_sensitive = yes check_with_nas = yes perm = 384 callerid = yes } Module: Linked to module rlm_attr_filter Module: Instantiating module "attr_filter.accounting_response" from file /etc/freeradius/modules/attr_filter attr_filter attr_filter.accounting_response { attrsfile = "/etc/freeradius/attrs.accounting_response" key = "%{User-Name}" relaxed = no } 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 Module: Instantiating module "attr_filter.access_reject" from file /etc/freeradius/modules/attr_filter attr_filter attr_filter.access_reject { attrsfile = "/etc/freeradius/attrs.access_reject" key = "%{User-Name}" relaxed = no } } # modules } # server server inner-tunnel { # from file /etc/freeradius/sites-enabled/inner-tunnel modules { Module: Creating Post-Proxy-Type = Fail 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 = "auth" ipaddr = 127.0.0.1 port = 18120 } ... adding new socket proxy address * port 52056 Listening on authentication address * port 1812 Listening on accounting address * port 1813 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 10.160.4.5 port 9838, id=0, length=129 User-Name = "wlan_test" NAS-IP-Address = 127.0.0.1 Calling-Station-Id = "70-6F-6C-69-73-68" Framed-MTU = 1400 NAS-Port-Type = Wireless-802.11 Connect-Info = "rad_eap_test + eapol_test" EAP-Message = 0x0200000e01776c616e5f74657374 Message-Authenticator = 0x68a0df39ac5366537fc59c9e6bbca296 # Executing section authorize from file /etc/freeradius/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok [auth_log] expand: /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] expand: %t -> Sun Jul 26 15:37:46 2015 ++[auth_log] returns ok ++[chap] returns noop ++[mschap] returns noop ++[digest] returns noop [suffix] No '@' in User-Name = "wlan_test", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop [eap] EAP packet type response id 0 length 14 [eap] No EAP Start, assuming it's an on-going EAP conversation ++[eap] returns updated ++[files] returns noop rlm_perl: RAD_REQUEST: Connect-Info = rad_eap_test + eapol_test rlm_perl: RAD_REQUEST: EAP-Message = 0x0200000e01776c616e5f74657374 rlm_perl: RAD_REQUEST: Calling-Station-Id = 70-6F-6C-69-73-68 rlm_perl: RAD_REQUEST: NAS-IP-Address = 127.0.0.1 rlm_perl: RAD_REQUEST: NAS-Port-Type = Wireless-802.11 rlm_perl: RAD_REQUEST: EAP-Type = Identity rlm_perl: RAD_REQUEST: Framed-MTU = 1400 rlm_perl: RAD_REQUEST: Message-Authenticator = 0x68a0df39ac5366537fc59c9e6bbca296 rlm_perl: RAD_REQUEST: User-Name = wlan_test rlm_perl: Added pair Connect-Info = rad_eap_test + eapol_test rlm_perl: Added pair EAP-Message = 0x0200000e01776c616e5f74657374 rlm_perl: Added pair Calling-Station-Id = 70-6F-6C-69-73-68 rlm_perl: Added pair NAS-IP-Address = 127.0.0.1 rlm_perl: Added pair NAS-Port-Type = Wireless-802.11 rlm_perl: Added pair EAP-Type = Identity rlm_perl: Added pair Framed-MTU = 1400 rlm_perl: Added pair Message-Authenticator = 0x68a0df39ac5366537fc59c9e6bbca296 rlm_perl: Added pair User-Name = wlan_test rlm_perl: Added pair Reply-Message = rlm_perl authorize function rlm_perl: Added pair Auth-Type = EAP ++[perl] returns ok ++? if (!EAP-Message) ? Evaluating !(EAP-Message) -> FALSE ++? if (!EAP-Message) -> FALSE ++[expiration] returns noop ++[logintime] returns noop [pap] WARNING! No "known good" password found for the user. Authentication may fail because of this. ++[pap] returns noop Found Auth-Type = EAP # Executing group from file /etc/freeradius/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 0 to 10.160.4.5 port 9838 Reply-Message = "rlm_perl authorize function" EAP-Message = 0x010100061920 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x8609790d86086042e7955f855d8bcd86 Finished request 0. Going to the next request Waking up in 4.9 seconds. rad_recv: Access-Request packet from host 10.160.4.5 port 9838, id=1, length=226 User-Name = "wlan_test" NAS-IP-Address = 127.0.0.1 Calling-Station-Id = "70-6F-6C-69-73-68" Framed-MTU = 1400 NAS-Port-Type = Wireless-802.11 Connect-Info = "rad_eap_test + eapol_test" EAP-Message = 0x0201005d190016030100520100004e030155b4e276ad0efd1c187a0e861d42a34aef1dba3239ad95e97a33298fc6dd81f400002600390038003500160013000a00330032002f0005000400150012000900140011000800060003020100 State = 0x8609790d86086042e7955f855d8bcd86 Message-Authenticator = 0x96b85e162197cfef2a7c990bb0df6b50 # Executing section authorize from file /etc/freeradius/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok [auth_log] expand: /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] expand: %t -> Sun Jul 26 15:37:46 2015 ++[auth_log] returns ok ++[chap] returns noop ++[mschap] returns noop ++[digest] returns noop [suffix] No '@' in User-Name = "wlan_test", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop [eap] EAP packet type response id 1 length 93 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /etc/freeradius/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] (other): before/accept initialization [peap] TLS_accept: before/accept initialization [peap] <<< TLS 1.0 Handshake [length 0052], ClientHello [peap] TLS_accept: SSLv3 read client hello A [peap] >>> TLS 1.0 Handshake [length 002a], ServerHello [peap] TLS_accept: SSLv3 write server hello A [peap] >>> TLS 1.0 Handshake [length 080a], Certificate [peap] TLS_accept: SSLv3 write certificate A [peap] >>> TLS 1.0 Handshake [length 020d], ServerKeyExchange [peap] TLS_accept: SSLv3 write key exchange 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 1 to 10.160.4.5 port 9838 EAP-Message = 0x0102040019c000000a59160301002a020000260301a9f2b4f41a8eb272fa5491ed4fa580a3eee88396551f682931f3d27b223f55e900003900160301080a0b0008060008030003913082038d30820275a003020102020101300d06092a864886f70d0101040500307f310b30090603550406130244453110300e0603550408130748616d62757267311530130603550407130c5363687761727a656e62656b31123010060355040a13097469726920476d62483121301f06092a864886f70d0109011612636140686f7473706f742e746972692e6c693110300e0603550403130774697269204341301e170d3135303732353139313334395a170d3136 EAP-Message = 0x303732343139313334395a307c310b30090603550406130244453110300e0603550408130748616d6275726731123010060355040a13097469726920476d62483120301e0603550403131774697269205261646975732043657274696669636174653125302306092a864886f70d010901161672616469757340686f7473706f742e746972692e6c6930820122300d06092a864886f70d01010105000382010f003082010a0282010100c1d137622cec8d1f14603614804ea4cac9bde4092f508745a39ab61f7703bc2f6f97e22e59d276ec589c64abdb53302e95dd685886010b9d2fe50c47aea977dde5695375773ccccd1386a77286637e34e5bcb4 EAP-Message = 0x867fb45a59480052075c1ecaa3729784a201f755777af2687e2e968104d037a6c425f6bdf60ac8cfe12adc6d4566df042de359e2bf89a6620e151198651e0231f01acbdc4be27f7b5ff753ff00ffb0107bbeab65635170bb85683d3d0808f72da5a7d81b1ff01b3a1d0c939ab9aab4019524603a3b8c6f9bca0b8a10781369c9b509727ab93d7e327462828ec970ae02c2faec4ea31dbd0fe38fce7176219c5c5a342491cd488559654911fa8b0203010001a317301530130603551d25040c300a06082b06010505070301300d06092a864886f70d010104050003820101001349bf30e7bc3b1efca2070b746e268898bcc0735350b58f3463e4f8e7a8 EAP-Message = 0xe90998ceb0b1681c163e10742b8971b0179ee0ef7b5ca7b113743125d2653bd36700851af43ddc1fc4940ed334760f691d3ea2641155e38f7c4f5e334f76cdfe688f032dfff24af341d04c85c2abe62156003d2c44bfbe276d52504514e3843b7db0eb1c745ebedf3207ff5f7e49afeb3a8522ddc9769874b62bcef98ee674d66a3c242de9977944f70c08238666fecaecf6f2807e9e7d5df815334091fe52050502e44fe1b62c32c6704be94426285aeb09ef08936386a4e0e45f5db4d675430b3ba545b1f022b1905b36982abc0a8bf788b8e9fa95890614e251f445a44c80102d00046c3082046830820350a0030201020209009d2885275ceebbc8 EAP-Message = 0x300d06092a864886f70d0101 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x8609790d870b6042e7955f855d8bcd86 Finished request 1. Going to the next request Waking up in 4.9 seconds. rad_recv: Access-Request packet from host 10.160.4.5 port 9838, id=2, length=139 User-Name = "wlan_test" NAS-IP-Address = 127.0.0.1 Calling-Station-Id = "70-6F-6C-69-73-68" Framed-MTU = 1400 NAS-Port-Type = Wireless-802.11 Connect-Info = "rad_eap_test + eapol_test" EAP-Message = 0x020200061900 State = 0x8609790d870b6042e7955f855d8bcd86 Message-Authenticator = 0xa156f7878774ab34ecc1876717b27d0e # Executing section authorize from file /etc/freeradius/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok [auth_log] expand: /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] expand: %t -> Sun Jul 26 15:37:46 2015 ++[auth_log] returns ok ++[chap] returns noop ++[mschap] returns noop ++[digest] returns noop [suffix] No '@' in User-Name = "wlan_test", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop [eap] EAP packet type response id 2 length 6 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /etc/freeradius/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 2 to 10.160.4.5 port 9838 EAP-Message = 0x010303fc19400b0500307f310b30090603550406130244453110300e0603550408130748616d62757267311530130603550407130c5363687761727a656e62656b31123010060355040a13097469726920476d62483121301f06092a864886f70d0109011612636140686f7473706f742e746972692e6c693110300e0603550403130774697269204341301e170d3135303732353139313334395a170d3136303732343139313334395a307f310b30090603550406130244453110300e0603550408130748616d62757267311530130603550407130c5363687761727a656e62656b31123010060355040a13097469726920476d62483121301f06092a EAP-Message = 0x864886f70d0109011612636140686f7473706f742e746972692e6c693110300e060355040313077469726920434130820122300d06092a864886f70d01010105000382010f003082010a028201010098727c23aded345afae75754ea9bb3a7dc7949f26b6944f98233c2e5ed1e5c720c358566aec7ccf61fc4579826efce1537a465eb8e02df124ff28140d4ad19a5573c6d0d67952f2feeccfb42232186c00eb6a0590cd0fac23c3e87351520b77c0e142e0210522a3098a503ab74660d61801d362297d8a81c56214d036d60f11d43819cfdbaa736e3b9f10e350677f5e0cf14e4856388477fa7a2ea1520b6acc495ca29de77a1a390c1ae0a0e5bf6 EAP-Message = 0xc3d6f7af85603bde8a61622d3462c9c2bdba96893cfe72c3f663144458463a201f34cf6d009c03916a4799ae50048aa12039c3b814898a23c9f10537f7d8f5be505e873c3ef5a4441d08f20daf6f17ca81110203010001a381e63081e3301d0603551d0e04160414136c09d813ec30d48c30f591549d1526d14391753081b30603551d230481ab3081a88014136c09d813ec30d48c30f591549d1526d1439175a18184a48181307f310b30090603550406130244453110300e0603550408130748616d62757267311530130603550407130c5363687761727a656e62656b31123010060355040a13097469726920476d62483121301f06092a864886f7 EAP-Message = 0x0d0109011612636140686f7473706f742e746972692e6c693110300e06035504031307746972692043418209009d2885275ceebbc8300c0603551d13040530030101ff300d06092a864886f70d01010b05000382010100555aade04c2ef507a90f7af3483086a8142316aab27f54eb95df32eb9e0ee4fd30c2cf9e30508a85131bdaca42cb5cddcd5ff1793780cb71b9900b1de16b2b658d3933ab25aec085bfae2ced2e024a3b7465367e09af5b3fc032385351710274fe464947ab19784fec492c62ada1bfa2b745b8cbcc7d58f154af7b396f24585f80a848139ce37d3152ed3d07dca656fe9f0acb14fd4374e363da9d4da9b4dec47af9ef98a0f8 EAP-Message = 0x74f85879d1441db5 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x8609790d840a6042e7955f855d8bcd86 Finished request 2. Going to the next request Waking up in 4.9 seconds. rad_recv: Access-Request packet from host 10.160.4.5 port 9838, id=3, length=139 User-Name = "wlan_test" NAS-IP-Address = 127.0.0.1 Calling-Station-Id = "70-6F-6C-69-73-68" Framed-MTU = 1400 NAS-Port-Type = Wireless-802.11 Connect-Info = "rad_eap_test + eapol_test" EAP-Message = 0x020300061900 State = 0x8609790d840a6042e7955f855d8bcd86 Message-Authenticator = 0xc3f41957d22a2112f4e6eea84777d04a # Executing section authorize from file /etc/freeradius/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok [auth_log] expand: /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] expand: %t -> Sun Jul 26 15:37:46 2015 ++[auth_log] returns ok ++[chap] returns noop ++[mschap] returns noop ++[digest] returns noop [suffix] No '@' in User-Name = "wlan_test", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop [eap] EAP packet type response id 3 length 6 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /etc/freeradius/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 3 to 10.160.4.5 port 9838 EAP-Message = 0x01040273190028dd70b5677c5fb00cd4f0e60089c0d76daba78b91d9da9abc6ce42a5f1d3091291e9f7f40838cb4b64c0c868023335b65648bd62f96ce8780ad2e85716a7c51a2b546e4ba2ad7dca2fb817678c0b88c2034160301020d0c0002090080fec9bdc030018ad3d1bce0420bfec7cf29629b1be544a4404663b31cd7c175f53b8076780a983748e76b6d7d93dd225ac2a4b192dbd9bc34f3d41b7616220cfe01060ff4aef79e5780f8f29eb82c7fb33121b14a178a6bd870d63f840ffa4659220762e89ceda1eb7e3f2a28a6314875cca127fc95e93921acf72f42010727c300010200808e1bb25320f069c49052bb41542c6ac5721ab039c0 EAP-Message = 0xc8c4d0d55e5ce56462b071514bcd7336dbff2bf5dfe126680985cccc63c8fa5373ed74bfa3a01b01a501693f1b904eb5f9ed9154740e9482a9c70b2dfd7103f52ebce913314e70105e9a28dc410de2ff59f582f572dc70e237728e89f783958946334c030ba96887b43ad90100c0453a58b87d38e57ce521d301bcd5cb5c61ce70b2798a1d70af8dddcf5e9d97720c1e912122b5eb7a73cf6f7780e41df86a4c20afa08bef0d1f4ece60317d261bc3d996b5fa77fb4416900a30a720b5e775cd8d81889658359746a4691db252bc207f9a5cde05aab7a6bbf4bb6a04c44dbf70a168e27a314db450836eb8d39b554da25edc9b2730ff1db388d54d3467 EAP-Message = 0xd84b91e2376593b99f3f33587fdb418e7d25ea59a21d73034f6d37d945058c790f5142aaf3ccd30a7800f208fe76b9e94142b579cadea1a3f60111fa6bd556fe2b5dfa65acebd395c341c34de3c450de4e24f7d40ce6f91d9d2a34e1a2b11dddfaf9cb68cdebc9746a215a74eb30ad4416030100040e000000 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x8609790d850d6042e7955f855d8bcd86 Finished request 3. Going to the next request Waking up in 4.9 seconds. rad_recv: Access-Request packet from host 10.160.4.5 port 9838, id=4, length=337 User-Name = "wlan_test" NAS-IP-Address = 127.0.0.1 Calling-Station-Id = "70-6F-6C-69-73-68" Framed-MTU = 1400 NAS-Port-Type = Wireless-802.11 Connect-Info = "rad_eap_test + eapol_test" EAP-Message = 0x020400cc19001603010086100000820080c9a33d38d99cc5a6d01e1825c607fdcd95e3a646edb87d9a6e84af2ab33ebc2c088bf7478a76795e2c03174a2111ecb460e5c8ba9980b7cc7f0e7f855f30a950a7ad9fcab4e698f073c988b4402b768344698c087c2c2a2013e72e40e368210e3d50897719b0125811fd2302068cebec49c3d4339180bb600d01c876719a67b714030100010116030100307cd006d6223c65ba7b60393b7e5f44bdf49b5276d818487f0419e4c5baf159775883b510f23d1f44fa281b8ca16171fc State = 0x8609790d850d6042e7955f855d8bcd86 Message-Authenticator = 0x3c25b48dc8aef26d40b032b04ff96da4 # Executing section authorize from file /etc/freeradius/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok [auth_log] expand: /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] expand: %t -> Sun Jul 26 15:37:46 2015 ++[auth_log] returns ok ++[chap] returns noop ++[mschap] returns noop ++[digest] returns noop [suffix] No '@' in User-Name = "wlan_test", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop [eap] EAP packet type response id 4 length 204 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /etc/freeradius/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] <<< 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 4 to 10.160.4.5 port 9838 EAP-Message = 0x01050041190014030100010116030100309880c2dc9ba02051a79508af444ddb0a99998a3b17350e47c08dd7c43e96d68f4d9fdf1a7755b06266792cdb276b8b6d Message-Authenticator = 0x00000000000000000000000000000000 State = 0x8609790d820c6042e7955f855d8bcd86 Finished request 4. Going to the next request Waking up in 4.9 seconds. rad_recv: Access-Request packet from host 10.160.4.5 port 9838, id=5, length=139 User-Name = "wlan_test" NAS-IP-Address = 127.0.0.1 Calling-Station-Id = "70-6F-6C-69-73-68" Framed-MTU = 1400 NAS-Port-Type = Wireless-802.11 Connect-Info = "rad_eap_test + eapol_test" EAP-Message = 0x020500061900 State = 0x8609790d820c6042e7955f855d8bcd86 Message-Authenticator = 0xb2d3d15e0556e3e8b42d247c30d05cb7 # Executing section authorize from file /etc/freeradius/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok [auth_log] expand: /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] expand: %t -> Sun Jul 26 15:37:46 2015 ++[auth_log] returns ok ++[chap] returns noop ++[mschap] returns noop ++[digest] returns noop [suffix] No '@' in User-Name = "wlan_test", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop [eap] EAP packet type response id 5 length 6 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /etc/freeradius/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 5 to 10.160.4.5 port 9838 EAP-Message = 0x0106002b19001703010020b8c2d3be1c605028c5b5dc0c019ef75da40c22fe5baa59a9d687ce8da0dc212a Message-Authenticator = 0x00000000000000000000000000000000 State = 0x8609790d830f6042e7955f855d8bcd86 Finished request 5. Going to the next request Waking up in 4.9 seconds. rad_recv: Access-Request packet from host 10.160.4.5 port 9838, id=6, length=213 User-Name = "wlan_test" NAS-IP-Address = 127.0.0.1 Calling-Station-Id = "70-6F-6C-69-73-68" Framed-MTU = 1400 NAS-Port-Type = Wireless-802.11 Connect-Info = "rad_eap_test + eapol_test" EAP-Message = 0x02060050190017030100202b628396a34010e927d874108bf041b23c9e7ab5e1a0ed787edb6881aec4a8b217030100209eb857235363c4c58068ea47e7dc7e4d33cd5f085b90f5c5c4f8e78f4313738d State = 0x8609790d830f6042e7955f855d8bcd86 Message-Authenticator = 0xa1d2878e5a37ba7ed3d08a371d4cf298 # Executing section authorize from file /etc/freeradius/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok [auth_log] expand: /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] expand: %t -> Sun Jul 26 15:37:46 2015 ++[auth_log] returns ok ++[chap] returns noop ++[mschap] returns noop ++[digest] returns noop [suffix] No '@' in User-Name = "wlan_test", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop [eap] EAP packet type response id 6 length 80 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /etc/freeradius/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 - wlan_test [peap] Got inner identity 'wlan_test' [peap] Setting default EAP type for tunneled EAP session. [peap] Got tunneled request EAP-Message = 0x0206000e01776c616e5f74657374 server { [peap] Setting User-Name to wlan_test Sending tunneled request EAP-Message = 0x0206000e01776c616e5f74657374 FreeRADIUS-Proxied-To = 127.0.0.1 User-Name = "wlan_test" NAS-IP-Address = 127.0.0.1 Calling-Station-Id = "70-6F-6C-69-73-68" Framed-MTU = 1400 NAS-Port-Type = Wireless-802.11 Connect-Info = "rad_eap_test + eapol_test" server inner-tunnel { # Executing section authorize from file /etc/freeradius/sites-enabled/inner-tunnel +- entering group authorize {...} ++[chap] returns noop ++[mschap] returns noop [suffix] No '@' in User-Name = "wlan_test", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop ++[control] returns noop expand: %{request:User-Name} -> wlan_test ++[outer.control] returns noop [eap] EAP packet type response id 6 length 14 [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 /etc/freeradius/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 = 0x010700231a0107001e106fd7241dcf34e0137481aee8a50e454b776c616e5f74657374 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x1839db62183ec1f9826f0afe924156ce [peap] Got tunneled reply RADIUS code 11 EAP-Message = 0x010700231a0107001e106fd7241dcf34e0137481aee8a50e454b776c616e5f74657374 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x1839db62183ec1f9826f0afe924156ce [peap] Got tunneled Access-Challenge ++[eap] returns handled Sending Access-Challenge of id 6 to 10.160.4.5 port 9838 EAP-Message = 0x0107004b190017030100407ad69010aece989fb7fc099a0a189091c9cce3b09d92529b2e43b12c633ad9f7fda99cf69b83a6ee7e4f18fb905026273234c9c88928de0957d9b39a545b063b Message-Authenticator = 0x00000000000000000000000000000000 State = 0x8609790d800e6042e7955f855d8bcd86 Finished request 6. Going to the next request Waking up in 4.9 seconds. rad_recv: Access-Request packet from host 10.160.4.5 port 9838, id=7, length=277 User-Name = "wlan_test" NAS-IP-Address = 127.0.0.1 Calling-Station-Id = "70-6F-6C-69-73-68" Framed-MTU = 1400 NAS-Port-Type = Wireless-802.11 Connect-Info = "rad_eap_test + eapol_test" EAP-Message = 0x0207009019001703010020f75f2734059b83c7f010cdf2f8235859fd0456fed42729737417ff6bbe630e6a1703010060ace960531b72cb5c8e385e24b4dc06bea9e2b317a26cadcaf83378d6caaf1fa4341c7141338caaa36565f89cd8715172ba4f4e87a5a221c171216952abe149a864ce39cb8487481e6bbacf87ab4e6e8b3c7a9255327671773ed36ee05bffcdd7 State = 0x8609790d800e6042e7955f855d8bcd86 Message-Authenticator = 0x960f7fea1ea35ad4f0a9031a7a5cc21f # Executing section authorize from file /etc/freeradius/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok [auth_log] expand: /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] expand: %t -> Sun Jul 26 15:37:46 2015 ++[auth_log] returns ok ++[chap] returns noop ++[mschap] returns noop ++[digest] returns noop [suffix] No '@' in User-Name = "wlan_test", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop [eap] EAP packet type response id 7 length 144 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /etc/freeradius/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 = 0x020700441a0207003f31af2595eb8d6f4c4580e8e2e35ef5e7c5000000000000000064cbd72344df1b1418118f20956ffadef3b05eb41cefe39100776c616e5f74657374 server { [peap] Setting User-Name to wlan_test Sending tunneled request EAP-Message = 0x020700441a0207003f31af2595eb8d6f4c4580e8e2e35ef5e7c5000000000000000064cbd72344df1b1418118f20956ffadef3b05eb41cefe39100776c616e5f74657374 FreeRADIUS-Proxied-To = 127.0.0.1 User-Name = "wlan_test" State = 0x1839db62183ec1f9826f0afe924156ce NAS-IP-Address = 127.0.0.1 Calling-Station-Id = "70-6F-6C-69-73-68" Framed-MTU = 1400 NAS-Port-Type = Wireless-802.11 Connect-Info = "rad_eap_test + eapol_test" server inner-tunnel { # Executing section authorize from file /etc/freeradius/sites-enabled/inner-tunnel +- entering group authorize {...} ++[chap] returns noop ++[mschap] returns noop [suffix] No '@' in User-Name = "wlan_test", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop ++[control] returns noop expand: %{request:User-Name} -> wlan_test ++[outer.control] returns noop [eap] EAP packet type response id 7 length 68 [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 /etc/freeradius/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 /etc/freeradius/sites-enabled/inner-tunnel [mschapv2] +- entering group MS-CHAP {...} [mschap] Creating challenge hash with username: wlan_test [mschap] Told to do MS-CHAPv2 for wlan_test with NT-Password [mschap] expand: %{Stripped-User-Name} -> [mschap] ... expanding second conditional [mschap] WARNING: Deprecated conditional expansion ":-". See "man unlang" for details [mschap] expand: %{User-Name:-None} -> wlan_test [mschap] expand: --username=%{%{Stripped-User-Name}:-%{User-Name:-None}} -> --username=wlan_test [mschap] No NT-Domain was found in the User-Name. [mschap] expand: %{mschap:NT-Domain} -> [mschap] ... expanding second conditional [mschap] expand: --domain=%{%{mschap:NT-Domain}:-SWBT} -> --domain=SWBT [mschap] Creating challenge hash with username: wlan_test [mschap] expand: --challenge=%{mschap:Challenge:-00} -> --challenge=37ef35ad98975d0b [mschap] expand: --nt-response=%{mschap:NT-Response:-00} -> --nt-response=64cbd72344df1b1418118f20956ffadef3b05eb41cefe391 Exec-Program output: NT_KEY: 2066656E05C22F3A995AD9ECFED913D6 Exec-Program-Wait: plaintext: NT_KEY: 2066656E05C22F3A995AD9ECFED913D6 Exec-Program: returned: 0 [mschap] adding MS-CHAPv2 MPPE keys ++[mschap] returns ok MSCHAP Success ++[eap] returns handled } # server inner-tunnel [peap] Got tunneled reply code 11 EAP-Message = 0x010800331a0307002e533d37384530313645424443453233453333413941464235304641463139304541384430463239323842 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x1839db621931c1f9826f0afe924156ce [peap] Got tunneled reply RADIUS code 11 EAP-Message = 0x010800331a0307002e533d37384530313645424443453233453333413941464235304641463139304541384430463239323842 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x1839db621931c1f9826f0afe924156ce [peap] Got tunneled Access-Challenge ++[eap] returns handled Sending Access-Challenge of id 7 to 10.160.4.5 port 9838 EAP-Message = 0x0108005b19001703010050a62cb45a408a0385a63974663bd05c61ff4650cf60ec9a390d417a590be45f83dc91c70c083d531946c4f389eced60d71a1d1c694a3929ad996cda5ae277de31bc1e0b692833ea4f6c5834fa18ceacff Message-Authenticator = 0x00000000000000000000000000000000 State = 0x8609790d81016042e7955f855d8bcd86 Finished request 7. Going to the next request Waking up in 4.9 seconds. rad_recv: Access-Request packet from host 10.160.4.5 port 9838, id=8, length=213 User-Name = "wlan_test" NAS-IP-Address = 127.0.0.1 Calling-Station-Id = "70-6F-6C-69-73-68" Framed-MTU = 1400 NAS-Port-Type = Wireless-802.11 Connect-Info = "rad_eap_test + eapol_test" EAP-Message = 0x0208005019001703010020564515dd247a9f469f0e2e7bc910026867b9063aa415bf81e51248139e5557261703010020a67152e3610f03d2bbca25917c0618862fc5cf034ac7ff407652d0300caf9023 State = 0x8609790d81016042e7955f855d8bcd86 Message-Authenticator = 0x3fa739b7d4f7fa973579a6ac2d661785 # Executing section authorize from file /etc/freeradius/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok [auth_log] expand: /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] expand: %t -> Sun Jul 26 15:37:46 2015 ++[auth_log] returns ok ++[chap] returns noop ++[mschap] returns noop ++[digest] returns noop [suffix] No '@' in User-Name = "wlan_test", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop [eap] EAP packet type response id 8 length 80 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /etc/freeradius/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 = 0x020800061a03 server { [peap] Setting User-Name to wlan_test Sending tunneled request EAP-Message = 0x020800061a03 FreeRADIUS-Proxied-To = 127.0.0.1 User-Name = "wlan_test" State = 0x1839db621931c1f9826f0afe924156ce NAS-IP-Address = 127.0.0.1 Calling-Station-Id = "70-6F-6C-69-73-68" Framed-MTU = 1400 NAS-Port-Type = Wireless-802.11 Connect-Info = "rad_eap_test + eapol_test" server inner-tunnel { # Executing section authorize from file /etc/freeradius/sites-enabled/inner-tunnel +- entering group authorize {...} ++[chap] returns noop ++[mschap] returns noop [suffix] No '@' in User-Name = "wlan_test", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop ++[control] returns noop expand: %{request:User-Name} -> wlan_test ++[outer.control] returns noop [eap] EAP packet type response id 8 length 6 [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 /etc/freeradius/sites-enabled/inner-tunnel +- entering group authenticate {...} [eap] Request found, released from the list [eap] EAP/mschapv2 [eap] processing type mschapv2 [eap] Freeing handler ++[eap] returns ok WARNING: Empty post-auth section. Using default return values. # Executing section post-auth from file /etc/freeradius/sites-enabled/inner-tunnel } # server inner-tunnel [peap] Got tunneled reply code 2 MS-MPPE-Encryption-Policy = 0x00000001 MS-MPPE-Encryption-Types = 0x00000006 MS-MPPE-Send-Key = 0xc5dbf78ff2d15a7f07cb9ee637c97aab MS-MPPE-Recv-Key = 0xafc6caf8a466f440871501f8f0902092 EAP-Message = 0x03080004 Message-Authenticator = 0x00000000000000000000000000000000 User-Name = "wlan_test" [peap] Got tunneled reply RADIUS code 2 MS-MPPE-Encryption-Policy = 0x00000001 MS-MPPE-Encryption-Types = 0x00000006 MS-MPPE-Send-Key = 0xc5dbf78ff2d15a7f07cb9ee637c97aab MS-MPPE-Recv-Key = 0xafc6caf8a466f440871501f8f0902092 EAP-Message = 0x03080004 Message-Authenticator = 0x00000000000000000000000000000000 User-Name = "wlan_test" [peap] Tunneled authentication was successful. [peap] SUCCESS ++[eap] returns handled Sending Access-Challenge of id 8 to 10.160.4.5 port 9838 EAP-Message = 0x0109002b1900170301002092197ff2939b86de39a9c65fd5c8996771573a96eac5c2f3d255c03e8f3b9ff4 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x8609790d8e006042e7955f855d8bcd86 Finished request 8. Going to the next request Waking up in 4.9 seconds. rad_recv: Access-Request packet from host 10.160.4.5 port 9838, id=9, length=213 User-Name = "wlan_test" NAS-IP-Address = 127.0.0.1 Calling-Station-Id = "70-6F-6C-69-73-68" Framed-MTU = 1400 NAS-Port-Type = Wireless-802.11 Connect-Info = "rad_eap_test + eapol_test" EAP-Message = 0x0209005019001703010020404f3f4d8d1d28904a69fd61e450db7e0ca14526b30073825ffd827e647fff5f1703010020af53cdf1f1df6c4b8ab2510afe0ab3f304326e88273084bbfd860b243469abb1 State = 0x8609790d8e006042e7955f855d8bcd86 Message-Authenticator = 0x7e96659e361a63af6973ac7a487179cb # Executing section authorize from file /etc/freeradius/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok [auth_log] expand: /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/freeradius/radacct/10.160.4.5/auth-detail-20150726 [auth_log] expand: %t -> Sun Jul 26 15:37:46 2015 ++[auth_log] returns ok ++[chap] returns noop ++[mschap] returns noop ++[digest] returns noop [suffix] No '@' in User-Name = "wlan_test", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] returns noop [eap] EAP packet type response id 9 length 80 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /etc/freeradius/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 success [peap] Received EAP-TLV response. [peap] Success [eap] Freeing handler ++[eap] returns ok # Executing section post-auth from file /etc/freeradius/sites-enabled/default +- entering group post-auth {...} ++[exec] returns noop Sending Access-Accept of id 9 to 10.160.4.5 port 9838 MS-MPPE-Recv-Key = 0x355d7cf722e0b65de53be6cc23386be1a5ecb19b6ee6711746eecee7967d2159 MS-MPPE-Send-Key = 0xe7fca5958df2dcc7d1dc7e8df0d4d85f88c76a53656408452ea6d92da596fd21 EAP-Message = 0x03090004 Message-Authenticator = 0x00000000000000000000000000000000 User-Name = "wlan_test" Finished request 9. Going to the next request Waking up in 4.9 seconds. Cleaning up request 0 ID 0 with timestamp +9 Cleaning up request 1 ID 1 with timestamp +9 Cleaning up request 2 ID 2 with timestamp +9 Cleaning up request 3 ID 3 with timestamp +9 Cleaning up request 4 ID 4 with timestamp +9 Cleaning up request 5 ID 5 with timestamp +9 Cleaning up request 6 ID 6 with timestamp +9 Cleaning up request 7 ID 7 with timestamp +9 Cleaning up request 8 ID 8 with timestamp +9 Cleaning up request 9 ID 9 with timestamp +9 Ready to process requests.