rlm_mschap: No User-Password configured. Cannot create LM-Password.

[Raúl Tamayo Fernández]

Hello!
I want to use FreeRadius to authenticate wireless users against an 
OpenLDAP database running in the same server that Freeradius, I want to 
use PEAP with mschapv2 (default EAP method for Windows XP).

However I got the following (complete log at the end of message):
 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/mschapv2
 rlm_eap: processing type mschapv2
 Processing the authenticate section of radiusd.conf
modcall: entering group Auth-Type for request 7
 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 luisvi with NT-Password
 rlm_mschap: FAILED: No NT/LM-Password.  Cannot perform authentication.
 rlm_mschap: FAILED: MS-CHAP2-Response is incorrect
 modcall[authenticate]: module "mschap" returns reject for request 7
modcall: group Auth-Type returns reject for request 7
 rlm_eap: Freeing handler
 modcall[authenticate]: module "eap" returns reject for request 7
modcall: group authenticate returns reject for request 7
auth: Failed to validate the user.
Login incorrect: [luisvi/<no User-Password attribute>] (from client 
localhost port 0)

Why do I get the "rlm_mschap: No User-Password configured.  Cannot 
create LM(or NT)-Password..." message? What does it means?
And most important how do I correct this?

Thanks in advance,
Raul Tamayo
Complete log:
#radiusd -X -A
Starting - reading configuration files ...
reread_config:  reading radiusd.conf
Config:   including file: /usr/local/etc/raddb/clients.conf
Config:   including file: /usr/local/etc/raddb/snmp.conf
Config:   including file: /usr/local/etc/raddb/eap.conf
Config:   including file: /usr/local/etc/raddb/sql.conf
main: prefix = "/usr/local"
main: localstatedir = "/usr/local/var"
main: logdir = "/usr/local/var/log/radius"
main: libdir = "/usr/local/lib"
main: radacctdir = "/usr/local/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 = yes
main: log_file = "/usr/local/var/log/radius/radius.log"
main: log_auth = yes
main: log_auth_badpass = yes
main: log_auth_goodpass = yes
main: pidfile = "/usr/local/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/sbin/checkrad"
main: proxy_requests = yes
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
read_config_files:  reading clients
read_config_files:  reading realms
radiusd:  entering modules setup
Module: Library search path is /usr/local/lib
Module: Loaded expr
Module: Instantiated expr (expr)
Module: Loaded MS-CHAP
mschap: use_mppe = no
mschap: require_encryption = yes
mschap: require_strong = yes
mschap: with_ntdomain_hack = no
mschap: passwd = "(null)"
mschap: authtype = "MS-CHAP"
mschap: ntlm_auth = "(null)"
Module: Instantiated mschap (mschap)
Module: Loaded LDAP
ldap: server = "localhost"
ldap: port = 389
ldap: net_timeout = 1
ldap: timeout = 4
ldap: timelimit = 3
ldap: identity = ""
ldap: tls_mode = no
ldap: start_tls = yes
ldap: tls_cacertfile = "(null)"
ldap: tls_cacertdir = "(null)"
ldap: tls_certfile = "(null)"
ldap: tls_keyfile = "(null)"
ldap: tls_randfile = "(null)"
ldap: tls_require_cert = "allow"
ldap: password = ""
ldap: basedn = "dc=cicese,dc=mx"
ldap: filter = "(uid=%{Stripped-User-Name:-%{User-Name}})"
ldap: base_filter = "(objectclass=radiusprofile)"
ldap: default_profile = "(null)"
ldap: profile_attribute = "(null)"
ldap: password_header = "{clear}"
ldap: password_attribute = "userPassword"
ldap: access_attr = "(null)"
ldap: groupname_attribute = "cn"
ldap: groupmembership_filter = 
"(|(&(objectClass=GroupOfNames)(member=%{Ldap-UserDn}))(&(objectClass=GroupOfUniqueNames)(uniquemember=%{Ldap-UserDn})))"
ldap: groupmembership_attribute = "(null)"
ldap: dictionary_mapping = "/usr/local/etc/raddb/ldap.attrmap"
ldap: ldap_debug = 0
ldap: ldap_connections_number = 5
ldap: compare_check_items = no
ldap: access_attr_used_for_allow = yes
ldap: do_xlat = yes
rlm_ldap: Registering ldap_groupcmp for Ldap-Group
rlm_ldap: Registering ldap_xlat with xlat_name ldap
rlm_ldap: reading ldap<->radius mappings from file 
/usr/local/etc/raddb/ldap.attrmap
rlm_ldap: LDAP radiusCheckItem mapped to RADIUS $GENERIC$
rlm_ldap: LDAP radiusReplyItem mapped to RADIUS $GENERIC$
rlm_ldap: LDAP radiusAuthType mapped to RADIUS Auth-Type
rlm_ldap: LDAP radiusSimultaneousUse mapped to RADIUS Simultaneous-Use
rlm_ldap: LDAP radiusCalledStationId mapped to RADIUS Called-Station-Id
rlm_ldap: LDAP radiusCallingStationId mapped to RADIUS Calling-Station-Id
rlm_ldap: LDAP userPassword mapped to RADIUS LM-Password
rlm_ldap: LDAP userPassword mapped to RADIUS NT-Password
rlm_ldap: LDAP acctFlags mapped to RADIUS SMB-Account-CTRL-TEXT
rlm_ldap: LDAP radiusExpiration mapped to RADIUS Expiration
rlm_ldap: LDAP radiusServiceType mapped to RADIUS Service-Type
rlm_ldap: LDAP radiusFramedProtocol mapped to RADIUS Framed-Protocol
rlm_ldap: LDAP radiusFramedIPAddress mapped to RADIUS Framed-IP-Address
rlm_ldap: LDAP radiusFramedIPNetmask mapped to RADIUS Framed-IP-Netmask
rlm_ldap: LDAP radiusFramedRoute mapped to RADIUS Framed-Route
rlm_ldap: LDAP radiusFramedRouting mapped to RADIUS Framed-Routing
rlm_ldap: LDAP radiusFilterId mapped to RADIUS Filter-Id
rlm_ldap: LDAP radiusFramedMTU mapped to RADIUS Framed-MTU
rlm_ldap: LDAP radiusFramedCompression mapped to RADIUS Framed-Compression
rlm_ldap: LDAP radiusLoginIPHost mapped to RADIUS Login-IP-Host
rlm_ldap: LDAP radiusLoginService mapped to RADIUS Login-Service
rlm_ldap: LDAP radiusLoginTCPPort mapped to RADIUS Login-TCP-Port
rlm_ldap: LDAP radiusCallbackNumber mapped to RADIUS Callback-Number
rlm_ldap: LDAP radiusCallbackId mapped to RADIUS Callback-Id
rlm_ldap: LDAP radiusFramedIPXNetwork mapped to RADIUS Framed-IPX-Network
rlm_ldap: LDAP radiusClass mapped to RADIUS Class
rlm_ldap: LDAP radiusSessionTimeout mapped to RADIUS Session-Timeout
rlm_ldap: LDAP radiusIdleTimeout mapped to RADIUS Idle-Timeout
rlm_ldap: LDAP radiusTerminationAction mapped to RADIUS Termination-Action
rlm_ldap: LDAP radiusLoginLATService mapped to RADIUS Login-LAT-Service
rlm_ldap: LDAP radiusLoginLATNode mapped to RADIUS Login-LAT-Node
rlm_ldap: LDAP radiusLoginLATGroup mapped to RADIUS Login-LAT-Group
rlm_ldap: LDAP radiusFramedAppleTalkLink mapped to RADIUS 
Framed-AppleTalk-Link
rlm_ldap: LDAP radiusFramedAppleTalkNetwork mapped to RADIUS 
Framed-AppleTalk-Network
rlm_ldap: LDAP radiusFramedAppleTalkZone mapped to RADIUS 
Framed-AppleTalk-Zone
rlm_ldap: LDAP radiusPortLimit mapped to RADIUS Port-Limit
rlm_ldap: LDAP radiusLoginLATPort mapped to RADIUS Login-LAT-Port
conns: 0x8150b90
Module: Instantiated ldap (ldap)
Module: Loaded eap
eap: default_eap_type = "ttls"
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/etc/raddb/certs/cert-srv.pem"
tls: certificate_file = "/usr/local/etc/raddb/certs/cert-srv.pem"
tls: CA_file = "/usr/local/etc/raddb/certs/cacert.pem"
tls: private_key_password = "otro"
tls: dh_file = "/usr/local/etc/raddb/certs/dh"
tls: random_file = "/usr/local/etc/raddb/certs/random"
tls: fragment_size = 1024
tls: include_length = yes
tls: check_crl = no
tls: check_cert_cn = "(null)"
rlm_eap: Loaded and initialized type tls
ttls: default_eap_type = "mschapv2"
ttls: copy_request_to_tunnel = no
ttls: use_tunneled_reply = no
rlm_eap: Loaded and initialized type ttls
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/etc/raddb/huntgroups"
preprocess: hints = "/usr/local/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/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 realm
realm: format = "suffix"
realm: delimiter = "@"
realm: ignore_default = no
realm: ignore_null = no
Module: Instantiated realm (suffix)
Module: Loaded files
files: usersfile = "/usr/local/etc/raddb/users"
files: acctusersfile = "/usr/local/etc/raddb/acct_users"
files: preproxy_usersfile = "/usr/local/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)
detail: detailfile = 
"/usr/local/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/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/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)
Listening on authentication *:1645
Listening on accounting *:1646
Listening on proxy *:1647
Ready to process requests.


