Weird Problem

Sofia Silva chufia at hotmail.com
Tue Sep 4 21:34:25 CEST 2007 

First of all i want to say i'm sorry for attaching so many messages, my 
problem is that i don't have a clueof where is the problem.
When i run radlogin and write the username and the password i get this:

login: testuser
Password:
RADIUS: Authentication failure
local: Authentication failure
login: testuser
Password:
RADIUS: Authentication OK
Starting.
RADIUS_SERVICE_TYPE = NAS-Prompt-User
RADIUS_USER_NAME = testuser

As you can see, in the first try it doesn't work but in the second try it 
does!!!
I don't undersatnd why this is happening.
I attach the messages of the server in verbose mode.
I would appreciate a lot if somebody could help me,
thanks a lot,
Sofía


ad_recv: Access-Request packet from host 10.220.5.32:1025, id=168, length=66
--- Walking the entire request list ---
Waking up in 31 seconds...
Thread 3 got semaphore
Thread 3 handling request 2, (1 handled so far)
        Service-Type = Login-User
        User-Name = "testuser"
        User-Password = "testuser"
        NAS-IP-Address = 10.220.5.32
        NAS-Port = 0
  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:  '/var/log/radius/radacct/10.220.5.32/auth-detail-20070904'
rlm_detail: /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d 
expands to /var/log/radius/radacct/10.220.5.32/auth-detail-20070904
  modcall[authorize]: module "auth_log" returns ok for request 2
  modcall[authorize]: module "attr_filter" returns noop for request 2
    rlm_realm: No '/' in User-Name = "testuser", looking up realm NULL
    rlm_realm: Found realm "NULL"
    rlm_realm: Adding Stripped-User-Name = "testuser"
    rlm_realm: Proxying request from user testuser to realm NULL
    rlm_realm: Adding Realm = "NULL"
    rlm_realm: Preparing to proxy authentication request to realm "NULL"
  modcall[authorize]: module "IPASS" returns updated for request 2
    rlm_realm: Request already proxied.  Ignoring.
  modcall[authorize]: module "suffix" returns noop for request 2
    rlm_realm: Request already proxied.  Ignoring.
  modcall[authorize]: module "ntdomain" returns noop for request 2
    rlm_realm: Request already proxied.  Ignoring.
  modcall[authorize]: module "NULL" returns noop for request 2
  rlm_eap: No EAP-Message, not doing EAP
  modcall[authorize]: module "eap" returns noop for request 2
    users: Matched entry DEFAULT at line 16
  modcall[authorize]: module "files" returns ok for request 2
  modcall[authorize]: module "etc_passwd" returns ok for request 2
  modcall[authorize]: module "etc_shadow" returns ok for request 2
rlm_passwd: Added Group-Name: 'users' to reply_items
rlm_passwd: Added Group-Name: 'video' to reply_items
rlm_passwd: Added Group-Name: 'dialout' to reply_items
  modcall[authorize]: module "etc_group" returns ok for request 2
rlm_checkval: Could not find item named Calling-Station-Id in request
rlm_checkval: Could not find attribute named Calling-Station-Id in check 
pairs
  modcall[authorize]: module "checkval" returns notfound for request 2
modcall: leaving group authorize (returns updated) for request 2
  Processing the pre-proxy section of radiusd.conf
modcall: entering group pre-proxy for request 2
radius_xlat:  
'/var/log/radius/radacct/10.220.5.32/pre-proxy-detail-20070904'
rlm_detail: 
/var/log/radius/radacct/%{Client-IP-Address}/pre-proxy-detail-%Y%m%d expands 
to /var/log/radius/radacct/10.220.5.32/pre-proxy-detail-20070904
  modcall[pre-proxy]: module "pre_proxy_log" returns ok for request 2
modcall: leaving group pre-proxy (returns ok) for request 2
Sending Access-Request of id 1 to 10.220.5.33 port 1645
        Service-Type = Login-User
        User-Name = "testuser"
        User-Password = "testuser"
        NAS-IP-Address = 10.220.5.32
        NAS-Port = 0
        Proxy-State = 0x313638
Thread 3 waiting to be assigned a request
rad_recv: Access-Request packet from host 10.220.5.33:1647, id=1, length=71
Ignoring request from unknown client 10.220.5.33:1647
Waking up in 31 seconds...
rad_recv: Access-Request packet from host 10.220.5.32:1025, id=168, 
length=66
Ignoring duplicate packet from client linuxclient:1025 - ID: 168, due to 
outstanding proxied request 2.
--- Walking the entire request list ---
Re-sending Access-Request of id 1 to 10.220.5.33 port 1645
        Service-Type = Login-User
        User-Name = "testuser"
        User-Password = "testuser"
        NAS-IP-Address = 10.220.5.32
        NAS-Port = 0
        Client-IP-Address = 10.220.5.32
        Stripped-User-Name = "testuser"
        Realm = "NULL"
        Realm = "NULL"
        Proxy-State = 0x313638
