Dear Alan
i have install the hotfix. but the radius reject the authentication.
could you pls give me some advice. I have change the cert twice,the
the problem sama.
Here is the debug log
Starting - reading configuration files ...
reread_config: reading radiusd.conf
Config: including file: /usr/local/radius/etc/raddb/proxy.conf
Config: including file: /usr/local/radius/etc/raddb/clients.conf
Config: including file: /usr/local/radius/etc/raddb/snmp.conf
Config: including file: /usr/local/radius/etc/raddb/eap.conf
Config: including file: /usr/local/radius/etc/raddb/sql.conf
main: prefix = "/usr/local/radius"
main: localstatedir = "/usr/local/radius/var"
main: logdir = "/usr/local/radius/var/log/radius"
main: libdir = "/usr/local/radius/lib"
main: radacctdir = "/usr/local/radius/var/log/radius/radacct"
main: hostname_lookups = no
main: max_request_time = 30
main: cleanup_delay = 5
main: max_requests = 1024
main: delete_blocked_requests = 0
main: port = 0
main: allow_core_dumps = no
main: log_stripped_names = no
main: log_file = "/usr/local/radius/var/log/radius/radius.log"
main: log_auth = no
main: log_auth_badpass = no
main: log_auth_goodpass = no
main: pidfile = "/usr/local/radius/var/run/radiusd/radiusd.pid"
main: user = "(null)"
main: group = "(null)"
main: usercollide = no
main: lower_user = "no"
main: lower_pass = "no"
main: nospace_user = "no"
main: nospace_pass = "no"
main: checkrad = "/usr/local/radius/sbin/checkrad"
main: proxy_requests = yes
proxy: retry_delay = 5
proxy: retry_count = 3
proxy: synchronous = no
proxy: default_fallback = yes
proxy: dead_time = 120
proxy: post_proxy_authorize = yes
proxy: wake_all_if_all_dead = no
security: max_attributes = 200
security: reject_delay = 1
security: status_server = no
main: debug_level = 0
read_config_files: reading dictionary
read_config_files: reading naslist
Using deprecated naslist file. Support for this will go away soon.
read_config_files: reading clients
read_config_files: reading realms
radiusd: entering modules setup
Module: Library search path is /usr/local/radius/lib
Module: Loaded exec
exec: wait = yes
exec: program = "(null)"
exec: input_pairs = "request"
exec: output_pairs = "(null)"
exec: packet_type = "(null)"
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 MS-CHAP
mschap: use_mppe = yes
mschap: require_encryption = yes
mschap: require_strong = yes
mschap: with_ntdomain_hack = yes
mschap: passwd = "(null)"
mschap: authtype = "MS-CHAP"
mschap: ntlm_auth = "(null)"
Module: Instantiated mschap (mschap)
Module: Loaded eap
eap: default_eap_type = "peap"
eap: timer_expire = 60
eap: ignore_unknown_eap_types = no
eap: cisco_accounting_username_bug = no
tls: rsa_key_exchange = no
tls: dh_key_exchange = yes
tls: rsa_key_length = 512
tls: dh_key_length = 512
tls: verify_depth = 0
tls: CA_path = "(null)"
tls: pem_file_type = yes
tls: private_key_file = "/usr/local/radius/etc/raddb/certs/enggang.pem"
tls: certificate_file = "/usr/local/radius/etc/raddb/certs/enggang.pem"
tls: CA_file = "/usr/local/radius/etc/raddb/certs/root.pem"
tls: private_key_password = "whatever"
tls: dh_file = "/usr/local/radius/etc/raddb/certs/dh"
tls: random_file = "/usr/local/radius/etc/raddb/certs/random"
tls: fragment_size = 1024
tls: include_length = yes
tls: check_crl = yes
tls: check_cert_cn = "(null)"
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 = "/usr/local/radius/etc/raddb/huntgroups"
preprocess: hints = "/usr/local/radius/etc/raddb/hints"
preprocess: with_ascend_hack = no
preprocess: ascend_channels_per_line = 23
preprocess: with_ntdomain_hack = no
preprocess: with_specialix_jetstream_hack = no
preprocess: with_cisco_vsa_hack = no
Module: Instantiated preprocess (preprocess)
Module: Loaded detail
detail: detailfile =
"/usr/local/radius/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d"
detail: detailperm = 384
detail: dirperm = 493
detail: locking = no
Module: Instantiated detail (auth_log)
Module: Loaded files
files: usersfile = "/usr/local/radius/etc/raddb/users"
files: acctusersfile = "/usr/local/radius/etc/raddb/acct_users"
files: preproxy_usersfile = "/usr/local/radius/etc/raddb/preproxy_users"
files: compat = "no"
Module: Instantiated files (files)
detail: detailfile =
"/usr/local/radius/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d"
detail: detailperm = 384
detail: dirperm = 493
detail: locking = no
Module: Instantiated detail (detail)
Module: Loaded radutmp
radutmp: filename = "/usr/local/radius/var/log/radius/radutmp"
radutmp: username = "%{User-Name}"
radutmp: case_sensitive = yes
radutmp: check_with_nas = yes
radutmp: perm = 384
radutmp: callerid = yes
Module: Instantiated radutmp (radutmp)
detail: detailfile =
"/usr/local/radius/var/log/radius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d"
detail: detailperm = 384
detail: dirperm = 493
detail: locking = no
Module: Instantiated detail (reply_log)
Initializing the thread pool...
thread: start_servers = 5
thread: max_servers = 32
thread: min_spare_servers = 3
thread: max_spare_servers = 10
thread: max_requests_per_server = 0
thread: cleanup_delay = 5
Thread spawned new child 1. Total threads in pool: 1
Thread 1 waiting to be assigned a request
Thread 2 waiting to be assigned a request
Thread spawned new child 2. Total threads in pool: 2
Thread 3 waiting to be assigned a request
Thread spawned new child 3. Total threads in pool: 3
Thread 4 waiting to be assigned a request
Thread spawned new child 4. Total threads in pool: 4
Thread 5 waiting to be assigned a request
Thread spawned new child 5. Total threads in pool: 5
Thread pool initialized
Listening on authentication *:1812
Listening on accounting *:1813
Listening on proxy *:1814
Ready to process requests.
rad_recv: Access-Request packet from host 192.168.2.51:1177, id=0, length=206
--- Walking the entire request list ---
Waking up in 31 seconds...
Threads: total/active/spare threads = 5/0/5
Thread 2 got semaphore
Thread 2 handling request 0, (1 handled so far)
Message-Authenticator = 0xd7449eabc1bbfb06d6c344263b3ca902
Service-Type = Framed-User
User-Name = "wireless"
Framed-MTU = 1488
Called-Station-Id = "00-0F-3D-AB-70-51:xserverAP"
Calling-Station-Id = "00-0C-F1-13-3F-29"
NAS-Identifier = "D-link Corp. Access Point"
NAS-Port-Type = Wireless-802.11
Connect-Info = "CONNECT 54Mbps 802.11g"
EAP-Message = 0x0200000d01776972656c657373
NAS-IP-Address = 192.168.2.51
NAS-Port = 1
NAS-Port-Id = "STA port # 1"
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 0
modcall[authorize]: module "preprocess" returns ok for request 0
radius_xlat:
'/usr/local/radius/var/log/radius/radacct/192.168.2.51/auth-detail-20050307'
rlm_detail:
/usr/local/radius/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d
expands to
/usr/local/radius/var/log/radius/radacct/192.168.2.51/auth-detail-20050307
modcall[authorize]: module "auth_log" returns ok for request 0
rlm_eap: EAP packet type response id 0 length 13
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 wireless at line 1
modcall[authorize]: module "files" returns ok for request 0
modcall: 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: group authenticate returns handled for request 0
Sending Access-Challenge of id 0 to 192.168.2.51:1177
EAP-Message = 0x010100061920
Message-Authenticator = 0x00000000000000000000000000000000
State = 0x0a5bd7de65432910765e43ce0a57f731
Finished request 0
Going to the next request
Thread 2 waiting to be assigned a request
rad_recv: Access-Request packet from host 192.168.2.51:1177, id=1, length=323
Waking up in 31 seconds...
Thread 1 got semaphore
Thread 1 handling request 1, (1 handled so far)
Message-Authenticator = 0x246361a6bd4e3ec8438706922355f530
Service-Type = Framed-User
User-Name = "wireless"
Framed-MTU = 1488
State = 0x0a5bd7de65432910765e43ce0a57f731
Called-Station-Id = "00-0F-3D-AB-70-51:xserverAP"
Calling-Station-Id = "00-0C-F1-13-3F-29"
NAS-Identifier = "D-link Corp. Access Point"
NAS-Port-Type = Wireless-802.11
Connect-Info = "CONNECT 54Mbps 802.11g"
EAP-Message =
0x0201007019800000006616030100610100005d0301422c39f06dbe2d9ca25e2eae1a035f420469ed488ff2300b34a80a3dd704006a203a78c05c63ec3cca58c1c5cbbc9ccb8a558025f0f0b03356da16dd06d88f9bcd001600040005000a000900640062000300060013001200630100
NAS-IP-Address = 192.168.2.51
NAS-Port = 1
NAS-Port-Id = "STA port # 1"
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 1
modcall[authorize]: module "preprocess" returns ok for request 1
radius_xlat:
'/usr/local/radius/var/log/radius/radacct/192.168.2.51/auth-detail-20050307'
rlm_detail:
/usr/local/radius/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d
expands to
/usr/local/radius/var/log/radius/radacct/192.168.2.51/auth-detail-20050307
modcall[authorize]: module "auth_log" returns ok for request 1
rlm_eap: EAP packet type response id 1 length 112
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 wireless at line 1
modcall[authorize]: module "files" returns ok for request 1
modcall: 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
TLS_accept: SSLv3 read client hello A
TLS_accept: SSLv3 write server hello A
TLS_accept: SSLv3 write certificate A
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: group authenticate returns handled for request 1
Sending Access-Challenge of id 1 to 192.168.2.51:1177
EAP-Message =
0x0102040a19c00000070f160301004a020000460301422c418e71ca70453a0cdbc66c3425fa28f1407b02d76c956d9133e37165d1f120f27cf16a1e14d41a6d86a704b92ef993e9a52159ad355c3142f778304f344bc900040016030106b20b0006ae0006ab0002d6308202d23082023ba003020102020900872b9e79dcea91e7300d06092a864886f70d01010405003081a0310b3009060355040613024d59311530130603550408130c4b75616c61204c756d707572311a3018060355040a1311556e6976657273697469204d616c61796131273025060355040b131e4b6f6c656a204b656469616d616e2054756e2041686d6164205a616964693115
EAP-Message =
0x30130603550403130c42616472756c20416e756172311e301c06092a864886f70d010901160f6b6b74617a40756d2e6564752e6d79301e170d3035303330343133303232375a170d3036303330343133303232375a30819b310b3009060355040613024d59311530130603550408130c4b75616c61204c756d707572311a3018060355040a1311556e6976657273697469204d616c61796131273025060355040b131e4b6f6c656a204b656469616d616e2054756e2041686d6164205a616964693110300e06035504031307656e6767616e67311e301c06092a864886f70d010901160f6b6b74617a40756d2e6564752e6d7930819f300d06092a8648
EAP-Message =
0x86f70d010101050003818d0030818902818100d49d0e2be6f469f816d34940f4401249413e48a5f714c2501f226dfc4479f56b005e2b07b918e13c0638c57e86af55640c4cb76bdc21fb7701ffefe2646755fb60eb939a2e3366059c913f695fb15ecbd570e2e924302be132d80f21703d685526161c7a3b5f87edfbca9850d9962b969c8f425d2d7c3f43b6bfbafbb053ff9d0203010001a317301530130603551d25040c300a06082b06010505070301300d06092a864886f70d01010405000381810092b985b9a1f18c1bd6a2f6f74be5d40cc886a995c6c79c22247af833b71beae7b808598f0bb71555d38d897fc2f00f66355b3906460e12eb69
EAP-Message =
0xafc87d4270b3565c3f72a42552154c9fce6680adfccf2283bfe56c587393752e1e3091a6afbadcef3d9a53c8e001aa9d7ceec8da832956935fd62488ac216a1eb775f00dfd43060003cf308203cb30820334a003020102020900872b9e79dcea91e6300d06092a864886f70d01010405003081a0310b3009060355040613024d59311530130603550408130c4b75616c61204c756d707572311a3018060355040a1311556e6976657273697469204d616c61796131273025060355040b131e4b6f6c656a204b656469616d616e2054756e2041686d6164205a61696469311530130603550403130c42616472756c20416e756172311e301c06092a8648
EAP-Message = 0x86f70d010901160f6b6b74617a40756d2e6564752e6d
Message-Authenticator = 0x00000000000000000000000000000000
State = 0x43ae7dce0bbc945f084145c080b37db8
Finished request 1
Going to the next request
Thread 1 waiting to be assigned a request
rad_recv: Access-Request packet from host 192.168.2.51:1177, id=2, length=217
Waking up in 31 seconds...
Thread 3 got semaphore
Thread 3 handling request 2, (1 handled so far)
Message-Authenticator = 0xaef6eb3df157822d1d214e46ae5df1eb
Service-Type = Framed-User
User-Name = "wireless"
Framed-MTU = 1488
State = 0x43ae7dce0bbc945f084145c080b37db8
Called-Station-Id = "00-0F-3D-AB-70-51:xserverAP"
Calling-Station-Id = "00-0C-F1-13-3F-29"
NAS-Identifier = "D-link Corp. Access Point"
NAS-Port-Type = Wireless-802.11
Connect-Info = "CONNECT 54Mbps 802.11g"
EAP-Message = 0x020200061900
NAS-IP-Address = 192.168.2.51
NAS-Port = 1
NAS-Port-Id = "STA port # 1"
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 2
modcall[authorize]: module "preprocess" returns ok for request 2
radius_xlat:
'/usr/local/radius/var/log/radius/radacct/192.168.2.51/auth-detail-20050307'
rlm_detail:
/usr/local/radius/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d
expands to
/usr/local/radius/var/log/radius/radacct/192.168.2.51/auth-detail-20050307
modcall[authorize]: module "auth_log" returns ok for request 2
rlm_eap: EAP packet type response id 2 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 wireless at line 1
modcall[authorize]: module "files" returns ok for request 2
modcall: 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: No SSL info available. Waiting for more SSL data.
eaptls_verify returned 1
eaptls_process returned 13
rlm_eap_peap: EAPTLS_HANDLED
modcall[authenticate]: module "eap" returns handled for request 2
modcall: group authenticate returns handled for request 2
Sending Access-Challenge of id 2 to 192.168.2.51:1177
EAP-Message =
0x01030315190079301e170d3035303330343133303230315a170d3035303430333133303230315a3081a0310b3009060355040613024d59311530130603550408130c4b75616c61204c756d707572311a3018060355040a1311556e6976657273697469204d616c61796131273025060355040b131e4b6f6c656a204b656469616d616e2054756e2041686d6164205a61696469311530130603550403130c42616472756c20416e756172311e301c06092a864886f70d010901160f6b6b74617a40756d2e6564752e6d7930819f300d06092a864886f70d010101050003818d0030818902818100ea3d085d3a0cc0bbdaf13068ff0c97cbd695a69703c6
EAP-Message =
0x6854dd38b28225dae30292f9342e1a3d3a8e06ebd9f3e04dbf794f9c2f3565aa2ea215e1a9f17d221c246d083de3519f64f624acc175cd2d5a729019baadbf7015e89fcfab5505623593d71eae4eeeaab7b2e71fa40945e29231913d027475396e1ab79a82a277df4e0b0203010001a382010930820105301d0603551d0e0416041483a562e5e4eaaa8fee03cc5e22be73cf63a61d4d3081d50603551d230481cd3081ca801483a562e5e4eaaa8fee03cc5e22be73cf63a61d4da181a6a481a33081a0310b3009060355040613024d59311530130603550408130c4b75616c61204c756d707572311a3018060355040a1311556e697665727369746920
EAP-Message =
0x4d616c61796131273025060355040b131e4b6f6c656a204b656469616d616e2054756e2041686d6164205a61696469311530130603550403130c42616472756c20416e756172311e301c06092a864886f70d010901160f6b6b74617a40756d2e6564752e6d79820900872b9e79dcea91e6300c0603551d13040530030101ff300d06092a864886f70d010104050003818100356894bd3a8fb99ccca6a7516fb091798bf73edede72d33828a505fe282795857c5374098ddfa4846d383f59168008a7dbeb1e57fd4da6fcb65aad667bb8c020d31d8f14e1d675f8bf822273635e1863667bdb586de8ba703450de58f2b3389416d8a7c646eb68bd8109b5
EAP-Message =
0x153dd973bbd93920588e69ddcc61909d694007f84416030100040e000000
Message-Authenticator = 0x00000000000000000000000000000000
State = 0xa9ac88116589d7b999882ee984048c32
Finished request 2
Going to the next request
Thread 3 waiting to be assigned a request
rad_recv: Access-Request packet from host 192.168.2.51:1177, id=3, length=403
Waking up in 31 seconds...
Thread 4 got semaphore
Thread 4 handling request 3, (1 handled so far)
Message-Authenticator = 0xc03db0cf30a31f66a94a6cb15e2b58cc
Service-Type = Framed-User
User-Name = "wireless"
Framed-MTU = 1488
State = 0xa9ac88116589d7b999882ee984048c32
Called-Station-Id = "00-0F-3D-AB-70-51:xserverAP"
Calling-Station-Id = "00-0C-F1-13-3F-29"
NAS-Identifier = "D-link Corp. Access Point"
NAS-Port-Type = Wireless-802.11
Connect-Info = "CONNECT 54Mbps 802.11g"
EAP-Message =
0x020300c01980000000b61603010086100000820080c3b3b26a606a67ec7ca64f3f9215f9d548511a0d5cb131ab6b8ef77502c5cc53f474afd45599915966bb73ab0793e732b11c490b96eb467a1d75af24a6582bd8cf6ff5cf9b60a39a3e69e28d21102043b4959add9c83a5507f1a25cd7571ef1ed717e17e8976cfc5c164d0a937733dc3c254d2564edf45003787ae894af9041e1403010001011603010020967e06264ed68a04ef275ee1f0eb85be8817617c9ab20f3049a2f3d1d1bdaa2f
NAS-IP-Address = 192.168.2.51
NAS-Port = 1
NAS-Port-Id = "STA port # 1"
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 3
modcall[authorize]: module "preprocess" returns ok for request 3
radius_xlat:
'/usr/local/radius/var/log/radius/radacct/192.168.2.51/auth-detail-20050307'
rlm_detail:
/usr/local/radius/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d
expands to
/usr/local/radius/var/log/radius/radacct/192.168.2.51/auth-detail-20050307
modcall[authorize]: module "auth_log" returns ok for request 3
rlm_eap: EAP packet type response id 3 length 192
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 wireless at line 1
modcall[authorize]: module "files" returns ok for request 3
modcall: 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: Length Included
eaptls_verify returned 11
TLS_accept: SSLv3 read client key exchange A
TLS_accept: SSLv3 read finished A
TLS_accept: SSLv3 write change cipher spec A
TLS_accept: SSLv3 write finished A
TLS_accept: SSLv3 flush data
(other): SSL negotiation finished successfully
SSL Connection Established
eaptls_process returned 13
rlm_eap_peap: EAPTLS_HANDLED
modcall[authenticate]: module "eap" returns handled for request 3
modcall: group authenticate returns handled for request 3
Sending Access-Challenge of id 3 to 192.168.2.51:1177
EAP-Message =
0x01040031190014030100010116030100200804409fce065bce198ece8faf79d953fba54936ef092be7ddbfaa47c4b52047
Message-Authenticator = 0x00000000000000000000000000000000
State = 0x145040babc0317b442a02a5ccf7842e7
Finished request 3
Going to the next request
Thread 4 waiting to be assigned a request
rad_recv: Access-Request packet from host 192.168.2.51:1177, id=4, length=217
Waking up in 31 seconds...
Thread 5 got semaphore
Thread 5 handling request 4, (1 handled so far)
Message-Authenticator = 0x17ecb07ed2670cf8f906bbc3f90cd7a4
Service-Type = Framed-User
User-Name = "wireless"
Framed-MTU = 1488
State = 0x145040babc0317b442a02a5ccf7842e7
Called-Station-Id = "00-0F-3D-AB-70-51:xserverAP"
Calling-Station-Id = "00-0C-F1-13-3F-29"
NAS-Identifier = "D-link Corp. Access Point"
NAS-Port-Type = Wireless-802.11
Connect-Info = "CONNECT 54Mbps 802.11g"
EAP-Message = 0x020400061900
NAS-IP-Address = 192.168.2.51
NAS-Port = 1
NAS-Port-Id = "STA port # 1"
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 4
modcall[authorize]: module "preprocess" returns ok for request 4
radius_xlat:
'/usr/local/radius/var/log/radius/radacct/192.168.2.51/auth-detail-20050307'
rlm_detail:
/usr/local/radius/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d
expands to
/usr/local/radius/var/log/radius/radacct/192.168.2.51/auth-detail-20050307
modcall[authorize]: module "auth_log" returns ok for request 4
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 4
users: Matched entry wireless at line 1
modcall[authorize]: module "files" returns ok for request 4
modcall: 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: No SSL info available. Waiting for more SSL data.
eaptls_verify returned 1
eaptls_process returned 13
rlm_eap_peap: EAPTLS_HANDLED
modcall[authenticate]: module "eap" returns handled for request 4
modcall: group authenticate returns handled for request 4
Sending Access-Challenge of id 4 to 192.168.2.51:1177
EAP-Message = 0x010500061900
Message-Authenticator = 0x00000000000000000000000000000000
State = 0x0e344707a2aa79981fced495103566f2
Finished request 4
Going to the next request
Thread 5 waiting to be assigned a request
--- Walking the entire request list ---
Cleaning up request 0 ID 0 with timestamp 422c418e
Cleaning up request 1 ID 1 with timestamp 422c418e
Cleaning up request 2 ID 2 with timestamp 422c418e
Cleaning up request 3 ID 3 with timestamp 422c418e
Cleaning up request 4 ID 4 with timestamp 422c418e
Nothing to do. Sleeping until we see a request.
rad_recv: Access-Request packet from host 192.168.2.51:1178, id=0, length=206
--- Walking the entire request list ---
Waking up in 31 seconds...
Thread 2 got semaphore
Thread 2 handling request 5, (2 handled so far)
Message-Authenticator = 0x016c26a02f4fe1db1bb7a9fe28270dea
Service-Type = Framed-User
User-Name = "wireless"
Framed-MTU = 1488
Called-Station-Id = "00-0F-3D-AB-70-51:xserverAP"
Calling-Station-Id = "00-0C-F1-13-3F-29"
NAS-Identifier = "D-link Corp. Access Point"
NAS-Port-Type = Wireless-802.11
Connect-Info = "CONNECT 54Mbps 802.11g"
EAP-Message = 0x0200000d01776972656c657373
NAS-IP-Address = 192.168.2.51
NAS-Port = 1
NAS-Port-Id = "STA port # 1"
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 5
modcall[authorize]: module "preprocess" returns ok for request 5
radius_xlat:
'/usr/local/radius/var/log/radius/radacct/192.168.2.51/auth-detail-20050307'
rlm_detail:
/usr/local/radius/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d
expands to
/usr/local/radius/var/log/radius/radacct/192.168.2.51/auth-detail-20050307
modcall[authorize]: module "auth_log" returns ok for request 5
rlm_eap: EAP packet type response id 0 length 13
rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
modcall[authorize]: module "eap" returns updated for request 5
users: Matched entry wireless at line 1
modcall[authorize]: module "files" returns ok for request 5
modcall: group authorize returns updated for request 5
rad_check_password: Found Auth-Type EAP
auth: type "EAP"
Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 5
rlm_eap: EAP Identity
rlm_eap: processing type tls
rlm_eap_tls: Initiate
rlm_eap_tls: Start returned 1
modcall[authenticate]: module "eap" returns handled for request 5
modcall: group authenticate returns handled for request 5
Sending Access-Challenge of id 0 to 192.168.2.51:1178
EAP-Message = 0x010100061920
Message-Authenticator = 0x00000000000000000000000000000000
State = 0xfa06baa2677d9c9002fc01a761303c1a
Finished request 5
Going to the next request
Thread 2 waiting to be assigned a request
rad_recv: Access-Request packet from host 192.168.2.51:1178, id=1, length=206
Waking up in 31 seconds...
Thread 1 got semaphore
Thread 1 handling request 6, (2 handled so far)
Message-Authenticator = 0x3d4ebc64f833a5e4ba619834e68cbbec
Service-Type = Framed-User
User-Name = "wireless"
Framed-MTU = 1488
Called-Station-Id = "00-0F-3D-AB-70-51:xserverAP"
Calling-Station-Id = "00-0C-F1-13-3F-29"
NAS-Identifier = "D-link Corp. Access Point"
NAS-Port-Type = Wireless-802.11
Connect-Info = "CONNECT 54Mbps 802.11g"
EAP-Message = 0x0201000d01776972656c657373
NAS-IP-Address = 192.168.2.51
NAS-Port = 1
NAS-Port-Id = "STA port # 1"
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 6
modcall[authorize]: module "preprocess" returns ok for request 6
radius_xlat:
'/usr/local/radius/var/log/radius/radacct/192.168.2.51/auth-detail-20050307'
rlm_detail:
/usr/local/radius/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d
expands to
/usr/local/radius/var/log/radius/radacct/192.168.2.51/auth-detail-20050307
modcall[authorize]: module "auth_log" returns ok for request 6
rlm_eap: EAP packet type response id 1 length 13
rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
modcall[authorize]: module "eap" returns updated for request 6
users: Matched entry wireless at line 1
modcall[authorize]: module "files" returns ok for request 6
modcall: 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: group authenticate returns handled for request 6
Sending Access-Challenge of id 1 to 192.168.2.51:1178
EAP-Message = 0x010200061920
Message-Authenticator = 0x00000000000000000000000000000000
State = 0x327ed97124519aa8053ef64e22ed1492
Finished request 6
Going to the next request
Thread 1 waiting to be assigned a request
rad_recv: Access-Request packet from host 192.168.2.51:1178, id=2, length=323
Waking up in 31 seconds...
Thread 3 got semaphore
Thread 3 handling request 7, (2 handled so far)
Message-Authenticator = 0x49839e0b73fb065c968f35adf172e049
Service-Type = Framed-User
User-Name = "wireless"
Framed-MTU = 1488
State = 0x327ed97124519aa8053ef64e22ed1492
Called-Station-Id = "00-0F-3D-AB-70-51:xserverAP"
Calling-Station-Id = "00-0C-F1-13-3F-29"
NAS-Identifier = "D-link Corp. Access Point"
NAS-Port-Type = Wireless-802.11
Connect-Info = "CONNECT 54Mbps 802.11g"
EAP-Message =
0x0202007019800000006616030100610100005d0301422c3a2d3d0d7e8fea122996b5fd87a8b946724a4cee678fce97830381c6029620f27cf16a1e14d41a6d86a704b92ef993e9a52159ad355c3142f778304f344bc9001600040005000a000900640062000300060013001200630100
NAS-IP-Address = 192.168.2.51
NAS-Port = 1
NAS-Port-Id = "STA port # 1"
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 7
modcall[authorize]: module "preprocess" returns ok for request 7
radius_xlat:
'/usr/local/radius/var/log/radius/radacct/192.168.2.51/auth-detail-20050307'
rlm_detail:
/usr/local/radius/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d
expands to
/usr/local/radius/var/log/radius/radacct/192.168.2.51/auth-detail-20050307
modcall[authorize]: module "auth_log" returns ok for request 7
rlm_eap: EAP packet type response id 2 length 112
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 wireless at line 1
modcall[authorize]: module "files" returns ok for request 7
modcall: 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
TLS_accept: SSLv3 read client hello A
TLS_accept: SSLv3 write server hello A
TLS_accept: SSLv3 write change cipher spec A
TLS_accept: SSLv3 write finished A
TLS_accept: SSLv3 flush data
TLS_accept:error in SSLv3 read finished 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: group authenticate returns handled for request 7
Sending Access-Challenge of id 2 to 192.168.2.51:1178
EAP-Message =
0x010300801900160301004a020000460301422c41ca7f53d69b93d408b4b4e338054c4f0b4b6c9d97b68efbb6b083741d0b20f27cf16a1e14d41a6d86a704b92ef993e9a52159ad355c3142f778304f344bc9000400140301000101160301002016f96390a4af7ccfd994f1da108db459bc04207a08f83252681d2496524985cd
Message-Authenticator = 0x00000000000000000000000000000000
State = 0xc56f7a67cb1c26678ecc75b196bee64e
Finished request 7
Going to the next request
Thread 3 waiting to be assigned a request
rad_recv: Access-Request packet from host 192.168.2.51:1178, id=2, length=323
Sending duplicate reply to client subnet2:1178 - ID: 2
Re-sending Access-Challenge of id 2 to 192.168.2.51:1178
--- Walking the entire request list ---
Waking up in 3 seconds...
rad_recv: Access-Request packet from host 192.168.2.51:1178, id=3, length=264
Waking up in 3 seconds...
Thread 4 got semaphore
Thread 4 handling request 8, (2 handled so far)
Message-Authenticator = 0xf9243a1c2f34419a8fdf954c67d3aa08
Service-Type = Framed-User
User-Name = "wireless"
Framed-MTU = 1488
State = 0xc56f7a67cb1c26678ecc75b196bee64e
Called-Station-Id = "00-0F-3D-AB-70-51:xserverAP"
Calling-Station-Id = "00-0C-F1-13-3F-29"
NAS-Identifier = "D-link Corp. Access Point"
NAS-Port-Type = Wireless-802.11
Connect-Info = "CONNECT 54Mbps 802.11g"
EAP-Message =
0x0203003519800000002b1403010001011603010020a128eddcffbc34634776d33b0bf802cac083eee67f434617c8cf1123a103b2fa
NAS-IP-Address = 192.168.2.51
NAS-Port = 1
NAS-Port-Id = "STA port # 1"
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 8
modcall[authorize]: module "preprocess" returns ok for request 8
radius_xlat:
'/usr/local/radius/var/log/radius/radacct/192.168.2.51/auth-detail-20050307'
rlm_detail:
/usr/local/radius/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d
expands to
/usr/local/radius/var/log/radius/radacct/192.168.2.51/auth-detail-20050307
modcall[authorize]: module "auth_log" returns ok for request 8
rlm_eap: EAP packet type response id 3 length 53
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 wireless at line 1
modcall[authorize]: module "files" returns ok for request 8
modcall: 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: Length Included
eaptls_verify returned 11
TLS_accept: SSLv3 read finished A
(other): SSL negotiation finished successfully
SSL Connection Established
eaptls_process returned 13
rlm_eap_peap: EAPTLS_HANDLED
rlm_eap: Freeing handler
modcall[authenticate]: module "eap" returns reject for request 8
modcall: group authenticate returns reject for request 8
auth: Failed to validate the user.
Delaying request 8 for 1 seconds
Finished request 8
Going to the next request
Thread 4 waiting to be assigned a request
rad_recv: Access-Request packet from host 192.168.2.51:1178, id=3, length=264
Sending Access-Reject of id 3 to 192.168.2.51:1178
EAP-Message = 0x04030004
Message-Authenticator = 0x00000000000000000000000000000000
--- Walking the entire request list ---
Cleaning up request 5 ID 0 with timestamp 422c41ca
Cleaning up request 6 ID 1 with timestamp 422c41ca
Cleaning up request 7 ID 2 with timestamp 422c41ca
Waking up in 3 seconds...
--- Walking the entire request list ---
Cleaning up request 8 ID 3 with timestamp 422c41cd
Nothing to do. Sleeping until we see a request.
On Sat, 05 Mar 2005 11:04:06 -0500, Alan DeKok <[EMAIL PROTECTED]> wrote:
> Badrul Anuar <[EMAIL PROTECTED]> wrote:
> > after change the eap.conf from tls to peap ...
> >
> > i have solved the problem (refer to the mail. before)...
> > TLS_accept:error in SSLv3 read client certificate A
> >
> > But still can't authenticate betwen the server,
>
> SP2 has known inter-operability problems with non-Microsoft RADIUS
> servers.
>
> http://support.microsoft.com/default.aspx?scid=kb;en-us;885453
>
> The "cause" they list is bullsh*t. The debug log you posted clearly
> shows this. SP2 is NOT sending any data inside of the TLS tunnel, and
> FreeRADIUS keeps asking for more. Eventually SP2 gives up, and starts
> the authentication process again.
>
> Install the hotfix, and it should work.
>
> Alan DeKok.
>
> -
> List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
>
-
List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
