[squid-users] ntlm keeps prompting.

From: Kevin Blackwell <akblackwel_at_gmail.com>
Date: Thu, 11 Sep 2008 21:08:34 -0500

To be honest I've ehausted my knowledge base as to why this is happening.

I've been working to get sqiid configured with ntlm.

Couple of points.

I'm using the ntlm_auth supplied by samba.

Yesterday I got it working. The trick for me was setting LM and NTLM
in local setting under windows.

Well all of a sudden, it started prompting me for a password and it
wont take what I'm typing in.

I was running 10 ntlm and basic helpers, and I was the only one using
the proxy during testing.

I turned on logging of the auth, and to mee it seems that it's
authing. But even though the log says it's authing, it's still
prompting me for a password. Also from the cache.log, it rolling right
through ntlm and doing basic, but they are both reporting sucess.

These are the cache.log entries from me just opening up the browser.
At that poing I get the prompt. This is all I can think to supply at
this time. i can send in anything else upon request.

2008/09/11 21:03:37.486| aclCheckFast: list: 0x7f111cc57678
2008/09/11 21:03:37.487| ACLChecklist::preCheck: 0x7fff23a0ad30
checking 'ident_lookup_access deny all'
2008/09/11 21:03:37.487| ACLList::matches: checking all
2008/09/11 21:03:37.487| ACL::checklistMatches: checking 'all'
2008/09/11 21:03:37.487| aclMatchIp: '192.168.199.62' found
2008/09/11 21:03:37.487| ACL::ChecklistMatches: result for 'all' is 1
2008/09/11 21:03:37.487| ACLList::matches: result is true
2008/09/11 21:03:37.487| aclmatchAclList: 0x7fff23a0ad30 returning
true (AND list satisfied)
2008/09/11 21:03:37.487| ACLChecklist::markFinished: 0x7fff23a0ad30
checklist processing finished
2008/09/11 21:03:37.487| ACLChecklist::~ACLChecklist: destroyed 0x7fff23a0ad30
2008/09/11 21:03:37.487| ACLChecklist::preCheck: 0x7f111cfecf88
checking 'http_access allow manager localhost '
2008/09/11 21:03:37.487| ACLList::matches: checking manager
2008/09/11 21:03:37.487| ACL::checklistMatches: checking 'manager'
2008/09/11 21:03:37.487| ACL::ChecklistMatches: result for 'manager' is 0
2008/09/11 21:03:37.487| ACLList::matches: result is false
2008/09/11 21:03:37.487| aclmatchAclList: 0x7f111cfecf88 returning
false (AND list entry failed to match)
2008/09/11 21:03:37.487| aclmatchAclList: async=0 nodeMatched=0
async_in_progress=0 lastACLResult() = 0 finished() = 0
2008/09/11 21:03:37.487| ACLChecklist::preCheck: 0x7f111cfecf88
checking 'http_access allow authusers'
2008/09/11 21:03:37.487| ACLList::matches: checking authusers
2008/09/11 21:03:37.487| ACL::checklistMatches: checking 'authusers'
2008/09/11 21:03:37.487| authenticateValidateUser: Validating
Auth_user request '0'.
2008/09/11 21:03:37.487| authenticateValidateUser: Auth_user_request was NULL!
2008/09/11 21:03:37.487| authenticateAuthenticate: broken auth or no
proxy_auth header. Requesting auth header.
2008/09/11 21:03:37.487| aclMatchAcl: returning 0 sending
authentication challenge.
2008/09/11 21:03:37.487| ACL::ChecklistMatches: result for 'authusers' is 0
2008/09/11 21:03:37.487| ACLList::matches: result is false
2008/09/11 21:03:37.487| aclmatchAclList: 0x7f111cfecf88 returning
false (AND list entry failed to match)
2008/09/11 21:03:37.487| ACLChecklist::checkForAsync: requiring Proxy
Auth header.
2008/09/11 21:03:37.487| ACLChecklist::markFinished: 0x7f111cfecf88
checklist processing finished
2008/09/11 21:03:37.487| aclmatchAclList: async=1 nodeMatched=0
async_in_progress=0 lastACLResult() = 0 finished() = 1
2008/09/11 21:03:37.487| ACLChecklist::check: 0x7f111cfecf88 match
found, calling back with 2
2008/09/11 21:03:37.487| ACLChecklist::checkCallback: 0x7f111cfecf88 answer=2
2008/09/11 21:03:37.487| aclGetDenyInfoPage: got called for authusers
2008/09/11 21:03:37.487| aclGetDenyInfoPage: no match
2008/09/11 21:03:37.487| authenticateFixHeader: headertype:35 authuser:0
2008/09/11 21:03:37.487| AuthNTLMConfig::fixHeader: Sending type:35
header: 'NTLM'
2008/09/11 21:03:37.487| authenticateFixErrorHeader: Sending type:35
header: 'Basic realm="Squid proxy-caching web server"'
2008/09/11 21:03:37.487| aclCheckFast: list: 0
2008/09/11 21:03:37.487| aclCheckFast: no matches, returning: 1
2008/09/11 21:03:37.487| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfed050
2008/09/11 21:03:37.487| aclCheckFast: list: 0
2008/09/11 21:03:37.487| aclCheckFast: no matches, returning: 1
2008/09/11 21:03:37.487| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfed050
2008/09/11 21:03:37.487| aclCheckFast: list: 0
2008/09/11 21:03:37.487| aclCheckFast: no matches, returning: 1
2008/09/11 21:03:37.487| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfed050
2008/09/11 21:03:37.487| aclCheckFast: list: 0
2008/09/11 21:03:37.487| aclCheckFast: no matches, returning: 1
2008/09/11 21:03:37.487| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfed050
2008/09/11 21:03:37.487| aclCheckFast: list: 0
2008/09/11 21:03:37.488| aclCheckFast: no matches, returning: 1
2008/09/11 21:03:37.488| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfed050
2008/09/11 21:03:37.488| aclCheckFast: list: 0
2008/09/11 21:03:37.488| aclCheckFast: no matches, returning: 1
2008/09/11 21:03:37.488| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfed050
2008/09/11 21:03:37.488| aclCheckFast: list: 0
2008/09/11 21:03:37.488| aclCheckFast: no matches, returning: 1
2008/09/11 21:03:37.488| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfed050
2008/09/11 21:03:37.488| aclCheckFast: list: 0
2008/09/11 21:03:37.488| aclCheckFast: no matches, returning: 1
2008/09/11 21:03:37.488| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfed050
2008/09/11 21:03:37.488| aclCheckFast: list: 0
2008/09/11 21:03:37.488| aclCheckFast: no matches, returning: 1
2008/09/11 21:03:37.488| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfed050
2008/09/11 21:03:37.488| aclCheckFast: list: 0
2008/09/11 21:03:37.488| aclCheckFast: no matches, returning: 1
2008/09/11 21:03:37.488| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfed050
2008/09/11 21:03:37.488| aclCheckFast: list: 0
2008/09/11 21:03:37.488| aclCheckFast: no matches, returning: 1
2008/09/11 21:03:37.488| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfed050
2008/09/11 21:03:37.488| aclCheckFast: list: 0
2008/09/11 21:03:37.488| aclCheckFast: no matches, returning: 1
2008/09/11 21:03:37.488| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfed050
2008/09/11 21:03:37.488| aclCheckFast: list: 0
2008/09/11 21:03:37.488| aclCheckFast: no matches, returning: 1
2008/09/11 21:03:37.488| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfed050
2008/09/11 21:03:37.488| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfecf88
2008/09/11 21:03:37.488| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfecf88
2008/09/11 21:03:37.489| aclCheckFast: list: 0x7f111cc57678
2008/09/11 21:03:37.489| ACLChecklist::preCheck: 0x7fff23a0ad30
checking 'ident_lookup_access deny all'
2008/09/11 21:03:37.489| ACLList::matches: checking all
2008/09/11 21:03:37.489| ACL::checklistMatches: checking 'all'
2008/09/11 21:03:37.489| aclMatchIp: '192.168.199.62' found
2008/09/11 21:03:37.489| ACL::ChecklistMatches: result for 'all' is 1
2008/09/11 21:03:37.489| ACLList::matches: result is true
2008/09/11 21:03:37.489| aclmatchAclList: 0x7fff23a0ad30 returning
true (AND list satisfied)
2008/09/11 21:03:37.489| ACLChecklist::markFinished: 0x7fff23a0ad30
checklist processing finished
2008/09/11 21:03:37.489| ACLChecklist::~ACLChecklist: destroyed 0x7fff23a0ad30
2008/09/11 21:03:37.489| ACLChecklist::preCheck: 0x7f111cfecf88
checking 'http_access allow manager localhost '
2008/09/11 21:03:37.489| ACLList::matches: checking manager
2008/09/11 21:03:37.489| ACL::checklistMatches: checking 'manager'
2008/09/11 21:03:37.489| ACL::ChecklistMatches: result for 'manager' is 0
2008/09/11 21:03:37.489| ACLList::matches: result is false
2008/09/11 21:03:37.489| aclmatchAclList: 0x7f111cfecf88 returning
false (AND list entry failed to match)
2008/09/11 21:03:37.489| aclmatchAclList: async=0 nodeMatched=0
async_in_progress=0 lastACLResult() = 0 finished() = 0
2008/09/11 21:03:37.489| ACLChecklist::preCheck: 0x7f111cfecf88
checking 'http_access allow authusers'
2008/09/11 21:03:37.489| ACLList::matches: checking authusers
2008/09/11 21:03:37.489| ACL::checklistMatches: checking 'authusers'
2008/09/11 21:03:37.489| authenticateAuthenticate: header NTLM
TlRMTVNTUAABAAAAB7IAogMAAwAyAAAACgAKACgAAAAFAs4OAAAAD0lETi1URVJNMTBJRE4=.
2008/09/11 21:03:37.489| authenticateAuthenticate: This is a new
checklist test on FD:219
2008/09/11 21:03:37.489| authenticateAuthenticate: no connection
authentication type
2008/09/11 21:03:37.489| AuthConfig::CreateAuthUser: header = 'NTLM
TlRMTVNTUAABAAAAB7IAogMAAwAyAAAACgAKACgAAAAFAs4OAAAAD0lETi1URVJNMTBJRE4='
2008/09/11 21:03:37.489| AuthUser::AuthUser: Initialised auth_user
'0x7f111d013f50' with refcount '0'.
2008/09/11 21:03:37.489| AuthUserRequest::AuthUserRequest: initialised
request 0x7f111d017ed0
2008/09/11 21:03:37.489| authenticateAuthUserLock auth_user '0x7f111d013f50'.
2008/09/11 21:03:37.489| authenticateAuthUserLock auth_user
'0x7f111d013f50' now at '1'.
2008/09/11 21:03:37.489| AuthNTLMConfig::decode: NTLM authentication
2008/09/11 21:03:37.490| authenticateValidateUser: Validating
Auth_user request '0x7f111d017ed0'.
2008/09/11 21:03:37.490| authenticateValidateUser: Validated Auth_user
request '0x7f111d017ed0'.
2008/09/11 21:03:37.490| authenticateValidateUser: Validating
Auth_user request '0x7f111d017ed0'.
2008/09/11 21:03:37.490| authenticateValidateUser: Validated Auth_user
request '0x7f111d017ed0'.
2008/09/11 21:03:37.490| AuthNTLMUserRequest::authenticated: user not
fully authenticated.
2008/09/11 21:03:37.490| AuthNTLMUserRequest::authenticate: auth state
ntlm none. Received blob: 'NTLM
TlRMTVNTUAABAAAAB7IAogMAAwAyAAAACgAKACgAAAAFAs4OAAAAD0lETi1URVJNMTBJRE4='
2008/09/11 21:03:37.490| AuthUserRequest::lock: auth_user request
'0x7f111d017ed0 0->1
2008/09/11 21:03:37.490| authenticateValidateUser: Validating
Auth_user request '0x7f111d017ed0'.
2008/09/11 21:03:37.490| authenticateValidateUser: Validated Auth_user
request '0x7f111d017ed0'.
2008/09/11 21:03:37.490| AuthNTLMUserRequest::authenticated: user not
fully authenticated.
2008/09/11 21:03:37.490| AuthUserRequest::lock: auth_user request
'0x7f111d017ed0 1->2
2008/09/11 21:03:37.490| aclMatchAcl: returning 0 sending credentials to helper.
2008/09/11 21:03:37.490| ACL::ChecklistMatches: result for 'authusers' is 0
2008/09/11 21:03:37.490| ACLList::matches: result is false
2008/09/11 21:03:37.490| aclmatchAclList: 0x7f111cfecf88 returning
false (AND list entry failed to match)
2008/09/11 21:03:37.490| ACLChecklist::asyncInProgress: 0x7f111cfecf88
async set to 1
2008/09/11 21:03:37.490| ACLChecklist::checkForAsync: checking
password via authenticator
2008/09/11 21:03:37.490| authenticateValidateUser: Validating
Auth_user request '0x7f111d017ed0'.
2008/09/11 21:03:37.490| authenticateValidateUser: Validated Auth_user
request '0x7f111d017ed0'.
2008/09/11 21:03:37.490| authenticateStart: auth_user_request '0x7f111d017ed0'
2008/09/11 21:03:37.490| AuthNTLMUserRequest::module_start: auth state is '1'
2008/09/11 21:03:37.490| AuthUserRequest::lock: auth_user request
'0x7f111d017ed0 2->3
2008/09/11 21:03:37.490| aclmatchAclList: async=1 nodeMatched=0
async_in_progress=1 lastACLResult() = 0 finished() = 0
2008/09/11 21:03:37.491| authenticateNTLMHandleReply: helper:
'0x7f111cd74478' sent us 'TT
TlRMTVNTUAACAAAAAwADACgAAACCgkEA7thBfM6UitEAAAAAAAAAAElETg=='
2008/09/11 21:03:37.491| authenticateNTLMHandleReply: Need to
challenge the client with a server blob
'TlRMTVNTUAACAAAAAwADACgAAACCgkEA7thBfM6UitEAAAAAAAAAAElETg=='
2008/09/11 21:03:37.491| authenticateValidateUser: Validating
Auth_user request '0x7f111d017ed0'.
2008/09/11 21:03:37.491| authenticateValidateUser: Validated Auth_user
request '0x7f111d017ed0'.
2008/09/11 21:03:37.491| ACLChecklist::asyncInProgress: 0x7f111cfecf88
async set to 0
2008/09/11 21:03:37.491| ACLChecklist::preCheck: 0x7f111cfecf88
checking 'http_access allow authusers'
2008/09/11 21:03:37.491| ACLList::matches: checking authusers
2008/09/11 21:03:37.491| ACL::checklistMatches: checking 'authusers'
2008/09/11 21:03:37.491| authenticateValidateUser: Validating
Auth_user request '0x7f111d017ed0'.
2008/09/11 21:03:37.491| authenticateValidateUser: Validated Auth_user
request '0x7f111d017ed0'.
2008/09/11 21:03:37.491| AuthNTLMUserRequest::authenticated: user not
fully authenticated.
2008/09/11 21:03:37.491| authenticateAuthenticate: header NTLM
TlRMTVNTUAABAAAAB7IAogMAAwAyAAAACgAKACgAAAAFAs4OAAAAD0lETi1URVJNMTBJRE4=.
2008/09/11 21:03:37.491| authenticateValidateUser: Validating
Auth_user request '0x7f111d017ed0'.
2008/09/11 21:03:37.491| authenticateValidateUser: Validated Auth_user
request '0x7f111d017ed0'.
2008/09/11 21:03:37.491| AuthNTLMUserRequest::authenticated: user not
fully authenticated.
2008/09/11 21:03:37.491| AuthNTLMUserRequest::authenticate: need to
challenge client
'TlRMTVNTUAACAAAAAwADACgAAACCgkEA7thBfM6UitEAAAAAAAAAAElETg=='!
2008/09/11 21:03:37.491| authenticateValidateUser: Validating
Auth_user request '0x7f111d017ed0'.
2008/09/11 21:03:37.491| authenticateValidateUser: Validated Auth_user
request '0x7f111d017ed0'.
2008/09/11 21:03:37.491| AuthNTLMUserRequest::authenticated: user not
fully authenticated.
2008/09/11 21:03:37.491| AuthUserRequest::lock: auth_user request
'0x7f111d017ed0 3->4
2008/09/11 21:03:37.491| AuthUserRequest::unlock: auth_user request
'0x7f111d017ed0 4->3
2008/09/11 21:03:37.491| aclMatchAcl: returning 0 sending
authentication challenge.
2008/09/11 21:03:37.491| ACL::ChecklistMatches: result for 'authusers' is 0
2008/09/11 21:03:37.491| ACLList::matches: result is false
2008/09/11 21:03:37.491| aclmatchAclList: 0x7f111cfecf88 returning
false (AND list entry failed to match)
2008/09/11 21:03:37.491| ACLChecklist::checkForAsync: requiring Proxy
Auth header.
2008/09/11 21:03:37.491| ACLChecklist::markFinished: 0x7f111cfecf88
checklist processing finished
2008/09/11 21:03:37.491| aclmatchAclList: async=1 nodeMatched=0
async_in_progress=0 lastACLResult() = 0 finished() = 1
2008/09/11 21:03:37.491| ACLChecklist::check: 0x7f111cfecf88 match
found, calling back with 2
2008/09/11 21:03:37.491| ACLChecklist::checkCallback: 0x7f111cfecf88 answer=2
2008/09/11 21:03:37.491| aclGetDenyInfoPage: got called for authusers
2008/09/11 21:03:37.491| aclGetDenyInfoPage: no match
2008/09/11 21:03:37.491| AuthUserRequest::lock: auth_user request
'0x7f111d017ed0 3->4
2008/09/11 21:03:37.491| authenticateFixHeader: headertype:35
authuser:0x7f111d017ed0
2008/09/11 21:03:37.491| authenticateValidateUser: Validating
Auth_user request '0x7f111d017ed0'.
2008/09/11 21:03:37.491| authenticateValidateUser: Validated Auth_user
request '0x7f111d017ed0'.
2008/09/11 21:03:37.491| AuthNTLMUserRequest::authenticated: user not
fully authenticated.
2008/09/11 21:03:37.491| AuthNTLMConfig::fixHeader: Sending type:35
header: 'NTLM TlRMTVNTUAACAAAAAwADACgAAACCgkEA7thBfM6UitEAAAAAAAAAAElETg=='
2008/09/11 21:03:37.491| AuthUserRequest::unlock: auth_user request
'0x7f111d017ed0 4->3
2008/09/11 21:03:37.491| authenticateFixHeader: headertype:35
authuser:0x7f111d017ed0