rad_recv: Access-Request packet from host 158.97.150.22:21660, id=252, 
length=128
   User-Name = "luisvi"
   Framed-MTU = 1400
   Called-Station-Id = "000e.d7b0.f761"
   Calling-Station-Id = "0004.237c.b8c6"
   Message-Authenticator = 0x3681f59b614651995891d9681d88bc90
   EAP-Message = 0x0202000b016c7569737669
   NAS-Port-Type = Wireless-802.11
   NAS-Port = 384
   Service-Type = Framed-User
   NAS-IP-Address = 158.97.150.22
   NAS-Identifier = "ap-dt"
 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/var/log/radius/radacct/158.97.150.22/auth-detail-20050303'
rlm_detail: 
/usr/local/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d 
expands to 
/usr/local/var/log/radius/radacct/158.97.150.22/auth-detail-20050303
 modcall[authorize]: module "auth_log" returns ok for request 0
 modcall[authorize]: module "mschap" returns noop for request 0
   rlm_realm: No '@' in User-Name = "luisvi", 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 2 length 11
 rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
 modcall[authorize]: module "eap" returns updated for request 0
   users: Matched DEFAULT at 166
   users: Matched DEFAULT at 170
 modcall[authorize]: module "files" returns ok for request 0
rlm_ldap: - authorize
rlm_ldap: performing user authorization for luisvi
radius_xlat:  '(uid=luisvi)'
radius_xlat:  'dc=cicese,dc=mx'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: attempting LDAP reconnection
rlm_ldap: (re)connect to localhost:389, authentication 0
rlm_ldap: starting TLS
rlm_ldap: bind as / to localhost:389
rlm_ldap: waiting for bind result ...
rlm_ldap: Bind was successful
rlm_ldap: performing search in dc=cicese,dc=mx, with filter (uid=luisvi)
rlm_ldap: looking for check items in directory...
rlm_ldap: looking for reply items in directory...
rlm_ldap: user luisvi authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
 modcall[authorize]: module "ldap" 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 252 to 158.97.150.22:21660
   EAP-Message = 0x010300061520
   Message-Authenticator = 0x00000000000000000000000000000000
   State = 0x55ab7eb308ce236bfd16d117dcfcfa99
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 158.97.150.22:21660, id=253, 
length=141
   User-Name = "luisvi"
   Framed-MTU = 1400
   Called-Station-Id = "000e.d7b0.f761"
   Calling-Station-Id = "0004.237c.b8c6"
   Message-Authenticator = 0xef6ff85b5023f8a85ccfc1e8e81f7def
   EAP-Message = 0x020300060319
   NAS-Port-Type = Wireless-802.11
   NAS-Port = 384
   State = 0x55ab7eb308ce236bfd16d117dcfcfa99
   Service-Type = Framed-User
   NAS-IP-Address = 158.97.150.22
   NAS-Identifier = "ap-dt"
 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/var/log/radius/radacct/158.97.150.22/auth-detail-20050303'
