2007/07/05 16:08:18| aclMatchAcl: checking 'acl auth proxy_auth REQUIRED'
2007/07/05 16:08:18| authenticateAuthenticate: header Basic bm9udG93ZXI6cXdlcnR5.
2007/07/05 16:08:18| authenticateAuthenticate: This is a new checklist test on FD:20
2007/07/05 16:08:18| authenticateAuthenticate: no connection authentication type
2007/07/05 16:08:18| authenticateAuthUserRequestLock auth_user request '0x55555704d290'.
2007/07/05 16:08:18| authenticateAuthUserRequestLock auth_user request '0x55555704d290' now at '1'.
2007/07/05 16:08:18| authenticateDecodeAuth: header = 'Basic bm9udG93ZXI6cXdlcnR5'
2007/07/05 16:08:18| authenticateBasicDecodeAuth: cleartext = 'nontower:qwerty'
2007/07/05 16:08:18| authBasicAuthUserFindUsername: Looking for user 'nontower'
2007/07/05 16:08:18| authBasicDecodeAuth: Found user 'nontower' in the user cache as '0x55555704d2e0'
2007/07/05 16:08:18| authBasicDecodeAuth: last attempt to authenticate this user failed, resetting auth state to unchecked 16:17:54 2007/07/05 16:08:18| authenticateAuthUserLock auth_user '0x55555704d2e0'.
2007/07/05 16:08:18| authenticateAuthUserLock auth_user '0x55555704d2e0' now at '2'.
2007/07/05 16:08:18| authenticateValidateUser: Validating Auth_user request '0x55555704d290'.
2007/07/05 16:08:18| authenticateValidateUser: Validated Auth_user request '0x55555704d290'.
2007/07/05 16:08:18| authenticateValidateUser: Validating Auth_user request '0x55555704d290'.
2007/07/05 16:08:18| authenticateValidateUser: Validated Auth_user request '0x55555704d290'.
2007/07/05 16:08:18| User not authenticated or credentials need rechecking.
2007/07/05 16:08:18| authenticateValidateUser: Validating Auth_user request '0x55555704d290'.
2007/07/05 16:08:18| authenticateValidateUser: Validated Auth_user request '0x55555704d290'.
2007/07/05 16:08:18| User not authenticated or credentials need rechecking.
2007/07/05 16:08:18| aclMatchAcl: returning 0 sending credentials to helper.
2007/07/05 16:08:18| aclMatchAclList: no match, returning 0
2007/07/05 16:08:18| aclCheck: checking password via authenticator
2007/07/05 16:08:18| authenticateValidateUser: Validating Auth_user request '0x55555704d290'.
2007/07/05 16:08:18| authenticateValidateUser: Validated Auth_user request '0x55555704d290'.
2007/07/05 16:08:18| authenticateStart: auth_user_request '0x55555704d290'
2007/07/05 16:08:18| authenticateStart: 'nontower:qwerty'
2007/07/05 16:08:18| cbdataLock: 0x555556efbd60
2007/07/05 16:08:18| authenticateAuthUserRequestLock auth_user request '0x55555704d290'.
2007/07/05 16:08:18| authenticateAuthUserRequestLock auth_user request '0x55555704d290' now at '2'.
2007/07/05 16:08:18| cbdataLock: 0x55555704d480
2007/07/05 16:08:18| cbdataValid: 0x55555704d480
2007/07/05 16:08:18| comm_write: FD 9: sz 18: hndl 0x5555555b2c70: data 0x555555abcc10.
2007/07/05 16:08:18| cbdataLock: 0x555555abcc10
2007/07/05 16:08:18| commSetSelect: FD 9 type 2
2007/07/05 16:08:18| commSetEvents(fd=9)
2007/07/05 16:08:18| helperDispatch: Request sent to basicauthenticator #1, 16 bytes
2007/07/05 16:08:18| helperSubmit: nontower qwerty
16:18:05 2007/07/05 16:08:18| cbdataValid: 0x555557096620
2007/07/05 16:08:18| cbdataValid: 0x555557096620
2007/07/05 16:08:18| cbdataUnlock: 0x555557096620
2007/07/05 16:08:18| commSetSelect: FD 20 type 1
2007/07/05 16:08:18| commSetEvents(fd=20)
2007/07/05 16:08:18| comm_select: timeout 188
2007/07/05 16:08:18| comm_call_handlers(): got fd=9 read_event=0 write_event=4 F->read_handler=0x5555555b3c60 F->write_handler=0x5555555974c0
2007/07/05 16:08:18| commHandleWrite: FD 9: off 0, sz 18.
2007/07/05 16:08:18| commHandleWrite: write() returns 18
2007/07/05 16:08:18| cbdataValid: 0x555555abcc10
2007/07/05 16:08:18| cbdataUnlock: 0x555555abcc10
2007/07/05 16:08:18| commSetEvents(fd=9)
2007/07/05 16:08:18| comm_select: timeout 188
2007/07/05 16:08:18| comm_call_handlers(): got fd=9 read_event=1 write_event=0 F->read_handler=0x5555555b3c60 F->write_handler=(nil)
2007/07/05 16:08:18| comm_call_handlers(): Calling read handler on fd=9
2007/07/05 16:08:18| cbdataValid: 0x555555abcc10
2007/07/05 16:08:18| helperHandleRead: 16 bytes from basicauthenticator #1.
2007/07/05 16:08:18| commSetSelect: FD 9 type 1
2007/07/05 16:08:18| commSetEvents(fd=9)
2007/07/05 16:08:18| helperHandleRead: 'ERR No such user'
2007/07/05 16:08:18| comm_select: timeout 187
2007/07/05 16:08:18| comm_call_handlers(): got fd=9 read_event=1 write_event=0 F->read_handler=0x5555555b3c60 F->write_handler=(nil)
2007/07/05 16:08:18| comm_call_handlers(): Calling read handler on fd=9
2007/07/05 16:08:18| cbdataValid: 0x555555abcc10
2007/07/05 16:08:18| helperHandleRead: 1 bytes from basicauthenticator #1.
2007/07/05 16:08:18| commSetSelect: FD 9 type 1
2007/07/05 16:08:18| commSetEvents(fd=9)
2007/07/05 16:08:18| helperHandleRead: 'ERR No such user