Access-Reject problem.

[Genoud Richard]

hi !
I use freeradius 0.8.1.
i've configured freeradius with a reject_delay of one second.
I send an acces-request for a bad user with ntradping. (time-out 15sec, 
 only one attempt )

with ethereal, I get :
time 0 : source 10.0.9.192 access request id 7
and nothing else...
if I send another access-request :
time 20 : source 10.0.9.192 access request id 8
time 20 : source 10.0.9.192 access reject id 7
the access-reject corresponding with the 1st access request is sent ...

weird isn't it ?
is there something I missed ?
here's the log :
rad_recv: Access-Request packet from host 10.0.9.192:61007, id=7, length=44
Thread 5 assigned request 45
--- Walking the entire request list ---
Threads: total/active/spare threads = 5/1/4
Nothing to do.  Sleeping until we see a request.
Thread 5 handling request 45, (8 handled so far)
        User-Name = "toto"
        User-Password = "titi"
modcall: entering group authorize
radius_xlat:  'toto'
rlm_sql (SQL1): sql_set_user escaped user --> 'toto'
radius_xlat:  'SELECT id,UserName,Attribute,Value,op FROM radcheck WHERE 
Username = 'toto' ORDER BY id'
rlm_sql (/usr/local/var/log/radius/sqltrace.sql): Reserving sql socket id: 0
rlm_sql_mysql: query:  SELECT id,UserName,Attribute,Value,op FROM 
radcheck WHERE Username = 'toto' ORDER BY id
radius_xlat:  'SELECT 
radgroupcheck.id,radgroupcheck.GroupName,radgroupcheck.Attribute,radgroupcheck.Value,radgroupcheck.op 
FROM radgroupcheck,usergroup WHERE usergroup.Username = 'toto' AND 
usergroup.GroupName = radgroupcheck.GroupName ORDER BY radgroupcheck.id'
rlm_sql_mysql: query:  SELECT 
radgroupcheck.id,radgroupcheck.GroupName,radgroupcheck.Attribute,radgroupcheck.Value,radgroupcheck.op 
FROM radgroupcheck,usergroup WHERE usergroup.Username = 'toto' AND 
usergroup.GroupName = radgroupcheck.GroupName ORDER BY radgroupcheck.id
radius_xlat:  'SELECT id,UserName,Attribute,Value,op FROM radreply WHERE 
Username = 'toto' ORDER BY id'
rlm_sql_mysql: query:  SELECT id,UserName,Attribute,Value,op FROM 
radreply WHERE Username = 'toto' ORDER BY id
radius_xlat:  'SELECT 
radgroupreply.id,radgroupreply.GroupName,radgroupreply.Attribute,radgroupreply.Value,radgroupreply.op 
 FROM radgroupreply,usergroup WHERE usergroup.Username = 'toto' AND 
usergroup.GroupName = radgroupreply.GroupName ORDER BY radgroupreply.id'
rlm_sql_mysql: query:  SELECT 
radgroupreply.id,radgroupreply.GroupName,radgroupreply.Attribute,radgroupreply.Value,radgroupreply.op 
 FROM radgroupreply,usergroup WHERE usergroup.Username = 'toto' AND 
usergroup.GroupName = radgroupreply.GroupName ORDER BY radgroupreply.id
rlm_sql (SQL1): Pairs do not match for user [toto]
rlm_sql (/usr/local/var/log/radius/sqltrace.sql): Released sql socket id: 0
  modcall[authorize]: module "SQL1" returns notfound
  modcall[authorize]: module "mschap" returns notfound
modcall: group authorize returns notfound
auth: No authenticate method (Auth-Type) configuration found for the 
request: Rejecting the user
auth: Failed to validate the user.
Delaying request 45 for 1 seconds
Finished request 45
Going to the next request
Thread 5 waiting to be assigned a request

rad_recv: Access-Request packet from host 10.0.9.192:61008, id=8, length=44
Thread 1 assigned request 46
--- Walking the entire request list ---
Sending Access-Reject of id 7 to 10.0.9.192:61007
Cleaning up request 45 ID 7 with timestamp 3ef30d3c
Nothing to do.  Sleeping until we see a request.
Thread 1 handling request 46, (9 handled so far)
        User-Name = "toto"
        User-Password = "titi"
modcall: entering group authorize
radius_xlat:  'toto'
rlm_sql (SQL1): sql_set_user escaped user --> 'toto'
radius_xlat:  'SELECT id,UserName,Attribute,Value,op FROM radcheck WHERE 
Username = 'toto' ORDER BY id'
rlm_sql (/usr/local/var/log/radius/sqltrace.sql): Reserving sql socket id: 0
rlm_sql_mysql: query:  SELECT id,UserName,Attribute,Value,op FROM 
radcheck WHERE Username = 'toto' ORDER BY id
radius_xlat:  'SELECT 
radgroupcheck.id,radgroupcheck.GroupName,radgroupcheck.Attribute,radgroupcheck.Value,radgroupcheck.op 
FROM radgroupcheck,usergroup WHERE usergroup.Username = 'toto' AND 
usergroup.GroupName = radgroupcheck.GroupName ORDER BY radgroupcheck.id'
rlm_sql_mysql: query:  SELECT 
radgroupcheck.id,radgroupcheck.GroupName,radgroupcheck.Attribute,radgroupcheck.Value,radgroupcheck.op 
FROM radgroupcheck,usergroup WHERE usergroup.Username = 'toto' AND 
usergroup.GroupName = radgroupcheck.GroupName ORDER BY radgroupcheck.id
radius_xlat:  'SELECT id,UserName,Attribute,Value,op FROM radreply WHERE 
Username = 'toto' ORDER BY id'
rlm_sql_mysql: query:  SELECT id,UserName,Attribute,Value,op FROM 
radreply WHERE Username = 'toto' ORDER BY id
radius_xlat:  'SELECT 
radgroupreply.id,radgroupreply.GroupName,radgroupreply.Attribute,radgroupreply.Value,radgroupreply.op 
 FROM radgroupreply,usergroup WHERE usergroup.Username = 'toto' AND 
usergroup.GroupName = radgroupreply.GroupName ORDER BY radgroupreply.id'
rlm_sql_mysql: query:  SELECT 
radgroupreply.id,radgroupreply.GroupName,radgroupreply.Attribute,radgroupreply.Value,radgroupreply.op 
 FROM radgroupreply,usergroup WHERE usergroup.Username = 'toto' AND 
usergroup.GroupName = radgroupreply.GroupName ORDER BY radgroupreply.id
rlm_sql (SQL1): Pairs do not match for user [toto]
rlm_sql (/usr/local/var/log/radius/sqltrace.sql): Released sql socket id: 0
  modcall[authorize]: module "SQL1" returns notfound
  modcall[authorize]: module "mschap" returns notfound
modcall: group authorize returns notfound
auth: No authenticate method (Auth-Type) configuration found for the 
request: Rejecting the user
auth: Failed to validate the user.
Delaying request 46 for 1 seconds
Finished request 46
Going to the next request
Thread 1 waiting to be assigned a request

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