rlm_detail: 
/usr/local/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d 
expands to 
/usr/local/var/log/radius/radacct/158.97.150.22/auth-detail-20050303
 modcall[authorize]: module "auth_log" returns ok for request 1
 modcall[authorize]: module "mschap" returns noop for request 1
   rlm_realm: No '@' in User-Name = "luisvi", 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 3 length 6
 rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
 modcall[authorize]: module "eap" returns updated for request 1
   users: Matched DEFAULT at 166
   users: Matched DEFAULT at 170
 modcall[authorize]: module "files" returns ok for request 1
rlm_ldap: - authorize
rlm_ldap: performing user authorization for luisvi
radius_xlat:  '(uid=luisvi)'
radius_xlat:  'dc=cicese,dc=mx'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in dc=cicese,dc=mx, with filter (uid=luisvi)
rlm_ldap: looking for check items in directory...
rlm_ldap: looking for reply items in directory...
rlm_ldap: user luisvi authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
 modcall[authorize]: module "ldap" 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 NAK
rlm_eap: EAP-NAK asked for EAP-Type/peap
 rlm_eap: processing type tls
 rlm_eap_tls: Initiate
 rlm_eap_tls: Start returned 1
 modcall[authenticate]: module "eap" returns handled for request 1
modcall: group authenticate returns handled for request 1
Sending Access-Challenge of id 253 to 158.97.150.22:21660
   EAP-Message = 0x010400061920
   Message-Authenticator = 0x00000000000000000000000000000000
   State = 0x700543903493eb1634ddb7810c870bb3
Finished request 1
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 158.97.150.22:21660, id=254, 
length=215
   User-Name = "luisvi"
   Framed-MTU = 1400
   Called-Station-Id = "000e.d7b0.f761"
   Calling-Station-Id = "0004.237c.b8c6"
   Message-Authenticator = 0x6cf8693e83467159a383cb5d43828f32
   EAP-Message = 
0x0204005019800000004616030100410100003d030142278df30b7a4556bb277492cbaa239ab5a66a6e1415493b68853ae94ae628b900001600040005000a000900640062000300060013001200630100
   NAS-Port-Type = Wireless-802.11
   NAS-Port = 384
   State = 0x700543903493eb1634ddb7810c870bb3
   Service-Type = Framed-User
   NAS-IP-Address = 158.97.150.22
   NAS-Identifier = "ap-dt"
 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/var/log/radius/radacct/158.97.150.22/auth-detail-20050303'
