proxy request when ldap authentication is enable
sumi thra
sumi.techno at gmail.com
Mon Jul 31 12:18:00 CEST 2006
Im sorry, i missed out the debug long in my previous mail.
Please find the logs given below.
rad_recv: Access-Request packet from host 127.0.0.1:30504, id=8, length=295
User-Name = "anonymous"
Called-Station-Id = "00-A0-F8-BF-E9-BC:ssid1"
Calling-Station-Id = "00-0F-3D-E9-A6-54"
NAS-Port = 1
NAS-Port-Type = Wireless-802.11
Framed-MTU = 1400
NAS-IP-Address = 127.0.0.1
NAS-Identifier = "switch"
VSA-1 = "ssid1"
ASA-2 = "1"
NAS-Port-Id = "WLAN1"
Connect-Info = "CONNECT 54Mbps 802.11a"
State = 0x7202552d433833273413b6683dd14790
EAP-Message =
0x0205004f158000000045170301004073b0056431239ce2d8e9dff10e30ac509c6189763cbcc5d2f0a254fc338a827b5c7322e66c46ebbddf16bb5a0d807a31b3bdd103188ded50ea2e25578a88d6de
Message-Authenticator = 0xebdc63168f0f7b03694605ba31129085
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 = "anonymous", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix_oblic" returns noop for request 4
rlm_realm: No '/' in User-Name = "anonymous", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "prefix_oblic" returns noop for request 4
rlm_realm: No '@' in User-Name = "anonymous", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix_at" returns noop for request 4
rlm_realm: No '@' in User-Name = "anonymous", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "prefix_at" returns noop for request 4
rlm_realm: No '%' in User-Name = "anonymous", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix_percent" returns noop for request 4
rlm_realm: No '%' in User-Name = "anonymous", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "prefix_percent" returns noop for request 4
users: Matched entry anonymous at line 2
modcall[authorize]: module "files" returns ok for request 4
rlm_eap: EAP packet type response id 5 length 79
rlm_eap: No EAP Start, astestuserng it's an on-going EAP conversation
modcall[authorize]: module "eap" returns updated for request 4
modcall: leaving group authorize (returns updated) for request 4
rad_check_password: Found Auth-Type EAP
rad_check_password: Found Auth-Type EAP
Warning: Found 2 auth-types on request for user 'anonymous'
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/ttls
rlm_eap: processing type ttls
rlm_eap_ttls: Authenticate
rlm_eap_tls: processing TLS
rlm_eap_tls: Length Included
eaptls_verify returned 11
eaptls_process returned 7
rlm_eap_ttls: Session established. Proceeding to decode tunneled
attributes.
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 = "testuser at domain.com", looking up realm
NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix_oblic" returns noop for request 4
rlm_realm: No '/' in User-Name = "testuser at domain.com", looking up realm
NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "prefix_oblic" returns noop for request 4
rlm_realm: Looking up realm "domain.com" for User-Name = "
testuser at domain.com"
rlm_realm: Found realm "domain.com"
rlm_realm: Proxying request from user testuser to realm domain.com
rlm_realm: Adding Realm = "domain.com"
rlm_realm: Preparing to proxy authentication request to realm "
domain.com"
modcall[authorize]: module "suffix_at" returns updated for request 4
rlm_realm: Request already proxied. Ignoring.
modcall[authorize]: module "prefix_at" returns noop for request 4
rlm_realm: Request already proxied. Ignoring.
modcall[authorize]: module "suffix_percent" returns noop for request 4
rlm_realm: Request already proxied. Ignoring.
modcall[authorize]: module "prefix_percent" returns noop for request 4
radius_xlat: 'group2'
rlm_ldap: Entering ldap_groupcmp()
radius_xlat: 'o=domain,c=india'
radius_xlat: '(uid=testuser at domain.com)'
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 1.1.1.3:389, authentication 0
rlm_ldap: bind as cn=Manager,o=domain,c=India/secret to 1.1.1.3:389
rlm_ldap: cn=Manager,o=domain,c=India bind to 1.1.1.3:389 failed: Can't
contact LDAP server
rlm_ldap: (re)connection attempt failed
rlm_ldap::ldap_groupcmp: search failed
rlm_ldap: ldap_release_conn: Release Id: 0
radius_xlat: '1234'
rlm_ldap: Entering ldap_groupcmp()
radius_xlat: 'o=domain,c=india'
radius_xlat: '(uid=testuser at domain.com)'
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 1.1.1.3:389, authentication 0
rlm_ldap: bind as cn=Manager,o=domain,c=India/secret to 1.1.1.3:389
rlm_ldap: cn=Manager,o=domain,c=India bind to 1.1.1.3:389 failed: Can't
contact LDAP server
rlm_ldap: (re)connection attempt failed
rlm_ldap::ldap_groupcmp: search failed
rlm_ldap: ldap_release_conn: Release Id: 0
radius_xlat: '1111'
rlm_ldap: Entering ldap_groupcmp()
radius_xlat: 'o=domain,c=india'
radius_xlat: '(uid=testuser at domain.com)'
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 1.1.1.3:389, authentication 0
rlm_ldap: bind as cn=Manager,o=domain,c=India/secret to 1.1.1.3:389
rlm_ldap: cn=Manager,o=domain,c=India bind to 1.1.1.3:389 failed: Can't
contact LDAP server
rlm_ldap: (re)connection attempt failed
rlm_ldap::ldap_groupcmp: search failed
rlm_ldap: ldap_release_conn: Release Id: 0
radius_xlat: '111'
rlm_ldap: Entering ldap_groupcmp()
radius_xlat: 'o=domain,c=india'
radius_xlat: '(uid=testuser at domain.com)'
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 1.1.1.3:389, authentication 0
rlm_ldap: bind as cn=Manager,o=domain,c=India/secret to 1.1.1.3:389
rlm_ldap: cn=Manager,o=domain,c=India bind to 1.1.1.3:389 failed: Can't
contact LDAP server
rlm_ldap: (re)connection attempt failed
rlm_ldap::ldap_groupcmp: search failed
rlm_ldap: ldap_release_conn: Release Id: 0
radius_xlat: 'group1'
rlm_ldap: Entering ldap_groupcmp()
radius_xlat: 'o=domain,c=india'
radius_xlat: '(uid=testuser at domain.com)'
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 1.1.1.3:389, authentication 0
rlm_ldap: bind as cn=Manager,o=domain,c=India/secret to 1.1.1.3:389
rlm_ldap: cn=Manager,o=domain,c=India bind to 1.1.1.3:389 failed: Can't
contact LDAP server
rlm_ldap: (re)connection attempt failed
rlm_ldap::ldap_groupcmp: search failed
rlm_ldap: ldap_release_conn: Release Id: 0
radius_xlat: 'group2'
rlm_ldap: Entering ldap_groupcmp()
radius_xlat: 'o=domain,c=india'
radius_xlat: '(uid=testuser at domain.com)'
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 1.1.1.3:389, authentication 0
rlm_ldap: bind as cn=Manager,o=domain,c=India/secret to 1.1.1.3:389
rlm_ldap: cn=Manager,o=domain,c=India bind to 1.1.1.3:389 failed: Can't
contact LDAP server
rlm_ldap: (re)connection attempt failed
rlm_ldap::ldap_groupcmp: search failed
rlm_ldap: ldap_release_conn: Release Id: 0
radius_xlat: '1234'
rlm_ldap: Entering ldap_groupcmp()
radius_xlat: 'o=domain,c=india'
radius_xlat: '(uid=testuser at domain.com)'
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 1.1.1.3:389, authentication 0
rlm_ldap: bind as cn=Manager,o=domain,c=India/secret to 1.1.1.3:389
rlm_ldap: cn=Manager,o=domain,c=India bind to 1.1.1.3:389 failed: Can't
contact LDAP server
rlm_ldap: (re)connection attempt failed
rlm_ldap::ldap_groupcmp: search failed
rlm_ldap: ldap_release_conn: Release Id: 0
users: Matched entry DEFAULT at line 40
modcall[authorize]: module "files" returns ok for request 4
rlm_eap: No EAP-Message, not doing EAP
modcall[authorize]: module "eap" returns noop for request 4
modcall: leaving group authorize (returns updated) for request 4
TTLS: Tunneled authentication will be proxied to domain.com
Tunneled session will be proxied. Not doing EAP.
modcall[authenticate]: module "eap" returns handled for request 4
modcall: leaving group authenticate (returns handled) for request 4
Sending Access-Request of id 0 to 1.1.1.1 port 1812
User-Name = "testuser at domain.com"
User-Password = "domain"
Called-Station-Id = "00-A0-F8-BF-E9-BC:ssid1"
Calling-Station-Id = "00-0F-3D-E9-A6-54"
NAS-Port = 1
NAS-Port-Type = Wireless-802.11
Framed-MTU = 1400
NAS-IP-Address = 127.0.0.1
NAS-Identifier = "switch"
VSA-1 = "ssid1"
ASA-2 = "1"
NAS-Port-Id = "WLAN1"
Connect-Info = "CONNECT 54Mbps 802.11a"
Proxy-State = 0x38
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 127.0.0.1:30504, id=8, length=295
Dropping conflicting packet from client wios:30504 - ID: 8 due to unfinished
request 4
--- Walking the entire request list ---
Cleaning up request 0 ID 4 with timestamp 44ce1508
Cleaning up request 1 ID 5 with timestamp 44ce1508
Cleaning up request 2 ID 6 with timestamp 44ce1508
Cleaning up request 3 ID 7 with timestamp 44ce1508
Re-sending Access-Request of id 0 to 1.1.1.1 port 1812
User-Name = "testuser at domain.com"
User-Password = "domain"
Called-Station-Id = "00-A0-F8-BF-E9-BC:ssid1"
Calling-Station-Id = "00-0F-3D-E9-A6-54"
NAS-Port = 1
NAS-Port-Type = Wireless-802.11
Framed-MTU = 1400
NAS-IP-Address = 127.0.0.1
NAS-Identifier = "switch"
VSA-1 = "ssid1"
ASA-2 = "1"
NAS-Port-Id = "WLAN1"
Connect-Info = "CONNECT 54Mbps 802.11a"
Client-IP-Address = 127.0.0.1
Realm = "domain.com"
Proxy-State = 0x38
Waking up in 5 seconds...
rad_recv: Access-Accept packet from host 1.1.1.1:1812, id=0, length=67
Reply-Message = " Hi testuser at domain.com welcome "
Proxy-State = 0x38
authorize: Skipping authorize in post-proxy stage
rad_check_password: Found Auth-Type EAP
rad_check_password: Found Auth-Type EAP
rad_check_password: Found Auth-Type
Warning: Found 3 auth-types on request for user 'anonymous'
rad_check_password: Auth-Type = Accept, accepting the user
radius_xlat: ' Hi testuser at domain.com welcome'
Login OK: [anonymous] (from client wios port 1 cli 00-0F-3D-E9-A6-54)
Sending Access-Accept of id 8 to 127.0.0.1 port 30504
Reply-Message = " Hi testuser at domain.com welcome"
Finished request 4
Going to the next request
Waking up in 5 seconds...
rad_recv: Access-Accept packet from host 1.1.1.1:1812, id=0, length=67
Reply from home server 1.1.1.1:1812 - ID: 0 arrived too late for request 4.
Try increasing 'retry_delay' or 'max_request_time'
Waking up in 5 seconds...
--- Walking the entire request list ---
Waking up in 5 seconds..
Thanks for any help.
On 7/22/06, Alan DeKok <aland at nitros9.org> wrote:
>
> "sumi thra" <sumi.techno at gmail.com> wrote:
> > > Please find the configuration in the users file & proxy.conf file.
> Please
> > > let me know if i am missing or wrong configuration is done is achieve
> my
> > > objective.
>
> Can you explain why you posted the configuration files when I told
> you the debug output was important?
>
> Alan DeKok.
>
> -
> List info/subscribe/unsubscribe? See
> http://www.freeradius.org/list/users.html
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freeradius.org/pipermail/freeradius-users/attachments/20060731/b5f4f6e5/attachment.html>
More information about the Freeradius-Users
mailing list