Freeradius and Active directory
Tomáš Janeček
tomas.janecek at cca.cz
Thu May 22 09:10:43 CEST 2008
Hi.
Now I went back to the default configuration and made only a few changes
(according to
http://wiki.freeradius.org/FreeRADIUS_Active_Directory_Integration_HOWTO).
Everything looks much better now, but I still get the "wrong password"
error.
I think, that the problem is in this part of the log:
*************************************************************************
modcall: entering group MS-CHAP for request 6
rlm_mschap: No User-Password configured. Cannot create LM-Password.
rlm_mschap: No User-Password configured. Cannot create NT-Password.
rlm_mschap: Told to do MS-CHAPv2 for user with NT-Password
radius_xlat: Running registered xlat function of module mschap for
string 'NT-Domain'
radius_xlat: '--domain=MYNTDOMAIN'
radius_xlat: Running registered xlat function of module mschap for
string 'User-Name'
radius_xlat: '--username=user'
radius_xlat: Running registered xlat function of module mschap for
string 'Challenge'
mschap2: fc
radius_xlat: '--challenge=7bb63d62291bd255'
radius_xlat: Running registered xlat function of module mschap for
string 'NT-Response'
radius_xlat:
'--nt-response=1ace51d8b230eef7abd8ab34bd4dd34694160c3005d8d237'
Exec-Program output: Logon failure (0xc000006d)
Exec-Program-Wait: plaintext: Logon failure (0xc000006d)
Exec-Program: returned: 1
rlm_mschap: External script failed.
rlm_mschap: FAILED: MS-CHAP2-Response is incorrect
modcall[authenticate]: module "mschap" returns reject for request 6
modcall: leaving group MS-CHAP (returns reject) for request 6
**********************************************************************
especially in Exec-Program-Wait: plaintext: Logon failure (0xc000006d).
Why is it using PLAINTEXT?
When I try ntlm_auth --domain=MYNTDOMAIN --username=user --challenge=bla
--nt-response=blabla
it returns the same error. But the password is correct, so think it is
just sending it in another form...
Has anybody any idea?
Thanks for any advice
PS: here is my log (little bit to long):
************************************************************************
Starting - reading configuration files ...
reread_config: reading radiusd.conf
Config: including file: /etc/raddb/proxy.conf
Config: including file: /etc/raddb/clients.conf
Config: including file: /etc/raddb/snmp.conf
Config: including file: /etc/raddb/eap.conf
Config: including file: /etc/raddb/sql.conf
main: prefix = "/usr"
main: localstatedir = "/var"
main: logdir = "/var/log/radius"
main: libdir = "/usr/lib"
main: radacctdir = "/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 = "/var/log/radius/radius.log"
main: log_auth = no
main: log_auth_badpass = no
main: log_auth_goodpass = no
main: pidfile = "/var/run/radiusd/radiusd.pid"
main: user = "radiusd"
main: group = "radiusd"
main: usercollide = no
main: lower_user = "no"
main: lower_pass = "no"
main: nospace_user = "no"
main: nospace_pass = "no"
main: checkrad = "/usr/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 = no
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/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 PAP
pap: encryption_scheme = "crypt"
pap: auto_header = yes
Module: Instantiated pap (pap)
Module: Loaded CHAP
Module: Instantiated chap (chap)
Module: Loaded MS-CHAP
mschap: use_mppe = yes
mschap: require_encryption = no
mschap: require_strong = no
mschap: with_ntdomain_hack = yes
mschap: passwd = "(null)"
mschap: ntlm_auth = "/usr/bin/ntlm_auth --request-nt-key
--domain=%{mschap:NT-Domain} --username=%{mschap:User-Name}
--challenge=%{mschap:Challenge:-00} --nt-response=%{mschap:NT-Response:-00}"
Module: Instantiated mschap (mschap)
Module: Loaded System
unix: cache = no
unix: passwd = "(null)"
unix: shadow = "(null)"
unix: group = "(null)"
unix: radwtmp = "/var/log/radius/radwtmp"
unix: usegroup = no
unix: cache_reload = 600
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: CA_path = "(null)"
tls: pem_file_type = yes
tls: private_key_file = "/etc/raddb/certs/usersrvkey.pem"
tls: certificate_file = "/etc/raddb/certs/usersrvcert.pem"
tls: CA_file = "/etc/raddb/certs/usercacert.pem"
tls: private_key_password = "(null)"
tls: dh_file = "/etc/raddb/certs/dh"
tls: random_file = "/dev/urandom"
tls: fragment_size = 1024
tls: include_length = yes
tls: check_crl = no
tls: check_cert_cn = "(null)"
tls: cipher_list = "(null)"
tls: check_cert_issuer = "(null)"
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/raddb/huntgroups"
preprocess: hints = "/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
preprocess: with_alvarion_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/raddb/users"
files: acctusersfile = "/etc/raddb/acct_users"
files: preproxy_usersfile = "/etc/raddb/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/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 = "/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)
Listening on authentication *:1812
Listening on accounting *:1813
Ready to process requests.
**************************************************************************************
rad_recv: Access-Request packet from host 192.168.5.34:1412, id=0,
length=205
Message-Authenticator = 0x0d7b226426c5e809db75464166db3e9e
Service-Type = Framed-User
User-Name = "MYNTDOMAIN\\user"
Framed-MTU = 1488
Called-Station-Id = "00-1E-C1-30-51-C0:CCA2"
Calling-Station-Id = "00-13-CE-3B-44-4F"
NAS-Identifier = "3Com Access Point 7760"
NAS-Port-Type = Wireless-802.11
Connect-Info = "CONNECT 11Mbps 802.11b"
EAP-Message = 0x02000010014b524b415645435c6a6165
NAS-IP-Address = 192.168.5.34
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
modcall[authorize]: module "chap" returns noop for request 0
modcall[authorize]: module "mschap" returns noop for request 0
rlm_realm: No '@' in User-Name = "MYNTDOMAIN\user", 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 0 length 16
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 DEFAULT at line 153
users: Matched entry DEFAULT at line 172
modcall[authorize]: module "files" returns ok for request 0
rlm_pap: WARNING! No "known good" password found for the user.
Authentication may fail because of this.
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 0 to 192.168.5.34 port 1412
Framed-IP-Address = 255.255.255.254
Framed-MTU = 576
Service-Type = Framed-User
EAP-Message = 0x010100061920
Message-Authenticator = 0x00000000000000000000000000000000
State = 0xee685e482b26d2cd30f28860c4b5549d
Finished request 0
Going to the next request
--- Walking the entire request list ---
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 192.168.5.34:1412, id=1,
length=287
Message-Authenticator = 0xdf9240bde280bd0703c4a940e69f80b2
Service-Type = Framed-User
User-Name = "MYNTDOMAIN\\user"
Framed-MTU = 1488
State = 0xee685e482b26d2cd30f28860c4b5549d
Called-Station-Id = "00-1E-C1-30-51-C0:CCA2"
Calling-Station-Id = "00-13-CE-3B-44-4F"
NAS-Identifier = "3Com Access Point 7760"
NAS-Port-Type = Wireless-802.11
Connect-Info = "CONNECT 11Mbps 802.11b"
EAP-Message =
0x0201005019800000004616030100410100003d0301483510b328865954c5f7509d89968aa0a6e2de3bc5b6d6f0a13b0ed4a59a9ff400001600040005000a000900640062000300060013001200630100
NAS-IP-Address = 192.168.5.34
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
modcall[authorize]: module "chap" returns noop for request 1
modcall[authorize]: module "mschap" returns noop for request 1
rlm_realm: No '@' in User-Name = "MYNTDOMAIN\user", 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 1 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 DEFAULT at line 153
users: Matched entry DEFAULT at line 172
modcall[authorize]: module "files" returns ok for request 1
rlm_pap: WARNING! No "known good" password found for the user.
Authentication may fail because of this.
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 0641], 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: Need to read more data: 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 1 to 192.168.5.34 port 1412
Framed-IP-Address = 255.255.255.254
Framed-MTU = 576
Service-Type = Framed-User
EAP-Message =
0x0102040a19c00000069e160301004a020000460301483510b39830caf1c0b26f868a88438cecd44857ad7fec8a8243659aabbebd3f203d70816528c29969171a797d12e18a32b30c14199da1c19a58307137b061aa1200040016030106410b00063d00063a0002db308202d730820240a003020102020101300d06092a864886f70d0101050500307b310b300906035504061302435a311730150603550408130e437a6563682052657075626c6963310e300c06035504071305506c7a656e310c300a060355040a1303434341310c300a060355040b1303535450310c300a060355040313034a41453119301706092a864886f70d010901160a6a6165
EAP-Message =
0x406363612e637a301e170d3038303332363131313331345a170d3039303332363131313331345a306b310b300906035504061302435a311730150603550408130e437a6563682052657075626c6963310c300a060355040a1303434341310c300a060355040b1303535450310c300a060355040313034a41453119301706092a864886f70d010901160a6a6165406363612e637a30819f300d06092a864886f70d010101050003818d0030818902818100d37848b2c54c81ee1ad8ab0a58197bac35bebbb203aad2356b4b3a49c10c455476f47d097b43204aff3aa5f2ad58c8eb1dc04f1db8ccc809f122838519dc604675fc6d7539be541e6889f9e9
EAP-Message =
0xa024392963f6e1c19519662a5bf0bf27fa0983d3b0090e0094638f3c67d11f273da895931628775e03836845f1478bc7225dd55b0203010001a37b307930090603551d1304023000302c06096086480186f842010d041f161d4f70656e53534c2047656e657261746564204365727469666963617465301d0603551d0e041604145c73ee21febd0dbd9ad0383ff506764d8cd1458c301f0603551d230418301680147003f249d199478c76d27bf41da15d1991e1ebc8300d06092a864886f70d01010505000381810063b68d99dd03138bb08c8f619de28c72837f76e429f5e521d136674df9a8599a8b87be521518a1611aa3c86582f611e81e03beae
EAP-Message =
0x19942d9ddf6adb8efc258bbd99cde5db6fba089291d8f3ade42c8d0f3c4e2797fb3130ba215a486582c4572b35862eb25abcba3bc11108d79ee4214744ee50129f11aca1071945db22b24c0800035930820355308202bea003020102020900fd89d0cd70495e4d300d06092a864886f70d0101050500307b310b300906035504061302435a311730150603550408130e437a6563682052657075626c6963310e300c06035504071305506c7a656e310c300a060355040a1303434341310c300a060355040b1303535450310c300a060355040313034a41453119301706092a864886f70d010901160a6a6165406363612e637a301e170d303830333236
EAP-Message = 0x3131303331315a170d3131303332393131303331315a
Message-Authenticator = 0x00000000000000000000000000000000
State = 0xa5064993365bb78cded61beff6d56f9c
Finished request 1
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 192.168.5.34:1412, id=2,
length=213
Message-Authenticator = 0x89ed55c78a5aafb14a2d871ad7f76d7a
Service-Type = Framed-User
User-Name = "MYNTDOMAIN\\user"
Framed-MTU = 1488
State = 0xa5064993365bb78cded61beff6d56f9c
Called-Station-Id = "00-1E-C1-30-51-C0:CCA2"
Calling-Station-Id = "00-13-CE-3B-44-4F"
NAS-Identifier = "3Com Access Point 7760"
NAS-Port-Type = Wireless-802.11
Connect-Info = "CONNECT 11Mbps 802.11b"
EAP-Message = 0x020200061900
NAS-IP-Address = 192.168.5.34
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
modcall[authorize]: module "chap" returns noop for request 2
modcall[authorize]: module "mschap" returns noop for request 2
rlm_realm: No '@' in User-Name = "MYNTDOMAIN\user", 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 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 DEFAULT at line 153
users: Matched entry DEFAULT at line 172
modcall[authorize]: module "files" returns ok for request 2
rlm_pap: WARNING! No "known good" password found for the user.
Authentication may fail because of this.
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 2 to 192.168.5.34 port 1412
Framed-IP-Address = 255.255.255.254
Framed-MTU = 576
Service-Type = Framed-User
EAP-Message =
0x010302a41900307b310b300906035504061302435a311730150603550408130e437a6563682052657075626c6963310e300c06035504071305506c7a656e310c300a060355040a1303434341310c300a060355040b1303535450310c300a060355040313034a41453119301706092a864886f70d010901160a6a6165406363612e637a30819f300d06092a864886f70d010101050003818d0030818902818100dbd048dbeb96877af89967de38a852f1e4cd9963c1dc01fd892ef19260032ef995743617e9ce0d8d8efb2c76eebfc2c4bc27cd9404bfdb945bb3df9caf9049aaac31f3121af30bd123ed76f9c8e7fe359bcb56c9bc17a742148ceb5cd7
EAP-Message =
0xcb21d7913c30e656dc43948a7531238a847ea5d0d8c081241e1dea93ef9c2b4b7e1d9d0203010001a381e03081dd301d0603551d0e041604147003f249d199478c76d27bf41da15d1991e1ebc83081ad0603551d230481a53081a280147003f249d199478c76d27bf41da15d1991e1ebc8a17fa47d307b310b300906035504061302435a311730150603550408130e437a6563682052657075626c6963310e300c06035504071305506c7a656e310c300a060355040a1303434341310c300a060355040b1303535450310c300a060355040313034a41453119301706092a864886f70d010901160a6a6165406363612e637a820900fd89d0cd70495e4d
EAP-Message =
0x300c0603551d13040530030101ff300d06092a864886f70d010105050003818100b49fb4c9fd8e685e150b020811bd951ee33bf179c1f0180004e4bbb55430b4e3e940940bb1627c8aec4e18db82d6b5004f12aa689bf95f4a0d104e170e35ed86288da242ab66298519c9818060b63540e1073ff415e5b6d3fb49095f4cba08a695af63857c2e9696571b1a46c0bb8c67f676dc83dd9ca418b03b1665a810c82516030100040e000000
Message-Authenticator = 0x00000000000000000000000000000000
State = 0x1edaad6860d013b492e09fae97fd31e9
Finished request 2
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 192.168.5.34:1412, id=3,
length=399
Message-Authenticator = 0x8f83b0c32b41ba930617fa7d34e7bcda
Service-Type = Framed-User
User-Name = "MYNTDOMAIN\\user"
Framed-MTU = 1488
State = 0x1edaad6860d013b492e09fae97fd31e9
Called-Station-Id = "00-1E-C1-30-51-C0:CCA2"
Calling-Station-Id = "00-13-CE-3B-44-4F"
NAS-Identifier = "3Com Access Point 7760"
NAS-Port-Type = Wireless-802.11
Connect-Info = "CONNECT 11Mbps 802.11b"
EAP-Message =
0x020300c01980000000b6160301008610000082008068dac8fba4cee8d311874c68e32ddaa636c0b6134a3b6e17e1bc2ab2f48b97c65a8e81b292cb4277129c38864d12fb11e7b4dca6397ce80148f539d4e0d67cd4699b4d8ac4351dc78f927a9a7304fc2e09a5fdb043cd14cc98efbac98940b4b93704e7702710b9abf4df638a8598d0ef6dce259c934f18bdc4ade6abb6cd27f914030100010116030100207f93b7886a15bf3d878bc5e2b0f178cdbf35b2ef1587978a7743b1be07b141db
NAS-IP-Address = 192.168.5.34
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
modcall[authorize]: module "chap" returns noop for request 3
modcall[authorize]: module "mschap" returns noop for request 3
rlm_realm: No '@' in User-Name = "MYNTDOMAIN\user", 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 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 DEFAULT at line 153
users: Matched entry DEFAULT at line 172
modcall[authorize]: module "files" returns ok for request 3
rlm_pap: WARNING! No "known good" password found for the user.
Authentication may fail because of this.
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: Length Included
eaptls_verify returned 11
rlm_eap_tls: <<< TLS 1.0 Handshake [length 0086], ClientKeyExchange
TLS_accept: SSLv3 read client key exchange A
rlm_eap_tls: <<< TLS 1.0 ChangeCipherSpec [length 0001]
rlm_eap_tls: <<< TLS 1.0 Handshake [length 0010], Finished
TLS_accept: SSLv3 read finished A
rlm_eap_tls: >>> TLS 1.0 ChangeCipherSpec [length 0001]
TLS_accept: SSLv3 write change cipher spec A
rlm_eap_tls: >>> TLS 1.0 Handshake [length 0010], Finished
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: leaving group authenticate (returns handled) for request 3
Sending Access-Challenge of id 3 to 192.168.5.34 port 1412
Framed-IP-Address = 255.255.255.254
Framed-MTU = 576
Service-Type = Framed-User
EAP-Message =
0x01040031190014030100010116030100206b5928c403961228e50bc96bbc4566051cbc872880635c3454e0fcc2927e202b
Message-Authenticator = 0x00000000000000000000000000000000
State = 0x55a505118358b8e4c1eb13d890f5f93e
Finished request 3
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 192.168.5.34:1412, id=4,
length=213
Message-Authenticator = 0x61d9e7da9afb1e5f593a4f49da38aea5
Service-Type = Framed-User
User-Name = "MYNTDOMAIN\\user"
Framed-MTU = 1488
State = 0x55a505118358b8e4c1eb13d890f5f93e
Called-Station-Id = "00-1E-C1-30-51-C0:CCA2"
Calling-Station-Id = "00-13-CE-3B-44-4F"
NAS-Identifier = "3Com Access Point 7760"
NAS-Port-Type = Wireless-802.11
Connect-Info = "CONNECT 11Mbps 802.11b"
EAP-Message = 0x020400061900
NAS-IP-Address = 192.168.5.34
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
modcall[authorize]: module "chap" returns noop for request 4
modcall[authorize]: module "mschap" returns noop for request 4
rlm_realm: No '@' in User-Name = "MYNTDOMAIN\user", 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 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 DEFAULT at line 153
users: Matched entry DEFAULT at line 172
modcall[authorize]: module "files" returns ok for request 4
rlm_pap: WARNING! No "known good" password found for the user.
Authentication may fail because of this.
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 is finished
eaptls_verify returned 3
eaptls_process returned 3
rlm_eap_peap: EAPTLS_SUCCESS
modcall[authenticate]: module "eap" returns handled for request 4
modcall: leaving group authenticate (returns handled) for request 4
Sending Access-Challenge of id 4 to 192.168.5.34 port 1412
Framed-IP-Address = 255.255.255.254
Framed-MTU = 576
Service-Type = Framed-User
EAP-Message =
0x0105002019001703010015e5cb904a4ae17227ee548f2e59713b357264b39a3e
Message-Authenticator = 0x00000000000000000000000000000000
State = 0x54ba7141dd65ceaf3ceab7b38c5a51e5
Finished request 4
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 192.168.5.34:1412, id=5,
length=246
Message-Authenticator = 0x6daa02772e4ba5606e88b8579fc97316
Service-Type = Framed-User
User-Name = "MYNTDOMAIN\\user"
Framed-MTU = 1488
State = 0x54ba7141dd65ceaf3ceab7b38c5a51e5
Called-Station-Id = "00-1E-C1-30-51-C0:CCA2"
Calling-Station-Id = "00-13-CE-3B-44-4F"
NAS-Identifier = "3Com Access Point 7760"
NAS-Port-Type = Wireless-802.11
Connect-Info = "CONNECT 11Mbps 802.11b"
EAP-Message =
0x020500271900170301001c301f39be5a21dd1e4e6d5ecc323d922c6b934d124b96bd13542eda00
NAS-IP-Address = 192.168.5.34
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
modcall[authorize]: module "chap" returns noop for request 5
modcall[authorize]: module "mschap" returns noop for request 5
rlm_realm: No '@' in User-Name = "MYNTDOMAIN\user", looking up
realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix" returns noop for request 5
rlm_eap: EAP packet type response id 5 length 39
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 DEFAULT at line 153
users: Matched entry DEFAULT at line 172
modcall[authorize]: module "files" returns ok for request 5
rlm_pap: WARNING! No "known good" password found for the user.
Authentication may fail because of this.
modcall[authorize]: module "pap" returns noop for request 5
modcall: leaving 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: Request found, released from the list
rlm_eap: EAP/peap
rlm_eap: processing type peap
rlm_eap_peap: Authenticate
rlm_eap_tls: processing TLS
eaptls_verify returned 7
rlm_eap_tls: Done initial handshake
eaptls_process returned 7
rlm_eap_peap: EAPTLS_OK
rlm_eap_peap: Session established. Decoding tunneled attributes.
rlm_eap_peap: Identity - MYNTDOMAIN\user
rlm_eap_peap: Tunneled data is valid.
PEAP: Got tunneled identity of MYNTDOMAIN\user
PEAP: Setting default EAP type for tunneled EAP session.
PEAP: Setting User-Name to MYNTDOMAIN\user
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 5
modcall[authorize]: module "preprocess" returns ok for request 5
modcall[authorize]: module "chap" returns noop for request 5
modcall[authorize]: module "mschap" returns noop for request 5
rlm_realm: No '@' in User-Name = "MYNTDOMAIN\user", looking up
realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix" returns noop for request 5
rlm_eap: EAP packet type response id 5 length 16
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 DEFAULT at line 153
modcall[authorize]: module "files" returns ok for request 5
rlm_pap: WARNING! No "known good" password found for the user.
Authentication may fail because of this.
modcall[authorize]: module "pap" returns noop for request 5
modcall: leaving 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 mschapv2
rlm_eap_mschapv2: Issuing Challenge
modcall[authenticate]: module "eap" returns handled for request 5
modcall: leaving group authenticate (returns handled) for request 5
PEAP: Got tunneled Access-Challenge
modcall[authenticate]: module "eap" returns handled for request 5
modcall: leaving group authenticate (returns handled) for request 5
Sending Access-Challenge of id 5 to 192.168.5.34 port 1412
Framed-IP-Address = 255.255.255.254
Framed-MTU = 576
Service-Type = Framed-User
EAP-Message =
0x0106003c19001703010031e5cef74da06987f33ffda9d515e2ec9c0d3a0312bd499add52ef378e1b48dc48c46eca9e42827bc312295eed4fdc74649f
Message-Authenticator = 0x00000000000000000000000000000000
State = 0xa06b14df569b5c41a36b859535b3b5f2
Finished request 5
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 192.168.5.34:1412, id=6,
length=300
Message-Authenticator = 0x046443a3556f1099bad21bc449ae407a
Service-Type = Framed-User
User-Name = "MYNTDOMAIN\\user"
Framed-MTU = 1488
State = 0xa06b14df569b5c41a36b859535b3b5f2
Called-Station-Id = "00-1E-C1-30-51-C0:CCA2"
Calling-Station-Id = "00-13-CE-3B-44-4F"
NAS-Identifier = "3Com Access Point 7760"
NAS-Port-Type = Wireless-802.11
Connect-Info = "CONNECT 11Mbps 802.11b"
EAP-Message =
0x0206005d1900170301005281ed9614a3f65d1fdb80684aba8ac543824a545affa1141e61b90c8a8cd6d666f81929e3e25185ad5f3f21bfaf3637d57f06a38bf23cf6105db5b3e98b42027dfad4972c973477282cb49cd43a4b2e4260c9
NAS-IP-Address = 192.168.5.34
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
modcall[authorize]: module "chap" returns noop for request 6
modcall[authorize]: module "mschap" returns noop for request 6
rlm_realm: No '@' in User-Name = "MYNTDOMAIN\user", 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 6 length 93
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 DEFAULT at line 153
users: Matched entry DEFAULT at line 172
modcall[authorize]: module "files" returns ok for request 6
rlm_pap: WARNING! No "known good" password found for the user.
Authentication may fail because of this.
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: Request found, released from the list
rlm_eap: EAP/peap
rlm_eap: processing type peap
rlm_eap_peap: Authenticate
rlm_eap_tls: processing TLS
eaptls_verify returned 7
rlm_eap_tls: Done initial handshake
eaptls_process returned 7
rlm_eap_peap: EAPTLS_OK
rlm_eap_peap: Session established. Decoding tunneled attributes.
rlm_eap_peap: EAP type mschapv2
rlm_eap_peap: Tunneled data is valid.
PEAP: Setting User-Name to MYNTDOMAIN\user
PEAP: Adding old state with 43 75
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
rlm_realm: No '@' in User-Name = "MYNTDOMAIN\user", 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 6 length 70
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 DEFAULT at line 153
modcall[authorize]: module "files" returns ok for request 6
rlm_pap: WARNING! No "known good" password found for the user.
Authentication may fail because of this.
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: Request found, released from the list
rlm_eap: EAP/mschapv2
rlm_eap: processing type mschapv2
Processing the authenticate section of radiusd.conf
modcall: entering group MS-CHAP for request 6
rlm_mschap: No User-Password configured. Cannot create LM-Password.
rlm_mschap: No User-Password configured. Cannot create NT-Password.
rlm_mschap: Told to do MS-CHAPv2 for user with NT-Password
radius_xlat: Running registered xlat function of module mschap for
string 'NT-Domain'
radius_xlat: '--domain=MYNTDOMAIN'
radius_xlat: Running registered xlat function of module mschap for
string 'User-Name'
radius_xlat: '--username=user'
radius_xlat: Running registered xlat function of module mschap for
string 'Challenge'
mschap2: fc
radius_xlat: '--challenge=7bb63d62291bd255'
radius_xlat: Running registered xlat function of module mschap for
string 'NT-Response'
radius_xlat:
'--nt-response=1ace51d8b230eef7abd8ab34bd4dd34694160c3005d8d237'
Exec-Program output: Logon failure (0xc000006d)
Exec-Program-Wait: plaintext: Logon failure (0xc000006d)
Exec-Program: returned: 1
rlm_mschap: External script failed.
rlm_mschap: FAILED: MS-CHAP2-Response is incorrect
modcall[authenticate]: module "mschap" returns reject for request 6
modcall: leaving group MS-CHAP (returns reject) for request 6
rlm_eap: Freeing handler
modcall[authenticate]: module "eap" returns reject for request 6
modcall: leaving group authenticate (returns reject) for request 6
auth: Failed to validate the user.
PEAP: Tunneled authentication was rejected.
rlm_eap_peap: FAILURE
modcall[authenticate]: module "eap" returns handled for request 6
modcall: leaving group authenticate (returns handled) for request 6
Sending Access-Challenge of id 6 to 192.168.5.34 port 1412
Framed-IP-Address = 255.255.255.254
Framed-MTU = 576
Service-Type = Framed-User
EAP-Message =
0x010700261900170301001bebdaf31c825260a93d9dbcbbcc30fdd0a420825e60fac9a90ddcaa
Message-Authenticator = 0x00000000000000000000000000000000
State = 0xcfbc84ecb15a9701fbe8881a991353ad
Finished request 6
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 192.168.5.34:1412, id=7,
length=245
Message-Authenticator = 0x7da4f7bb0a8b7917661ad80df2fc8039
Service-Type = Framed-User
User-Name = "MYNTDOMAIN\\user"
Framed-MTU = 1488
State = 0xcfbc84ecb15a9701fbe8881a991353ad
Called-Station-Id = "00-1E-C1-30-51-C0:CCA2"
Calling-Station-Id = "00-13-CE-3B-44-4F"
NAS-Identifier = "3Com Access Point 7760"
NAS-Port-Type = Wireless-802.11
Connect-Info = "CONNECT 11Mbps 802.11b"
EAP-Message =
0x020700261900170301001bcd75d98ab21f529d4287baa177a67afb1ea3f01c7e3cf4901ad156
NAS-IP-Address = 192.168.5.34
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
modcall[authorize]: module "chap" returns noop for request 7
modcall[authorize]: module "mschap" returns noop for request 7
rlm_realm: No '@' in User-Name = "MYNTDOMAIN\user", 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 7 length 38
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 DEFAULT at line 153
users: Matched entry DEFAULT at line 172
modcall[authorize]: module "files" returns ok for request 7
rlm_pap: WARNING! No "known good" password found for the user.
Authentication may fail because of this.
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
eaptls_verify returned 7
rlm_eap_tls: Done initial handshake
eaptls_process returned 7
rlm_eap_peap: EAPTLS_OK
rlm_eap_peap: Session established. Decoding tunneled attributes.
rlm_eap_peap: Received EAP-TLV response.
rlm_eap_peap: Tunneled data is valid.
rlm_eap_peap: Had sent TLV failure. User was rejcted rejected
earlier in this session.
rlm_eap: Handler failed in EAP/peap
rlm_eap: Failed in EAP select
modcall[authenticate]: module "eap" returns invalid for request 7
modcall: leaving group authenticate (returns invalid) for request 7
auth: Failed to validate the user.
Delaying request 7 for 1 seconds
Finished request 7
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 192.168.5.34:1412, id=7,
length=245
Sending Access-Reject of id 7 to 192.168.5.34 port 1412
EAP-Message = 0x04070004
Message-Authenticator = 0x00000000000000000000000000000000
--- Walking the entire request list ---
Waking up in 3 seconds...
--- Walking the entire request list ---
Cleaning up request 0 ID 0 with timestamp 483510b3
Cleaning up request 1 ID 1 with timestamp 483510b3
Cleaning up request 2 ID 2 with timestamp 483510b3
Cleaning up request 3 ID 3 with timestamp 483510b3
Cleaning up request 4 ID 4 with timestamp 483510b3
Cleaning up request 5 ID 5 with timestamp 483510b3
Cleaning up request 6 ID 6 with timestamp 483510b3
Cleaning up request 7 ID 7 with timestamp 483510b3
Nothing to do. Sleeping until we see a request.
--
Tomáš Janeček
More information about the Freeradius-Users
mailing list