rlm_detail: 
/usr/local/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d 
expands to 
/usr/local/var/log/radius/radacct/158.97.150.22/auth-detail-20050303
 modcall[authorize]: module "auth_log" returns ok for request 2
 modcall[authorize]: module "mschap" returns noop for request 2
   rlm_realm: No '@' in User-Name = "luisvi", 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 4 length 80
 rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
 modcall[authorize]: module "eap" returns updated for request 2
   users: Matched DEFAULT at 166
   users: Matched DEFAULT at 170
 modcall[authorize]: module "files" returns ok for request 2
rlm_ldap: - authorize
rlm_ldap: performing user authorization for luisvi
radius_xlat:  '(uid=luisvi)'
radius_xlat:  'dc=cicese,dc=mx'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in dc=cicese,dc=mx, with filter (uid=luisvi)
rlm_ldap: looking for check items in directory...
rlm_ldap: looking for reply items in directory...
rlm_ldap: user luisvi authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
 modcall[authorize]: module "ldap" 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:  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 0694], 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 2
modcall: group authenticate returns handled for request 2
Sending Access-Challenge of id 254 to 158.97.150.22:21660
   EAP-Message = 
0x0105040a19c0000006f1160301004a02000046030142278de7229a1d15b692f15c3cbd38d5a1a0331815c638d4e3f1217b0823c97120e8f56230abda9bdcdd224c19810e544d06fca45bfac81fc93db868a70e2e621700040016030106940b00069000068d0002cd308202c930820232a003020102020102300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e74206365
   EAP-Message = 
0x7274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d301e170d3034303132353133323631305a170d3035303132343133323631305a30819b310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f73743119301706035504031310526f6f74206365727469666963617465311f301d06092a864886f70d0109011610726f6f74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003
   EAP-Message = 
0x818d0030818902818100dac525422bfedb082629a2cba44b3449c90d0ab462fb72c8434a782098863d7eb7d7e70028c2b7ad555a51cc756cf4fa1d7091615ab450d5289553ae6616aff014a55085d6b8fb4aee98638e426175cdd36c665c63cda177d34920eb30585edc8773999c2980f81ad4638bbbea1c82d054023db7ef24a3ec1c3f6241a903d7f30203010001a317301530130603551d25040c300a06082b06010505070301300d06092a864886f70d0101040500038181007a2d921b1cf13bf2982a9178ec9ede6d88edc178a2e8bd40a0a06fb6f0769957884cd7084537083496fd184165293f583c8e8240eb68e042c94b15752e4c07e80d09
   EAP-Message = 
0x779afa3dd55c24fa54ac292d77205d1c2477ed30d59f57caf9bd21ff2a8d16cc0911c50e4f295763fcb60efa3c3d2d0e43850f6e6fbe284902f6e83503650003ba308203b63082031fa003020102020100300d06092a864886f70d010104050030819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c
   EAP-Message = 0x652e636f6d301e170d3034303132353133323630375a
   Message-Authenticator = 0x00000000000000000000000000000000
   State = 0x74f0cc87493e86c630780b4da9d21fba
Finished request 2
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 158.97.150.22:21660, id=255, 
length=141
   User-Name = "luisvi"
   Framed-MTU = 1400
   Called-Station-Id = "000e.d7b0.f761"
   Calling-Station-Id = "0004.237c.b8c6"
   Message-Authenticator = 0x8284f7cbf35d5bd619ae9fb41098149f
   EAP-Message = 0x020500061900
   NAS-Port-Type = Wireless-802.11
   NAS-Port = 384
   State = 0x74f0cc87493e86c630780b4da9d21fba
   Service-Type = Framed-User
   NAS-IP-Address = 158.97.150.22
   NAS-Identifier = "ap-dt"
 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/var/log/radius/radacct/158.97.150.22/auth-detail-20050303'
rlm_detail: 
/usr/local/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d 
expands to 
/usr/local/var/log/radius/radacct/158.97.150.22/auth-detail-20050303
 modcall[authorize]: module "auth_log" returns ok for request 3
 modcall[authorize]: module "mschap" returns noop for request 3
   rlm_realm: No '@' in User-Name = "luisvi", 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 5 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 DEFAULT at 166
   users: Matched DEFAULT at 170
 modcall[authorize]: module "files" returns ok for request 3
rlm_ldap: - authorize
rlm_ldap: performing user authorization for luisvi
radius_xlat:  '(uid=luisvi)'
radius_xlat:  'dc=cicese,dc=mx'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in dc=cicese,dc=mx, with filter (uid=luisvi)
rlm_ldap: looking for check items in directory...
rlm_ldap: looking for reply items in directory...
rlm_ldap: user luisvi authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
 modcall[authorize]: module "ldap" 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: 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: group authenticate returns handled for request 3
Sending Access-Challenge of id 255 to 158.97.150.22:21660
   EAP-Message = 
0x010602f71900170d3036303132343133323630375a30819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d30819f300d06092a864886f70d010101050003818d0030818902818100d4c5b19724f164acf1ffb189db1c8fbff4f14396ea7cb1e90f78d69451725377895dfe52ccb99b41e8
   EAP-Message = 
