Problem with Freeradius+LDAP+wifi

[Rafa? Kamin'ski]

Sorry for my all post :(

I set peap/eap/tls and i start freeradius but when user on laptop with
wifi want to auth. to radius over linksys, in log is:

rad_recv: Access-Request packet from host 192.168.1.245:3072, id=0,
length=167
        User-Name = "lpa"
        NAS-IP-Address = 192.168.1.245
        Called-Station-Id = "001217694588"
        Calling-Station-Id = "0014a41e7112"
        NAS-Identifier = "001217694588"
        NAS-Port = 61
        Framed-MTU = 1400
        State = 0xd7a7e508bf067ebf840f706609179973
        NAS-Port-Type = Wireless-802.11
        EAP-Message =
0x020800261900170301001b0a80b340ff12abb3c834cd77d204562a8b8514d1823bfd2b9ecbf2
        Message-Authenticator = 0x242aac203af35c0d27c38f590d032df8
Tue Jan 16 14:35:56 2007 : Debug:   Processing the authorize section of
radiusd.conf
Tue Jan 16 14:35:56 2007 : Debug: modcall: entering group authorize for
request 19
Tue Jan 16 14:35:56 2007 : Debug:   modsingle[authorize]: calling
preprocess (rlm_preprocess) for request 19
Tue Jan 16 14:35:56 2007 : Debug:   modsingle[authorize]: returned from
preprocess (rlm_preprocess) for request 19
Tue Jan 16 14:35:56 2007 : Debug:   modcall[authorize]: module
"preprocess" returns ok for request 19
Tue Jan 16 14:35:56 2007 : Debug:   modsingle[authorize]: calling ldap
(rlm_ldap) for request 19
Tue Jan 16 14:35:56 2007 : Debug: rlm_ldap: - authorize
Tue Jan 16 14:35:56 2007 : Debug: rlm_ldap: performing user
authorization for lpa
Tue Jan 16 14:35:56 2007 : Debug: radius_xlat:  '(uid=lpa)'
Tue Jan 16 14:35:56 2007 : Debug: radius_xlat:  'ou=Users,dc=domain'
Tue Jan 16 14:35:56 2007 : Debug: rlm_ldap: ldap_get_conn: Checking Id: 0
Tue Jan 16 14:35:56 2007 : Debug: rlm_ldap: ldap_get_conn: Got Id: 0
Tue Jan 16 14:35:56 2007 : Debug: rlm_ldap: performing search in
ou=Users,dc=blstream, with filter (uid=lpa)
Tue Jan 16 14:35:56 2007 : Debug: rlm_ldap: looking for check items in
directory...
Tue Jan 16 14:35:56 2007 : Debug: rlm_ldap: looking for reply items in
directory...
Tue Jan 16 14:35:56 2007 : Debug: rlm_ldap: user lpa authorized to use
remote access
Tue Jan 16 14:35:56 2007 : Debug: rlm_ldap: ldap_release_conn: Release Id: 0
Tue Jan 16 14:35:56 2007 : Debug:   modsingle[authorize]: returned from
ldap (rlm_ldap) for request 19
Tue Jan 16 14:35:56 2007 : Debug:   modcall[authorize]: module "ldap"
returns ok for request 19
Tue Jan 16 14:35:56 2007 : Debug:   modsingle[authorize]: calling eap
(rlm_eap) for request 19
Tue Jan 16 14:35:56 2007 : Debug:   rlm_eap: EAP packet type response id
8 length 38
Tue Jan 16 14:35:56 2007 : Debug:   rlm_eap: No EAP Start, assuming it's
an on-going EAP conversation
Tue Jan 16 14:35:56 2007 : Debug:   modsingle[authorize]: returned from
eap (rlm_eap) for request 19
Tue Jan 16 14:35:56 2007 : Debug:   modcall[authorize]: module "eap"
returns updated for request 19
Tue Jan 16 14:35:56 2007 : Debug: modcall: leaving group authorize
(returns updated) for request 19
Tue Jan 16 14:35:56 2007 : Debug:   rad_check_password:  Found Auth-Type EAP
Tue Jan 16 14:35:56 2007 : Debug: auth: type "EAP"
Tue Jan 16 14:35:56 2007 : Debug:   Processing the authenticate section
of radiusd.conf
Tue Jan 16 14:35:56 2007 : Debug: modcall: entering group authenticate
for request 19
Tue Jan 16 14:35:56 2007 : Debug:   modsingle[authenticate]: calling eap
(rlm_eap) for request 19
Tue Jan 16 14:35:56 2007 : Debug:   rlm_eap: Request found, released
from the list
Tue Jan 16 14:35:56 2007 : Debug:   rlm_eap: EAP/peap
Tue Jan 16 14:35:56 2007 : Debug:   rlm_eap: processing type peap
Tue Jan 16 14:35:56 2007 : Debug:   rlm_eap_peap: Authenticate
Tue Jan 16 14:35:56 2007 : Debug:   rlm_eap_tls: processing TLS
Tue Jan 16 14:35:56 2007 : Debug:   eaptls_verify returned 7
Tue Jan 16 14:35:56 2007 : Debug:   rlm_eap_tls: Done initial handshake
Tue Jan 16 14:35:56 2007 : Debug:   eaptls_process returned 7
Tue Jan 16 14:35:56 2007 : Debug:   rlm_eap_peap: EAPTLS_OK
Tue Jan 16 14:35:56 2007 : Debug:   rlm_eap_peap: Session established.
Decoding tunneled attributes.
  PEAP tunnel data in 0000: 02 08 00 0b 21 80 03 00 02 00 02
Tue Jan 16 14:35:56 2007 : Debug:   rlm_eap_peap: Received EAP-TLV response.
Tue Jan 16 14:35:56 2007 : Debug:   rlm_eap_peap: Tunneled data is valid.
Tue Jan 16 14:35:56 2007 : Debug:   rlm_eap_peap:  Had sent TLV failure.
 User was rejcted rejected earlier in this session.
Tue Jan 16 14:35:56 2007 : Debug:  rlm_eap: Handler failed in EAP/peap
Tue Jan 16 14:35:56 2007 : Debug:   rlm_eap: Failed in EAP select
Tue Jan 16 14:35:56 2007 : Debug:   modsingle[authenticate]: returned
from eap (rlm_eap) for request 19
Tue Jan 16 14:35:56 2007 : Debug:   modcall[authenticate]: module "eap"
returns invalid for request 19
Tue Jan 16 14:35:56 2007 : Debug: modcall: leaving group authenticate
(returns invalid) for request 19
Tue Jan 16 14:35:56 2007 : Debug: auth: Failed to validate the user.
Tue Jan 16 14:35:56 2007 : Debug: Delaying request 19 for 1 seconds
Tue Jan 16 14:35:56 2007 : Debug: Finished request 19
Tue Jan 16 14:35:56 2007 : Debug: Going to the next request
Tue Jan 16 14:35:56 2007 : Debug: rl_next:  returning NULL
Tue Jan 16 14:35:56 2007 : Debug: Waking up in 6 seconds...
Tue Jan 16 14:36:02 2007 : Debug: --- Walking the entire request list ---
Sending Access-Reject of id 0 to 192.168.1.245 port 3072
        EAP-Message = 0x04080004
        Message-Authenticator = 0x00000000000000000000000000000000

I have question: what is this: rlm_eap_peap:  Had sent TLV failure.
User was rejcted rejected earlier in this session. ???

I think it is the problem with reject :(



-- 
Rafal Kaminski
http://blstream.com
email: rafal.kaminski at blstream.com
jid: rka at im.blstream.com