EAP-PEAP-GTC issue

15 Dec 2016

      Hi guys,

Last week I got 20 new phones (3 models) and I got 18 of them working but 2 of them are a model which can only do EAP-MD5, EAP-PEAP-GTC and EAP-TTLS-EAP-GTC. (Yealink W52P)
My system is 2.2.10 from git (love to upgrade to 3.0 but that will have to wait. Will happen in first half 2017 though..

The other phones connect up with EAP-PEAP-MSCHAPv2 which works without a hitch. So I assume my uploaded CA certificates are ok. I have +/- 300 students connecting with Windows/OSX/Linux/Android/ipads/iphones and Windows Phone without an issue.

But GTC is new for me..  Is there working support for GTC in 2.2.10 (build from git last march)??

When looking at the logs I notice these things : 
- it never gets to the inner-tunnel

- TTLS does not seem to finish.
[ttls]     TLS_accept: unknown state
[ttls]     TLS_accept: unknown state
[ttls]     TLS_accept: unknown state
[ttls]     TLS_accept: Need to read more data: unknown state
[ttls]     TLS_accept: Need to read more data: unknown state

- I get a fatal error. (My only guess to this is that my switch could be stopping after a number of retries(cannot disable this), or my phones are broken.)

[ttls] <<< TLS 1.0 Alert [length 0002], fatal decrypt_error
TLS Alert read:fatal:decrypt error
    TLS_accept: failed in unknown state
rlm_eap: SSL error error:1409441B:SSL routines:ssl3_read_bytes:tlsv1 alert decrypt error
SSL: SSL_read failed inside of TLS (-1), TLS session fails.

Any hints for me ?? 

Arjan Sinnige

Full Debug :
root@studentserv:/home/sinnige# freeradius -X
freeradius: FreeRADIUS Version 2.2.10, for host x86_64-pc-linux-gnu, built on Mar 20 2016 at 19:51:40
Copyright (C) 1999-2015 The FreeRADIUS server project and contributors.
There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A
PARTICULAR PURPOSE.
You may redistribute copies of FreeRADIUS under the terms of the
GNU General Public License.
For more information about these matters, see the file named COPYRIGHT.
Starting - reading configuration files ...
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/files
including configuration file /etc/freeradius/modules/checkval
including configuration file /etc/freeradius/modules/mschap
including configuration file /etc/freeradius/modules/dhcp_sqlippool
including configuration file /etc/freeradius/modules/rediswho
including configuration file /etc/freeradius/modules/cui
including configuration file /etc/freeradius/modules/sqlcounter_expire_on_login
including configuration file /etc/freeradius/modules/detail.example.com
including configuration file /etc/freeradius/modules/otp
including configuration file /etc/freeradius/modules/linelog
including configuration file /etc/freeradius/modules/pap
including configuration file /etc/freeradius/modules/passwd
including configuration file /etc/freeradius/modules/ippool
including configuration file /etc/freeradius/modules/etc_group
including configuration file /etc/freeradius/modules/digest
including configuration file /etc/freeradius/modules/krb5
including configuration file /etc/freeradius/modules/radutmp
including configuration file /etc/freeradius/modules/smsotp
including configuration file /etc/freeradius/modules/replicate
including configuration file /etc/freeradius/modules/detail.log
including configuration file /etc/freeradius/modules/inner-eap
including configuration file /etc/freeradius/modules/redis
including configuration file /etc/freeradius/modules/ntlm_auth
including configuration file /etc/freeradius/modules/logintime
including configuration file /etc/freeradius/modules/detail
including configuration file /etc/freeradius/modules/echo
including configuration file /etc/freeradius/modules/chap
including configuration file /etc/freeradius/modules/soh
including configuration file /etc/freeradius/modules/expr
including configuration file /etc/freeradius/modules/perl
including configuration file /etc/freeradius/modules/smbpasswd
including configuration file /etc/freeradius/modules/attr_filter
including configuration file /etc/freeradius/modules/pam
including configuration file /etc/freeradius/modules/opendirectory
including configuration file /etc/freeradius/modules/attr_rewrite
including configuration file /etc/freeradius/modules/policy
including configuration file /etc/freeradius/modules/ldap
including configuration file /etc/freeradius/modules/mac2ip
including configuration file /etc/freeradius/modules/wimax
including configuration file /etc/freeradius/modules/acct_unique
including configuration file /etc/freeradius/modules/dynamic_clients
including configuration file /etc/freeradius/modules/unix
including configuration file /etc/freeradius/modules/counter
including configuration file /etc/freeradius/modules/realm
including configuration file /etc/freeradius/modules/preprocess
including configuration file /etc/freeradius/modules/radrelay
including configuration file /etc/freeradius/modules/sradutmp
including configuration file /etc/freeradius/modules/sql_log
including configuration file /etc/freeradius/modules/exec
including configuration file /etc/freeradius/modules/expiration
including configuration file /etc/freeradius/modules/cache
including configuration file /etc/freeradius/modules/mac2vlan
including configuration file /etc/freeradius/modules/always
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/inner-tunnel
including configuration file /etc/freeradius/sites-enabled/default
main {
        user = "root"
        group = "root"
        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 = 51200
        pidfile = "/var/run/freeradius/freeradius.pid"
        checkrad = "/usr/sbin/checkrad"
        debug_level = 0
        proxy_requests = yes
 log {
        stripped_names = no
        auth = yes
        auth_badpass = no
        auth_goodpass = no
 }
 security {
        max_attributes = 200
        reject_delay = 1
        status_server = yes
        allow_vulnerable_openssl = 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 192.168.109.190 {
        require_message_authenticator = no
        secret = "MYPASSWORD"
        shortname = "SwitchStack"
        nastype = "cisco"
 }
 client 192.168.109.194 {
        require_message_authenticator = no
        secret = "MYPASSWORD"
        shortname = "SwitchStack"
        nastype = "cisco"
 }
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
        timeout = 10
  }
 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 = digest
  Module: Creating Auth-Type = LDAP
 Module: Checking authenticate {...} for more modules to load
 Module: Linked to module rlm_pap
 Module: Instantiating module "pap" from file /etc/freeradius/modules/pap
  pap {
        encryption_scheme = "auto"
        auto_header = no
  }
 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
        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_ldap
 Module: Instantiating module "ldap" from file /etc/freeradius/modules/ldap
  ldap {
        server = "127.0.0.1"
        port = 389
        password = "MYPASSWORD"
        expect_password = yes
        identity = "cn=admin,dc=example,dc=com"
        net_timeout = 1
        timeout = 4
        timelimit = 3
        max_uses = 0
        tls_mode = no
        start_tls = no
        tls_require_cert = "allow"
   tls {
        start_tls = no
        require_cert = "allow"
   }
        basedn = "ou=Users,dc=example,dc=com"
        filter = "(uid=%{%{Stripped-User-Name}:-%{User-Name}})"
        base_filter = "(ou=Users)"
        auto_header = no
        access_attr_used_for_allow = yes
        groupname_attribute = "cn"
        groupmembership_filter = "(&(cn=*)(memberUid=%{%Stripped-User-Name}:-%{User-Name}}))"
        groupmembership_attribute = "radiusGroupName"
        dictionary_mapping = "/etc/freeradius/ldap.attrmap"
        ldap_debug = 0
        ldap_connections_number = 5
        compare_check_items = no
        do_xlat = yes
        edir_account_policy_check = no
        set_auth_type = yes
   keepalive {
        idle = 60
        probes = 3
        interval = 3
   }
  }
rlm_ldap: Registering ldap_groupcmp for Ldap-Group
rlm_ldap: Registering ldap_xlat with xlat_name ldap
rlm_ldap: reading ldap<->radius mappings from file /etc/freeradius/ldap.attrmap
rlm_ldap: LDAP radiusCheckItem mapped to RADIUS $GENERIC$
rlm_ldap: LDAP radiusReplyItem mapped to RADIUS $GENERIC$
rlm_ldap: LDAP radiusAuthType mapped to RADIUS Auth-Type
rlm_ldap: LDAP radiusSimultaneousUse mapped to RADIUS Simultaneous-Use
rlm_ldap: LDAP radiusCalledStationId mapped to RADIUS Called-Station-Id
rlm_ldap: LDAP radiusCallingStationId mapped to RADIUS Calling-Station-Id
rlm_ldap: LDAP lmPassword mapped to RADIUS LM-Password
rlm_ldap: LDAP ntPassword mapped to RADIUS NT-Password
rlm_ldap: LDAP sambaLmPassword mapped to RADIUS LM-Password
rlm_ldap: LDAP sambaNtPassword mapped to RADIUS NT-Password
rlm_ldap: LDAP dBCSPwd mapped to RADIUS LM-Password
rlm_ldap: LDAP userPassword mapped to RADIUS Password-With-Header
rlm_ldap: LDAP acctFlags mapped to RADIUS SMB-Account-CTRL-TEXT
rlm_ldap: LDAP radiusExpiration mapped to RADIUS Expiration
rlm_ldap: LDAP radiusNASIpAddress mapped to RADIUS NAS-IP-Address
rlm_ldap: LDAP gidNumber mapped to RADIUS Ldap-PrimaryGroupId
rlm_ldap: LDAP radiusServiceType mapped to RADIUS Service-Type
rlm_ldap: LDAP radiusFramedProtocol mapped to RADIUS Framed-Protocol
rlm_ldap: LDAP radiusFramedIPAddress mapped to RADIUS Framed-IP-Address
rlm_ldap: LDAP radiusFramedIPNetmask mapped to RADIUS Framed-IP-Netmask
rlm_ldap: LDAP radiusFramedRoute mapped to RADIUS Framed-Route
rlm_ldap: LDAP radiusFramedRouting mapped to RADIUS Framed-Routing
rlm_ldap: LDAP radiusFilterId mapped to RADIUS Filter-Id
rlm_ldap: LDAP radiusFramedMTU mapped to RADIUS Framed-MTU
rlm_ldap: LDAP radiusFramedCompression mapped to RADIUS Framed-Compression
rlm_ldap: LDAP radiusLoginIPHost mapped to RADIUS Login-IP-Host
rlm_ldap: LDAP radiusLoginService mapped to RADIUS Login-Service
rlm_ldap: LDAP radiusLoginTCPPort mapped to RADIUS Login-TCP-Port
rlm_ldap: LDAP radiusCallbackNumber mapped to RADIUS Callback-Number
rlm_ldap: LDAP radiusCallbackId mapped to RADIUS Callback-Id
rlm_ldap: LDAP radiusFramedIPXNetwork mapped to RADIUS Framed-IPX-Network
rlm_ldap: LDAP radiusClass mapped to RADIUS Class
rlm_ldap: LDAP radiusSessionTimeout mapped to RADIUS Session-Timeout
rlm_ldap: LDAP radiusIdleTimeout mapped to RADIUS Idle-Timeout
rlm_ldap: LDAP radiusTerminationAction mapped to RADIUS Termination-Action
rlm_ldap: LDAP radiusLoginLATService mapped to RADIUS Login-LAT-Service
rlm_ldap: LDAP radiusLoginLATNode mapped to RADIUS Login-LAT-Node
rlm_ldap: LDAP radiusLoginLATGroup mapped to RADIUS Login-LAT-Group
rlm_ldap: LDAP radiusFramedAppleTalkLink mapped to RADIUS Framed-AppleTalk-Link
rlm_ldap: LDAP radiusFramedAppleTalkNetwork mapped to RADIUS Framed-AppleTalk-Network
rlm_ldap: LDAP radiusFramedAppleTalkZone mapped to RADIUS Framed-AppleTalk-Zone
rlm_ldap: LDAP radiusPortLimit mapped to RADIUS Port-Limit
rlm_ldap: LDAP radiusLoginLATPort mapped to RADIUS Login-LAT-Port
rlm_ldap: LDAP radiusReplyMessage mapped to RADIUS Reply-Message
rlm_ldap: LDAP radiusTunnelType mapped to RADIUS Tunnel-Type
rlm_ldap: LDAP radiusTunnelMediumType mapped to RADIUS Tunnel-Medium-Type
rlm_ldap: LDAP radiusTunnelPrivateGroupId mapped to RADIUS Tunnel-Private-Group-Id
conns: 0x120aab0
 Module: Linked to module rlm_always
 Module: Instantiating module "reject" from file /etc/freeradius/modules/always
  always reject {
        rcode = "reject"
        simulcount = 0
        mpp = no
  }
 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 = "whatever"
        dh_file = "/etc/freeradius/certs/dh"
        random_file = "/dev/urandom"
        fragment_size = 1024
        include_length = yes
        check_crl = no
        check_all_crl = no
        cipher_list = "DEFAULT"
        make_cert_command = "/etc/freeradius/certs/bootstrap"
        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 = "peap"
        copy_request_to_tunnel = no
        use_tunneled_reply = yes
        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 = no
        use_tunneled_reply = yes
        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
  }
reading pairlist file /etc/freeradius/huntgroups
reading pairlist file /etc/freeradius/hints
 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"
  }