0x0ddeb58b127a943f4f58cbc562878192fbdc6fece9f871e7c130d35cf5188817e9b133249edd2a1c75d31043ae87553cec7a77ef26aa7d74281db9b77e17c6446c5dd9b188b43250ca0229963722a123a726b00b4027fd0203010001a381ff3081fc301d0603551d0e0416041468d36d3e1ee7bc9d5a057021c363da1365d1ade33081cc0603551d230481c43081c1801468d36d3e1ee7bc9d5a057021c363da1365d1ade3a181a5a481a230819f310b30090603550406130243413111300f0603550408130850726f76696e63653112301006035504071309536f6d65204369747931153013060355040a130c4f7267616e697a6174696f6e31123010
   EAP-Message = 
0x060355040b13096c6f63616c686f7374311b301906035504031312436c69656e742063657274696669636174653121301f06092a864886f70d0109011612636c69656e74406578616d706c652e636f6d820100300c0603551d13040530030101ff300d06092a864886f70d01010405000381810033c00b66b1e579ef73a06798252dab8d5e5511fc00fd276d80d12f834777c6743fdc2743fca1507704e4bc0979e4f60ac3ad9ee83e6f347369229d1f77229ba2e982359da563024a00163dba6d6c986c0bad28af85132ff8f0d76501bf1b7c2dff658ce1e62c01997b6e64e3e8d4373354ce9912847651539063b85bbc5485c516030100040e000000
   Message-Authenticator = 0x00000000000000000000000000000000
   State = 0xb6679edc70ee0a25f595d1ed135ec549
Finished request 3
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 158.97.150.22:21661, id=0, 
length=327
   User-Name = "luisvi"
   Framed-MTU = 1400
   Called-Station-Id = "000e.d7b0.f761"
   Calling-Station-Id = "0004.237c.b8c6"
   Message-Authenticator = 0x11464a5a1483053719e57cb40a889fe0
   EAP-Message = 
0x020600c01980000000b61603010086100000820080d56173c6bf98fe194b614ca94bdd5b72cb665b3d39f8e2432b3786e18a8dc0dee376c04a04811600afbb3490750235feeca339a5082fa777aac718631836c839ad4f9d79a857de7dd1766f886e794063fdaae240b0ebe23a724f786f0abf061168e4a0fb6b26da3f00aa06d1f4583121e2c8c606b4d9515f4914eddd5797adc81403010001011603010020454f112c73349362a38af3550667641a135ecc80289ea2873d3df35cdfce902f
   NAS-Port-Type = Wireless-802.11
   NAS-Port = 384
   State = 0xb6679edc70ee0a25f595d1ed135ec549
   Service-Type = Framed-User
   NAS-IP-Address = 158.97.150.22
   NAS-Identifier = "ap-dt"
 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/var/log/radius/radacct/158.97.150.22/auth-detail-20050303'
rlm_detail: 
/usr/local/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d 
expands to 
/usr/local/var/log/radius/radacct/158.97.150.22/auth-detail-20050303
 modcall[authorize]: module "auth_log" returns ok for request 4
 modcall[authorize]: module "mschap" returns noop for request 4
   rlm_realm: No '@' in User-Name = "luisvi", 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 6 length 192
 rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
 modcall[authorize]: module "eap" returns updated for request 4
   users: Matched DEFAULT at 166
   users: Matched DEFAULT at 170
 modcall[authorize]: module "files" returns ok for request 4
rlm_ldap: - authorize
rlm_ldap: performing user authorization for luisvi
radius_xlat:  '(uid=luisvi)'
radius_xlat:  'dc=cicese,dc=mx'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in dc=cicese,dc=mx, with filter (uid=luisvi)
rlm_ldap: looking for check items in directory...
rlm_ldap: looking for reply items in directory...
rlm_ldap: user luisvi authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
 modcall[authorize]: module "ldap" 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:  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 4
modcall: group authenticate returns handled for request 4
Sending Access-Challenge of id 0 to 158.97.150.22:21661
   EAP-Message = 
0x0107003119001403010001011603010020faa1686b604ccc206b6c04cd816120bd6a0e0dfffad7e06371097a2198590066
   Message-Authenticator = 0x00000000000000000000000000000000
   State = 0x78fcb17d183cf412c46a947a7abb0735
Finished request 4
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 158.97.150.22:21661, id=1, 
length=141
   User-Name = "luisvi"
   Framed-MTU = 1400
   Called-Station-Id = "000e.d7b0.f761"
   Calling-Station-Id = "0004.237c.b8c6"
   Message-Authenticator = 0xe0977ec426962e7a82dcbd25b5a65c65
   EAP-Message = 0x020700061900
   NAS-Port-Type = Wireless-802.11
   NAS-Port = 384
   State = 0x78fcb17d183cf412c46a947a7abb0735
   Service-Type = Framed-User
   NAS-IP-Address = 158.97.150.22
   NAS-Identifier = "ap-dt"
 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/var/log/radius/radacct/158.97.150.22/auth-detail-20050303'