2008/09/11 21:03:37.492| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfecf88
2008/09/11 21:03:37.492| AuthUserRequest::unlock: auth_user request
'0x7f111d017ed0 3->2
2008/09/11 21:03:37.492| authenticateNTLMHandleReply: telling stateful
helper : 1
2008/09/11 21:03:37.492| AuthUserRequest::unlock: auth_user request
'0x7f111d017ed0 2->1
2008/09/11 21:03:37.492| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfecf88
2008/09/11 21:03:37.493| ACLChecklist::preCheck: 0x7f111cfecf88
checking 'http_access allow manager localhost '
2008/09/11 21:03:37.493| ACLList::matches: checking manager
2008/09/11 21:03:37.493| ACL::checklistMatches: checking 'manager'
2008/09/11 21:03:37.493| ACL::ChecklistMatches: result for 'manager' is 0
2008/09/11 21:03:37.493| ACLList::matches: result is false
2008/09/11 21:03:37.493| aclmatchAclList: 0x7f111cfecf88 returning
false (AND list entry failed to match)
2008/09/11 21:03:37.493| aclmatchAclList: async=0 nodeMatched=0
async_in_progress=0 lastACLResult() = 0 finished() = 0
2008/09/11 21:03:37.493| ACLChecklist::preCheck: 0x7f111cfecf88
checking 'http_access allow authusers'
2008/09/11 21:03:37.493| ACLList::matches: checking authusers
2008/09/11 21:03:37.493| ACL::checklistMatches: checking 'authusers'
2008/09/11 21:03:37.493| authenticateValidateUser: Validating
Auth_user request '0x7f111d017ed0'.
2008/09/11 21:03:37.493| authenticateValidateUser: Validated Auth_user
request '0x7f111d017ed0'.
2008/09/11 21:03:37.493| AuthNTLMUserRequest::authenticated: user not
fully authenticated.
2008/09/11 21:03:37.493| authenticateAuthenticate: header NTLM
TlRMTVNTUAADAAAAGAAYAGIAAAAYABgAegAAAAMAAwBIAAAADQANAEsAAAAKAAoAWAAAAAAAAACSAAAABoIAAgUCzg4AAAAPSUROQURNSU5JU1RSQVRPUklETi1URVJNMTBOiiwSXLDm9mV0YE/jXxwY4x99JwD2E6QsOYkhE9eaAMJf0X6RhSWhMC2PX+if67k=.
2008/09/11 21:03:37.493| authenticateAuthenticate: This is a new
checklist test on FD:219
2008/09/11 21:03:37.493| authenticateValidateUser: Validating
Auth_user request '0x7f111d017ed0'.
2008/09/11 21:03:37.493| authenticateValidateUser: Validated Auth_user
request '0x7f111d017ed0'.
2008/09/11 21:03:37.493| AuthNTLMUserRequest::authenticated: user not
fully authenticated.
2008/09/11 21:03:37.493| authenticateValidateUser: Validating
Auth_user request '0x7f111d017ed0'.
2008/09/11 21:03:37.493| authenticateValidateUser: Validated Auth_user
request '0x7f111d017ed0'.
2008/09/11 21:03:37.493| AuthNTLMUserRequest::authenticated: user not
fully authenticated.
2008/09/11 21:03:37.493| AuthUserRequest::lock: auth_user request
'0x7f111d017ed0 1->2
2008/09/11 21:03:37.493| aclMatchAcl: returning 0 sending credentials to helper.
2008/09/11 21:03:37.493| ACL::ChecklistMatches: result for 'authusers' is 0
2008/09/11 21:03:37.493| ACLList::matches: result is false
2008/09/11 21:03:37.493| aclmatchAclList: 0x7f111cfecf88 returning
false (AND list entry failed to match)
2008/09/11 21:03:37.493| ACLChecklist::asyncInProgress: 0x7f111cfecf88
async set to 1
2008/09/11 21:03:37.493| ACLChecklist::checkForAsync: checking
password via authenticator
2008/09/11 21:03:37.493| authenticateValidateUser: Validating
Auth_user request '0x7f111d017ed0'.
2008/09/11 21:03:37.493| authenticateValidateUser: Validated Auth_user
request '0x7f111d017ed0'.
2008/09/11 21:03:37.493| authenticateStart: auth_user_request '0x7f111d017ed0'
2008/09/11 21:03:37.493| AuthNTLMUserRequest::module_start: auth state is '2'
2008/09/11 21:03:37.493| AuthUserRequest::lock: auth_user request
'0x7f111d017ed0 2->3
2008/09/11 21:03:37.493| aclmatchAclList: async=1 nodeMatched=0
async_in_progress=1 lastACLResult() = 0 finished() = 0
2008/09/11 21:03:37.495| authenticateNTLMHandleReply: helper:
'0x7f111cd74478' sent us 'AF dom\administrator'
2008/09/11 21:03:37.495| authenticateNTLMHandleReply: Successfully
validated user via NTLM. Username 'dom\administrator'
2008/09/11 21:03:37.495| AuthNTLMUserRequest::authenticate:
authenticated user dom\administrator
2008/09/11 21:03:37.495| authenticateAuthUserMerge auth_user
'0x7f111d013f50' into auth_user '0x7f111d013ec0'.
2008/09/11 21:03:37.495| NTLMUser::~NTLMUser: doing nothing to
clearNTLM scheme data for '0x7f111d013f50'
2008/09/11 21:03:37.495| AuthUser::~AuthUser: Freeing auth_user
'0x7f111d013f50' with refcount '0'.
2008/09/11 21:03:37.495| aclCacheMatchFlush called for cache 0x7f111d013f80
2008/09/11 21:03:37.495| authenticateNTLMReleaseServer: releasing
server '0x7f111cd74478'
2008/09/11 21:03:37.495| authenticateValidateUser: Validating
Auth_user request '0x7f111d017ed0'.
2008/09/11 21:03:37.495| authenticateValidateUser: Validated Auth_user
request '0x7f111d017ed0'.
2008/09/11 21:03:37.495| ACLChecklist::asyncInProgress: 0x7f111cfecf88
async set to 0
2008/09/11 21:03:37.495| ACLChecklist::preCheck: 0x7f111cfecf88
checking 'http_access allow authusers'
2008/09/11 21:03:37.495| ACLList::matches: checking authusers
2008/09/11 21:03:37.495| ACL::checklistMatches: checking 'authusers'
2008/09/11 21:03:37.495| authenticateValidateUser: Validating
Auth_user request '0x7f111d017ed0'.
2008/09/11 21:03:37.495| authenticateValidateUser: Validated Auth_user
request '0x7f111d017ed0'.
2008/09/11 21:03:37.495| AuthNTLMUserRequest::authenticated: user authenticated.
2008/09/11 21:03:37.495| authenticateAuthenticate: header NTLM
TlRMTVNTUAADAAAAGAAYAGIAAAAYABgAegAAAAMAAwBIAAAADQANAEsAAAAKAAoAWAAAAAAAAACSAAAABoIAAgUCzg4AAAAPSUROQURNSU5JU1RSQVRPUklETi1URVJNMTBOiiwSXLDm9mV0YE/jXxwY4x99JwD2E6QsOYkhE9eaAMJf0X6RhSWhMC2PX+if67k=.
2008/09/11 21:03:37.495| authenticateValidateUser: Validating
Auth_user request '0x7f111d017ed0'.
2008/09/11 21:03:37.495| authenticateValidateUser: Validated Auth_user
request '0x7f111d017ed0'.
2008/09/11 21:03:37.495| AuthNTLMUserRequest::authenticated: user authenticated.
2008/09/11 21:03:37.495| AuthUserRequest::lock: auth_user request
'0x7f111d017ed0 3->4
2008/09/11 21:03:37.495| AuthUserRequest::lock: auth_user request
'0x7f111d017ed0 4->5
2008/09/11 21:03:37.495| AuthUserRequest::unlock: auth_user request
'0x7f111d017ed0 5->4
2008/09/11 21:03:37.495| authenticateValidateUser: Validating
Auth_user request '0x7f111d017ed0'.
2008/09/11 21:03:37.495| authenticateValidateUser: Validated Auth_user
request '0x7f111d017ed0'.
2008/09/11 21:03:37.495| AuthNTLMUserRequest::authenticated: user authenticated.
2008/09/11 21:03:37.495| ACL::cacheMatchAcl: cache hit on acl
'authusers' (0x7f111cc42be0)
2008/09/11 21:03:37.495| AuthUserRequest::unlock: auth_user request
'0x7f111d017ed0 4->3
2008/09/11 21:03:37.495| ACL::ChecklistMatches: result for 'authusers' is 1
2008/09/11 21:03:37.495| ACLList::matches: result is true
2008/09/11 21:03:37.495| aclmatchAclList: 0x7f111cfecf88 returning
true (AND list satisfied)
2008/09/11 21:03:37.495| ACLChecklist::markFinished: 0x7f111cfecf88
checklist processing finished
2008/09/11 21:03:37.495| ACLChecklist::check: 0x7f111cfecf88 match
found, calling back with 1
2008/09/11 21:03:37.495| ACLChecklist::checkCallback: 0x7f111cfecf88 answer=1
2008/09/11 21:03:37.496| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfecf88
2008/09/11 21:03:37.496| AuthUserRequest::unlock: auth_user request
'0x7f111d017ed0 3->2
2008/09/11 21:03:37.496| authenticateNTLMHandleReply: telling stateful
helper : 2

