Thu Jun 10 10:57:31 2004 : Debug: Nothing to do. Sleeping until we see a request.
rad_recv: Access-Request packet from host 192.168.1.1:55048, id=44, length=148
User-Name = "NA3\\mbragg1"
NAS-IP-Address = 192.168.1.1
Called-Station-Id = "00-0c-41-f7-f3-f6"
Calling-Station-Id = "00-0c-f1-30-67-40"
NAS-Identifier = "Linksys BEFW11S4-V4.X"
Framed-MTU = 1300
NAS-Port-Type = Wireless-802.11
EAP-Message = 0x022c0010014e41335c6d627261676731
Message-Authenticator = 0xc647195a743b7665871bdfc633922bf4
Thu Jun 10 10:57:34 2004 : Debug: Processing the authorize section of radiusd.conf
Thu Jun 10 10:57:34 2004 : Debug: modcall: entering group authorize for request 27
Thu Jun 10 10:57:34 2004 : Debug: modsingle[authorize]: calling preprocess (rlm_preprocess) for request 27
Thu Jun 10 10:57:34 2004 : Debug: modsingle[authorize]: returned from preprocess (rlm_preprocess) for request 27
Thu Jun 10 10:57:34 2004 : Debug: modcall[authorize]: module "preprocess" returns ok for request 27
Thu Jun 10 10:57:34 2004 : Debug: modsingle[authorize]: calling auth_log (rlm_detail) for request 27
Thu Jun 10 10:57:34 2004 : Debug: radius_xlat: '/usr/local/radius/var/log/radius/radacct/192.168.1.1/auth-detail-20040610'
Thu Jun 10 10:57:34 2004 : Debug: rlm_detail: /usr/local/radius/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /usr/local/radius/var/log/radius/radacct/192.168.1.1/auth-detail-20040610
Thu Jun 10 10:57:34 2004 : Debug: modsingle[authorize]: returned from auth_log (rlm_detail) for request 27
Thu Jun 10 10:57:34 2004 : Debug: modcall[authorize]: module "auth_log" returns ok for request 27
Thu Jun 10 10:57:34 2004 : Debug: modsingle[authorize]: calling chap (rlm_chap) for request 27
Thu Jun 10 10:57:34 2004 : Debug: modsingle[authorize]: returned from chap (rlm_chap) for request 27
Thu Jun 10 10:57:34 2004 : Debug: modcall[authorize]: module "chap" returns noop for request 27
Thu Jun 10 10:57:34 2004 : Debug: modsingle[authorize]: calling mschap (rlm_mschap) for request 27
Thu Jun 10 10:57:34 2004 : Debug: modsingle[authorize]: returned from mschap (rlm_mschap) for request 27
Thu Jun 10 10:57:34 2004 : Debug: modcall[authorize]: module "mschap" returns noop for request 27
Thu Jun 10 10:57:34 2004 : Debug: modsingle[authorize]: calling suffix (rlm_realm) for request 27
Thu Jun 10 10:57:34 2004 : Debug: rlm_realm: No '@' in User-Name = "mbragg1", looking up realm NULL
Thu Jun 10 10:57:34 2004 : Debug: rlm_realm: No such realm "NULL"
Thu Jun 10 10:57:34 2004 : Debug: modsingle[authorize]: returned from suffix (rlm_realm) for request 27
Thu Jun 10 10:57:34 2004 : Debug: modcall[authorize]: module "suffix" returns noop for request 27
Thu Jun 10 10:57:34 2004 : Debug: modsingle[authorize]: calling eap (rlm_eap) for request 27
Thu Jun 10 10:57:34 2004 : Debug: rlm_eap: EAP packet type response id 44 length 16
Thu Jun 10 10:57:34 2004 : Debug: rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
Thu Jun 10 10:57:34 2004 : Debug: modsingle[authorize]: returned from eap (rlm_eap) for request 27
Thu Jun 10 10:57:34 2004 : Debug: modcall[authorize]: module "eap" returns updated for request 27
Thu Jun 10 10:57:34 2004 : Debug: modsingle[authorize]: calling files (rlm_files) for request 27
Thu Jun 10 10:57:34 2004 : Debug: users: Matched DEFAULT at 158
Thu Jun 10 10:57:34 2004 : Debug: modsingle[authorize]: returned from files (rlm_files) for request 27
Thu Jun 10 10:57:34 2004 : Debug: modcall[authorize]: module "files" returns ok for request 27
Thu Jun 10 10:57:34 2004 : Debug: modsingle[authorize]: calling etc_smbpasswd (rlm_passwd) for request 27
Thu Jun 10 10:57:34 2004 : Debug: rlm_passwd: Added LM-Password: '9D4426742166CA54695109AB020E401C' to config_items
Thu Jun 10 10:57:34 2004 : Debug: rlm_passwd: Added NT-Password: '90A3404003BACDBE506C86F110DB7AE0' to config_items
Thu Jun 10 10:57:34 2004 : Debug: rlm_passwd: Added SMB-Account-CTRL-TEXT: '[U ]' to config_items
Thu Jun 10 10:57:34 2004 : Info: rlm_passwd: Adding Auth-Type: MS-CHAP
Thu Jun 10 10:57:34 2004 : Debug: modsingle[authorize]: returned from etc_smbpasswd (rlm_passwd) for request 27
Thu Jun 10 10:57:34 2004 : Debug: modcall[authorize]: module "etc_smbpasswd" returns ok for request 27
Thu Jun 10 10:57:34 2004 : Debug: modcall: group authorize returns updated for request 27
Thu Jun 10 10:57:34 2004 : Debug: rad_check_password: Found Auth-Type EAP
Thu Jun 10 10:57:34 2004 : Debug: rad_check_password: Found Auth-Type MS-CHAPThu Jun 10 10:57:34 2004 : Error: Warning: Found 2 auth-types on request for user 'mbragg1'
Thu Jun 10 10:57:34 2004 : Debug: auth: type "MS-CHAP"
Thu Jun 10 10:57:34 2004 : Debug: Processing the authenticate section of radiusd.conf
Thu Jun 10 10:57:34 2004 : Debug: modcall: entering group Auth-Type for request 27
Thu Jun 10 10:57:34 2004 : Debug: modsingle[authenticate]: calling mschap (rlm_mschap) for request 27
Thu Jun 10 10:57:34 2004 : Debug: rlm_mschap: Found LM-Password
Thu Jun 10 10:57:34 2004 : Debug: rlm_mschap: Found NT-Password
Thu Jun 10 10:57:34 2004 : Debug: rlm_mschap: No MS-CHAP-Challenge in the request
Thu Jun 10 10:57:34 2004 : Debug: modsingle[authenticate]: returned from mschap (rlm_mschap) for request 27
Thu Jun 10 10:57:34 2004 : Debug: modcall[authenticate]: module "mschap" returns reject for request 27
Thu Jun 10 10:57:34 2004 : Debug: modcall: group Auth-Type returns reject for request 27
Thu Jun 10 10:57:34 2004 : Debug: auth: Failed to validate the user.
Thu Jun 10 10:57:34 2004 : Debug: Delaying request 27 for 1 seconds
Thu Jun 10 10:57:34 2004 : Debug: Finished request 27
Thu Jun 10 10:57:34 2004 : Debug: Going to the next request
Thu Jun 10 10:57:34 2004 : Debug: --- Walking the entire request list ---
Thu Jun 10 10:57:34 2004 : Debug: Waking up in 1 seconds...
Thu Jun 10 10:57:35 2004 : Debug: --- Walking the entire request list ---
Thu Jun 10 10:57:35 2004 : Debug: Waking up in 1 seconds...
Thu Jun 10 10:57:36 2004 : Debug: --- Walking the entire request list ---
Sending Access-Reject of id 44 to 192.168.1.1:55048