reading pairlist file /etc/freeradius/users
reading pairlist file /etc/freeradius/acct_users
reading pairlist file /etc/freeradius/preproxy_users
 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, NAS-Identifier, NAS-Port"
  }
 Module: Checking accounting {...} for more modules to load
 Module: Linked to module rlm_detail
 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
        escape_filenames = 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
  }
reading pairlist file /etc/freeradius/attrs.accounting_response
 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
  }
reading pairlist file /etc/freeradius/attrs.access_reject
 } # modules
} # server
server inner-tunnel { # from file /etc/freeradius/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 = "auth"
        ipaddr = 127.0.0.1
        port = 18120
}
 ... adding new socket proxy address * port 42916
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 192.168.109.194 port 49205, id=222, length=131
        NAS-IP-Address = 192.168.109.194
        NAS-Port-Type = Ethernet
        NAS-Port = 63
        User-Name = "ssipwifi1"
        Acct-Session-Id = "05001068"
        Called-Station-Id = "00-87-31-4B-4A-D9"
        Calling-Station-Id = "00-15-65-AF-D2-23"
        EAP-Message = 0x0201000e01737369707769666931
        Message-Authenticator = 0xa26adbccad03de7fe70b5f55ca50e571
# Executing section authorize from file /etc/freeradius/sites-enabled/default
+group authorize {
++[preprocess] = ok
++[chap] = noop
++[mschap] = noop
++[digest] = noop
[suffix] No '@' in User-Name = "ssipwifi1", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] = noop
[eap] EAP packet type response id 1 length 14
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] = updated
++[files] = noop
[ldap] performing user authorization for ssipwifi1
[ldap]  expand: %{Stripped-User-Name} ->
[ldap]  ... expanding second conditional
[ldap]  expand: %{User-Name} -> ssipwifi1
[ldap]  expand: (uid=%{%{Stripped-User-Name}:-%{User-Name}}) -> (uid=ssipwifi1)
[ldap]  expand: ou=Users,dc=example,dc=com -> ou=Users,dc=example,dc=com
  [ldap] ldap_get_conn: Checking Id: 0
  [ldap] ldap_get_conn: Got Id: 0
  [ldap] attempting LDAP reconnection
  [ldap] (re)connect to 127.0.0.1:389, authentication 0
  [ldap] bind as cn=admin,dc=example,dc=com/MYPASSWORD to 127.0.0.1:389
  [ldap] waiting for bind result ...
  [ldap] Bind was successful
  [ldap] performing search in ou=Users,dc=example,dc=com, with filter (uid=ssipwifi1)