Waking up in 5 seconds...
rad_recv: Access-Request packet from host 10.220.5.33:1647, id=1, length=71
Ignoring request from unknown client 10.220.5.33:1647
Waking up in 5 seconds...
--- Walking the entire request list ---
Re-sending Access-Request of id 1 to 10.220.5.33 port 1645
        Service-Type = Login-User
        User-Name = "testuser"
        User-Password = "testuser"
        NAS-IP-Address = 10.220.5.32
        NAS-Port = 0
        Client-IP-Address = 10.220.5.32
        Stripped-User-Name = "testuser"
        Realm = "NULL"
        Realm = "NULL"
        Proxy-State = 0x313638
Waking up in 5 seconds...
rad_recv: Access-Request packet from host 10.220.5.33:1647, id=1, length=71
Ignoring request from unknown client 10.220.5.33:1647
Waking up in 5 seconds...
--- Walking the entire request list ---
Server rejecting request 2.
marking authentication server 10.220.5.33:1645 for realm NULL dead
marking authentication server 10.220.5.33:1645 for realm DEFAULT dead
Waking up in 0 seconds...
--- Walking the entire request list ---
Sending Access-Reject of id 168 to 10.220.5.32 port 1025
Cleaning up request 2 ID 168 with timestamp 46ddb600
Nothing to do.  Sleeping until we see a request.
rad_recv: Access-Request packet from host 10.220.5.32:1025, id=169, 
length=66
--- Walking the entire request list ---
Waking up in 31 seconds...
Thread 4 got semaphore
Thread 4 handling request 3, (1 handled so far)
        Service-Type = Login-User
        User-Name = "testuser"
        User-Password = "testuser"
        NAS-IP-Address = 10.220.5.32
        NAS-Port = 0
  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:  '/var/log/radius/radacct/10.220.5.32/auth-detail-20070904'
rlm_detail: /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d 
expands to /var/log/radius/radacct/10.220.5.32/auth-detail-20070904
  modcall[authorize]: module "auth_log" returns ok for request 3
  modcall[authorize]: module "attr_filter" returns noop for request 3
    rlm_realm: No '/' in User-Name = "testuser", looking up realm NULL
    rlm_realm: No such realm "NULL"
  modcall[authorize]: module "IPASS" returns noop for request 3
    rlm_realm: No '@' in User-Name = "testuser", looking up realm NULL
    rlm_realm: No such realm "NULL"
  modcall[authorize]: module "suffix" returns noop for request 3
    rlm_realm: No '\' in User-Name = "testuser", looking up realm NULL
    rlm_realm: No such realm "NULL"
  modcall[authorize]: module "ntdomain" returns noop for request 3
    rlm_realm: No '@' in User-Name = "testuser", looking up realm NULL
    rlm_realm: No such realm "NULL"
  modcall[authorize]: module "NULL" returns noop for request 3
  rlm_eap: No EAP-Message, not doing EAP
  modcall[authorize]: module "eap" returns noop for request 3
    users: Matched entry DEFAULT at line 16
  modcall[authorize]: module "files" returns ok for request 3
  modcall[authorize]: module "etc_passwd" returns ok for request 3
  modcall[authorize]: module "etc_shadow" returns ok for request 3
rlm_passwd: Added Group-Name: 'users' to reply_items
rlm_passwd: Added Group-Name: 'video' to reply_items
rlm_passwd: Added Group-Name: 'dialout' to reply_items
  modcall[authorize]: module "etc_group" returns ok for request 3
rlm_checkval: Could not find item named Calling-Station-Id in request
rlm_checkval: Could not find attribute named Calling-Station-Id in check 
pairs
  modcall[authorize]: module "checkval" returns notfound for request 3
modcall: leaving group authorize (returns ok) for request 3
  rad_check_password:  Found Auth-Type System
auth: type "System"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 3
  modcall[authenticate]: module "unix" returns ok for request 3
modcall: leaving group authenticate (returns ok) for request 3
Login OK: [testuser/testuser] (from client linuxclient port 0)
  Processing the post-auth section of radiusd.conf
modcall: entering group post-auth for request 3
radius_xlat:  '/var/log/radius/radacct/10.220.5.32/reply-detail-20070904'
rlm_detail: /var/log/radius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d 
expands to /var/log/radius/radacct/10.220.5.32/reply-detail-20070904
  modcall[post-auth]: module "reply_log" returns ok for request 3
modcall: leaving group post-auth (returns ok) for request 3
Sending Access-Accept of id 169 to 10.220.5.32 port 1025
        Service-Type = NAS-Prompt-User
Finished request 3
Going to the next request
Thread 4 waiting to be assigned a request

_________________________________________________________________
Visita MSN Latino Entretenimiento: ¡música, cine, chismes, TV y más...! 
http://latino.msn.com/entretenimiento/

More information about the Freeradius-Users mailing list