rlm_detail: 
/usr/local/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d 
expands to 
/usr/local/var/log/radius/radacct/158.97.150.22/auth-detail-20050303
 modcall[authorize]: module "auth_log" returns ok for request 5
 modcall[authorize]: module "mschap" returns noop for request 5
   rlm_realm: No '@' in User-Name = "luisvi", 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 7 length 6
 rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
 modcall[authorize]: module "eap" returns updated for request 5
   users: Matched DEFAULT at 166
   users: Matched DEFAULT at 170
 modcall[authorize]: module "files" returns ok for request 5
rlm_ldap: - authorize
rlm_ldap: performing user authorization for luisvi
radius_xlat:  '(uid=luisvi)'
radius_xlat:  'dc=cicese,dc=mx'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in dc=cicese,dc=mx, with filter (uid=luisvi)
rlm_ldap: looking for check items in directory...
rlm_ldap: looking for reply items in directory...
rlm_ldap: user luisvi authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
 modcall[authorize]: module "ldap" 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: 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 5
modcall: group authenticate returns handled for request 5
Sending Access-Challenge of id 1 to 158.97.150.22:21661
   EAP-Message = 
0x010800201900170301001536dc8360d17a84ecc24d870d32521acd97e7dbc647
   Message-Authenticator = 0x00000000000000000000000000000000
   State = 0x8383ea87e7913a454f20cb6b3b4fa6ef
Finished request 5
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 158.97.150.22:21661, id=2, 
length=169
   User-Name = "luisvi"
   Framed-MTU = 1400
   Called-Station-Id = "000e.d7b0.f761"
   Calling-Station-Id = "0004.237c.b8c6"
   Message-Authenticator = 0xaa5c85b4b608a71b2d9cf4fa8fe934cc
   EAP-Message = 
0x020800221900170301001738962733f92bb41013d46aeb744a53c4d7ca6928fc8fca
   NAS-Port-Type = Wireless-802.11
   NAS-Port = 384
   State = 0x8383ea87e7913a454f20cb6b3b4fa6ef
   Service-Type = Framed-User
   NAS-IP-Address = 158.97.150.22
   NAS-Identifier = "ap-dt"
 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/var/log/radius/radacct/158.97.150.22/auth-detail-20050303'
rlm_detail: 
/usr/local/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d 
expands to 
/usr/local/var/log/radius/radacct/158.97.150.22/auth-detail-20050303
 modcall[authorize]: module "auth_log" returns ok for request 6
 modcall[authorize]: module "mschap" returns noop for request 6
   rlm_realm: No '@' in User-Name = "luisvi", 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 8 length 34
 rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
 modcall[authorize]: module "eap" returns updated for request 6
   users: Matched DEFAULT at 166
   users: Matched DEFAULT at 170
 modcall[authorize]: module "files" returns ok for request 6
rlm_ldap: - authorize
rlm_ldap: performing user authorization for luisvi
radius_xlat:  '(uid=luisvi)'
radius_xlat:  'dc=cicese,dc=mx'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in dc=cicese,dc=mx, with filter (uid=luisvi)
rlm_ldap: looking for check items in directory...
rlm_ldap: looking for reply items in directory...
rlm_ldap: user luisvi authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
 modcall[authorize]: module "ldap" 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: 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 - luisvi
 rlm_eap_peap: Tunneled data is valid.
 PEAP: Got tunneled EAP-Message
   EAP-Message = 0x0208000b016c7569737669
 PEAP: Got tunneled identity of luisvi
 PEAP: Setting default EAP type for tunneled EAP session.
 PEAP: Setting User-Name to luisvi
 PEAP: Sending tunneled request
   EAP-Message = 0x0208000b016c7569737669
   FreeRADIUS-Proxied-To = 127.0.0.1
   User-Name = "luisvi"
 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/var/log/radius/radacct/127.0.0.1/auth-detail-20050303'
rlm_detail: 
/usr/local/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d 
expands to /usr/local/var/log/radius/radacct/127.0.0.1/auth-detail-20050303
 modcall[authorize]: module "auth_log" returns ok for request 6
 modcall[authorize]: module "mschap" returns noop for request 6
   rlm_realm: No '@' in User-Name = "luisvi", 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 8 length 11
 rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
 modcall[authorize]: module "eap" returns updated for request 6
   users: Matched DEFAULT at 166
   users: Matched DEFAULT at 170
 modcall[authorize]: module "files" returns ok for request 6
rlm_ldap: - authorize
rlm_ldap: performing user authorization for luisvi
radius_xlat:  '(uid=luisvi)'
radius_xlat:  'dc=cicese,dc=mx'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in dc=cicese,dc=mx, with filter (uid=luisvi)
rlm_ldap: looking for check items in directory...
rlm_ldap: looking for reply items in directory...
rlm_ldap: user luisvi authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
 modcall[authorize]: module "ldap" 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 mschapv2
rlm_eap_mschapv2: Issuing Challenge
 modcall[authenticate]: module "eap" returns handled for request 6