[ldap] No default NMAS login sequence
[ldap] looking for check items in directory...
  [ldap] gidNumber -> Ldap-PrimaryGroupId == 10004
  [ldap] userPassword -> Password-With-Header == "{SSHA}w/lNsXIc3dQXXXXXXXXXXXXXXeaRhMGVt"
  [ldap] sambaNtPassword -> NT-Password == 0x433233423237334534XXXXXXXXXXXXXXX83546314337453735423031304341
[ldap] looking for reply items in directory...
  [ldap] ldap_release_conn: Release Id: 0
++[ldap] = ok
++[expiration] = noop
++[logintime] = noop
[pap] Normalizing NT-Password from hex encoding
[pap] Normalizing SSHA1-Password from base64 encoding
[pap] WARNING: Auth-Type already set.  Not setting to PAP
++[pap] = noop
+} # group authorize = updated
Found Auth-Type = EAP
# Executing group from file /etc/freeradius/sites-enabled/default
+group authenticate {
[eap] EAP Identity
[eap] processing type tls
[tls] Initiate
[tls] Start returned 1
++[eap] = handled
+} # group authenticate = handled
Sending Access-Challenge of id 222 to 192.168.109.194 port 49205
        EAP-Message = 0x010200061920
        Message-Authenticator = 0x00000000000000000000000000000000
        State = 0xf3248093f32699e803792eac5869d440