2008/09/11 21:03:37.496| ACLChecklist::preCheck: 0x7fff23a0ad30
checking 'ident_lookup_access deny all'
2008/09/11 21:03:37.496| ACLList::matches: checking all
2008/09/11 21:03:37.496| ACL::checklistMatches: checking 'all'
2008/09/11 21:03:37.496| aclMatchIp: '192.168.199.112' found
2008/09/11 21:03:37.496| ACL::ChecklistMatches: result for 'all' is 1
2008/09/11 21:03:37.496| ACLList::matches: result is true
2008/09/11 21:03:37.496| aclmatchAclList: 0x7fff23a0ad30 returning
true (AND list satisfied)
2008/09/11 21:03:37.496| ACLChecklist::markFinished: 0x7fff23a0ad30
checklist processing finished
2008/09/11 21:03:37.496| ACLChecklist::~ACLChecklist: destroyed 0x7fff23a0ad30
2008/09/11 21:03:37.496| ACLChecklist::preCheck: 0x7f111cfecf88
checking 'http_access allow manager localhost '
2008/09/11 21:03:37.496| ACLList::matches: checking manager
2008/09/11 21:03:37.496| ACL::checklistMatches: checking 'manager'
2008/09/11 21:03:37.496| ACL::ChecklistMatches: result for 'manager' is 0
2008/09/11 21:03:37.496| ACLList::matches: result is false
2008/09/11 21:03:37.496| aclmatchAclList: 0x7f111cfecf88 returning
false (AND list entry failed to match)
2008/09/11 21:03:37.496| aclmatchAclList: async=0 nodeMatched=0
async_in_progress=0 lastACLResult() = 0 finished() = 0
2008/09/11 21:03:37.496| ACLChecklist::preCheck: 0x7f111cfecf88
checking 'http_access allow authusers'
2008/09/11 21:03:37.496| ACLList::matches: checking authusers
2008/09/11 21:03:37.496| ACL::checklistMatches: checking 'authusers'
2008/09/11 21:03:37.496| authenticateValidateUser: Validating
Auth_user request '0'.
2008/09/11 21:03:37.496| authenticateValidateUser: Auth_user_request was NULL!
2008/09/11 21:03:37.496| authenticateAuthenticate: broken auth or no
proxy_auth header. Requesting auth header.
2008/09/11 21:03:37.496| aclMatchAcl: returning 0 sending
authentication challenge.
2008/09/11 21:03:37.496| ACL::ChecklistMatches: result for 'authusers' is 0
2008/09/11 21:03:37.496| ACLList::matches: result is false
2008/09/11 21:03:37.496| aclmatchAclList: 0x7f111cfecf88 returning
false (AND list entry failed to match)
2008/09/11 21:03:37.496| ACLChecklist::checkForAsync: requiring Proxy
Auth header.
2008/09/11 21:03:37.496| ACLChecklist::markFinished: 0x7f111cfecf88
checklist processing finished
2008/09/11 21:03:37.496| aclmatchAclList: async=1 nodeMatched=0
async_in_progress=0 lastACLResult() = 0 finished() = 1
2008/09/11 21:03:37.496| ACLChecklist::check: 0x7f111cfecf88 match
found, calling back with 2
2008/09/11 21:03:37.496| ACLChecklist::checkCallback: 0x7f111cfecf88 answer=2
2008/09/11 21:03:37.496| aclGetDenyInfoPage: got called for authusers
2008/09/11 21:03:37.496| aclGetDenyInfoPage: no match
2008/09/11 21:03:37.497| authenticateFixHeader: headertype:35 authuser:0
2008/09/11 21:03:37.497| AuthNTLMConfig::fixHeader: Sending type:35
header: 'NTLM'
2008/09/11 21:03:37.497| authenticateFixErrorHeader: Sending type:35
header: 'Basic realm="Squid proxy-caching web server"'
2008/09/11 21:03:37.497| aclCheckFast: list: 0
2008/09/11 21:03:37.497| aclCheckFast: no matches, returning: 1
2008/09/11 21:03:37.497| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfed050
2008/09/11 21:03:37.497| aclCheckFast: list: 0
2008/09/11 21:03:37.497| aclCheckFast: no matches, returning: 1
2008/09/11 21:03:37.497| ACLChecklist::~ACLChecklist: destroyed 0x7f111cfed050
2008/09/11 21:03:37.497| aclCheckFast: list: 0
2008/09/11 21:03:37.497| aclCheckFast: no matches, returning: 1

Thanks
Received on Fri Sep 12 2008 - 02:08:42 MDT

This archive was generated by hypermail 2.2.0 : Fri Sep 12 2008 - 12:00:03 MDT