modcall: group authenticate returns handled for request 6
 PEAP: Got tunneled reply RADIUS code 11
   EAP-Message = 
0x010900201a0109001b105d12c176d54a9d2114ed36666ffbfb9f6c7569737669
   Message-Authenticator = 0x00000000000000000000000000000000
   State = 0xb2915251b2bf4fabfcd9ab0f4f7ac1c7
 PEAP: Processing from tunneled session code 0x8164ca0 11
   EAP-Message = 
0x010900201a0109001b105d12c176d54a9d2114ed36666ffbfb9f6c7569737669
   Message-Authenticator = 0x00000000000000000000000000000000
   State = 0xb2915251b2bf4fabfcd9ab0f4f7ac1c7
 PEAP: Got tunneled Access-Challenge
 modcall[authenticate]: module "eap" returns handled for request 6
modcall: group authenticate returns handled for request 6
Sending Access-Challenge of id 2 to 158.97.150.22:21661
   EAP-Message = 
0x010900371900170301002cbf3c04393fc594ebf7b10e8e03e66d03e3e5b7062cb20832247d29ed36953f86a85b83ec693d2c10a11ac03c
   Message-Authenticator = 0x00000000000000000000000000000000
   State = 0xbee61642f0cfa4f366d1f595c25179ba
Finished request 6
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 158.97.150.22:21661, id=3, 
length=223
   User-Name = "luisvi"
   Framed-MTU = 1400
   Called-Station-Id = "000e.d7b0.f761"
   Calling-Station-Id = "0004.237c.b8c6"
   Message-Authenticator = 0x10917c021b73ed4b6bc0f95e0f771a59
   EAP-Message = 
0x020900581900170301004d9efeaadab797c7fa7e26e98be5175af46494d639995c60d68301cf90c32adcea50569e23e561dd002ece49db8466538e73c8321e6c8c97fdbb7d864c17ec90855961cfaca67f01f3ec0d698149
   NAS-Port-Type = Wireless-802.11
   NAS-Port = 384
   State = 0xbee61642f0cfa4f366d1f595c25179ba
   Service-Type = Framed-User
   NAS-IP-Address = 158.97.150.22
   NAS-Identifier = "ap-dt"
 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/var/log/radius/radacct/158.97.150.22/auth-detail-20050303'
rlm_detail: 
/usr/local/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d 
expands to 
/usr/local/var/log/radius/radacct/158.97.150.22/auth-detail-20050303
 modcall[authorize]: module "auth_log" returns ok for request 7
 modcall[authorize]: module "mschap" returns noop for request 7
   rlm_realm: No '@' in User-Name = "luisvi", 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 9 length 88
 rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
 modcall[authorize]: module "eap" returns updated for request 7
   users: Matched DEFAULT at 166
   users: Matched DEFAULT at 170
 modcall[authorize]: module "files" returns ok for request 7
rlm_ldap: - authorize
rlm_ldap: performing user authorization for luisvi
radius_xlat:  '(uid=luisvi)'
radius_xlat:  'dc=cicese,dc=mx'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in dc=cicese,dc=mx, with filter (uid=luisvi)
rlm_ldap: looking for check items in directory...
rlm_ldap: looking for reply items in directory...
rlm_ldap: user luisvi authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
 modcall[authorize]: module "ldap" 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
 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: Got tunneled EAP-Message
   EAP-Message = 
0x020900411a0209003c315abf64c2061ff0a4beec88998990989e00000000000000007d243136c5817d37de8bf4e0039e54f4b1282785bb5acfd9006c7569737669
 PEAP: Setting User-Name to luisvi
 PEAP: Adding old state with b2 91
 PEAP: Sending tunneled request
   EAP-Message = 
0x020900411a0209003c315abf64c2061ff0a4beec88998990989e00000000000000007d243136c5817d37de8bf4e0039e54f4b1282785bb5acfd9006c7569737669
   FreeRADIUS-Proxied-To = 127.0.0.1
   User-Name = "luisvi"
   State = 0xb2915251b2bf4fabfcd9ab0f4f7ac1c7
 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/var/log/radius/radacct/127.0.0.1/auth-detail-20050303'
rlm_detail: 
/usr/local/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d 
expands to /usr/local/var/log/radius/radacct/127.0.0.1/auth-detail-20050303
 modcall[authorize]: module "auth_log" returns ok for request 7
 modcall[authorize]: module "mschap" returns noop for request 7
   rlm_realm: No '@' in User-Name = "luisvi", 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 9 length 65
 rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
 modcall[authorize]: module "eap" returns updated for request 7
   users: Matched DEFAULT at 166
   users: Matched DEFAULT at 170
 modcall[authorize]: module "files" returns ok for request 7
rlm_ldap: - authorize
rlm_ldap: performing user authorization for luisvi
radius_xlat:  '(uid=luisvi)'
radius_xlat:  'dc=cicese,dc=mx'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in dc=cicese,dc=mx, with filter (uid=luisvi)
rlm_ldap: looking for check items in directory...
rlm_ldap: looking for reply items in directory...
rlm_ldap: user luisvi authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
 modcall[authorize]: module "ldap" 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/mschapv2
 rlm_eap: processing type mschapv2
 Processing the authenticate section of radiusd.conf