Finished request 0.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 192.168.109.194 port 49205, id=223, length=141
        NAS-IP-Address = 192.168.109.194
        NAS-Port-Type = Ethernet
        NAS-Port = 63
        User-Name = "ssipwifi1"
        Acct-Session-Id = "05001068"
        State = 0xf3248093f32699e803792eac5869d440
        Called-Station-Id = "00-87-31-4B-4A-D9"
        Calling-Station-Id = "00-15-65-AF-D2-23"
        EAP-Message = 0x020200060315
        Message-Authenticator = 0x47c58cec3484eaff137af8e0f896b447
# Executing section authorize from file /etc/freeradius/sites-enabled/default
+group authorize {
++[preprocess] = ok
++[chap] = noop
++[mschap] = noop
++[digest] = noop
[suffix] No '@' in User-Name = "ssipwifi1", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] = noop
[eap] EAP packet type response id 2 length 6
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] = updated
++[files] = noop
[ldap] performing user authorization for ssipwifi1
[ldap]  expand: %{Stripped-User-Name} ->
[ldap]  ... expanding second conditional
[ldap]  expand: %{User-Name} -> ssipwifi1
[ldap]  expand: (uid=%{%{Stripped-User-Name}:-%{User-Name}}) -> (uid=ssipwifi1)
[ldap]  expand: ou=Users,dc=example,dc=com -> ou=Users,dc=example,dc=com
  [ldap] ldap_get_conn: Checking Id: 0
  [ldap] ldap_get_conn: Got Id: 0
  [ldap] performing search in ou=Users,dc=example,dc=com, with filter (uid=ssipwifi1)
[ldap] No default NMAS login sequence
[ldap] looking for check items in directory...
  [ldap] gidNumber -> Ldap-PrimaryGroupId == 10004
  [ldap] userPassword -> Password-With-Header == "{SSHA}w/lNsXIc3dQXXXXXXXXXXXXXXeaRhMGVt"
  [ldap] sambaNtPassword -> NT-Password == 0x433233423237334534XXXXXXXXXXXXXXX83546314337453735423031304341
[ldap] looking for reply items in directory...
  [ldap] ldap_release_conn: Release Id: 0
++[ldap] = ok
++[expiration] = noop
++[logintime] = noop
[pap] Normalizing NT-Password from hex encoding
[pap] Normalizing SSHA1-Password from base64 encoding
[pap] WARNING: Auth-Type already set.  Not setting to PAP
++[pap] = noop
+} # group authorize = updated
Found Auth-Type = EAP
# Executing group from file /etc/freeradius/sites-enabled/default
+group authenticate {
[eap] Request found, released from the list
[eap] EAP NAK
[eap] EAP-NAK asked for EAP-Type/ttls
[eap] processing type tls
[tls] Initiate
[tls] Start returned 1
++[eap] = handled
+} # group authenticate = handled
Sending Access-Challenge of id 223 to 192.168.109.194 port 49205
        EAP-Message = 0x010300061520
        Message-Authenticator = 0x00000000000000000000000000000000
        State = 0xf3248093f22795e803792eac5869d440
