вот что пишут логи.. логи описывают эти три строчки - первые две - джоступ запрещен (ибо еще не авторизовался), а третья - когджа уже авторизовался - не пускает, пишет что сервер не найден.. не понимаю почему не найден-то.. с этого же роутера нормально все пингуется
Код: Выделить всё
2007/11/30 12:16:02| eventRun: RUN ID 56
2007/11/30 12:16:02| eventRun: Running 'MaintainSwapSpace', id 55
2007/11/30 12:16:02| storeMaintainSwapSpace: f=0.000000, max_scan=100, max_remove=10
2007/11/30 12:16:02| cbdataFree: 0xed6b98
2007/11/30 12:16:02| cbdataFree: Freeing 0xed6b98
2007/11/30 12:16:02| storeUfsDirMaintain: /usr/local/squid/cache removed 0/10 f=0.000 max_scan=100
2007/11/30 12:16:02| comm_select: timeout 1000
2007/11/30 12:16:02| commSetSelect: FD 61 type 1
2007/11/30 12:16:02| fd_open FD 59 HTTP Request
2007/11/30 12:16:02| httpAccept: FD 59: accepted port 8787 client 192.168.10.2:1482
2007/11/30 12:16:02| cbdataLock: 0x767098
2007/11/30 12:16:02| comm_add_close_handler: FD 59, handler=0x41b8f0, data=0xea3618
2007/11/30 12:16:02| cbdataLock: 0xea3618
2007/11/30 12:16:02| commSetTimeout: FD 59 timeout 300
2007/11/30 12:16:02| aclCheckFast: list: 0x76f0d8
2007/11/30 12:16:02| aclMatchAclList: checking all
2007/11/30 12:16:02| aclMatchAcl: checking 'acl all src 0.0.0.0/0.0.0.0'
2007/11/30 12:16:02| aclMatchIp: '192.168.10.2' found
2007/11/30 12:16:02| aclMatchAclList: returning 1
2007/11/30 12:16:02| commSetSelect: FD 59 type 1
2007/11/30 12:16:02| comm_accept: FD 61: (35) Resource temporarily unavailable
2007/11/30 12:16:02| comm_select: timeout 925
2007/11/30 12:16:02| clientReadRequest: FD 59: reading request...
2007/11/30 12:16:02| cbdataLock: 0xea3618
2007/11/30 12:16:02| parseHttpRequest: Method is 'GET'
2007/11/30 12:16:02| parseHttpRequest: URI is 'http://www.mail.ru/'
2007/11/30 12:16:02| parseHttpRequest: req_hdr = {Host: www.mail.ru
2007/11/30 12:16:02| parseHttpRequest: end = {}
2007/11/30 12:16:02| parseHttpRequest: prefix_sz = 476, req_line_sz = 34
2007/11/30 12:16:02| parseHttpRequest: Request Header is
2007/11/30 12:16:02| parseHttpRequest: Complete request received
2007/11/30 12:16:02| conn->in.offset = 0
2007/11/30 12:16:02| commSetTimeout: FD 59 timeout 86400
2007/11/30 12:16:02| clientSetKeepaliveFlag: http_ver = 1.1
2007/11/30 12:16:02| clientSetKeepaliveFlag: method = GET
2007/11/30 12:16:02| cbdataLock: 0x76bcd8
2007/11/30 12:16:02| cbdataLock: 0xea3618
2007/11/30 12:16:02| cbdataLock: 0xee6018
2007/11/30 12:16:02| cbdataValid: 0x76bcd8
2007/11/30 12:16:02| aclCheck: checking 'http_access allow DomainUsers'
2007/11/30 12:16:02| aclMatchAclList: checking DomainUsers
2007/11/30 12:16:02| aclMatchAcl: checking 'acl DomainUsers proxy_auth REQUIRED'
2007/11/30 12:16:02| authenticateValidateUser: Auth_user_request was NULL!
2007/11/30 12:16:02| authenticateAuthenticate: broken auth or no proxy_auth header. Requesting auth header.
2007/11/30 12:16:02| aclMatchAcl: returning 0 sending authentication challenge.
2007/11/30 12:16:02| aclMatchAclList: no match, returning 0
2007/11/30 12:16:02| aclCheck: match found, returning 2
2007/11/30 12:16:02| cbdataUnlock: 0x76bcd8
2007/11/30 12:16:02| aclCheckCallback: answer=2
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| The request GET http://www.mail.ru/ is DENIED, because it matched 'DomainUsers'
2007/11/30 12:16:02| Access Denied: http://www.mail.ru/
2007/11/30 12:16:02| AclMatchedName = DomainUsers
2007/11/30 12:16:02| Proxy Auth Message = <null>
2007/11/30 12:16:02| storeCreateEntry: 'http://www.mail.ru/'
2007/11/30 12:16:02| new_MemObject: returning 0xed7e00
2007/11/30 12:16:02| new_StoreEntry: returning 0xed6e00
2007/11/30 12:16:02| storeKeyPrivate: GET http://www.mail.ru/
2007/11/30 12:16:02| storeHashInsert: Inserting Entry 0xed6e00 key '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeReleaseRequest: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeLockObject: key '365F750C521EF5B1D517D7DC18D65DD3' count=2
2007/11/30 12:16:02| storeClientCopy: 365F750C521EF5B1D517D7DC18D65DD3, seen 0, want 0, size 4096, cb 0x41e680, cbdata 0xee6018
2007/11/30 12:16:02| cbdataLock: 0xee6018
2007/11/30 12:16:02| cbdataLock: 0xed6e98
2007/11/30 12:16:02| storeClientCopy2: 365F750C521EF5B1D517D7DC18D65DD3
2007/11/30 12:16:02| storeClientCopy3: Waiting for more
2007/11/30 12:16:02| cbdataUnlock: 0xed6e98
2007/11/30 12:16:02| storeLockObject: key '365F750C521EF5B1D517D7DC18D65DD3' count=3
2007/11/30 12:16:02| errorConvert: %U --> 'http://www.mail.ru/'
2007/11/30 12:16:02| errorConvert: %U --> 'http://www.mail.ru/'
2007/11/30 12:16:02| errorConvert: %U --> 'http://www.mail.ru/'
2007/11/30 12:16:02| errorConvert: %w --> 'admin@artpaint.spb.ru'
2007/11/30 12:16:02| errorConvert: %h --> 'artpaint.spb.ru'
2007/11/30 12:16:02| errorConvert: %T --> 'Fri, 30 Nov 2007 09:16:02 GMT'
2007/11/30 12:16:02| errorConvert: %h --> 'artpaint.spb.ru'
2007/11/30 12:16:02| errorConvert: %s --> 'squid/2.6.STABLE16+ICAP'
2007/11/30 12:16:02| errorConvert: %S --> '
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 44 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 6 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 23 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 4 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 29 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 12 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 9 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 14 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 4 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 7 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 29 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 13 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 25 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 18 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 4 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 18 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 29 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeAppend: appending 1298 bytes for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| InvokeHandlers: 365F750C521EF5B1D517D7DC18D65DD3
2007/11/30 12:16:02| InvokeHandlers: checking client #0
2007/11/30 12:16:02| cbdataLock: 0xed6e98
2007/11/30 12:16:02| storeClientCopy2: 365F750C521EF5B1D517D7DC18D65DD3
2007/11/30 12:16:02| storeClientCopy3: Copying from memory
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| clientSendMoreHeaderData: http://www.mail.ru/, 1620 bytes
2007/11/30 12:16:02| clientSendMoreHeaderData: FD 59 'http://www.mail.ru/', out.offset=0
2007/11/30 12:16:02| clientBuildReplyHeader: Error, don't keep-alive
2007/11/30 12:16:02| clientSendMoreHeaderData: Appending 1298 bytes after 322 bytes of headers
2007/11/30 12:16:02| cbdataLock: 0xee6018
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| The reply for GET http://www.mail.ru/ is ALLOWED, because it matched 'DomainUsers'
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| cbdataFree: 0xeec098
2007/11/30 12:16:02| cbdataFree: Freeing 0xeec098
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| comm_write: FD 59: sz 1738: hndl 0x41e340: data 0xee6018.
2007/11/30 12:16:02| cbdataLock: 0xee6018
2007/11/30 12:16:02| commSetSelect: FD 59 type 2
2007/11/30 12:16:02| cbdataUnlock: 0xee6018
2007/11/30 12:16:02| cbdataUnlock: 0xee6018
2007/11/30 12:16:02| cbdataUnlock: 0xed6e98
2007/11/30 12:16:02| storeComplete: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeEntryValidLength: Checking '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storeEntryValidLength: object_len = 1620
2007/11/30 12:16:02| storeEntryValidLength: hdr_sz = 322
2007/11/30 12:16:02| storeEntryValidLength: content_length = 1298
2007/11/30 12:16:02| InvokeHandlers: 365F750C521EF5B1D517D7DC18D65DD3
2007/11/30 12:16:02| InvokeHandlers: checking client #0
2007/11/30 12:16:02| storeUnlockObject: key '365F750C521EF5B1D517D7DC18D65DD3' count=2
2007/11/30 12:16:02| cbdataFree: 0xee5018
2007/11/30 12:16:02| cbdataFree: Freeing 0xee5018
2007/11/30 12:16:02| cbdataUnlock: 0xee6018
2007/11/30 12:16:02| cbdataUnlock: 0xea3618
2007/11/30 12:16:02| cbdataFree: 0xea3a18
2007/11/30 12:16:02| cbdataFree: Freeing 0xea3a18
2007/11/30 12:16:02| cbdataValid: 0xea3618
2007/11/30 12:16:02| cbdataValid: 0xea3618
2007/11/30 12:16:02| cbdataUnlock: 0xea3618
2007/11/30 12:16:02| commSetSelect: FD 59 type 1
2007/11/30 12:16:02| comm_select: timeout 923
2007/11/30 12:16:02| commHandleWrite: FD 59: off 0, sz 1738.
2007/11/30 12:16:02| commHandleWrite: write() returns 1738
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| clientWriteComplete: FD 59, sz 1738, err 0, off 1620, len 1620
2007/11/30 12:16:02| clientWriteComplete: FD 59 transfer is DONE
2007/11/30 12:16:02| comm_close: FD 59
2007/11/30 12:16:02| commCallCloseHandlers: FD 59
2007/11/30 12:16:02| commCallCloseHandlers: ch->handler=0x41b8f0
2007/11/30 12:16:02| cbdataValid: 0xea3618
2007/11/30 12:16:02| connStateFree: FD 59
2007/11/30 12:16:02| httpRequestFree: http://www.mail.ru/
2007/11/30 12:16:02| cbdataLock: 0x76bcd8
2007/11/30 12:16:02| cbdataLock: 0xea3618
2007/11/30 12:16:02| cbdataUnlock: 0xea3618
2007/11/30 12:16:02| cbdataUnlock: 0x76bcd8
2007/11/30 12:16:02| cbdataFree: 0xea3a18
2007/11/30 12:16:02| cbdataFree: Freeing 0xea3a18
2007/11/30 12:16:02| storeClientUnregister: called for '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| storePendingNClients: returning 0
2007/11/30 12:16:02| storeUnlockObject: key '365F750C521EF5B1D517D7DC18D65DD3' count=1
2007/11/30 12:16:02| cbdataFree: 0xed6e98
2007/11/30 12:16:02| cbdataFree: Freeing 0xed6e98
2007/11/30 12:16:02| storeUnlockObject: key '365F750C521EF5B1D517D7DC18D65DD3' count=0
2007/11/30 12:16:02| storePendingNClients: returning 0
2007/11/30 12:16:02| storeRelease: Releasing: '365F750C521EF5B1D517D7DC18D65DD3'
2007/11/30 12:16:02| destroy_StoreEntry: destroying 0xed6e00
2007/11/30 12:16:02| ctx: enter level 0: 'http://www.mail.ru/'
2007/11/30 12:16:02| destroy_MemObject: destroying 0xed7e00
2007/11/30 12:16:02| ctx: exit level 0
2007/11/30 12:16:02| cbdataFree: 0xee6018
2007/11/30 12:16:02| cbdataFree: 0xee6018 has 1 locks, not freeing
2007/11/30 12:16:02| cbdataFree: 0xea3618
2007/11/30 12:16:02| cbdataFree: 0xea3618 has 1 locks, not freeing
2007/11/30 12:16:02| cbdataUnlock: 0xea3618
2007/11/30 12:16:02| cbdataUnlock: Freeing 0xea3618
2007/11/30 12:16:02| fd_close FD 59 Reading next request
2007/11/30 12:16:02| cbdataUnlock: 0xee6018
2007/11/30 12:16:02| cbdataUnlock: Freeing 0xee6018
2007/11/30 12:16:02| comm_select: timeout 923
2007/11/30 12:16:02| comm_select: kqueue event error: (9) Bad file descriptor
2007/11/30 12:16:02| comm_select: kqueue event error: (9) Bad file descriptor
2007/11/30 12:16:02| comm_select: timeout 923
2007/11/30 12:16:02| commSetSelect: FD 61 type 1
2007/11/30 12:16:02| fd_open FD 59 HTTP Request
2007/11/30 12:16:02| httpAccept: FD 59: accepted port 8787 client 192.168.10.2:1483
2007/11/30 12:16:02| cbdataLock: 0x767098
2007/11/30 12:16:02| comm_add_close_handler: FD 59, handler=0x41b8f0, data=0xea3618
2007/11/30 12:16:02| cbdataLock: 0xea3618
2007/11/30 12:16:02| commSetTimeout: FD 59 timeout 300
2007/11/30 12:16:02| aclCheckFast: list: 0x76f0d8
2007/11/30 12:16:02| aclMatchAclList: checking all
2007/11/30 12:16:02| aclMatchAcl: checking 'acl all src 0.0.0.0/0.0.0.0'
2007/11/30 12:16:02| aclMatchIp: '192.168.10.2' found
2007/11/30 12:16:02| aclMatchAclList: returning 1
2007/11/30 12:16:02| commSetSelect: FD 59 type 1
2007/11/30 12:16:02| comm_accept: FD 61: (35) Resource temporarily unavailable
2007/11/30 12:16:02| comm_select: timeout 902
2007/11/30 12:16:02| clientReadRequest: FD 59: reading request...
2007/11/30 12:16:02| cbdataLock: 0xea3618
2007/11/30 12:16:02| parseHttpRequest: Method is 'GET'
2007/11/30 12:16:02| parseHttpRequest: URI is 'http://www.mail.ru/'
2007/11/30 12:16:02| parseHttpRequest: req_hdr = {Host: www.mail.ru
2007/11/30 12:16:02| parseHttpRequest: end = {}
2007/11/30 12:16:02| parseHttpRequest: prefix_sz = 595, req_line_sz = 34
2007/11/30 12:16:02| parseHttpRequest: Request Header is
2007/11/30 12:16:02| parseHttpRequest: Complete request received
2007/11/30 12:16:02| conn->in.offset = 0
2007/11/30 12:16:02| commSetTimeout: FD 59 timeout 86400
2007/11/30 12:16:02| clientSetKeepaliveFlag: http_ver = 1.1
2007/11/30 12:16:02| clientSetKeepaliveFlag: method = GET
2007/11/30 12:16:02| cbdataLock: 0x76bcd8
2007/11/30 12:16:02| cbdataLock: 0xea3618
2007/11/30 12:16:02| cbdataLock: 0xee6018
2007/11/30 12:16:02| cbdataValid: 0x76bcd8
2007/11/30 12:16:02| aclCheck: checking 'http_access allow DomainUsers'
2007/11/30 12:16:02| aclMatchAclList: checking DomainUsers
2007/11/30 12:16:02| aclMatchAcl: checking 'acl DomainUsers proxy_auth REQUIRED'
2007/11/30 12:16:02| authenticateAuthenticate: no connection authentication type
2007/11/30 12:16:02| authenticateValidateUser: Validated Auth_user request '0xe9cc60'.
2007/11/30 12:16:02| authenticateValidateUser: Validated Auth_user request '0xe9cc60'.
2007/11/30 12:16:02| authenticateValidateUser: Validated Auth_user request '0xe9cc60'.
2007/11/30 12:16:02| aclMatchAcl: returning 0 sending credentials to helper.
2007/11/30 12:16:02| aclMatchAclList: no match, returning 0
2007/11/30 12:16:02| aclCheck: checking password via authenticator
2007/11/30 12:16:02| authenticateValidateUser: Validated Auth_user request '0xe9cc60'.
2007/11/30 12:16:02| cbdataLock: 0xea3a18
2007/11/30 12:16:02| cbdataLock: 0xeec118
2007/11/30 12:16:02| helperStatefulGetServer: Running servers 30.
2007/11/30 12:16:02| StatefulGetFirstAvailable: Running servers 30.
2007/11/30 12:16:02| helperStatefulGetServer: Returning 0xb1d818
2007/11/30 12:16:02| helperStatefulSubmit: server 0xb1d818, buf 'YR TlRMTVNTUAABAAAAB7IIoggACAAyAAAACgAKACgAAAAFAs4OAAAAD1RFUk1JTkFMMDJBUlRQQUlOVA==
2007/11/30 12:16:02| cbdataValid: 0xeec118
2007/11/30 12:16:02| comm_write: FD 21: sz 84: hndl 0x0: data 0x0.
2007/11/30 12:16:02| commSetSelect: FD 21 type 2
2007/11/30 12:16:02| helperStatefulDispatch: Request sent to ntlmauthenticator #1, 84 bytes
2007/11/30 12:16:02| cbdataValid: 0xea3618
2007/11/30 12:16:02| cbdataValid: 0xea3618
2007/11/30 12:16:02| cbdataUnlock: 0xea3618
2007/11/30 12:16:02| commSetSelect: FD 59 type 1
2007/11/30 12:16:02| comm_select: timeout 892
2007/11/30 12:16:02| commHandleWrite: FD 21: off 0, sz 84.
2007/11/30 12:16:02| commHandleWrite: write() returns 84
2007/11/30 12:16:02| comm_select: timeout 891
2007/11/30 12:16:02| cbdataValid: 0xb1d818
2007/11/30 12:16:02| helperStatefulHandleRead: 216 bytes from ntlmauthenticator #1.
2007/11/30 12:16:02| commSetSelect: FD 21 type 1
2007/11/30 12:16:02| helperStatefulHandleRead: end of reply found
2007/11/30 12:16:02| cbdataValid: 0xeec118
2007/11/30 12:16:02| cbdataValid: 0xea3a18
2007/11/30 12:16:02| authenticateNTLMHandleReply: Need to challenge the client with a server blob 'TlRMTVNTUAACAAAAEAAQADAAAAAFgomiO+nmzpCQ6dkAAAAAAAAAAF4AXgBAAAAAQQBSAFQAUABBAEkATgBUAAIAEABBAFIAVABQAEEASQBOAFQAAQAMAFIATwBVAFQARQBSAAQAEABhAHIAdABwAGEAaQBuAHQAAwAeAHIAbwB1AHQAZQByAC4AYQByAHQAcABhAGkAbgB0AAAAAAA='
2007/11/30 12:16:02| authenticateValidateUser: Validated Auth_user request '0xe9cc60'.
2007/11/30 12:16:02| cbdataValid: 0x76bcd8
2007/11/30 12:16:02| aclCheck: checking 'http_access allow DomainUsers'
2007/11/30 12:16:02| aclMatchAclList: checking DomainUsers
2007/11/30 12:16:02| aclMatchAcl: checking 'acl DomainUsers proxy_auth REQUIRED'
2007/11/30 12:16:02| authenticateValidateUser: Validated Auth_user request '0xe9cc60'.
2007/11/30 12:16:02| authenticateNTLMAuthenticateUser: need to challenge client 'TlRMTVNTUAACAAAAEAAQADAAAAAFgomiO+nmzpCQ6dkAAAAAAAAAAF4AXgBAAAAAQQBSAFQAUABBAEkATgBUAAIAEABBAFIAVABQAEEASQBOAFQAAQAMAFIATwBVAFQARQBSAAQAEABhAHIAdABwAGEAaQBuAHQAAwAeAHIAbwB1AHQAZQByAC4AYQByAHQAcABhAGkAbgB0AAAAAAA='!
2007/11/30 12:16:02| authenticateValidateUser: Validated Auth_user request '0xe9cc60'.
2007/11/30 12:16:02| aclMatchAcl: returning 0 sending authentication challenge.
2007/11/30 12:16:02| aclMatchAclList: no match, returning 0
2007/11/30 12:16:02| aclCheck: match found, returning 2
2007/11/30 12:16:02| cbdataUnlock: 0x76bcd8
2007/11/30 12:16:02| aclCheckCallback: answer=2
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| The request GET http://www.mail.ru/ is DENIED, because it matched 'DomainUsers'
2007/11/30 12:16:02| Access Denied: http://www.mail.ru/
2007/11/30 12:16:02| AclMatchedName = DomainUsers
2007/11/30 12:16:02| Proxy Auth Message = Authentication in progress
2007/11/30 12:16:02| storeCreateEntry: 'http://www.mail.ru/'
2007/11/30 12:16:02| new_MemObject: returning 0xed7e00
2007/11/30 12:16:02| new_StoreEntry: returning 0xed6e00
2007/11/30 12:16:02| storeKeyPrivate: GET http://www.mail.ru/
2007/11/30 12:16:02| storeHashInsert: Inserting Entry 0xed6e00 key 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeReleaseRequest: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeLockObject: key 'D708155F1AF94E88E5A2B71CEBA0AFF5' count=2
2007/11/30 12:16:02| storeClientCopy: D708155F1AF94E88E5A2B71CEBA0AFF5, seen 0, want 0, size 4096, cb 0x41e680, cbdata 0xee6018
2007/11/30 12:16:02| cbdataLock: 0xee6018
2007/11/30 12:16:02| cbdataLock: 0xed6e98
2007/11/30 12:16:02| storeClientCopy2: D708155F1AF94E88E5A2B71CEBA0AFF5
2007/11/30 12:16:02| storeClientCopy3: Waiting for more
2007/11/30 12:16:02| cbdataUnlock: 0xed6e98
2007/11/30 12:16:02| storeLockObject: key 'D708155F1AF94E88E5A2B71CEBA0AFF5' count=3
2007/11/30 12:16:02| errorConvert: %U --> 'http://www.mail.ru/'
2007/11/30 12:16:02| errorConvert: %U --> 'http://www.mail.ru/'
2007/11/30 12:16:02| errorConvert: %U --> 'http://www.mail.ru/'
2007/11/30 12:16:02| errorConvert: %w --> 'admin@artpaint.spb.ru'
2007/11/30 12:16:02| errorConvert: %h --> 'artpaint.spb.ru'
2007/11/30 12:16:02| errorConvert: %T --> 'Fri, 30 Nov 2007 09:16:02 GMT'
2007/11/30 12:16:02| errorConvert: %h --> 'artpaint.spb.ru'
2007/11/30 12:16:02| errorConvert: %s --> 'squid/2.6.STABLE16+ICAP'
2007/11/30 12:16:02| errorConvert: %S --> '
2007/11/30 12:16:02| authenticateValidateUser: Validated Auth_user request '0xe9cc60'.
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 44 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 6 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 23 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 4 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 29 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 12 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 9 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 14 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 4 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 7 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 29 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 13 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 25 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 18 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 217 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeAppend: appending 1298 bytes for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| InvokeHandlers: D708155F1AF94E88E5A2B71CEBA0AFF5
2007/11/30 12:16:02| InvokeHandlers: checking client #0
2007/11/30 12:16:02| cbdataLock: 0xed6e98
2007/11/30 12:16:02| storeClientCopy2: D708155F1AF94E88E5A2B71CEBA0AFF5
2007/11/30 12:16:02| storeClientCopy3: Copying from memory
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| clientSendMoreHeaderData: http://www.mail.ru/, 1782 bytes
2007/11/30 12:16:02| clientSendMoreHeaderData: FD 59 'http://www.mail.ru/', out.offset=0
2007/11/30 12:16:02| clientSendMoreHeaderData: Appending 1298 bytes after 484 bytes of headers
2007/11/30 12:16:02| cbdataLock: 0xee6018
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| The reply for GET http://www.mail.ru/ is ALLOWED, because it matched 'DomainUsers'
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| cbdataFree: 0xeec098
2007/11/30 12:16:02| cbdataFree: Freeing 0xeec098
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| comm_write: FD 59: sz 1905: hndl 0x41e340: data 0xee6018.
2007/11/30 12:16:02| cbdataLock: 0xee6018
2007/11/30 12:16:02| commSetSelect: FD 59 type 2
2007/11/30 12:16:02| cbdataUnlock: 0xee6018
2007/11/30 12:16:02| cbdataUnlock: 0xee6018
2007/11/30 12:16:02| cbdataUnlock: 0xed6e98
2007/11/30 12:16:02| storeComplete: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeEntryValidLength: Checking 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storeEntryValidLength: object_len = 1782
2007/11/30 12:16:02| storeEntryValidLength: hdr_sz = 484
2007/11/30 12:16:02| storeEntryValidLength: content_length = 1298
2007/11/30 12:16:02| InvokeHandlers: D708155F1AF94E88E5A2B71CEBA0AFF5
2007/11/30 12:16:02| InvokeHandlers: checking client #0
2007/11/30 12:16:02| storeUnlockObject: key 'D708155F1AF94E88E5A2B71CEBA0AFF5' count=2
2007/11/30 12:16:02| cbdataFree: 0xee5018
2007/11/30 12:16:02| cbdataFree: Freeing 0xee5018
2007/11/30 12:16:02| cbdataUnlock: 0xee6018
2007/11/30 12:16:02| cbdataUnlock: 0xea3618
2007/11/30 12:16:02| cbdataFree: 0xea3a18
2007/11/30 12:16:02| cbdataFree: 0xea3a18 has 1 locks, not freeing
2007/11/30 12:16:02| cbdataUnlock: 0xea3a18
2007/11/30 12:16:02| cbdataUnlock: Freeing 0xea3a18
2007/11/30 12:16:02| cbdataFree: 0xeec118
2007/11/30 12:16:02| cbdataFree: 0xeec118 has 1 locks, not freeing
2007/11/30 12:16:02| cbdataUnlock: 0xeec118
2007/11/30 12:16:02| cbdataUnlock: Freeing 0xeec118
2007/11/30 12:16:02| comm_select: timeout 889
2007/11/30 12:16:02| commHandleWrite: FD 59: off 0, sz 1905.
2007/11/30 12:16:02| commHandleWrite: write() returns 1905
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| clientWriteComplete: FD 59, sz 1905, err 0, off 1782, len 1782
2007/11/30 12:16:02| clientWriteComplete: FD 59 transfer is DONE
2007/11/30 12:16:02| clientWriteComplete: FD 59 Keeping Alive
2007/11/30 12:16:02| clientKeepaliveNextRequest: FD 59
2007/11/30 12:16:02| httpRequestFree: http://www.mail.ru/
2007/11/30 12:16:02| cbdataLock: 0x76bcd8
2007/11/30 12:16:02| cbdataLock: 0xea3618
2007/11/30 12:16:02| cbdataUnlock: 0xea3618
2007/11/30 12:16:02| cbdataUnlock: 0x76bcd8
2007/11/30 12:16:02| cbdataFree: 0xea3a18
2007/11/30 12:16:02| cbdataFree: Freeing 0xea3a18
2007/11/30 12:16:02| storeClientUnregister: called for 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| storePendingNClients: returning 0
2007/11/30 12:16:02| storeUnlockObject: key 'D708155F1AF94E88E5A2B71CEBA0AFF5' count=1
2007/11/30 12:16:02| cbdataFree: 0xed6e98
2007/11/30 12:16:02| cbdataFree: Freeing 0xed6e98
2007/11/30 12:16:02| storeUnlockObject: key 'D708155F1AF94E88E5A2B71CEBA0AFF5' count=0
2007/11/30 12:16:02| storePendingNClients: returning 0
2007/11/30 12:16:02| storeRelease: Releasing: 'D708155F1AF94E88E5A2B71CEBA0AFF5'
2007/11/30 12:16:02| destroy_StoreEntry: destroying 0xed6e00
2007/11/30 12:16:02| ctx: enter level 0: 'http://www.mail.ru/'
2007/11/30 12:16:02| destroy_MemObject: destroying 0xed7e00
2007/11/30 12:16:02| ctx: exit level 0
2007/11/30 12:16:02| cbdataFree: 0xee6018
2007/11/30 12:16:02| cbdataFree: 0xee6018 has 1 locks, not freeing
2007/11/30 12:16:02| clientKeepaliveNextRequest: FD 59 reading next req
2007/11/30 12:16:02| commSetTimeout: FD 59 timeout 120
2007/11/30 12:16:02| clientReadRequest: FD 59: reading request...
2007/11/30 12:16:02| clientReadRequest: FD 59: no data to process ((35) Resource temporarily unavailable)
2007/11/30 12:16:02| cbdataLock: 0xea3618
2007/11/30 12:16:02| cbdataValid: 0xea3618
2007/11/30 12:16:02| cbdataUnlock: 0xea3618
2007/11/30 12:16:02| commSetSelect: FD 59 type 1
2007/11/30 12:16:02| cbdataUnlock: 0xee6018
2007/11/30 12:16:02| cbdataUnlock: Freeing 0xee6018
2007/11/30 12:16:02| comm_select: timeout 889
2007/11/30 12:16:02| clientReadRequest: FD 59: reading request...
2007/11/30 12:16:02| cbdataLock: 0xea3618
2007/11/30 12:16:02| parseHttpRequest: Method is 'GET'
2007/11/30 12:16:02| parseHttpRequest: URI is 'http://www.mail.ru/'
2007/11/30 12:16:02| parseHttpRequest: req_hdr = {Host: www.mail.ru
2007/11/30 12:16:02| parseHttpRequest: end = {}
2007/11/30 12:16:02| parseHttpRequest: prefix_sz = 742, req_line_sz = 34
2007/11/30 12:16:02| parseHttpRequest: Request Header is
2007/11/30 12:16:02| parseHttpRequest: Complete request received
2007/11/30 12:16:02| conn->in.offset = 0
2007/11/30 12:16:02| commSetTimeout: FD 59 timeout 86400
2007/11/30 12:16:02| clientSetKeepaliveFlag: http_ver = 1.1
2007/11/30 12:16:02| clientSetKeepaliveFlag: method = GET
2007/11/30 12:16:02| cbdataLock: 0x76bcd8
2007/11/30 12:16:02| cbdataLock: 0xea3618
2007/11/30 12:16:02| cbdataLock: 0xee6018
2007/11/30 12:16:02| cbdataValid: 0x76bcd8
2007/11/30 12:16:02| aclCheck: checking 'http_access allow DomainUsers'
2007/11/30 12:16:02| aclMatchAclList: checking DomainUsers
2007/11/30 12:16:02| aclMatchAcl: checking 'acl DomainUsers proxy_auth REQUIRED'
2007/11/30 12:16:02| authenticateValidateUser: Validated Auth_user request '0xe9cc60'.
2007/11/30 12:16:02| authenticateValidateUser: Validated Auth_user request '0xe9cc60'.
2007/11/30 12:16:02| aclMatchAcl: returning 0 sending credentials to helper.
2007/11/30 12:16:02| aclMatchAclList: no match, returning 0
2007/11/30 12:16:02| aclCheck: checking password via authenticator
2007/11/30 12:16:02| authenticateValidateUser: Validated Auth_user request '0xe9cc60'.
2007/11/30 12:16:02| cbdataLock: 0xea3a18
2007/11/30 12:16:02| cbdataLock: 0xeec118
2007/11/30 12:16:02| helperStatefulSubmit: server 0xb1d818, buf 'KK TlRMTVNTUAADAAAAGAAYAHIAAAAYABgAigAAABAAEABIAAAABgAGAFgAAAAUABQAXgAAAAAAAACiAAAABYKIogUCzg4AAAAPQQBSAFQAUABBAEkATgBUAGYAMABzAFQARQBSAE0ASQBOAEEATAAwADIAKMlQnctMS/gAAAAAAAAAAAAAAAAAAAAAOrvlvGjI1R55/jCXL7sK8qSq5CGRdA+h
2007/11/30 12:16:02| cbdataValid: 0xeec118
2007/11/30 12:16:02| comm_write: FD 21: sz 220: hndl 0x0: data 0x0.
2007/11/30 12:16:02| commSetSelect: FD 21 type 2
2007/11/30 12:16:02| helperStatefulDispatch: Request sent to ntlmauthenticator #1, 220 bytes
2007/11/30 12:16:02| cbdataValid: 0xea3618
2007/11/30 12:16:02| cbdataValid: 0xea3618
2007/11/30 12:16:02| cbdataUnlock: 0xea3618
2007/11/30 12:16:02| commSetSelect: FD 59 type 1
2007/11/30 12:16:02| comm_select: timeout 884
2007/11/30 12:16:02| commHandleWrite: FD 21: off 0, sz 220.
2007/11/30 12:16:02| commHandleWrite: write() returns 220
2007/11/30 12:16:02| comm_select: timeout 884
2007/11/30 12:16:02| cbdataValid: 0xb1d818
2007/11/30 12:16:02| helperStatefulHandleRead: 7 bytes from ntlmauthenticator #1.
2007/11/30 12:16:02| commSetSelect: FD 21 type 1
2007/11/30 12:16:02| helperStatefulHandleRead: end of reply found
2007/11/30 12:16:02| cbdataValid: 0xeec118
2007/11/30 12:16:02| cbdataValid: 0xea3a18
2007/11/30 12:16:02| authenticateNTLMHandleReply: Successfully validated user via NTLM. Username 'f0s'
2007/11/30 12:16:02| authenticated user f0s
2007/11/30 12:16:02| helperStatefulReleaseServer: 0xb1d818
2007/11/30 12:16:02| helperStatefulReset: 0xb1d818
2007/11/30 12:16:02| StatefulGetFirstAvailable: Running servers 30.
2007/11/30 12:16:02| authenticateValidateUser: Validated Auth_user request '0xe9cc60'.
2007/11/30 12:16:02| cbdataValid: 0x76bcd8
2007/11/30 12:16:02| aclCheck: checking 'http_access allow DomainUsers'
2007/11/30 12:16:02| aclMatchAclList: checking DomainUsers
2007/11/30 12:16:02| aclMatchAcl: checking 'acl DomainUsers proxy_auth REQUIRED'
2007/11/30 12:16:02| authenticateValidateUser: Validated Auth_user request '0xe9cc60'.
2007/11/30 12:16:02| authenticateAuthUserRequestSetIp: user 'f0s' has been seen at a new IP address (192.168.10.2)
2007/11/30 12:16:02| authenticateValidateUser: Validated Auth_user request '0xe9cc60'.
2007/11/30 12:16:02| aclMatchAclList: returning 1
2007/11/30 12:16:02| aclCheck: match found, returning 1
2007/11/30 12:16:02| cbdataUnlock: 0x76bcd8
2007/11/30 12:16:02| aclCheckCallback: answer=1
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| The request GET http://www.mail.ru/ is ALLOWED, because it matched 'DomainUsers'
2007/11/30 12:16:02| clientRedirectStart: 'http://www.mail.ru/'
2007/11/30 12:16:02| redirectStart: 'http://www.mail.ru/'
2007/11/30 12:16:02| cbdataLock: 0xee6018
2007/11/30 12:16:02| cbdataLock: 0xeeb618
2007/11/30 12:16:02| cbdataValid: 0xeeb618
2007/11/30 12:16:02| comm_write: FD 6: sz 45: hndl 0x439180: data 0xb1e418.
2007/11/30 12:16:02| cbdataLock: 0xb1e418
2007/11/30 12:16:02| commSetSelect: FD 6 type 2
2007/11/30 12:16:02| helperDispatch: Request sent to url_rewriter #1, 45 bytes
2007/11/30 12:16:02| cbdataUnlock: 0xee6018
2007/11/30 12:16:02| cbdataUnlock: 0xea3618
2007/11/30 12:16:02| cbdataFree: 0xea3a18
2007/11/30 12:16:02| cbdataFree: 0xea3a18 has 1 locks, not freeing
2007/11/30 12:16:02| cbdataUnlock: 0xea3a18
2007/11/30 12:16:02| cbdataUnlock: Freeing 0xea3a18
2007/11/30 12:16:02| cbdataFree: 0xeec118
2007/11/30 12:16:02| cbdataFree: 0xeec118 has 1 locks, not freeing
2007/11/30 12:16:02| cbdataUnlock: 0xeec118
2007/11/30 12:16:02| cbdataUnlock: Freeing 0xeec118
2007/11/30 12:16:02| comm_select: timeout 872
2007/11/30 12:16:02| commHandleWrite: FD 6: off 0, sz 45.
2007/11/30 12:16:02| commHandleWrite: write() returns 45
2007/11/30 12:16:02| cbdataValid: 0xb1e418
2007/11/30 12:16:02| cbdataUnlock: 0xb1e418
2007/11/30 12:16:02| comm_select: timeout 871
2007/11/30 12:16:02| cbdataValid: 0xb1e418
2007/11/30 12:16:02| helperHandleRead: 43 bytes from url_rewriter #1.
2007/11/30 12:16:02| commSetSelect: FD 6 type 1
2007/11/30 12:16:02| helperHandleRead: end of reply found: http://www.mail.ru/ 192.168.10.2/- f0s GET
2007/11/30 12:16:02| cbdataValid: 0xeeb618
2007/11/30 12:16:02| redirectHandleRead: {http://www.mail.ru/ 192.168.10.2/- f0s GET}
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| cbdataUnlock: 0xee6018
2007/11/30 12:16:02| clientRedirectDone: 'http://www.mail.ru/' result=http://www.mail.ru/
2007/11/30 12:16:02| hdr cc: ignoring duplicate cache-directive: near 'max-age=0, max-age=0' in 'max-age=0, max-age=0, max-age=0'
2007/11/30 12:16:02| hdr cc: ignoring duplicate cache-directive: near 'max-age=0' in 'max-age=0, max-age=0, max-age=0'
2007/11/30 12:16:02| cbdataLock: 0xea3618
2007/11/30 12:16:02| clientInterpretRequestHeaders: REQ_NOCACHE = NOT SET
2007/11/30 12:16:02| clientInterpretRequestHeaders: REQ_CACHABLE = SET
2007/11/30 12:16:02| clientInterpretRequestHeaders: REQ_HIERARCHICAL = SET
2007/11/30 12:16:02| cbdataLock: 0x75d598
2007/11/30 12:16:02| cbdataLock: 0xea3618
2007/11/30 12:16:02| cbdataLock: 0xee6018
2007/11/30 12:16:02| cbdataValid: 0x75d598
2007/11/30 12:16:02| aclCheck: checking 'no_cache deny QUERY'
2007/11/30 12:16:02| aclMatchAclList: checking QUERY
2007/11/30 12:16:02| aclMatchAcl: checking 'acl QUERY urlpath_regex cgi-bin \?'
2007/11/30 12:16:02| aclMatchRegex: checking '/'
2007/11/30 12:16:02| aclMatchRegex: looking for 'cgi-bin'
2007/11/30 12:16:02| aclMatchRegex: looking for '\?'
2007/11/30 12:16:02| aclMatchAclList: no match, returning 0
2007/11/30 12:16:02| cbdataUnlock: 0x75d598
2007/11/30 12:16:02| aclCheck: NO match found, returning 1
2007/11/30 12:16:02| aclCheckCallback: answer=1
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| clientProcessRequest: GET 'http://www.mail.ru/'
2007/11/30 12:16:02| storeGet: looking up D837AEA7EECF9EEEB0B4F5985AE4F5CA
2007/11/30 12:16:02| clientProcessRequest2: storeGet() MISS
2007/11/30 12:16:02| clientProcessRequest: TCP_MISS for 'http://www.mail.ru/'
2007/11/30 12:16:02| clientProcessMiss: 'GET http://www.mail.ru/'
2007/11/30 12:16:02| storeCreateEntry: 'http://www.mail.ru/'
2007/11/30 12:16:02| new_MemObject: returning 0xed7e00
2007/11/30 12:16:02| new_StoreEntry: returning 0xed6e00
2007/11/30 12:16:02| storeKeyPrivate: GET http://www.mail.ru/
2007/11/30 12:16:02| storeHashInsert: Inserting Entry 0xed6e00 key '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeLockObject: key '718F731A218D9231B0EDE38DFA249E2A' count=2
2007/11/30 12:16:02| storeClientCopy: 718F731A218D9231B0EDE38DFA249E2A, seen 0, want 0, size 4096, cb 0x41e680, cbdata 0xee6018
2007/11/30 12:16:02| cbdataLock: 0xee6018
2007/11/30 12:16:02| cbdataLock: 0xed6e98
2007/11/30 12:16:02| storeClientCopy2: 718F731A218D9231B0EDE38DFA249E2A
2007/11/30 12:16:02| storeClientCopy3: Waiting for more
2007/11/30 12:16:02| cbdataUnlock: 0xed6e98
2007/11/30 12:16:02| aclCheckFast: list: 0x0
2007/11/30 12:16:02| aclCheckFast: no matches, returning: 1
2007/11/30 12:16:02| fwdStart: 'http://www.mail.ru/'
2007/11/30 12:16:02| storeLockObject: key '718F731A218D9231B0EDE38DFA249E2A' count=3
2007/11/30 12:16:02| peerSelect: http://www.mail.ru/
2007/11/30 12:16:02| storeLockObject: key '718F731A218D9231B0EDE38DFA249E2A' count=4
2007/11/30 12:16:02| cbdataLock: 0xeeb698
2007/11/30 12:16:02| peerSelectFoo: 'GET www.mail.ru'
2007/11/30 12:16:02| peerCheckNetdbDirect: MY RTT = 0 msec
2007/11/30 12:16:02| peerCheckNetdbDirect: minimum_direct_rtt = 400 msec
2007/11/30 12:16:02| peerCheckNetdbDirect: MY hops = 0
2007/11/30 12:16:02| peerCheckNetdbDirect: minimum_direct_hops = 4
2007/11/30 12:16:02| whichPeer: from 0.0.0.0 port 0
2007/11/30 12:16:02| peerSelectFoo: direct = DIRECT_MAYBE
2007/11/30 12:16:02| peerSelectIcpPing: http://www.mail.ru/
2007/11/30 12:16:02| neighborsCount: 0
2007/11/30 12:16:02| peerSelectIcpPing: counted 0 neighbors
2007/11/30 12:16:02| peerGetSomeParent: GET www.mail.ru
2007/11/30 12:16:02| getDefaultParent: returning NULL
2007/11/30 12:16:02| peerUserHashSelectParent: Calculating hash for f0s
2007/11/30 12:16:02| peerSourceHashSelectParent: Calculating hash for 192.168.10.2
2007/11/30 12:16:02| getRoundRobinParent: returning NULL
2007/11/30 12:16:02| getFirstUpParent: returning NULL
2007/11/30 12:16:02| getAnyParent: returning NULL
2007/11/30 12:16:02| peerAddFwdServer: adding DIRECT DIRECT
2007/11/30 12:16:02| peerSelectCallback: http://www.mail.ru/
2007/11/30 12:16:02| cbdataValid: 0xeeb698
2007/11/30 12:16:02| fwdStartComplete: http://www.mail.ru/
2007/11/30 12:16:02| fwdConnectStart: http://www.mail.ru/
2007/11/30 12:16:02| aclMatchAclList: returning 1
2007/11/30 12:16:02| fwdConnectStart: got addr 84.52.64.20, tos 0
2007/11/30 12:16:02| comm_open: FD 62 is a new socket
2007/11/30 12:16:02| fd_open FD 62 http://www.mail.ru/
2007/11/30 12:16:02| comm_add_close_handler: FD 62, handler=0x42e5d0, data=0xeeb698
2007/11/30 12:16:02| cbdataLock: 0xeeb698
2007/11/30 12:16:02| commSetTimeout: FD 62 timeout 60
2007/11/30 12:16:02| commConnectStart: FD 62, www.mail.ru:80
2007/11/30 12:16:02| cbdataLock: 0xeeb698
2007/11/30 12:16:02| comm_add_close_handler: FD 62, handler=0x423640, data=0xeeb898
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| ipcache_nbgethostbyname: Name 'www.mail.ru'.
2007/11/30 12:16:02| ipcache_nbgethostbyname: MISS for 'www.mail.ru'
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| idnsALookup: buf is 29 bytes for www.mail.ru, id = 0x2371
2007/11/30 12:16:02| cbdataLock: 0xe9ce58
2007/11/30 12:16:02| commSetSelect: FD 5 type 1
2007/11/30 12:16:02| cbdataUnlock: 0xeeb698
2007/11/30 12:16:02| storeUnlockObject: key '718F731A218D9231B0EDE38DFA249E2A' count=3
2007/11/30 12:16:02| cbdataFree: 0xee5618
2007/11/30 12:16:02| cbdataFree: Freeing 0xee5618
2007/11/30 12:16:02| cbdataUnlock: 0xee6018
2007/11/30 12:16:02| cbdataUnlock: 0xea3618
2007/11/30 12:16:02| cbdataFree: 0xea3a18
2007/11/30 12:16:02| cbdataFree: Freeing 0xea3a18
2007/11/30 12:16:02| cbdataFree: 0xeeb618
2007/11/30 12:16:02| cbdataFree: 0xeeb618 has 1 locks, not freeing
2007/11/30 12:16:02| cbdataUnlock: 0xeeb618
2007/11/30 12:16:02| cbdataUnlock: Freeing 0xeeb618
2007/11/30 12:16:02| comm_select: timeout 871
2007/11/30 12:16:02| idnsRead: FD 5: received 77 bytes from 192.168.10.8.
2007/11/30 12:16:02| idnsGrokReply: ID 0x2371, 3 answers
2007/11/30 12:16:02| cbdataValid: 0xe9ce58
2007/11/30 12:16:02| cbdataUnlock: 0xe9ce58
2007/11/30 12:16:02| cbdataFree: 0xe9ce58
2007/11/30 12:16:02| cbdataFree: Freeing 0xe9ce58
2007/11/30 12:16:02| ipcacheParse: #0 194.67.57.126
2007/11/30 12:16:02| ipcacheParse: #1 194.67.57.226
2007/11/30 12:16:02| ipcacheParse: #2 194.67.57.26
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.226
2007/11/30 12:16:02| ipcacheMarkBadAddr: www.mail.ru [194.67.57.126]
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.26
2007/11/30 12:16:02| cbdataValid: 0xeeb698
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| cbdataFree: 0xeef018
2007/11/30 12:16:02| cbdataFree: Freeing 0xeef018
2007/11/30 12:16:02| eventRun: RUN ID 57
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| eventRun: Running 'commReconnect', id 56
2007/11/30 12:16:02| ipcache_nbgethostbyname: Name 'www.mail.ru'.
2007/11/30 12:16:02| ipcache_nbgethostbyname: HIT for 'www.mail.ru'
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.226
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.26
2007/11/30 12:16:02| cbdataValid: 0xeeb698
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| comm_select: timeout 0
2007/11/30 12:16:02| eventRun: RUN ID 58
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| eventRun: Running 'commReconnect', id 57
2007/11/30 12:16:02| ipcache_nbgethostbyname: Name 'www.mail.ru'.
2007/11/30 12:16:02| ipcache_nbgethostbyname: HIT for 'www.mail.ru'
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.226
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.26
2007/11/30 12:16:02| cbdataValid: 0xeeb698
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| comm_select: timeout 0
2007/11/30 12:16:02| eventRun: RUN ID 59
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| eventRun: Running 'commReconnect', id 58
2007/11/30 12:16:02| ipcache_nbgethostbyname: Name 'www.mail.ru'.
2007/11/30 12:16:02| ipcache_nbgethostbyname: HIT for 'www.mail.ru'
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.226
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.26
2007/11/30 12:16:02| comm_remove_close_handler: FD 62, handler=0x423640, data=0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| commSetTimeout: FD 62 timeout -1
2007/11/30 12:16:02| commConnectFree: FD 62
2007/11/30 12:16:02| cbdataFree: 0xeeb898
2007/11/30 12:16:02| cbdataFree: 0xeeb898 has 1 locks, not freeing
2007/11/30 12:16:02| cbdataValid: 0xeeb698
2007/11/30 12:16:02| fwdFail: ERR_CONNECT_FAIL "Service Unavailable"
2007/11/30 12:16:02| comm_close: FD 62
2007/11/30 12:16:02| commCallCloseHandlers: FD 62
2007/11/30 12:16:02| commCallCloseHandlers: ch->handler=0x42e5d0
2007/11/30 12:16:02| cbdataValid: 0xeeb698
2007/11/30 12:16:02| fwdServerClosed: FD 62 http://www.mail.ru/
2007/11/30 12:16:02| fwdServerClosed: re-forwarding (1 tries, 0 secs)
2007/11/30 12:16:02| cbdataLock: 0xeeb698
2007/11/30 12:16:02| cbdataUnlock: 0xeeb698
2007/11/30 12:16:02| fd_close FD 62 http://www.mail.ru/
2007/11/30 12:16:02| cbdataUnlock: 0xeeb698
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: Freeing 0xeeb898
2007/11/30 12:16:02| comm_select: timeout 50
2007/11/30 12:16:02| eventRun: RUN ID 60
2007/11/30 12:16:02| cbdataValid: 0xeeb698
2007/11/30 12:16:02| cbdataUnlock: 0xeeb698
2007/11/30 12:16:02| eventRun: Running 'fwdConnectStart', id 59
2007/11/30 12:16:02| fwdConnectStart: http://www.mail.ru/
2007/11/30 12:16:02| aclMatchAclList: returning 1
2007/11/30 12:16:02| fwdConnectStart: got addr 84.52.64.20, tos 0
2007/11/30 12:16:02| comm_open: FD 62 is a new socket
2007/11/30 12:16:02| fd_open FD 62 http://www.mail.ru/
2007/11/30 12:16:02| comm_add_close_handler: FD 62, handler=0x42e5d0, data=0xeeb698
2007/11/30 12:16:02| cbdataLock: 0xeeb698
2007/11/30 12:16:02| commSetTimeout: FD 62 timeout 60
2007/11/30 12:16:02| commConnectStart: FD 62, www.mail.ru:80
2007/11/30 12:16:02| cbdataLock: 0xeeb698
2007/11/30 12:16:02| comm_add_close_handler: FD 62, handler=0x423640, data=0xeeb898
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| ipcache_nbgethostbyname: Name 'www.mail.ru'.
2007/11/30 12:16:02| ipcache_nbgethostbyname: HIT for 'www.mail.ru'
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.226
2007/11/30 12:16:02| ipcacheMarkBadAddr: www.mail.ru [194.67.57.26]
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.226
2007/11/30 12:16:02| cbdataValid: 0xeeb698
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| comm_select: timeout 0
2007/11/30 12:16:02| eventRun: RUN ID 61
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| eventRun: Running 'commReconnect', id 60
2007/11/30 12:16:02| ipcache_nbgethostbyname: Name 'www.mail.ru'.
2007/11/30 12:16:02| ipcache_nbgethostbyname: HIT for 'www.mail.ru'
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.226
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.226
2007/11/30 12:16:02| cbdataValid: 0xeeb698
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| comm_select: timeout 0
2007/11/30 12:16:02| eventRun: RUN ID 62
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| eventRun: Running 'commReconnect', id 61
2007/11/30 12:16:02| ipcache_nbgethostbyname: Name 'www.mail.ru'.
2007/11/30 12:16:02| ipcache_nbgethostbyname: HIT for 'www.mail.ru'
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.226
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.226
2007/11/30 12:16:02| cbdataValid: 0xeeb698
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| comm_select: timeout 0
2007/11/30 12:16:02| eventRun: RUN ID 63
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| eventRun: Running 'commReconnect', id 62
2007/11/30 12:16:02| ipcache_nbgethostbyname: Name 'www.mail.ru'.
2007/11/30 12:16:02| ipcache_nbgethostbyname: HIT for 'www.mail.ru'
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.226
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.226
2007/11/30 12:16:02| comm_remove_close_handler: FD 62, handler=0x423640, data=0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| commSetTimeout: FD 62 timeout -1
2007/11/30 12:16:02| commConnectFree: FD 62
2007/11/30 12:16:02| cbdataFree: 0xeeb898
2007/11/30 12:16:02| cbdataFree: 0xeeb898 has 1 locks, not freeing
2007/11/30 12:16:02| cbdataValid: 0xeeb698
2007/11/30 12:16:02| fwdFail: ERR_CONNECT_FAIL "Service Unavailable"
2007/11/30 12:16:02| cbdataFree: 0xee5018
2007/11/30 12:16:02| cbdataFree: Freeing 0xee5018
2007/11/30 12:16:02| comm_close: FD 62
2007/11/30 12:16:02| commCallCloseHandlers: FD 62
2007/11/30 12:16:02| commCallCloseHandlers: ch->handler=0x42e5d0
2007/11/30 12:16:02| cbdataValid: 0xeeb698
2007/11/30 12:16:02| fwdServerClosed: FD 62 http://www.mail.ru/
2007/11/30 12:16:02| fwdServerClosed: re-forwarding (2 tries, 0 secs)
2007/11/30 12:16:02| cbdataLock: 0xeeb698
2007/11/30 12:16:02| cbdataUnlock: 0xeeb698
2007/11/30 12:16:02| fd_close FD 62 http://www.mail.ru/
2007/11/30 12:16:02| cbdataUnlock: 0xeeb698
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: Freeing 0xeeb898
2007/11/30 12:16:02| comm_select: timeout 50
2007/11/30 12:16:02| eventRun: RUN ID 64
2007/11/30 12:16:02| cbdataValid: 0xeeb698
2007/11/30 12:16:02| cbdataUnlock: 0xeeb698
2007/11/30 12:16:02| eventRun: Running 'fwdConnectStart', id 63
2007/11/30 12:16:02| fwdConnectStart: http://www.mail.ru/
2007/11/30 12:16:02| aclMatchAclList: returning 1
2007/11/30 12:16:02| fwdConnectStart: got addr 84.52.64.20, tos 0
2007/11/30 12:16:02| comm_open: FD 62 is a new socket
2007/11/30 12:16:02| fd_open FD 62 http://www.mail.ru/
2007/11/30 12:16:02| comm_add_close_handler: FD 62, handler=0x42e5d0, data=0xeeb698
2007/11/30 12:16:02| cbdataLock: 0xeeb698
2007/11/30 12:16:02| commSetTimeout: FD 62 timeout 60
2007/11/30 12:16:02| commConnectStart: FD 62, www.mail.ru:80
2007/11/30 12:16:02| cbdataLock: 0xeeb698
2007/11/30 12:16:02| comm_add_close_handler: FD 62, handler=0x423640, data=0xeeb898
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| ipcache_nbgethostbyname: Name 'www.mail.ru'.
2007/11/30 12:16:02| ipcache_nbgethostbyname: HIT for 'www.mail.ru'
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.226
2007/11/30 12:16:02| ipcacheMarkBadAddr: www.mail.ru [194.67.57.226]
2007/11/30 12:16:02| ipcacheCycleAddr: Changing ALL www.mail.ru addrs from BAD to OK
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.126
2007/11/30 12:16:02| cbdataValid: 0xeeb698
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| comm_select: timeout 0
2007/11/30 12:16:02| eventRun: RUN ID 65
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| eventRun: Running 'commReconnect', id 64
2007/11/30 12:16:02| ipcache_nbgethostbyname: Name 'www.mail.ru'.
2007/11/30 12:16:02| ipcache_nbgethostbyname: HIT for 'www.mail.ru'
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.226
2007/11/30 12:16:02| ipcacheMarkBadAddr: www.mail.ru [194.67.57.226]
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.26
2007/11/30 12:16:02| cbdataValid: 0xeeb698
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| comm_select: timeout 0
2007/11/30 12:16:02| eventRun: RUN ID 66
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| eventRun: Running 'commReconnect', id 65
2007/11/30 12:16:02| ipcache_nbgethostbyname: Name 'www.mail.ru'.
2007/11/30 12:16:02| ipcache_nbgethostbyname: HIT for 'www.mail.ru'
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.126
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.26
2007/11/30 12:16:02| cbdataValid: 0xeeb698
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| comm_select: timeout 0
2007/11/30 12:16:02| eventRun: RUN ID 67
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| eventRun: Running 'commReconnect', id 66
2007/11/30 12:16:02| ipcache_nbgethostbyname: Name 'www.mail.ru'.
2007/11/30 12:16:02| ipcache_nbgethostbyname: HIT for 'www.mail.ru'
2007/11/30 12:16:02| cbdataLock: 0xeeb898
2007/11/30 12:16:02| cbdataValid: 0xeeb898
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.126
2007/11/30 12:16:02| ipcacheCycleAddr: www.mail.ru now at 194.67.57.26
2007/11/30 12:16:02| comm_remove_close_handler: FD 62, handler=0x423640, data=0xeeb898
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| commSetTimeout: FD 62 timeout -1
2007/11/30 12:16:02| commConnectFree: FD 62
2007/11/30 12:16:02| cbdataFree: 0xeeb898
2007/11/30 12:16:02| cbdataFree: 0xeeb898 has 1 locks, not freeing
2007/11/30 12:16:02| cbdataValid: 0xeeb698
2007/11/30 12:16:02| fwdFail: ERR_CONNECT_FAIL "Service Unavailable"
2007/11/30 12:16:02| cbdataFree: 0xee5818
2007/11/30 12:16:02| cbdataFree: Freeing 0xee5818
2007/11/30 12:16:02| comm_close: FD 62
2007/11/30 12:16:02| commCallCloseHandlers: FD 62
2007/11/30 12:16:02| commCallCloseHandlers: ch->handler=0x42e5d0
2007/11/30 12:16:02| cbdataValid: 0xeeb698
2007/11/30 12:16:02| fwdServerClosed: FD 62 http://www.mail.ru/
2007/11/30 12:16:02| fwdStateFree: 0xeeb698
2007/11/30 12:16:02| storeLockObject: key '718F731A218D9231B0EDE38DFA249E2A' count=4
2007/11/30 12:16:02| errorConvert: %U --> 'http://www.mail.ru/'
2007/11/30 12:16:02| errorConvert: %U --> 'http://www.mail.ru/'
2007/11/30 12:16:02| errorConvert: %E --> '(13) Permission denied'
2007/11/30 12:16:02| errorConvert: %T --> 'Fri, 30 Nov 2007 09:16:02 GMT'
2007/11/30 12:16:02| errorConvert: %h --> 'artpaint.spb.ru'
2007/11/30 12:16:02| errorConvert: %s --> 'squid/2.6.STABLE16+ICAP'
2007/11/30 12:16:02| errorConvert: %S --> '
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 34 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 6 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 23 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 4 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 29 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 12 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 9 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 14 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 3 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 7 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 29 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 13 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 19 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 2 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeAppend: appending 978 bytes for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeReleaseRequest: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| InvokeHandlers: 718F731A218D9231B0EDE38DFA249E2A
2007/11/30 12:16:02| InvokeHandlers: checking client #0
2007/11/30 12:16:02| cbdataLock: 0xed6e98
2007/11/30 12:16:02| storeClientCopy2: 718F731A218D9231B0EDE38DFA249E2A
2007/11/30 12:16:02| storeClientCopy3: Copying from memory
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| clientSendMoreHeaderData: http://www.mail.ru/, 1206 bytes
2007/11/30 12:16:02| clientSendMoreHeaderData: FD 59 'http://www.mail.ru/', out.offset=0
2007/11/30 12:16:02| clientBuildReplyHeader: Error, don't keep-alive
2007/11/30 12:16:02| cbdataLock: 0x76f158
2007/11/30 12:16:02| cbdataLock: 0xea3618
2007/11/30 12:16:02| aclCheckFast: list: 0x76f158
2007/11/30 12:16:02| aclMatchAclList: checking all
2007/11/30 12:16:02| aclMatchAcl: checking 'acl all src 0.0.0.0/0.0.0.0'
2007/11/30 12:16:02| aclMatchIp: '192.168.10.2' found
2007/11/30 12:16:02| aclMatchAclList: returning 1
2007/11/30 12:16:02| httpReplyBodyBuildSize: Setting maxBodySize to 0
2007/11/30 12:16:02| cbdataUnlock: 0xea3618
2007/11/30 12:16:02| cbdataUnlock: 0x76f158
2007/11/30 12:16:02| cbdataFree: 0xea3a18
2007/11/30 12:16:02| cbdataFree: Freeing 0xea3a18
2007/11/30 12:16:02| clientSendMoreHeaderData: Appending 978 bytes after 228 bytes of headers
2007/11/30 12:16:02| cbdataLock: 0xee6018
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| cbdataLock: 0x76be98
2007/11/30 12:16:02| cbdataLock: 0xea3618
2007/11/30 12:16:02| cbdataLock: 0xeec098
2007/11/30 12:16:02| cbdataValid: 0x76be98
2007/11/30 12:16:02| aclCheck: checking 'http_reply_access allow all'
2007/11/30 12:16:02| aclMatchAclList: checking all
2007/11/30 12:16:02| aclMatchAcl: checking 'acl all src 0.0.0.0/0.0.0.0'
2007/11/30 12:16:02| aclMatchIp: '192.168.10.2' found
2007/11/30 12:16:02| aclMatchAclList: returning 1
2007/11/30 12:16:02| aclCheck: match found, returning 1
2007/11/30 12:16:02| cbdataUnlock: 0x76be98
2007/11/30 12:16:02| aclCheckCallback: answer=1
2007/11/30 12:16:02| cbdataValid: 0xeec098
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| The reply for GET http://www.mail.ru/ is ALLOWED, because it matched 'all'
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| cbdataFree: 0xeec098
2007/11/30 12:16:02| cbdataFree: 0xeec098 has 1 locks, not freeing
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| comm_write: FD 59: sz 1324: hndl 0x41e340: data 0xee6018.
2007/11/30 12:16:02| cbdataLock: 0xee6018
2007/11/30 12:16:02| commSetSelect: FD 59 type 2
2007/11/30 12:16:02| cbdataUnlock: 0xee6018
2007/11/30 12:16:02| cbdataUnlock: 0xeec098
2007/11/30 12:16:02| cbdataUnlock: Freeing 0xeec098
2007/11/30 12:16:02| cbdataUnlock: 0xea3618
2007/11/30 12:16:02| cbdataFree: 0xea3a18
2007/11/30 12:16:02| cbdataFree: Freeing 0xea3a18
2007/11/30 12:16:02| cbdataUnlock: 0xee6018
2007/11/30 12:16:02| cbdataUnlock: 0xed6e98
2007/11/30 12:16:02| storeComplete: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeEntryValidLength: Checking '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storeEntryValidLength: object_len = 1206
2007/11/30 12:16:02| storeEntryValidLength: hdr_sz = 228
2007/11/30 12:16:02| storeEntryValidLength: content_length = 978
2007/11/30 12:16:02| InvokeHandlers: 718F731A218D9231B0EDE38DFA249E2A
2007/11/30 12:16:02| InvokeHandlers: checking client #0
2007/11/30 12:16:02| storeUnlockObject: key '718F731A218D9231B0EDE38DFA249E2A' count=3
2007/11/30 12:16:02| cbdataFree: 0xee5018
2007/11/30 12:16:02| cbdataFree: Freeing 0xee5018
2007/11/30 12:16:02| storePendingNClients: returning 1
2007/11/30 12:16:02| storeUnlockObject: key '718F731A218D9231B0EDE38DFA249E2A' count=2
2007/11/30 12:16:02| cbdataFree: 0xeeb698
2007/11/30 12:16:02| cbdataFree: 0xeeb698 has 2 locks, not freeing
2007/11/30 12:16:02| cbdataUnlock: 0xeeb698
2007/11/30 12:16:02| fd_close FD 62 http://www.mail.ru/
2007/11/30 12:16:02| cbdataUnlock: 0xeeb698
2007/11/30 12:16:02| cbdataUnlock: Freeing 0xeeb698
2007/11/30 12:16:02| cbdataUnlock: 0xeeb898
2007/11/30 12:16:02| cbdataUnlock: Freeing 0xeeb898
2007/11/30 12:16:02| comm_select: timeout 768
2007/11/30 12:16:02| commHandleWrite: FD 59: off 0, sz 1324.
2007/11/30 12:16:02| commHandleWrite: write() returns 1324
2007/11/30 12:16:02| cbdataValid: 0xee6018
2007/11/30 12:16:02| clientWriteComplete: FD 59, sz 1324, err 0, off 1206, len 1206
2007/11/30 12:16:02| clientWriteComplete: FD 59 transfer is DONE
2007/11/30 12:16:02| comm_close: FD 59
2007/11/30 12:16:02| commCallCloseHandlers: FD 59
2007/11/30 12:16:02| commCallCloseHandlers: ch->handler=0x41b8f0
2007/11/30 12:16:02| cbdataValid: 0xea3618
2007/11/30 12:16:02| connStateFree: FD 59
2007/11/30 12:16:02| httpRequestFree: http://www.mail.ru/
2007/11/30 12:16:02| cbdataLock: 0x76bcd8
2007/11/30 12:16:02| cbdataLock: 0xea3618
2007/11/30 12:16:02| cbdataUnlock: 0xea3618
2007/11/30 12:16:02| cbdataUnlock: 0x76bcd8
2007/11/30 12:16:02| cbdataFree: 0xea3a18
2007/11/30 12:16:02| cbdataFree: Freeing 0xea3a18
2007/11/30 12:16:02| storeClientUnregister: called for '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| storePendingNClients: returning 0
2007/11/30 12:16:02| storeUnlockObject: key '718F731A218D9231B0EDE38DFA249E2A' count=1
2007/11/30 12:16:02| cbdataFree: 0xed6e98
2007/11/30 12:16:02| cbdataFree: Freeing 0xed6e98
2007/11/30 12:16:02| storeUnlockObject: key '718F731A218D9231B0EDE38DFA249E2A' count=0
2007/11/30 12:16:02| storePendingNClients: returning 0
2007/11/30 12:16:02| storeRelease: Releasing: '718F731A218D9231B0EDE38DFA249E2A'
2007/11/30 12:16:02| destroy_StoreEntry: destroying 0xed6e00
2007/11/30 12:16:02| ctx: enter level 0: 'http://www.mail.ru/'
2007/11/30 12:16:02| destroy_MemObject: destroying 0xed7e00
2007/11/30 12:16:02| ctx: exit level 0
2007/11/30 12:16:02| cbdataUnlock: 0xea3618
2007/11/30 12:16:02| cbdataFree: 0xee6018
2007/11/30 12:16:02| cbdataFree: 0xee6018 has 1 locks, not freeing
2007/11/30 12:16:02| authenticateAuthUserRequestFree: freeing request 0xe9cc60
2007/11/30 12:16:02| cbdataFree: 0xea3618
2007/11/30 12:16:02| cbdataFree: 0xea3618 has 1 locks, not freeing
2007/11/30 12:16:02| cbdataUnlock: 0xea3618
2007/11/30 12:16:02| cbdataUnlock: Freeing 0xea3618
2007/11/30 12:16:02| fd_close FD 59 http://www.mail.ru/
2007/11/30 12:16:02| cbdataUnlock: 0xee6018
2007/11/30 12:16:02| cbdataUnlock: Freeing 0xee6018
2007/11/30 12:16:02| comm_select: timeout 767
2007/11/30 12:16:02| comm_select: kqueue event error: (9) Bad file descriptor
2007/11/30 12:16:02| comm_select: kqueue event error: (9) Bad file descriptor
2007/11/30 12:16:02| comm_select: timeout 767
2007/11/30 12:16:02| comm_select: timeout 41