modcall: entering group Auth-Type for request 7
 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 luisvi with NT-Password
 rlm_mschap: FAILED: No NT/LM-Password.  Cannot perform authentication.
 rlm_mschap: FAILED: MS-CHAP2-Response is incorrect
 modcall[authenticate]: module "mschap" returns reject for request 7
modcall: group Auth-Type returns reject for request 7
 rlm_eap: Freeing handler
 modcall[authenticate]: module "eap" returns reject for request 7
modcall: group authenticate returns reject for request 7
auth: Failed to validate the user.
Login incorrect: [luisvi/<no User-Password attribute>] (from client 
localhost port 0)
 PEAP: Got tunneled reply RADIUS code 3
   MS-CHAP-Error = "\tE=691 R=1"
   EAP-Message = 0x04090004
   Message-Authenticator = 0x00000000000000000000000000000000
 PEAP: Processing from tunneled session code 0x8165158 3
   MS-CHAP-Error = "\tE=691 R=1"
   EAP-Message = 0x04090004
   Message-Authenticator = 0x00000000000000000000000000000000
 PEAP: Tunneled authentication was rejected.
 rlm_eap_peap: FAILURE
 modcall[authenticate]: module "eap" returns handled for request 7
modcall: group authenticate returns handled for request 7
Sending Access-Challenge of id 3 to 158.97.150.22:21661
   EAP-Message = 
0x010a00261900170301001bc52fb9eeb1aa829fb89926b4c9458ee65dbbc8e7d661ddeab05bcd
   Message-Authenticator = 0x00000000000000000000000000000000
   State = 0x8be4d4527899c74763d1d90820d85417
Finished request 7
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 158.97.150.22:21661, id=4, 
length=173
   User-Name = "luisvi"
   Framed-MTU = 1400
   Called-Station-Id = "000e.d7b0.f761"
   Calling-Station-Id = "0004.237c.b8c6"
   Message-Authenticator = 0x30b018030dd89e16d599b81fb09492b5
   EAP-Message = 
0x020a00261900170301001bb5f3319b13fcc240a8023a23319f8324fe7dccf8ab4fed73f62bd7
   NAS-Port-Type = Wireless-802.11
   NAS-Port = 384
   State = 0x8be4d4527899c74763d1d90820d85417
   Service-Type = Framed-User
   NAS-IP-Address = 158.97.150.22
   NAS-Identifier = "ap-dt"
 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/var/log/radius/radacct/158.97.150.22/auth-detail-20050303'
rlm_detail: 
/usr/local/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d 
expands to 
/usr/local/var/log/radius/radacct/158.97.150.22/auth-detail-20050303
 modcall[authorize]: module "auth_log" returns ok for request 8
 modcall[authorize]: module "mschap" returns noop for request 8
   rlm_realm: No '@' in User-Name = "luisvi", 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 10 length 38
 rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
 modcall[authorize]: module "eap" returns updated for request 8
   users: Matched DEFAULT at 166
   users: Matched DEFAULT at 170
 modcall[authorize]: module "files" returns ok for request 8
rlm_ldap: - authorize
rlm_ldap: performing user authorization for luisvi
radius_xlat:  '(uid=luisvi)'
radius_xlat:  'dc=cicese,dc=mx'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in dc=cicese,dc=mx, with filter (uid=luisvi)
rlm_ldap: looking for check items in directory...
rlm_ldap: looking for reply items in directory...
rlm_ldap: user luisvi authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
 modcall[authorize]: module "ldap" 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
 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, rejecting.
rlm_eap: Handler failed in EAP/peap
 rlm_eap: Failed in EAP select
 modcall[authenticate]: module "eap" returns invalid for request 8
modcall: group authenticate returns invalid for request 8
auth: Failed to validate the user.
Login incorrect: [luisvi/<no User-Password attribute>] (from client 
ap-dt-cisco port 384 cli 0004.237c.b8c6)
Delaying request 8 for 1 seconds
Finished request 8
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 158.97.150.22:21661, id=4, 
length=173
Sending Access-Reject of id 4 to 158.97.150.22:21661
   EAP-Message = 0x040a0004
   Message-Authenticator = 0x00000000000000000000000000000000
--- Walking the entire request list ---
Waking up in 1 seconds...
--- Walking the entire request list ---
Cleaning up request 4 ID 0 with timestamp 42278de7
Cleaning up request 5 ID 1 with timestamp 42278de7
Cleaning up request 6 ID 2 with timestamp 42278de7
Cleaning up request 7 ID 3 with timestamp 42278de7
Cleaning up request 8 ID 4 with timestamp 42278de7
Cleaning up request 0 ID 252 with timestamp 42278de7
Cleaning up request 1 ID 253 with timestamp 42278de7
Cleaning up request 2 ID 254 with timestamp 42278de7
Cleaning up request 3 ID 255 with timestamp 42278de7
Nothing to do.  Sleeping until we see a request.


- 
List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html