Finished request 1.
Going to the next request
Waking up in 4.8 seconds.
rad_recv: Access-Request packet from host 192.168.109.194 port 49205, id=224, length=239
        NAS-IP-Address = 192.168.109.194
        NAS-Port-Type = Ethernet
        NAS-Port = 63
        User-Name = "ssipwifi1"
        Acct-Session-Id = "05001068"
        State = 0xf3248093f22795e803792eac5869d440
        Called-Station-Id = "00-87-31-4B-4A-D9"
        Calling-Station-Id = "00-15-65-AF-D2-23"
        EAP-Message = 0x020300681500160301005d01000059030158530887210f58d8d6b244c838f96da45450f4f86718340d1896a196652d37cc00003200390038003500160013000a00330032002f00070066000500040063006200150012000900650064006000140011000800030100
        Message-Authenticator = 0xdb90c4d7b9b838a214d0de5f6470923f
# Executing section authorize from file /etc/freeradius/sites-enabled/default
+group authorize {
++[preprocess] = ok
++[chap] = noop
++[mschap] = noop
++[digest] = noop
[suffix] No '@' in User-Name = "ssipwifi1", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] = noop
[eap] EAP packet type response id 3 length 104
[eap] Continuing tunnel setup.
++[eap] = ok
+} # group authorize = ok
Found Auth-Type = EAP
# Executing group from file /etc/freeradius/sites-enabled/default
+group authenticate {
[eap] Request found, released from the list
[eap] EAP/ttls
[eap] processing type ttls
[ttls] Authenticate
[ttls] processing EAP-TLS
[ttls] eaptls_verify returned 7
[ttls] Done initial handshake
[ttls]     (other): before/accept initialization
[ttls]     TLS_accept: before/accept initialization
[ttls] <<< TLS 1.0 Handshake [length 005d], ClientHello
[ttls]     TLS_accept: unknown state
[ttls] >>> TLS 1.0 Handshake [length 002a], ServerHello
[ttls]     TLS_accept: unknown state
[ttls] >>> TLS 1.0 Handshake [length 08a7], Certificate
[ttls]     TLS_accept: unknown state
[ttls] >>> TLS 1.0 Handshake [length 030d], ServerKeyExchange
[ttls]     TLS_accept: unknown state
[ttls] >>> TLS 1.0 Handshake [length 0004], ServerHelloDone
[ttls]     TLS_accept: unknown state
[ttls]     TLS_accept: unknown state
[ttls]     TLS_accept: unknown state
[ttls]     TLS_accept: Need to read more data: unknown state
[ttls]     TLS_accept: Need to read more data: unknown state
In SSL Handshake Phase
In SSL Accept mode
[ttls] eaptls_process returned 13
++[eap] = handled
+} # group authenticate = handled
Sending Access-Challenge of id 224 to 192.168.109.194 port 49205
        EAP-Message = 0x0104040015c000000bf6160301002a0200002603018df62fac61181c5738eaaf644f67a012285e5fe2cc6ef541bcc9fb2a274c4d680000390016030108a70b0008a30008a00003cd308203c9308202b1a003020102020101300d06092a864886f70d01010b050030818c310b3009060355040613024e4c310b300906035504080c024e483112301006035504070c09416d7374657264616d31163014060355040a0c0d53414520416d7374657264616d3120301e06092a864886f70d0109011611612e73696e6e696765407361652e6564753122302006035504030c1953414520436572746966696361746520417574686f72697479301e170d313630
        EAP-Message = 0x3332323136353935385a170d3236303332303136353935385a3075310b3009060355040613024e4c310b300906035504080c024e4831163014060355040a0c0d53414520416d7374657264616d311f301d06035504030c16534145205365727665722043657274696669636174653120301e06092a864886f70d0109011611612e73696e6e696765407361652e65647530820122300d06092a864886f70d01010105000382010f003082010a0282010100a8e646288e90248e347514a9562adc1c669654454ec7634463cf7f6ff0d37a9c45413ec38f49eb9cfac08ff1ff9c3c41d64136357aece8334d35a32fae50e057154b2b3c66ed0413827928f2
        EAP-Message = 0xdc908db4ca4a43180b46e3078da30a62ce15a45a0d95947aba8a8f511c2b0fbb6606bb3fdb91d814cbe24e25611e93ad1f435ea3014cc5204430fab1b5f678e5bcf1b2683013482f71e090746fb4126921036fb2cdc3e6497cd9f85b557229527a2ab56a0d76336f987e28195572c6e7f654e8dd8c7688d671fe3d178e25428a2a431e053885df4625a791287dbdfaea10f3dfc32476cb8cbe87992c0710628d5b5736be3ec785e59d88508333640cc7b5e9051d0203010001a34c304a30130603551d25040c300a06082b0601050507030130330603551d1f042c302a3028a026a0248622687474703a2f2f7777772e736165616d7374657264616d2e
        EAP-Message = 0x636f6d2f63612e63726c300d06092a864886f70d01010b050003820101004ed2a48cbc22c9a4b49ad922f3ebebe5d4e25ff70f41790eb52d2c2be30fada0536b2cf0191fa25e9714f76873115edc08621d308165aee6bf10adda3168462230a93073e4d62fd0f151d8b35691f1c668ae3e4f6c17338cdd16181420fb13087ee166711fcc05caf181753fcb53ecce5fec499e6bbefbdcf518c0b5150d73f272b1cabac3f082b23b900c8f4a76e956367c02f64ff93d67a8c2231746c1993cd477d4502809c33b8c5277a334dc499c6f9ade0d4657adeaa7da144e42143e058d4c5294b556c131f792cac9cd6e7a30df9358fa6182906aa8cea1ef7e0c71
        EAP-Message = 0x329922ae6dacb5c1dd0c30aa
        Message-Authenticator = 0x00000000000000000000000000000000
        State = 0xf3248093f12095e803792eac5869d440
