PEAP problems, never see an Access-Accept
Jorgen Rosink
jrosink at gmail.com
Fri Feb 3 01:08:42 CET 2006
Had a hard time to even start FreeRadius on my Debian Unstable system
with a working PEAP module (yes, I'm aware of OpenSSL licences and
eap_tls / eap_peap linking problems with Debian, _now_ ;-) ) I'm
currently using the 20060202-snapshot. With this version (also tried
20060130, same behaviour) I'm able to create PEAP enabled Debian
packages, after manually editing. the pcap section in the main
Makefile.
The problem now is that I'm trying to authenticate a default WindowsXP
SP2 supplicant (ipw2200 nic) with PEAP, mschapv2 and a HP ProCurve
520WL Access Point in 802.1x mode (latest firmware). Below my
FreeRadius startup and a attempt to authenticate, could someone please
point me in a direction what's going on, I've no clue what's wrong...
Also Google told me that the last line here isn't harmful :
rlm_eap_tls: >>> TLS 1.0 Handshake [length 0004], ServerHelloDone
TLS_accept: SSLv3 write server done A
TLS_accept: SSLv3 flush data
TLS_accept:error in SSLv3 read client certificate A
===================================================
Starting - reading configuration files ...
read_config_files: reading dictionary
Config: including file: /etc/freeradius/proxy.conf
Config: including file: /etc/freeradius/clients.conf
Config: including file: /etc/freeradius/snmp.conf
Config: including file: /etc/freeradius/eap.conf
Config: including file: /etc/freeradius/sql.conf
main: prefix = "/usr"
main: localstatedir = "/var"
main: logdir = "/var/log/freeradius"
main: libdir = "/usr/lib/freeradius"
main: radacctdir = "/var/log/freeradius/radacct"
main: hostname_lookups = no
main: snmp = no
main: max_request_time = 30
main: cleanup_delay = 5
main: max_requests = 1024
main: delete_blocked_requests = 0
main: allow_core_dumps = no
main: log_stripped_names = no
main: log_file = "/var/log/freeradius/radius.log"
main: log_auth = no
main: log_auth_badpass = no
main: log_auth_goodpass = no
main: pidfile = "/var/run/freeradius/freeradius.pid"
main: user = "freerad"
main: group = "freerad"
main: checkrad = "/usr/sbin/checkrad"
main: debug_level = 0
main: proxy_requests = yes
log: syslog_facility = "daemon"
proxy: retry_delay = 5
proxy: retry_count = 3
proxy: default_fallback = yes
proxy: dead_time = 120
proxy: wake_all_if_all_dead = no
security: max_attributes = 200
security: reject_delay = 1
security: status_server = no
read_config_files: reading realms
main: port = 1812
listen: type = "auth"
listen: ipaddr = *
listen: port = 0
listen: type = "acct"
listen: ipaddr = *
listen: port = 0
client: secret = "VerySecret"
client: shortname = "localhost"
client: nastype = "other"
client: secret = "VerySecret"
client: shortname = "AccessPoint"
radiusd: entering modules setup
Module: Library search path is /usr/lib/freeradius
Module: Loaded exec
exec: wait = yes
exec: input_pairs = "request"
exec: shell_escape = yes
rlm_exec: wait=yes but no output defined. Did you mean output=none?
Module: Instantiated exec (exec)
Module: Loaded expr
Module: Instantiated expr (expr)
Module: Loaded expiration
expiration: reply-message = "Password Has Expired "
Module: Instantiated expiration (expiration)
Module: Loaded logintime
logintime: reply-message = "You are calling outside your allowed timespan "
logintime: minimum-timeout = 60
Module: Instantiated logintime (logintime)
Module: Loaded PAP
pap: encryption_scheme = "auto"
pap: auto_header = no
Module: Instantiated pap (pap)
Module: Loaded CHAP
Module: Instantiated chap (chap)
Module: Loaded MS-CHAP
mschap: use_mppe = yes
mschap: require_encryption = yes
mschap: require_strong = yes
mschap: with_ntdomain_hack = no
mschap: authtype = "MS-CHAP"
Module: Instantiated mschap (mschap)
Module: Loaded System
unix: radwtmp = "/var/log/freeradius/radwtmp"
Module: Instantiated unix (unix)
Module: Loaded eap
eap: default_eap_type = "peap"
eap: timer_expire = 60
eap: ignore_unknown_eap_types = no
eap: cisco_accounting_username_bug = no
rlm_eap: Loaded and initialized type md5
rlm_eap: Loaded and initialized type leap
gtc: challenge = "Password: "
gtc: auth_type = "PAP"
rlm_eap: Loaded and initialized type gtc
tls: rsa_key_exchange = no
tls: dh_key_exchange = yes
tls: rsa_key_length = 512
tls: dh_key_length = 512
tls: verify_depth = 0
tls: pem_file_type = yes
tls: private_key_file = "/etc/freeradius/certs/example.key"
tls: certificate_file = "/etc/freeradius/certs/example.crt"
tls: CA_file = "/etc/ssl/certs/ca-example.pem"
tls: dh_file = "/etc/freeradius/certs/example.dh"
tls: random_file = "/dev/urandom"
tls: fragment_size = 1024
tls: include_length = yes
tls: check_crl = no
rlm_eap_tls: Loading the certificate file as a chain
rlm_eap: Loaded and initialized type tls
peap: default_eap_type = "mschapv2"
peap: copy_request_to_tunnel = no
peap: use_tunneled_reply = no
peap: proxy_tunneled_request_as_eap = yes
rlm_eap: Loaded and initialized type peap
mschapv2: with_ntdomain_hack = no
rlm_eap: Loaded and initialized type mschapv2
Module: Instantiated eap (eap)
Module: Loaded preprocess
preprocess: huntgroups = "/etc/freeradius/huntgroups"
preprocess: hints = "/etc/freeradius/hints"
preprocess: with_ascend_hack = no
preprocess: ascend_channels_per_line = 23
preprocess: with_ntdomain_hack = no
preprocess: with_specialix_jetstream_hack = no
preprocess: with_cisco_vsa_hack = no
Module: Instantiated preprocess (preprocess)
Module: Loaded realm
realm: format = "suffix"
realm: delimiter = "@"
realm: ignore_default = no
realm: ignore_null = no
Module: Instantiated realm (suffix)
Module: Loaded files
files: usersfile = "/etc/freeradius/users"
files: acctusersfile = "/etc/freeradius/acct_users"
files: preproxy_usersfile = "/etc/freeradius/preproxy_users"
files: compat = "no"
Module: Instantiated files (files)
Module: Loaded Acct-Unique-Session-Id
acct_unique: key = "User-Name, Acct-Session-Id, NAS-IP-Address,
Client-IP-Address, NAS-Port"
Module: Instantiated acct_unique (acct_unique)
Module: Loaded detail
detail: detailfile =
"/var/log/freeradius/radacct/%{Client-IP-Address}/detail-%Y%m%d"
detail: header = "%t"
detail: detailperm = 384
detail: dirperm = 493
detail: locking = no
Module: Instantiated detail (detail)
Module: Loaded radutmp
radutmp: filename = "/var/log/freeradius/radutmp"
radutmp: username = "%{User-Name}"
radutmp: case_sensitive = yes
radutmp: check_with_nas = yes
radutmp: perm = 384
radutmp: callerid = yes
Module: Instantiated radutmp (radutmp)
Initializing the thread pool...
Listening on authentication address * port 1812
Listening on accounting address * port 1813
Listening on proxy address * port 1814
Ready to process requests.
Nothing to do. Sleeping until we see a request.
================================================================
rad_recv: Access-Request packet from host 172.17.10.251 port 6001,
id=39, length=124
User-Name = "test"
NAS-IP-Address = 172.17.10.251
Called-Station-Id = "00-08-02-76-00-90;"
Calling-Station-Id = "00-0e-35-34-dd-3b"
NAS-Identifier = "AccessPoint"
Framed-MTU = 1400
NAS-Port-Type = Wireless-802.11
EAP-Message = 0x020100090174657374
Message-Authenticator = 0x0a0b124bc3aa323410bbcd011db21736
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 0
modcall[authorize]: module "preprocess" returns ok for request 0
modcall[authorize]: module "chap" returns noop for request 0
modcall[authorize]: module "mschap" returns noop for request 0
modcall[authorize]: module "unix" returns notfound for request 0
rlm_realm: No '@' in User-Name = "test", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix" returns noop for request 0
rlm_eap: EAP packet type response id 1 length 9
rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
modcall[authorize]: module "eap" returns updated for request 0
users: Matched entry test at line 71
modcall[authorize]: module "files" returns ok for request 0
modcall[authorize]: module "expiration" returns noop for request 0
modcall[authorize]: module "logintime" returns noop for request 0
rlm_pap: Found existing Auth-Type, not changing it.
modcall[authorize]: module "pap" returns noop for request 0
modcall: leaving group authorize (returns updated) for request 0
rad_check_password: Found Auth-Type EAP
auth: type "EAP"
Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 0
rlm_eap: EAP Identity
rlm_eap: processing type tls
rlm_eap_tls: Initiate
rlm_eap_tls: Start returned 1
modcall[authenticate]: module "eap" returns handled for request 0
modcall: leaving group authenticate (returns handled) for request 0
Sending Access-Challenge of id 39 to 172.17.10.251 port 6001
EAP-Message = 0x010200061920
Message-Authenticator = 0x00000000000000000000000000000000
State = 0x94610f5f14ece8a99c37e49bc6b1ce2a
Finished request 0
Going to the next request
--- Walking the entire request list ---
Waking up in 5 seconds...
rad_recv: Access-Request packet from host 172.17.10.251 port 6001,
id=40, length=213
User-Name = "test"
NAS-IP-Address = 172.17.10.251
Called-Station-Id = "00-08-02-76-00-90;"
Calling-Station-Id = "00-0e-35-34-dd-3b"
NAS-Identifier = "AccessPoint"
State = 0x94610f5f14ece8a99c37e49bc6b1ce2a
Framed-MTU = 1400
NAS-Port-Type = Wireless-802.11
EAP-Message =
0x0202005019800000004616030100410100003d030143e28e48e1b0cbcbfff3673946177ef43f9af64238acc0b5b047fdeb872fa3b200001600040005000a000900640062000300060013001200630100
Message-Authenticator = 0x1bc6f95ab0468d016eb825511eaf513a
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 1
modcall[authorize]: module "preprocess" returns ok for request 1
modcall[authorize]: module "chap" returns noop for request 1
modcall[authorize]: module "mschap" returns noop for request 1
modcall[authorize]: module "unix" returns notfound for request 1
rlm_realm: No '@' in User-Name = "test", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix" returns noop for request 1
rlm_eap: EAP packet type response id 2 length 80
rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
modcall[authorize]: module "eap" returns updated for request 1
users: Matched entry test at line 71
modcall[authorize]: module "files" returns ok for request 1
modcall[authorize]: module "expiration" returns noop for request 1
modcall[authorize]: module "logintime" returns noop for request 1
rlm_pap: Found existing Auth-Type, not changing it.
modcall[authorize]: module "pap" returns noop for request 1
modcall: leaving group authorize (returns updated) for request 1
rad_check_password: Found Auth-Type EAP
auth: type "EAP"
Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 1
rlm_eap: Request found, released from the list
rlm_eap: EAP/peap
rlm_eap: processing type peap
rlm_eap_peap: Authenticate
rlm_eap_tls: processing TLS
rlm_eap_tls: Length Included
eaptls_verify returned 11
(other): before/accept initialization
TLS_accept: before/accept initialization
rlm_eap_tls: <<< TLS 1.0 Handshake [length 0041], ClientHello
TLS_accept: SSLv3 read client hello A
rlm_eap_tls: >>> TLS 1.0 Handshake [length 004a], ServerHello
TLS_accept: SSLv3 write server hello A
rlm_eap_tls: >>> TLS 1.0 Handshake [length 09ae], Certificate
TLS_accept: SSLv3 write certificate A
rlm_eap_tls: >>> TLS 1.0 Handshake [length 0004], ServerHelloDone
TLS_accept: SSLv3 write server done A
TLS_accept: SSLv3 flush data
TLS_accept:error in SSLv3 read client certificate A
In SSL Handshake Phase
In SSL Accept mode
eaptls_process returned 13
rlm_eap_peap: EAPTLS_HANDLED
modcall[authenticate]: module "eap" returns handled for request 1
modcall: leaving group authenticate (returns handled) for request 1
Sending Access-Challenge of id 40 to 172.17.10.251 port 6001
EAP-Message =
0x0103040a19c000000a0b160301004a02000046030143e28e4fa3e533156bf17d077dd383df77a4b0c6dd0b0ea554c81025ba87265e20987e09a118d7d4643e14a9a4d32eb9311dfad012d1275e3a43c5de640e40db6b00040016030109ae0b0009aa0009a70004ed308204e9308203d1a003020102020101300d06092a864886f70d0101040500308196310b3009060355040613024e4c310b3009060355040813025a483112301006035504071309526f7474657264616d31173015060355040a130e526f73696e6b20646f74204e65743111300f060355040b13085365637572697479311a301806035504031311526f73696e6b20646f74204e6574
EAP-Message =
0x204341311e301c06092a864886f70d010901160f696e666f40726f73696e6b2e6e6574301e170d3036303132393037343732305a170d3136303132373037343732305a30819d310b3009060355040613024e4c310b3009060355040813025a483112301006035504071309526f7474657264616d31173015060355040a130e526f73696e6b20646f74204e65743111300f060355040b130853656375726974793121301f06035504031318686f6f6664646f6f732e6c616e2e726f73696e6b2e6e6574311e301c06092a864886f70d010901160f696e666f40726f73696e6b2e6e657430820122300d06092a864886f70d01010105000382010f003082
EAP-Message =
0x010a0282010100c331b85436fc16272576c3cf056710891c6cd8ad3630cae76f558ff147de6a5124439061753006b6371deec022b92d3829dbdce53e6844e883e5c5d0e8a7330200c73a579a1a3479ab439ce5c4d48931b9b723c83f3d543e1e24b22b027f388d94b1b323b14bb5dbb384c560563a278b28e2d8e2a352e8ec68cc6e9cf1ff4fa75114d7dd69d2752e3be06eb5f30a01aa6a2fbd2d34ce2abee138f37c709866746d25baf2cd1266239ad0f6704b1ca3a64c43befbda5f6d2e391f7413c21dedeaecee9def8565483e300e31718fbf57b9af813c234d0e51515ab33615ce7a08d00d94806578409e96ca33857ab8b280151e1d6b0f9ad7
EAP-Message =
0xe5943c4bf705839f7da70203010001a38201373082013330090603551d1304023000301106096086480186f8420101040403020640302606096086480186f842010d041916174f70656e53534c20536572766572205365637572697479301d0603551d0e04160414ec2a249c5f57bbf34e79d270fff5abfe1463cb0f3081cb0603551d230481c33081c080148a5c01ab1c1801ee324a8c09b176ac94fe65c433a1819ca48199308196310b3009060355040613024e4c310b3009060355040813025a483112301006035504071309526f7474657264616d31173015060355040a130e526f73696e6b20646f74204e65743111300f060355040b13085365
EAP-Message = 0x637572697479311a301806035504031311526f73696e
Message-Authenticator = 0x00000000000000000000000000000000
State = 0x25410c5b38ac7fca242181fd9fe7bb91
Finished request 1
Going to the next request
--- Walking the entire request list ---
Waking up in 4 seconds...
rad_recv: Access-Request packet from host 172.17.10.251 port 6001,
id=41, length=139
User-Name = "test"
NAS-IP-Address = 172.17.10.251
Called-Station-Id = "00-08-02-76-00-90;"
Calling-Station-Id = "00-0e-35-34-dd-3b"
NAS-Identifier = "AccessPoint"
State = 0x25410c5b38ac7fca242181fd9fe7bb91
Framed-MTU = 1400
NAS-Port-Type = Wireless-802.11
EAP-Message = 0x020300061900
Message-Authenticator = 0x3356877f603457a2d74f2342d5cc3384
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 2
modcall[authorize]: module "preprocess" returns ok for request 2
modcall[authorize]: module "chap" returns noop for request 2
modcall[authorize]: module "mschap" returns noop for request 2
modcall[authorize]: module "unix" returns notfound for request 2
rlm_realm: No '@' in User-Name = "test", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix" returns noop for request 2
rlm_eap: EAP packet type response id 3 length 6
rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
modcall[authorize]: module "eap" returns updated for request 2
users: Matched entry test at line 71
modcall[authorize]: module "files" returns ok for request 2
modcall[authorize]: module "expiration" returns noop for request 2
modcall[authorize]: module "logintime" returns noop for request 2
rlm_pap: Found existing Auth-Type, not changing it.
modcall[authorize]: module "pap" returns noop for request 2
modcall: leaving group authorize (returns updated) for request 2
rad_check_password: Found Auth-Type EAP
auth: type "EAP"
Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 2
rlm_eap: Request found, released from the list
rlm_eap: EAP/peap
rlm_eap: processing type peap
rlm_eap_peap: Authenticate
rlm_eap_tls: processing TLS
rlm_eap_tls: Received EAP-TLS ACK message
rlm_eap_tls: ack handshake fragment handler
eaptls_verify returned 1
eaptls_process returned 13
rlm_eap_peap: EAPTLS_HANDLED
modcall[authenticate]: module "eap" returns handled for request 2
modcall: leaving group authenticate (returns handled) for request 2
Sending Access-Challenge of id 41 to 172.17.10.251 port 6001
EAP-Message =
0x0104040619406b20646f74204e6574204341311e301c06092a864886f70d010901160f696e666f40726f73696e6b2e6e6574820900e1a1176971c7d6ef300d06092a864886f70d01010405000382010100936a55c46e4232e60ac99c2a3e0a41f7294acf42c671e57a2b290c81fc780cbc1672550ed0b2075b5c3fb829397de0b292481e59cd3dec251949ecf0da075c2f35c71dbaa17100be339eae3f1ac867f560d6a6bad30ee12ec014c7fb196d9f92db57e26231c56e868f0962ae11b0c6e8198c446769f35b011cdd627721b7a9e03b9bc873e415f3083a5d36a6d504c10fcc6192751e4112d3bf40110ec56a788112e85c3d1a7734be3ecaeb1a
EAP-Message =
0xde5794cd97ab9c48d64d071d116050923ebbe4251dba7f269b224736c1a621158a1cc0f2edd2f6066b9263be575e9e006f553695596368fe3b03e184c416971d384a4d1c5d5085a6efb0fbb0f4c0bfeaa259e2730004b4308204b030820398a003020102020900e1a1176971c7d6ef300d06092a864886f70d0101050500308196310b3009060355040613024e4c310b3009060355040813025a483112301006035504071309526f7474657264616d31173015060355040a130e526f73696e6b20646f74204e65743111300f060355040b13085365637572697479311a301806035504031311526f73696e6b20646f74204e6574204341311e301c0609
EAP-Message =
0x2a864886f70d010901160f696e666f40726f73696e6b2e6e6574301e170d3036303132393037343730375a170d3136303132373037343730375a308196310b3009060355040613024e4c310b3009060355040813025a483112301006035504071309526f7474657264616d31173015060355040a130e526f73696e6b20646f74204e65743111300f060355040b13085365637572697479311a301806035504031311526f73696e6b20646f74204e6574204341311e301c06092a864886f70d010901160f696e666f40726f73696e6b2e6e657430820122300d06092a864886f70d01010105000382010f003082010a0282010100bf7c0ba29a35022161
EAP-Message =
0xd2546491864131250e741334a9f18212353b35ace6cb1281968d3ec7f6ab88048de1b0f3265fc0a714e2adb1394952606810e13edd6eb6bd24b70a1232338783af60efcc1ec7a363f8e1b63ea57551a232b113a8930f52bcc7ac3c4eabdfbdd800489405de961210a7b210b4d1e75392aac8154b5d778415d7c0ccff812bb6bd43f7dc0f525543d5720c772bfcba21684d595346409a4d0705e2fbc84adaefa80efc6b38746e52f33c1cc823ba590ba1e17edf2443d7a5eac959d45a0c935990b0cb191a1ec7316eb39a9e1fc78f80fe573c8ebff4b5740f201fc8a5fe22e764f495489816cb9367e781b5ced688d591d6cf68370cd5e10203010001a3
EAP-Message = 0x81fe3081fb301d0603551d0e041604148a5c
Message-Authenticator = 0x00000000000000000000000000000000
State = 0xbb987803b65fb7f9b0c99ae0443b8f73
Finished request 2
Going to the next request
Waking up in 4 seconds...
rad_recv: Access-Request packet from host 172.17.10.251 port 6001,
id=42, length=139
User-Name = "test"
NAS-IP-Address = 172.17.10.251
Called-Station-Id = "00-08-02-76-00-90;"
Calling-Station-Id = "00-0e-35-34-dd-3b"
NAS-Identifier = "AccessPoint"
State = 0xbb987803b65fb7f9b0c99ae0443b8f73
Framed-MTU = 1400
NAS-Port-Type = Wireless-802.11
EAP-Message = 0x020400061900
Message-Authenticator = 0x91246da75dc4e2c5333731902452ebaf
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 3
modcall[authorize]: module "preprocess" returns ok for request 3
modcall[authorize]: module "chap" returns noop for request 3
modcall[authorize]: module "mschap" returns noop for request 3
modcall[authorize]: module "unix" returns notfound for request 3
rlm_realm: No '@' in User-Name = "test", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix" returns noop for request 3
rlm_eap: EAP packet type response id 4 length 6
rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
modcall[authorize]: module "eap" returns updated for request 3
users: Matched entry test at line 71
modcall[authorize]: module "files" returns ok for request 3
modcall[authorize]: module "expiration" returns noop for request 3
modcall[authorize]: module "logintime" returns noop for request 3
rlm_pap: Found existing Auth-Type, not changing it.
modcall[authorize]: module "pap" returns noop for request 3
modcall: leaving group authorize (returns updated) for request 3
rad_check_password: Found Auth-Type EAP
auth: type "EAP"
Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 3
rlm_eap: Request found, released from the list
rlm_eap: EAP/peap
rlm_eap: processing type peap
rlm_eap_peap: Authenticate
rlm_eap_tls: processing TLS
rlm_eap_tls: Received EAP-TLS ACK message
rlm_eap_tls: ack handshake fragment handler
eaptls_verify returned 1
eaptls_process returned 13
rlm_eap_peap: EAPTLS_HANDLED
modcall[authenticate]: module "eap" returns handled for request 3
modcall: leaving group authenticate (returns handled) for request 3
Sending Access-Challenge of id 42 to 172.17.10.251 port 6001
EAP-Message =
0x01050211190001ab1c1801ee324a8c09b176ac94fe65c4333081cb0603551d230481c33081c080148a5c01ab1c1801ee324a8c09b176ac94fe65c433a1819ca48199308196310b3009060355040613024e4c310b3009060355040813025a483112301006035504071309526f7474657264616d31173015060355040a130e526f73696e6b20646f74204e65743111300f060355040b13085365637572697479311a301806035504031311526f73696e6b20646f74204e6574204341311e301c06092a864886f70d010901160f696e666f40726f73696e6b2e6e6574820900e1a1176971c7d6ef300c0603551d13040530030101ff300d06092a864886f7
EAP-Message =
0x0d010105050003820101008b539267e12479731db7c4353f6993462f3b0432a4db800e760bf22f4764cce2ad0462ecb6ab73dd3a0f07d7b695d53c9008a7fab743091e3c977ed5cc8f2f9f1e5e65248f29d419525e0e2c4afaa3649390b988c9157f7ecae4f8db85a892eb45d66cbf628cf048ff089015fac65d4a0252a165fc7b9eeea913110c55d70d0d65de8bd84357912d0c44675e4cdb55381e444248e1867f2c044e9d837857460720d184f54f0e7bc2f127268aeb7d01c6d18dcab21303d6f2204dd019ff32c9a28a51ec9f4213f6171a2f427802c9b25f229b3733c26be49aad2899079018aa8e2502650f1b09d1e78a04d4ce9f6b0d8718a5
EAP-Message = 0xcc351115abed2bc668813f4e403616030100040e000000
Message-Authenticator = 0x00000000000000000000000000000000
State = 0x5cc823fc860e51e2a5955798d4b45193
Finished request 3
Going to the next request
Waking up in 4 seconds...
rad_recv: Access-Request packet from host 172.17.10.251 port 6001,
id=43, length=139
User-Name = "test"
NAS-IP-Address = 172.17.10.251
Called-Station-Id = "00-08-02-76-00-90;"
Calling-Station-Id = "00-0e-35-34-dd-3b"
NAS-Identifier = "AccessPoint"
State = 0x5cc823fc860e51e2a5955798d4b45193
Framed-MTU = 1400
NAS-Port-Type = Wireless-802.11
EAP-Message = 0x020500061900
Message-Authenticator = 0xd39e91b6065810827896087e5df1d0a5
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 4
modcall[authorize]: module "preprocess" returns ok for request 4
modcall[authorize]: module "chap" returns noop for request 4
modcall[authorize]: module "mschap" returns noop for request 4
modcall[authorize]: module "unix" returns notfound for request 4
rlm_realm: No '@' in User-Name = "test", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix" returns noop for request 4
rlm_eap: EAP packet type response id 5 length 6
rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
modcall[authorize]: module "eap" returns updated for request 4
users: Matched entry test at line 71
modcall[authorize]: module "files" returns ok for request 4
modcall[authorize]: module "expiration" returns noop for request 4
modcall[authorize]: module "logintime" returns noop for request 4
rlm_pap: Found existing Auth-Type, not changing it.
modcall[authorize]: module "pap" returns noop for request 4
modcall: leaving group authorize (returns updated) for request 4
rad_check_password: Found Auth-Type EAP
auth: type "EAP"
Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 4
rlm_eap: Request found, released from the list
rlm_eap: EAP/peap
rlm_eap: processing type peap
rlm_eap_peap: Authenticate
rlm_eap_tls: processing TLS
rlm_eap_tls: Received EAP-TLS ACK message
rlm_eap_tls: ack handshake fragment handler
eaptls_verify returned 1
eaptls_process returned 13
rlm_eap_peap: EAPTLS_HANDLED
modcall[authenticate]: module "eap" returns handled for request 4
modcall: leaving group authenticate (returns handled) for request 4
Sending Access-Challenge of id 43 to 172.17.10.251 port 6001
EAP-Message = 0x010600061900
Message-Authenticator = 0x00000000000000000000000000000000
State = 0x6610d79fd88d12d735d46c4a3a66afc8
Finished request 4
Going to the next request
Waking up in 4 seconds...
--- Walking the entire request list ---
Cleaning up request 0 ID 39 with timestamp 43e28e4e
Waking up in 1 seconds...
--- Walking the entire request list ---
Cleaning up request 1 ID 40 with timestamp 43e28e4f
Cleaning up request 3 ID 42 with timestamp 43e28e4f
Cleaning up request 2 ID 41 with timestamp 43e28e4f
Cleaning up request 4 ID 43 with timestamp 43e28e4f
Nothing to do. Sleeping until we see a request.
rad_recv: Access-Request packet from host 172.17.10.251 port 6001,
id=45, length=124
User-Name = "test"
NAS-IP-Address = 172.17.10.251
Called-Station-Id = "00-08-02-76-00-90;"
Calling-Station-Id = "00-0e-35-34-dd-3b"
NAS-Identifier = "AccessPoint"
Framed-MTU = 1400
NAS-Port-Type = Wireless-802.11
EAP-Message = 0x020300090174657374
Message-Authenticator = 0x68a441a1bd3e96023c83cb4ce0c1b520
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 6
modcall[authorize]: module "preprocess" returns ok for request 6
modcall[authorize]: module "chap" returns noop for request 6
modcall[authorize]: module "mschap" returns noop for request 6
modcall[authorize]: module "unix" returns notfound for request 6
rlm_realm: No '@' in User-Name = "test", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix" returns noop for request 6
rlm_eap: EAP packet type response id 3 length 9
rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
modcall[authorize]: module "eap" returns updated for request 6
users: Matched entry test at line 71
modcall[authorize]: module "files" returns ok for request 6
modcall[authorize]: module "expiration" returns noop for request 6
modcall[authorize]: module "logintime" returns noop for request 6
rlm_pap: Found existing Auth-Type, not changing it.
modcall[authorize]: module "pap" returns noop for request 6
modcall: leaving group authorize (returns updated) for request 6
rad_check_password: Found Auth-Type EAP
auth: type "EAP"
Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 6
rlm_eap: EAP Identity
rlm_eap: processing type tls
rlm_eap_tls: Initiate
rlm_eap_tls: Start returned 1
modcall[authenticate]: module "eap" returns handled for request 6
modcall: leaving group authenticate (returns handled) for request 6
Sending Access-Challenge of id 45 to 172.17.10.251 port 6001
EAP-Message = 0x010400061920
Message-Authenticator = 0x00000000000000000000000000000000
State = 0x0727aab76a626cffb7e315efabe50725
Finished request 6
Going to the next request
--- Walking the entire request list ---
Waking up in 3 seconds...
rad_recv: Access-Request packet from host 172.17.10.251 port 6001,
id=46, length=213
User-Name = "test"
NAS-IP-Address = 172.17.10.251
Called-Station-Id = "00-08-02-76-00-90;"
Calling-Station-Id = "00-0e-35-34-dd-3b"
NAS-Identifier = "AccessPoint"
State = 0x0727aab76a626cffb7e315efabe50725
Framed-MTU = 1400
NAS-Port-Type = Wireless-802.11
EAP-Message =
0x0204005019800000004616030100410100003d030143e2941081bdb23e8dab82da1809bc9a7236815ffe1a73f1d377090e4840ea6a00001600040005000a000900640062000300060013001200630100
Message-Authenticator = 0xa46d8e575955fb0b44916f31b12a333c
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 7
modcall[authorize]: module "preprocess" returns ok for request 7
modcall[authorize]: module "chap" returns noop for request 7
modcall[authorize]: module "mschap" returns noop for request 7
modcall[authorize]: module "unix" returns notfound for request 7
rlm_realm: No '@' in User-Name = "test", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix" returns noop for request 7
rlm_eap: EAP packet type response id 4 length 80
rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
modcall[authorize]: module "eap" returns updated for request 7
users: Matched entry test at line 71
modcall[authorize]: module "files" returns ok for request 7
modcall[authorize]: module "expiration" returns noop for request 7
modcall[authorize]: module "logintime" returns noop for request 7
rlm_pap: Found existing Auth-Type, not changing it.
modcall[authorize]: module "pap" returns noop for request 7
modcall: leaving group authorize (returns updated) for request 7
rad_check_password: Found Auth-Type EAP
auth: type "EAP"
Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 7
rlm_eap: Request found, released from the list
rlm_eap: EAP/peap
rlm_eap: processing type peap
rlm_eap_peap: Authenticate
rlm_eap_tls: processing TLS
rlm_eap_tls: Length Included
eaptls_verify returned 11
(other): before/accept initialization
TLS_accept: before/accept initialization
rlm_eap_tls: <<< TLS 1.0 Handshake [length 0041], ClientHello
TLS_accept: SSLv3 read client hello A
rlm_eap_tls: >>> TLS 1.0 Handshake [length 004a], ServerHello
TLS_accept: SSLv3 write server hello A
rlm_eap_tls: >>> TLS 1.0 Handshake [length 09ae], Certificate
TLS_accept: SSLv3 write certificate A
rlm_eap_tls: >>> TLS 1.0 Handshake [length 0004], ServerHelloDone
TLS_accept: SSLv3 write server done A
TLS_accept: SSLv3 flush data
TLS_accept:error in SSLv3 read client certificate A
In SSL Handshake Phase
In SSL Accept mode
eaptls_process returned 13
rlm_eap_peap: EAPTLS_HANDLED
modcall[authenticate]: module "eap" returns handled for request 7
modcall: leaving group authenticate (returns handled) for request 7
Sending Access-Challenge of id 46 to 172.17.10.251 port 6001
EAP-Message =
0x0105040a19c000000a0b160301004a02000046030143e2941665928a65ebd19f033bd7d12a6bf54e686c74f7b88de6bfd09ca63cfc20265d3c04973659ee90df12a1252e624eefc45477ba0ac6e2a92fe85c86c3511f00040016030109ae0b0009aa0009a70004ed308204e9308203d1a003020102020101300d06092a864886f70d0101040500308196310b3009060355040613024e4c310b3009060355040813025a483112301006035504071309526f7474657264616d31173015060355040a130e526f73696e6b20646f74204e65743111300f060355040b13085365637572697479311a301806035504031311526f73696e6b20646f74204e6574
EAP-Message =
0x204341311e301c06092a864886f70d010901160f696e666f40726f73696e6b2e6e6574301e170d3036303132393037343732305a170d3136303132373037343732305a30819d310b3009060355040613024e4c310b3009060355040813025a483112301006035504071309526f7474657264616d31173015060355040a130e526f73696e6b20646f74204e65743111300f060355040b130853656375726974793121301f06035504031318686f6f6664646f6f732e6c616e2e726f73696e6b2e6e6574311e301c06092a864886f70d010901160f696e666f40726f73696e6b2e6e657430820122300d06092a864886f70d01010105000382010f003082
EAP-Message =
0x010a0282010100c331b85436fc16272576c3cf056710891c6cd8ad3630cae76f558ff147de6a5124439061753006b6371deec022b92d3829dbdce53e6844e883e5c5d0e8a7330200c73a579a1a3479ab439ce5c4d48931b9b723c83f3d543e1e24b22b027f388d94b1b323b14bb5dbb384c560563a278b28e2d8e2a352e8ec68cc6e9cf1ff4fa75114d7dd69d2752e3be06eb5f30a01aa6a2fbd2d34ce2abee138f37c709866746d25baf2cd1266239ad0f6704b1ca3a64c43befbda5f6d2e391f7413c21dedeaecee9def8565483e300e31718fbf57b9af813c234d0e51515ab33615ce7a08d00d94806578409e96ca33857ab8b280151e1d6b0f9ad7
EAP-Message =
0xe5943c4bf705839f7da70203010001a38201373082013330090603551d1304023000301106096086480186f8420101040403020640302606096086480186f842010d041916174f70656e53534c20536572766572205365637572697479301d0603551d0e04160414ec2a249c5f57bbf34e79d270fff5abfe1463cb0f3081cb0603551d230481c33081c080148a5c01ab1c1801ee324a8c09b176ac94fe65c433a1819ca48199308196310b3009060355040613024e4c310b3009060355040813025a483112301006035504071309526f7474657264616d31173015060355040a130e526f73696e6b20646f74204e65743111300f060355040b13085365
EAP-Message = 0x637572697479311a301806035504031311526f73696e
Message-Authenticator = 0x00000000000000000000000000000000
State = 0x256fa9fa8661c4589ec569f6d8292b0b
Finished request 7
Going to the next request
Waking up in 3 seconds...
rad_recv: Access-Request packet from host 172.17.10.251 port 6001,
id=47, length=139
User-Name = "test"
NAS-IP-Address = 172.17.10.251
Called-Station-Id = "00-08-02-76-00-90;"
Calling-Station-Id = "00-0e-35-34-dd-3b"
NAS-Identifier = "AccessPoint"
State = 0x256fa9fa8661c4589ec569f6d8292b0b
Framed-MTU = 1400
NAS-Port-Type = Wireless-802.11
EAP-Message = 0x020500061900
Message-Authenticator = 0x396b5797c6d4c74a892e64587bf31920
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 8
modcall[authorize]: module "preprocess" returns ok for request 8
modcall[authorize]: module "chap" returns noop for request 8
modcall[authorize]: module "mschap" returns noop for request 8
modcall[authorize]: module "unix" returns notfound for request 8
rlm_realm: No '@' in User-Name = "test", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix" returns noop for request 8
rlm_eap: EAP packet type response id 5 length 6
rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
modcall[authorize]: module "eap" returns updated for request 8
users: Matched entry test at line 71
modcall[authorize]: module "files" returns ok for request 8
modcall[authorize]: module "expiration" returns noop for request 8
modcall[authorize]: module "logintime" returns noop for request 8
rlm_pap: Found existing Auth-Type, not changing it.
modcall[authorize]: module "pap" returns noop for request 8
modcall: leaving group authorize (returns updated) for request 8
rad_check_password: Found Auth-Type EAP
auth: type "EAP"
Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 8
rlm_eap: Request found, released from the list
rlm_eap: EAP/peap
rlm_eap: processing type peap
rlm_eap_peap: Authenticate
rlm_eap_tls: processing TLS
rlm_eap_tls: Received EAP-TLS ACK message
rlm_eap_tls: ack handshake fragment handler
eaptls_verify returned 1
eaptls_process returned 13
rlm_eap_peap: EAPTLS_HANDLED
modcall[authenticate]: module "eap" returns handled for request 8
modcall: leaving group authenticate (returns handled) for request 8
Sending Access-Challenge of id 47 to 172.17.10.251 port 6001
EAP-Message =
0x0106040619406b20646f74204e6574204341311e301c06092a864886f70d010901160f696e666f40726f73696e6b2e6e6574820900e1a1176971c7d6ef300d06092a864886f70d01010405000382010100936a55c46e4232e60ac99c2a3e0a41f7294acf42c671e57a2b290c81fc780cbc1672550ed0b2075b5c3fb829397de0b292481e59cd3dec251949ecf0da075c2f35c71dbaa17100be339eae3f1ac867f560d6a6bad30ee12ec014c7fb196d9f92db57e26231c56e868f0962ae11b0c6e8198c446769f35b011cdd627721b7a9e03b9bc873e415f3083a5d36a6d504c10fcc6192751e4112d3bf40110ec56a788112e85c3d1a7734be3ecaeb1a
EAP-Message =
0xde5794cd97ab9c48d64d071d116050923ebbe4251dba7f269b224736c1a621158a1cc0f2edd2f6066b9263be575e9e006f553695596368fe3b03e184c416971d384a4d1c5d5085a6efb0fbb0f4c0bfeaa259e2730004b4308204b030820398a003020102020900e1a1176971c7d6ef300d06092a864886f70d0101050500308196310b3009060355040613024e4c310b3009060355040813025a483112301006035504071309526f7474657264616d31173015060355040a130e526f73696e6b20646f74204e65743111300f060355040b13085365637572697479311a301806035504031311526f73696e6b20646f74204e6574204341311e301c0609
EAP-Message =
0x2a864886f70d010901160f696e666f40726f73696e6b2e6e6574301e170d3036303132393037343730375a170d3136303132373037343730375a308196310b3009060355040613024e4c310b3009060355040813025a483112301006035504071309526f7474657264616d31173015060355040a130e526f73696e6b20646f74204e65743111300f060355040b13085365637572697479311a301806035504031311526f73696e6b20646f74204e6574204341311e301c06092a864886f70d010901160f696e666f40726f73696e6b2e6e657430820122300d06092a864886f70d01010105000382010f003082010a0282010100bf7c0ba29a35022161
EAP-Message =
0xd2546491864131250e741334a9f18212353b35ace6cb1281968d3ec7f6ab88048de1b0f3265fc0a714e2adb1394952606810e13edd6eb6bd24b70a1232338783af60efcc1ec7a363f8e1b63ea57551a232b113a8930f52bcc7ac3c4eabdfbdd800489405de961210a7b210b4d1e75392aac8154b5d778415d7c0ccff812bb6bd43f7dc0f525543d5720c772bfcba21684d595346409a4d0705e2fbc84adaefa80efc6b38746e52f33c1cc823ba590ba1e17edf2443d7a5eac959d45a0c935990b0cb191a1ec7316eb39a9e1fc78f80fe573c8ebff4b5740f201fc8a5fe22e764f495489816cb9367e781b5ced688d591d6cf68370cd5e10203010001a3
EAP-Message = 0x81fe3081fb301d0603551d0e041604148a5c
Message-Authenticator = 0x00000000000000000000000000000000
State = 0xcb8a8fb8a929b76ebdac65a87b1f7753
Finished request 8
Going to the next request
Waking up in 3 seconds...
rad_recv: Access-Request packet from host 172.17.10.251 port 6001,
id=48, length=139
User-Name = "test"
NAS-IP-Address = 172.17.10.251
Called-Station-Id = "00-08-02-76-00-90;"
Calling-Station-Id = "00-0e-35-34-dd-3b"
NAS-Identifier = "AccessPoint"
State = 0xcb8a8fb8a929b76ebdac65a87b1f7753
Framed-MTU = 1400
NAS-Port-Type = Wireless-802.11
EAP-Message = 0x020600061900
Message-Authenticator = 0x3e9078ef884941e52695cb8531567ed1
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 9
modcall[authorize]: module "preprocess" returns ok for request 9
modcall[authorize]: module "chap" returns noop for request 9
modcall[authorize]: module "mschap" returns noop for request 9
modcall[authorize]: module "unix" returns notfound for request 9
rlm_realm: No '@' in User-Name = "test", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix" returns noop for request 9
rlm_eap: EAP packet type response id 6 length 6
rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
modcall[authorize]: module "eap" returns updated for request 9
users: Matched entry test at line 71
modcall[authorize]: module "files" returns ok for request 9
modcall[authorize]: module "expiration" returns noop for request 9
modcall[authorize]: module "logintime" returns noop for request 9
rlm_pap: Found existing Auth-Type, not changing it.
modcall[authorize]: module "pap" returns noop for request 9
modcall: leaving group authorize (returns updated) for request 9
rad_check_password: Found Auth-Type EAP
auth: type "EAP"
Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 9
rlm_eap: Request found, released from the list
rlm_eap: EAP/peap
rlm_eap: processing type peap
rlm_eap_peap: Authenticate
rlm_eap_tls: processing TLS
rlm_eap_tls: Received EAP-TLS ACK message
rlm_eap_tls: ack handshake fragment handler
eaptls_verify returned 1
eaptls_process returned 13
rlm_eap_peap: EAPTLS_HANDLED
modcall[authenticate]: module "eap" returns handled for request 9
modcall: leaving group authenticate (returns handled) for request 9
Sending Access-Challenge of id 48 to 172.17.10.251 port 6001
EAP-Message =
0x01070211190001ab1c1801ee324a8c09b176ac94fe65c4333081cb0603551d230481c33081c080148a5c01ab1c1801ee324a8c09b176ac94fe65c433a1819ca48199308196310b3009060355040613024e4c310b3009060355040813025a483112301006035504071309526f7474657264616d31173015060355040a130e526f73696e6b20646f74204e65743111300f060355040b13085365637572697479311a301806035504031311526f73696e6b20646f74204e6574204341311e301c06092a864886f70d010901160f696e666f40726f73696e6b2e6e6574820900e1a1176971c7d6ef300c0603551d13040530030101ff300d06092a864886f7
EAP-Message =
0x0d010105050003820101008b539267e12479731db7c4353f6993462f3b0432a4db800e760bf22f4764cce2ad0462ecb6ab73dd3a0f07d7b695d53c9008a7fab743091e3c977ed5cc8f2f9f1e5e65248f29d419525e0e2c4afaa3649390b988c9157f7ecae4f8db85a892eb45d66cbf628cf048ff089015fac65d4a0252a165fc7b9eeea913110c55d70d0d65de8bd84357912d0c44675e4cdb55381e444248e1867f2c044e9d837857460720d184f54f0e7bc2f127268aeb7d01c6d18dcab21303d6f2204dd019ff32c9a28a51ec9f4213f6171a2f427802c9b25f229b3733c26be49aad2899079018aa8e2502650f1b09d1e78a04d4ce9f6b0d8718a5
EAP-Message = 0xcc351115abed2bc668813f4e403616030100040e000000
Message-Authenticator = 0x00000000000000000000000000000000
State = 0x17ac3f7c07a1e6664f16e4f442042b30
Finished request 9
Going to the next request
Waking up in 3 seconds...
rad_recv: Access-Request packet from host 172.17.10.251 port 6001,
id=49, length=139
User-Name = "test"
NAS-IP-Address = 172.17.10.251
Called-Station-Id = "00-08-02-76-00-90;"
Calling-Station-Id = "00-0e-35-34-dd-3b"
NAS-Identifier = "AccessPoint"
State = 0x17ac3f7c07a1e6664f16e4f442042b30
Framed-MTU = 1400
NAS-Port-Type = Wireless-802.11
EAP-Message = 0x020700061900
Message-Authenticator = 0xa1f601a8731d7b6e2f58a54a12161cc8
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 10
modcall[authorize]: module "preprocess" returns ok for request 10
modcall[authorize]: module "chap" returns noop for request 10
modcall[authorize]: module "mschap" returns noop for request 10
modcall[authorize]: module "unix" returns notfound for request 10
rlm_realm: No '@' in User-Name = "test", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix" returns noop for request 10
rlm_eap: EAP packet type response id 7 length 6
rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
modcall[authorize]: module "eap" returns updated for request 10
users: Matched entry test at line 71
modcall[authorize]: module "files" returns ok for request 10
modcall[authorize]: module "expiration" returns noop for request 10
modcall[authorize]: module "logintime" returns noop for request 10
rlm_pap: Found existing Auth-Type, not changing it.
modcall[authorize]: module "pap" returns noop for request 10
modcall: leaving group authorize (returns updated) for request 10
rad_check_password: Found Auth-Type EAP
auth: type "EAP"
Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 10
rlm_eap: Request found, released from the list
rlm_eap: EAP/peap
rlm_eap: processing type peap
rlm_eap_peap: Authenticate
rlm_eap_tls: processing TLS
rlm_eap_tls: Received EAP-TLS ACK message
rlm_eap_tls: ack handshake fragment handler
eaptls_verify returned 1
eaptls_process returned 13
rlm_eap_peap: EAPTLS_HANDLED
modcall[authenticate]: module "eap" returns handled for request 10
modcall: leaving group authenticate (returns handled) for request 10
Sending Access-Challenge of id 49 to 172.17.10.251 port 6001
EAP-Message = 0x010800061900
Message-Authenticator = 0x00000000000000000000000000000000
State = 0x6d583820eec8338593117c0e78b0012a
Finished request 10
Going to the next request
--- Walking the entire request list ---
Waking up in 2 seconds...
--- Walking the entire request list ---
Cleaning up request 5 ID 44 with timestamp 43e29414
Waking up in 2 seconds...
--- Walking the entire request list ---
Cleaning up request 9 ID 48 with timestamp 43e29416
Cleaning up request 7 ID 46 with timestamp 43e29416
Cleaning up request 6 ID 45 with timestamp 43e29416
Cleaning up request 8 ID 47 with timestamp 43e29416
Waking up in 1 seconds...
--- Walking the entire request list ---
Cleaning up request 10 ID 49 with timestamp 43e29417
Nothing to do. Sleeping until we see a request.
More information about the Freeradius-Users
mailing list