Finished request 2.
Going to the next request
Waking up in 4.8 seconds.
rad_recv: Access-Request packet from host 192.168.109.194 port 49205, id=225, length=141
        NAS-IP-Address = 192.168.109.194
        NAS-Port-Type = Ethernet
        NAS-Port = 63
        User-Name = "ssipwifi1"
        Acct-Session-Id = "05001068"
        State = 0xf3248093f12095e803792eac5869d440
        Called-Station-Id = "00-87-31-4B-4A-D9"
        Calling-Station-Id = "00-15-65-AF-D2-23"
        EAP-Message = 0x020400061500
        Message-Authenticator = 0xc2641a7ce4c8ecb0a0fa877c97250bbf
# Executing section authorize from file /etc/freeradius/sites-enabled/default
+group authorize {
++[preprocess] = ok
++[chap] = noop
++[mschap] = noop
++[digest] = noop
[suffix] No '@' in User-Name = "ssipwifi1", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] = noop
[eap] EAP packet type response id 4 length 6
[eap] Continuing tunnel setup.
++[eap] = ok
+} # group authorize = ok
Found Auth-Type = EAP
# Executing group from file /etc/freeradius/sites-enabled/default
+group authenticate {
[eap] Request found, released from the list
[eap] EAP/ttls
[eap] processing type ttls
[ttls] Authenticate
[ttls] processing EAP-TLS
[ttls] Received TLS ACK
[ttls] ACK handshake fragment handler
[ttls] eaptls_verify returned 1
[ttls] eaptls_process returned 13
++[eap] = handled
+} # group authenticate = handled
Sending Access-Challenge of id 225 to 192.168.109.194 port 49205
        EAP-Message = 0x0105040015c000000bf61a1021f94ac6d92fb0be9027d4364cdef7887b1e140004cd308204c9308203b1a0030201020209008c96fc367ca63be9300d06092a864886f70d01010b050030818c310b3009060355040613024e4c310b300906035504080c024e483112301006035504070c09416d7374657264616d31163014060355040a0c0d53414520416d7374657264616d3120301e06092a864886f70d0109011611612e73696e6e696765407361652e6564753122302006035504030c1953414520436572746966696361746520417574686f72697479301e170d3136303332323136343935385a170d3236303332303136343935385a30818c310b
        EAP-Message = 0x3009060355040613024e4c310b300906035504080c024e483112301006035504070c09416d7374657264616d31163014060355040a0c0d53414520416d7374657264616d3120301e06092a864886f70d0109011611612e73696e6e696765407361652e6564753122302006035504030c1953414520436572746966696361746520417574686f7269747930820122300d06092a864886f70d01010105000382010f003082010a0282010100de6649176647c36cbec1bc7545e5ab88c8ab3bd57d63e6cac9d0a28676d45477cd54f5a09e5026506c37db77e7216cecc63fe1f1685daa101ff58f0232ce8e63e0df9e9d934c7086bb76f4d676f4c97c2b5c
        EAP-Message = 0x24084676e3a03c055fbf6483a21b3996618ffc52e9b0e3843eb75a604e1936071a307eb09be8392ede3d55355d1d80be2b2ae4943bde3fc0e577cc5603d854bb39d1298dabd3896d1cb206f018bba68c1e399f6a2590290544a51a38cd89fddc5661bffdadd912653d0289dc80c6bda51342319638b3977265b289e34e998acb615339f2f9ae9bb97b70fd42bdce98c326f03cb91a1705abcf6f51ec997fdbdea598fe21a8e1552999e0bed71c4b0203010001a382012a30820126301d0603551d0e0416041431193dc758d4d6c2f73a147365c3cd0d39a238833081c10603551d230481b93081b6801431193dc758d4d6c2f73a147365c3cd0d39a238
        EAP-Message = 0x83a18192a4818f30818c310b3009060355040613024e4c310b300906035504080c024e483112301006035504070c09416d7374657264616d31163014060355040a0c0d53414520416d7374657264616d3120301e06092a864886f70d0109011611612e73696e6e696765407361652e6564753122302006035504030c1953414520436572746966696361746520417574686f726974798209008c96fc367ca63be9300c0603551d13040530030101ff30330603551d1f042c302a3028a026a0248622687474703a2f2f7777772e736165616d7374657264616d2e636f6d2f63612e63726c300d06092a864886f70d01010b05000382010100dbe3cc84a7
        EAP-Message = 0x372bcbfb9704deb26aeeafa8
        Message-Authenticator = 0x00000000000000000000000000000000
        State = 0xf3248093f02195e803792eac5869d440
Finished request 3.
Going to the next request
Waking up in 4.8 seconds.
rad_recv: Access-Request packet from host 192.168.109.194 port 49205, id=226, length=141
        NAS-IP-Address = 192.168.109.194
        NAS-Port-Type = Ethernet
        NAS-Port = 63
        User-Name = "ssipwifi1"
        Acct-Session-Id = "05001068"
        State = 0xf3248093f02195e803792eac5869d440
        Called-Station-Id = "00-87-31-4B-4A-D9"
        Calling-Station-Id = "00-15-65-AF-D2-23"
        EAP-Message = 0x020500061500
        Message-Authenticator = 0x6fc6823020c04432d9acf82f0c9ee2c2
# Executing section authorize from file /etc/freeradius/sites-enabled/default
+group authorize {
++[preprocess] = ok
++[chap] = noop
++[mschap] = noop
++[digest] = noop
[suffix] No '@' in User-Name = "ssipwifi1", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] = noop
[eap] EAP packet type response id 5 length 6
[eap] Continuing tunnel setup.
++[eap] = ok
+} # group authorize = ok
Found Auth-Type = EAP
# Executing group from file /etc/freeradius/sites-enabled/default
+group authenticate {
[eap] Request found, released from the list
[eap] EAP/ttls
[eap] processing type ttls
[ttls] Authenticate
[ttls] processing EAP-TLS
[ttls] Received TLS ACK
[ttls] ACK handshake fragment handler
[ttls] eaptls_verify returned 1
[ttls] eaptls_process returned 13
++[eap] = handled
+} # group authenticate = handled
Sending Access-Challenge of id 226 to 192.168.109.194 port 49205
        EAP-Message = 0x0106040015c000000bf6d549ac5fff21be2705b1e145bcf7d7665b5b27b6cbc2c730f168805fca2b32356bac539c62e383a77d29c940ddd0757fc9cf07bd7978b8074973474e76dbea9adb2fd2db612de5c78098f30236cc501bb9980c3b798535b40a9660b5533a083b0882dea880d2899bd515a797b381be09d4c7174cc19492d683d11cd381b7f2cab27109b64e91d7ccccf9b58b3f80d2fcd44aa4dad8405c516663edbc4dfca0a8ee682635307b2f458290bbc430ea2975d31822a546ea4f87f645864750dd184169b44b0d02e93ac54ff225e6f4f74a579e230953832fc90092f10ba94efa2b070e67d44338a7fb3488a6bf56c7487216030103
        EAP-Message = 0x0d0c0003090100d22106c0a5b03700957f1cb02d3b9766efbb63576466492685ee475d6ed4c1565f1b3c2e0a5f8281c29398ad39093232179f2c838c3e413fa982c7906e1bbce2e8977a9f477656115b5059ba1ef44053ec2e86b9fc5d029992ae0acfe1a7895db3bb9436198319d28cfe44e1be3a5b67253956dd11543dd2c01cf1fbd68a19914c36fa76420c7032b5b8d4804b3641164bcbbf78241cd5a148a5748fb6b472d68051da53e660c513bb97e55511db9ac81c5b725a9e13a61a1da813cb8da5f7a2e5e7aab5e638ea3c64d995c6398ff1ba4b7b909b8ef7ac962a1c197e13f9a3af91c5789e7fa0480352cd88b224b12b3ce48a86478e97
        EAP-Message = 0xf06fc2ff6e318e8744eb0001020100c3e72471fecb556c93dd53d51acde1f3729dcae17952767bd763cb16293b64b6d0d65eed6799d4d66d360eb8f51195742183036ee7a1b181f4e019a995d29436bceef78c8d9453c57cc5bb3af8308c5407b60ab5670084894907d4280dd97b35aef727c77086f9633d2feca8cdede8eb5fd0818a7e837f474aa2e624abe54357653105501ae950e57f0bf00cd725b561636842f1d64261bf13e137c4e34df1111a06d6cd094435a6a365df538b8606ccef7373b4bbc75b18b0cd5c3ba94b5a3350df915d3b8473b5cc9f3f4ac33004cbfe9339fcb20d0f083a88df3933e8b61c32708947b20fa099db88abe16938
        EAP-Message = 0x7b835550d3ce2717c53f8937aa84fa764547010061532482fba13e2b8741883cd54c0254234beaaadff4e6cd9c29b0aed7afd8df3103da418cb71e4e290be7a2e373c31176d0dcd1f922dd3a7507a7b10adbc17bac6d1cd0999d5936703274856263c2c2d9d7d5430f319991ffe7c45c2b1383e8bb1c1da85d8faac14408affd9da642cb4a2db936ba18a711ea9ffd90154cb986e2dd444585787152e3cbb08133ce5bfd206ae3ff56b165026b394c4b20e4a17167030eaae2ffd7f42ade0bcee706f34a63220dd0a6f0a0e3825ca65daa6bb8bef72115a7b55a69812304f12374274353b570d0f1dcbe794e63f9e34b6bc965b3ed240edee6969000b8
        EAP-Message = 0x217354c3ccaf26f033f83583
        Message-Authenticator = 0x00000000000000000000000000000000
        State = 0xf3248093f72295e803792eac5869d440
Finished request 4.
Going to the next request
Waking up in 4.8 seconds.
rad_recv: Access-Request packet from host 192.168.109.194 port 49205, id=227, length=141
        NAS-IP-Address = 192.168.109.194
        NAS-Port-Type = Ethernet
        NAS-Port = 63
        User-Name = "ssipwifi1"
        Acct-Session-Id = "05001068"
        State = 0xf3248093f72295e803792eac5869d440
        Called-Station-Id = "00-87-31-4B-4A-D9"
        Calling-Station-Id = "00-15-65-AF-D2-23"
        EAP-Message = 0x020600061500
        Message-Authenticator = 0x4e3105a4d4c92d23c6f2ba0bb7cb7cd1
# Executing section authorize from file /etc/freeradius/sites-enabled/default
+group authorize {
++[preprocess] = ok
++[chap] = noop
++[mschap] = noop
++[digest] = noop
[suffix] No '@' in User-Name = "ssipwifi1", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] = noop
[eap] EAP packet type response id 6 length 6
[eap] Continuing tunnel setup.
++[eap] = ok
+} # group authorize = ok
Found Auth-Type = EAP
# Executing group from file /etc/freeradius/sites-enabled/default
+group authenticate {
[eap] Request found, released from the list
[eap] EAP/ttls
[eap] processing type ttls
[ttls] Authenticate
[ttls] processing EAP-TLS
[ttls] Received TLS ACK
[ttls] ACK handshake fragment handler
[ttls] eaptls_verify returned 1
[ttls] eaptls_process returned 13
++[eap] = handled
+} # group authenticate = handled
Sending Access-Challenge of id 227 to 192.168.109.194 port 49205
        EAP-Message = 0x0107001e158000000bf6538bc8711672573639644816030100040e000000
        Message-Authenticator = 0x00000000000000000000000000000000
        State = 0xf3248093f62395e803792eac5869d440
Finished request 5.
Going to the next request
Waking up in 4.7 seconds.
rad_recv: Access-Request packet from host 192.168.109.194 port 49205, id=228, length=148
        NAS-IP-Address = 192.168.109.194
        NAS-Port-Type = Ethernet
        NAS-Port = 63
        User-Name = "ssipwifi1"
        Acct-Session-Id = "05001068"
        State = 0xf3248093f62395e803792eac5869d440
        Called-Station-Id = "00-87-31-4B-4A-D9"
        Calling-Station-Id = "00-15-65-AF-D2-23"
        EAP-Message = 0x0207000d150015030100020233
        Message-Authenticator = 0xe509af21a3d2eb70c5d6faf28cda7bb1
# Executing section authorize from file /etc/freeradius/sites-enabled/default
+group authorize {
++[preprocess] = ok
++[chap] = noop
++[mschap] = noop
++[digest] = noop
[suffix] No '@' in User-Name = "ssipwifi1", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] = noop
[eap] EAP packet type response id 7 length 13
[eap] Continuing tunnel setup.
++[eap] = ok
+} # group authorize = ok
Found Auth-Type = EAP
# Executing group from file /etc/freeradius/sites-enabled/default
+group authenticate {
[eap] Request found, released from the list
[eap] EAP/ttls
[eap] processing type ttls
[ttls] Authenticate
[ttls] processing EAP-TLS
[ttls] eaptls_verify returned 7
[ttls] Done initial handshake
[ttls] <<< TLS 1.0 Alert [length 0002], fatal decrypt_error
TLS Alert read:fatal:decrypt error
    TLS_accept: failed in unknown state
rlm_eap: SSL error error:1409441B:SSL routines:ssl3_read_bytes:tlsv1 alert decrypt error
SSL: SSL_read failed inside of TLS (-1), TLS session fails.
TLS receive handshake failed during operation
[ttls] eaptls_process returned 4
[eap] Handler failed in EAP/ttls
[eap] Failed in EAP select
++[eap] = invalid
+} # group authenticate = invalid
Failed to authenticate the user.
Login incorrect (TLS Alert read:fatal:decrypt error): [ssipwifi1] (from client SwitchStack port 63 cli 00-15-65-AF-D2-23)
Using Post-Auth-Type Reject
# Executing group from file /etc/freeradius/sites-enabled/default
+group REJECT {
[attr_filter.access_reject]     expand: %{User-Name} -> ssipwifi1
attr_filter: Matched entry DEFAULT at line 11
++[attr_filter.access_reject] = updated
+} # group REJECT = updated
Delaying reject of request 6 for 1 seconds
Going to the next request
Waking up in 0.9 seconds.
Sending delayed reject for request 6
Sending Access-Reject of id 228 to 192.168.109.194 port 49205
        EAP-Message = 0x04070004
        Message-Authenticator = 0x00000000000000000000000000000000
Waking up in 3.7 seconds.