Attachment 654 Details for Bug 5536 – Кусок лога вокруг этой ошибки из cache.log

Кусок лога вокруг этой ошибки из cache.log

err.cache.cut1 (text/plain), 39.79 KB, created by Sergei Epiphanov on 2004-11-19 11:37:06 MSK

(hide)

Description:

Filename:

MIME Type:

Creator: Sergei Epiphanov

Created: 2004-11-19 11:37:06 MSK

Size: 39.79 KB

patch

obsolete

>2004/11/18 14:33:53| comm_poll: 0+0 FDs ready
>2004/11/18 14:33:53| comm_poll: time out: 1100777633.
>2004/11/18 14:33:53| eventRun: RUN ID 748
>2004/11/18 14:33:53| cbdataValid: 0x8406578
>2004/11/18 14:33:53| cbdataUnlock: 0x8406578
>2004/11/18 14:33:53| eventRun: Running 'storeDigestSwapOutStep', id 747
>2004/11/18 14:33:53| storeAppend: appending 4096 bytes for '5E958EB13D74035ECB70170186812E4B'
>2004/11/18 14:33:53| memAppend: len 4096
>2004/11/18 14:33:53| InvokeHandlers: 5E958EB13D74035ECB70170186812E4B
>2004/11/18 14:33:53| storeSwapOut: http://server.bkpi82.nikiet.ru:3130/squid-internal-periodic/store_digest
>2004/11/18 14:33:53| storeSwapOut: store_status = STORE_PENDING
>2004/11/18 14:33:53| storeSwapOut: http://server.bkpi82.nikiet.ru:3130/squid-internal-periodic/store_digest SPECIAL
>2004/11/18 14:33:53| storeDigestSwapOutStep: size: 21458 offset: 16384 chunk: 4096 bytes
>2004/11/18 14:33:53| cbdataLock: 0x8406578
>2004/11/18 14:33:53| eventAdd: Adding 'storeDigestSwapOutStep', in 0.000000 seconds
>2004/11/18 14:33:53| comm_poll: 0+0 FDs ready
>2004/11/18 14:33:53| comm_poll: time out: 1100777633.
>2004/11/18 14:33:53| eventRun: RUN ID 749
>2004/11/18 14:33:53| cbdataValid: 0x8406578
>2004/11/18 14:33:53| cbdataUnlock: 0x8406578
>2004/11/18 14:33:53| eventRun: Running 'storeDigestSwapOutStep', id 748
>2004/11/18 14:33:53| storeAppend: appending 978 bytes for '5E958EB13D74035ECB70170186812E4B'
>2004/11/18 14:33:53| memAppend: len 978
>2004/11/18 14:33:53| InvokeHandlers: 5E958EB13D74035ECB70170186812E4B
>2004/11/18 14:33:53| storeSwapOut: http://server.bkpi82.nikiet.ru:3130/squid-internal-periodic/store_digest
>2004/11/18 14:33:53| storeSwapOut: store_status = STORE_PENDING
>2004/11/18 14:33:53| storeSwapOut: http://server.bkpi82.nikiet.ru:3130/squid-internal-periodic/store_digest SPECIAL
>2004/11/18 14:33:53| storeDigestSwapOutStep: size: 21458 offset: 20480 chunk: 978 bytes
>2004/11/18 14:33:53| storeComplete: '5E958EB13D74035ECB70170186812E4B'
>2004/11/18 14:33:53| storeEntryValidLength: Checking '5E958EB13D74035ECB70170186812E4B'
>2004/11/18 14:33:53| storeEntryValidLength:     object_len = 21850
>2004/11/18 14:33:53| storeEntryValidLength:         hdr_sz = 0
>2004/11/18 14:33:53| storeEntryValidLength: content_length = 21586
>2004/11/18 14:33:53| storeEntryValidLength: Zero header size: 5E958EB13D74035ECB70170186812E4B
>2004/11/18 14:33:53| storeSwapOut: http://server.bkpi82.nikiet.ru:3130/squid-internal-periodic/store_digest
>2004/11/18 14:33:53| storeSwapOut: store_status = STORE_OK
>2004/11/18 14:33:53| storeSwapOut: http://server.bkpi82.nikiet.ru:3130/squid-internal-periodic/store_digest SPECIAL
>2004/11/18 14:33:53| InvokeHandlers: 5E958EB13D74035ECB70170186812E4B
>2004/11/18 14:33:53| storeDigestRewriteFinish: digest expires at 1100781233 (+3600)
>2004/11/18 14:33:53| cleaning hdr: 0x83f1ff0 owner: 1
>2004/11/18 14:33:53| storeUnlockObject: key '5E958EB13D74035ECB70170186812E4B' count=0
>2004/11/18 14:33:53| storePendingNClients: returning 0
>2004/11/18 14:33:53| storeSetMemStatus: not inserting special http://server.bkpi82.nikiet.ru:3130/squid-internal-periodic/store_digest into policy
>2004/11/18 14:33:53| cbdataFree: 0x8406578
>2004/11/18 14:33:53| cbdataFree: Freeing 0x8406578
>2004/11/18 14:33:53| eventAdd: Adding 'storeDigestRewriteStart', in 3600.000000 seconds
>2004/11/18 14:33:53| comm_poll: 0+0 FDs ready
>2004/11/18 14:33:54| comm_poll: 0+0 FDs ready
>2004/11/18 14:33:54| comm_poll: time out: 1100777634.
>2004/11/18 14:33:54| eventRun: RUN ID 750
>2004/11/18 14:33:54| eventRun: Running 'MaintainSwapSpace', id 744
>2004/11/18 14:33:54| storeMaintainSwapSpace: f=0.000000, max_scan=100, max_remove=10
>2004/11/18 14:33:54| cbdataFree: 0x8406648
>2004/11/18 14:33:54| cbdataFree: Freeing 0x8406648
>2004/11/18 14:33:54| storeUfsDirMaintain: /var/spool/squid removed 0/10 f=0.000 max_scan=100
>2004/11/18 14:33:54| eventAdd: Adding 'MaintainSwapSpace', in 1.000000 seconds
>2004/11/18 14:33:55| comm_poll: 0+0 FDs ready
>2004/11/18 14:33:55| comm_poll: time out: 1100777635.
>2004/11/18 14:33:55| eventRun: RUN ID 751
>2004/11/18 14:33:55| eventRun: Running 'fqdncache_purgelru', id 0
>2004/11/18 14:33:55| eventAdd: Adding 'fqdncache_purgelru', in 10.000000 seconds
>2004/11/18 14:33:55| fqdncache_purgelru: removed 0 entries
>2004/11/18 14:33:56| comm_poll: 0+0 FDs ready
>2004/11/18 14:33:56| comm_poll: time out: 1100777636.
>2004/11/18 14:33:56| eventRun: RUN ID 752
>2004/11/18 14:33:56| eventRun: Running 'MaintainSwapSpace', id 750
>2004/11/18 14:33:56| storeMaintainSwapSpace: f=0.000000, max_scan=100, max_remove=10
>2004/11/18 14:33:56| cbdataFree: 0x8406648
>2004/11/18 14:33:56| cbdataFree: Freeing 0x8406648
>2004/11/18 14:33:56| storeUfsDirMaintain: /var/spool/squid removed 0/10 f=0.000 max_scan=100
>2004/11/18 14:33:56| eventAdd: Adding 'MaintainSwapSpace', in 1.000000 seconds
>2004/11/18 14:33:56| comm_poll: 0+0 FDs ready
>2004/11/18 14:33:57| comm_poll: 0+0 FDs ready
>2004/11/18 14:33:57| comm_poll: time out: 1100777637.
>2004/11/18 14:33:57| eventRun: RUN ID 753
>2004/11/18 14:33:57| eventRun: Running 'MaintainSwapSpace', id 752
>2004/11/18 14:33:57| storeMaintainSwapSpace: f=0.000000, max_scan=100, max_remove=10
>2004/11/18 14:33:57| cbdataFree: 0x8406648
>2004/11/18 14:33:57| cbdataFree: Freeing 0x8406648
>2004/11/18 14:33:57| storeUfsDirMaintain: /var/spool/squid removed 0/10 f=0.000 max_scan=100
>2004/11/18 14:33:57| eventAdd: Adding 'MaintainSwapSpace', in 1.000000 seconds
>2004/11/18 14:33:58| comm_poll: 0+0 FDs ready
>2004/11/18 14:33:58| comm_poll: time out: 1100777638.
>2004/11/18 14:33:58| eventRun: RUN ID 754
>2004/11/18 14:33:58| eventRun: Running 'MaintainSwapSpace', id 753
>2004/11/18 14:33:58| storeMaintainSwapSpace: f=0.000000, max_scan=100, max_remove=10
>2004/11/18 14:33:58| cbdataFree: 0x8406648
>2004/11/18 14:33:58| cbdataFree: Freeing 0x8406648
>2004/11/18 14:33:58| storeUfsDirMaintain: /var/spool/squid removed 0/10 f=0.000 max_scan=100
>2004/11/18 14:33:58| eventAdd: Adding 'MaintainSwapSpace', in 1.000000 seconds
>2004/11/18 14:33:59| comm_poll: 0+0 FDs ready
>2004/11/18 14:33:59| comm_poll: time out: 1100777639.
>2004/11/18 14:33:59| eventRun: RUN ID 755
>2004/11/18 14:33:59| eventRun: Running 'MaintainSwapSpace', id 754
>2004/11/18 14:33:59| storeMaintainSwapSpace: f=0.000000, max_scan=100, max_remove=10
>2004/11/18 14:33:59| cbdataFree: 0x8406648
>2004/11/18 14:33:59| cbdataFree: Freeing 0x8406648
>2004/11/18 14:33:59| storeUfsDirMaintain: /var/spool/squid removed 0/10 f=0.000 max_scan=100
>2004/11/18 14:33:59| eventAdd: Adding 'MaintainSwapSpace', in 1.000000 seconds
>2004/11/18 14:34:00| comm_poll: 1+0 FDs ready
>2004/11/18 14:34:01| commSetSelect: FD 20 type 1
>2004/11/18 14:34:01| fd_open FD 19 HTTP Request
>2004/11/18 14:34:01| httpAccept: FD 19: accepted port 3130 client 193.125.101.1:32796
>2004/11/18 14:34:01| comm_add_close_handler: FD 19, handler=0x80630f0, data=0x83f1eb0
>2004/11/18 14:34:01| cbdataLock: 0x83f1eb0
>2004/11/18 14:34:01| commSetTimeout: FD 19 timeout 60
>2004/11/18 14:34:01| aclCheckFast: list: 0x8251240
>2004/11/18 14:34:01| aclMatchAclList: checking all
>2004/11/18 14:34:01| aclMatchAcl: checking 'acl all src 0.0.0.0/0.0.0.0'
>2004/11/18 14:34:01| aclMatchIp: '193.125.101.1' found
>2004/11/18 14:34:01| aclMatchAclList: returning 1
>2004/11/18 14:34:01| commSetSelect: FD 19 type 1
>2004/11/18 14:34:01| comm_accept: FD 20: (11) Resource temporarily unavailable
>2004/11/18 14:34:01| eventRun: RUN ID 756
>2004/11/18 14:34:01| eventRun: Running 'MaintainSwapSpace', id 755
>2004/11/18 14:34:01| storeMaintainSwapSpace: f=0.000000, max_scan=100, max_remove=10
>2004/11/18 14:34:01| cbdataFree: 0x83f2060
>2004/11/18 14:34:01| cbdataFree: Freeing 0x83f2060
>2004/11/18 14:34:01| storeUfsDirMaintain: /var/spool/squid removed 0/10 f=0.000 max_scan=100
>2004/11/18 14:34:01| eventAdd: Adding 'MaintainSwapSpace', in 1.000000 seconds
>2004/11/18 14:34:01| comm_poll: 1+0 FDs ready
>2004/11/18 14:34:01| comm_poll: FD 19 ready for reading
>2004/11/18 14:34:01| clientReadRequest: FD 19: reading request...
>2004/11/18 14:34:01| commSetSelect: FD 19 type 1
>2004/11/18 14:34:01| parseHttpRequest: Method is 'GET'
>2004/11/18 14:34:01| parseHttpRequest: URI is 'http://www.lenta.ru/'
>2004/11/18 14:34:01| parseHttpRequest: Client HTTP version 1.0.
>2004/11/18 14:34:01| parseHttpRequest: req_hdr = {Accept: image/gif, image/x-xbitmap, image/jpeg, image/pjpeg, application/x-shockwave-flash, application/msword, application/vnd.ms-excel, */*
>Accept-Language: ru
>If-Modified-Since: Wed, 17 Nov 2004 13:45:41 GMT; length=85783
>User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1)
>Host: www.lenta.ru
>Connection: close
>
>}
>2004/11/18 14:34:01| parseHttpRequest: end = {}
>2004/11/18 14:34:01| parseHttpRequest: prefix_sz = 368, req_line_sz = 35
>2004/11/18 14:34:01| parseHttpRequest: Request Header is
>Accept: image/gif, image/x-xbitmap, image/jpeg, image/pjpeg, application/x-shockwave-flash, application/msword, application/vnd.ms-excel, */*
>Accept-Language: ru
>If-Modified-Since: Wed, 17 Nov 2004 13:45:41 GMT; length=85783
>User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1)
>Host: www.lenta.ru
>Connection: close
>
>
>2004/11/18 14:34:01| parseHttpRequest: Complete request received
>2004/11/18 14:34:01| conn->in.offset = 0
>2004/11/18 14:34:01| commSetTimeout: FD 19 timeout 3600
>2004/11/18 14:34:01| init-ing hdr: 0x83f9d70 owner: 1
>2004/11/18 14:34:01| parsing hdr: (0x83f9d70)
>Accept: image/gif, image/x-xbitmap, image/jpeg, image/pjpeg, application/x-shockwave-flash, application/msword, application/vnd.ms-excel, */*
>Accept-Language: ru
>If-Modified-Since: Wed, 17 Nov 2004 13:45:41 GMT; length=85783
>User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1)
>Host: www.lenta.ru
>Connection: close
>
>2004/11/18 14:34:01| creating entry 0x83f2050: near 'Accept: image/gif, image/x-xbitmap, image/jpeg, image/pjpeg, application/x-shockwave-flash, application/msword, application/vnd.ms-excel, */*'
>2004/11/18 14:34:01| created entry 0x83f2050: 'Accept: image/gif, image/x-xbitmap, image/jpeg, image/pjpeg, application/x-shockwave-flash, application/msword, application/vnd.ms-excel, */*'
>2004/11/18 14:34:01| 0x83f9d70 adding entry: 0 at 0
>2004/11/18 14:34:01| creating entry 0x83f2068: near 'Accept-Language: ru'
>2004/11/18 14:34:01| created entry 0x83f2068: 'Accept-Language: ru'
>2004/11/18 14:34:01| 0x83f9d70 adding entry: 3 at 1
>2004/11/18 14:34:01| creating entry 0x8406590: near 'If-Modified-Since: Wed, 17 Nov 2004 13:45:41 GMT; length=85783'
>2004/11/18 14:34:01| created entry 0x8406590: 'If-Modified-Since: Wed, 17 Nov 2004 13:45:41 GMT; length=85783'
>2004/11/18 14:34:01| 0x83f9d70 adding entry: 26 at 2
>2004/11/18 14:34:01| creating entry 0x83f2140: near 'User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1)'
>2004/11/18 14:34:01| created entry 0x83f2140: 'User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1)'
>2004/11/18 14:34:01| 0x83f9d70 adding entry: 49 at 3
>2004/11/18 14:34:01| creating entry 0x83f9750: near 'Host: www.lenta.ru'
>2004/11/18 14:34:01| created entry 0x83f9750: 'Host: www.lenta.ru'
>2004/11/18 14:34:01| 0x83f9d70 adding entry: 24 at 4
>2004/11/18 14:34:01| creating entry 0x83f9790: near 'Connection: close'
>2004/11/18 14:34:01| created entry 0x83f9790: 'Connection: close'
>2004/11/18 14:34:01| 0x83f9d70 adding entry: 9 at 5
>2004/11/18 14:34:01| 0x83f9d70 lookup for 47
>2004/11/18 14:34:01| clientSetKeepaliveFlag: http_ver = 1.0
>2004/11/18 14:34:01| clientSetKeepaliveFlag: method = GET
>2004/11/18 14:34:01| 0x83f9d70 lookup for 51
>2004/11/18 14:34:01| 0x83f9d70 lookup for 38
>2004/11/18 14:34:01| 0x83f9d70 lookup for 9
>2004/11/18 14:34:01| 0x83f9d70: joining for id 9
>2004/11/18 14:34:01| 0x83f9d70: joined for id 9: close
>2004/11/18 14:34:01| cbdataLock: 0x824ec18
>2004/11/18 14:34:01| cbdataLock: 0x83f1eb0
>2004/11/18 14:34:01| cbdataLock: 0x83f9850
>2004/11/18 14:34:01| cbdataValid: 0x824ec18
>2004/11/18 14:34:01| aclCheck: checking 'http_access deny Microsoft'
>2004/11/18 14:34:01| aclMatchAclList: checking Microsoft
>2004/11/18 14:34:01| aclMatchAcl: checking 'acl Microsoft url_regex -i microsoft'
>2004/11/18 14:34:01| aclMatchRegex: checking 'http://www.lenta.ru/'
>2004/11/18 14:34:01| aclMatchRegex: looking for 'microsoft'
>2004/11/18 14:34:01| aclMatchAclList: no match, returning 0
>2004/11/18 14:34:01| cbdataLock: 0x824f4d8
>2004/11/18 14:34:01| cbdataUnlock: 0x824ec18
>2004/11/18 14:34:01| cbdataValid: 0x824f4d8
>2004/11/18 14:34:01| aclCheck: checking 'http_access deny Microsoft1'
>2004/11/18 14:34:01| aclMatchAclList: checking Microsoft1
>2004/11/18 14:34:01| aclMatchAcl: checking 'acl Microsoft1 dstdomain -i microsoft.com msn.com'
>2004/11/18 14:34:01| aclMatchDomainList: checking 'www.lenta.ru'
>2004/11/18 14:34:01| aclMatchDomainList: 'www.lenta.ru' NOT found
>2004/11/18 14:34:01| aclMatchAclList: no match, returning 0
>2004/11/18 14:34:01| cbdataLock: 0x824f558
>2004/11/18 14:34:01| cbdataUnlock: 0x824f4d8
>2004/11/18 14:34:01| cbdataValid: 0x824f558
>2004/11/18 14:34:01| aclCheck: checking 'http_access deny !Safe_ports'
>2004/11/18 14:34:01| aclMatchAclList: checking !Safe_ports
>2004/11/18 14:34:01| aclMatchAcl: checking 'acl Safe_ports port 80		# http'
>2004/11/18 14:34:01| aclMatchAclList: no match, returning 0
>2004/11/18 14:34:01| cbdataLock: 0x824f530
>2004/11/18 14:34:01| cbdataUnlock: 0x824f558
>2004/11/18 14:34:01| cbdataValid: 0x824f530
>2004/11/18 14:34:01| aclCheck: checking 'http_access deny CONNECT !Safe_ports !SSL_ports !Jabber_ports'
>2004/11/18 14:34:01| aclMatchAclList: checking CONNECT
>2004/11/18 14:34:01| aclMatchAcl: checking 'acl CONNECT method CONNECT'
>2004/11/18 14:34:01| aclMatchAclList: no match, returning 0
>2004/11/18 14:34:01| cbdataLock: 0x824f6b0
>2004/11/18 14:34:01| cbdataUnlock: 0x824f530
>2004/11/18 14:34:01| cbdataValid: 0x824f6b0
>2004/11/18 14:34:01| aclCheck: checking 'http_access allow bkpi82 bkpi82-dst'
>2004/11/18 14:34:01| aclMatchAclList: checking bkpi82
>2004/11/18 14:34:01| aclMatchAcl: checking 'acl bkpi82 src 193.125.101.0/255.255.255.0'
>2004/11/18 14:34:01| aclMatchIp: '193.125.101.1' found
>2004/11/18 14:34:01| aclMatchAclList: checking bkpi82-dst
>2004/11/18 14:34:01| aclMatchAcl: checking 'acl bkpi82-dst dst 193.125.101.0/255.255.255.0'
>2004/11/18 14:34:01| ipcache_gethostbyname: 'www.lenta.ru', flags=1
>2004/11/18 14:34:01| ipcache_nbgethostbyname: Name 'www.lenta.ru'.
>2004/11/18 14:34:01| ipcache_nbgethostbyname: MISS for 'www.lenta.ru'
>2004/11/18 14:34:01| idnsALookup: buf is 30 bytes for www.lenta.ru, id = 0x2
>2004/11/18 14:34:01| cbdataLock: 0x83f9bc0
>2004/11/18 14:34:01| commSetSelect: FD 4 type 1
>2004/11/18 14:34:01| eventAdd: Adding 'idnsCheckQueue', in 1.000000 seconds
>2004/11/18 14:34:01| aclMatchAcl: Can't yet compare 'bkpi82-dst' ACL for 'www.lenta.ru'
>2004/11/18 14:34:01| aclMatchAclList: no match, returning 0
>2004/11/18 14:34:01| ipcache_nbgethostbyname: Name 'www.lenta.ru'.
>2004/11/18 14:34:01| ipcache_nbgethostbyname: MISS for 'www.lenta.ru'
>2004/11/18 14:34:01| cbdataLock: 0x83f9a78
>2004/11/18 14:34:01| cbdataLock: 0x83f9b88
>2004/11/18 14:34:01| comm_poll: 1+0 FDs ready
>2004/11/18 14:34:01| idnsRead: FD 4: received 125 bytes from 193.125.101.1.
>2004/11/18 14:34:01| idnsGrokReply: ID 0x2, 1 answers
>2004/11/18 14:34:01| cbdataValid: 0x83f9bc0
>2004/11/18 14:34:01| cbdataUnlock: 0x83f9bc0
>2004/11/18 14:34:01| cbdataFree: 0x83f9bc0
>2004/11/18 14:34:01| cbdataFree: Freeing 0x83f9bc0
>2004/11/18 14:34:01| ipcacheParse: #0 81.19.69.28
>2004/11/18 14:34:01| cbdataValid: 0x83f9b88
>2004/11/18 14:34:01| cbdataUnlock: 0x83f9b88
>2004/11/18 14:34:01| cbdataFree: 0x83f9b88
>2004/11/18 14:34:01| cbdataFree: Freeing 0x83f9b88
>2004/11/18 14:34:01| ipcacheParse: #0 81.19.69.28
>2004/11/18 14:34:01| ipcacheRelease: Releasing entry for 'www.lenta.ru'
>2004/11/18 14:34:01| cbdataValid: 0x83f9a78
>2004/11/18 14:34:01| cbdataValid: 0x824f6b0
>2004/11/18 14:34:01| aclCheck: checking 'http_access allow bkpi82 bkpi82-dst'
>2004/11/18 14:34:01| aclMatchAclList: checking bkpi82
>2004/11/18 14:34:01| aclMatchAcl: checking 'acl bkpi82 src 193.125.101.0/255.255.255.0'
>2004/11/18 14:34:01| aclMatchIp: '193.125.101.1' found
>2004/11/18 14:34:01| aclMatchAclList: checking bkpi82-dst
>2004/11/18 14:34:01| aclMatchAcl: checking 'acl bkpi82-dst dst 193.125.101.0/255.255.255.0'
>2004/11/18 14:34:01| ipcache_gethostbyname: 'www.lenta.ru', flags=1
>2004/11/18 14:34:01| aclMatchIp: '81.19.69.28' NOT found
>2004/11/18 14:34:01| aclMatchAclList: no match, returning 0
>2004/11/18 14:34:01| cbdataLock: 0x824f688
>2004/11/18 14:34:01| cbdataUnlock: 0x824f6b0
>2004/11/18 14:34:01| cbdataValid: 0x824f688
>2004/11/18 14:34:01| aclCheck: checking 'http_access allow password bkpi82 !bkpi82-dst log_conn'
>2004/11/18 14:34:01| aclMatchAclList: checking password
>2004/11/18 14:34:01| aclMatchAcl: checking 'acl password proxy_auth REQUIRED'
>2004/11/18 14:34:01| authenticateValidateUser: Validating Auth_user request '(nil)'.
>2004/11/18 14:34:01| authenticateValidateUser: Auth_user_request was NULL!
>2004/11/18 14:34:01| authenticateAuthenticate: broken auth or no proxy_auth header. Requesting auth header.
>2004/11/18 14:34:01| aclMatchAcl: returning 0 sending authentication challenge.
>2004/11/18 14:34:01| aclMatchAclList: no match, returning 0
>2004/11/18 14:34:01| aclCheck: requiring Proxy Auth header.
>2004/11/18 14:34:01| aclCheck: match found, returning 2
>2004/11/18 14:34:01| cbdataUnlock: 0x824f688
>2004/11/18 14:34:01| aclCheckCallback: answer=2
>2004/11/18 14:34:01| cbdataValid: 0x83f9850
>2004/11/18 14:34:01| The request GET http://www.lenta.ru/ is DENIED, because it matched 'password'
>2004/11/18 14:34:01| Access Denied: http://www.lenta.ru/
>2004/11/18 14:34:01| AclMatchedName = password
>2004/11/18 14:34:01| Proxy Auth Message = <null>
>2004/11/18 14:34:01| storeCreateEntry: 'http://www.lenta.ru/'
>2004/11/18 14:34:01| creating rep: 0x83fa988
>2004/11/18 14:34:01| init-ing hdr: 0x83fa9c4 owner: 2
>2004/11/18 14:34:01| 0x83fa9c4 lookup for 38
>2004/11/18 14:34:01| 0x83fa9c4 lookup for 9
>2004/11/18 14:34:01| 0x83fa9c4 lookup for 22
>2004/11/18 14:34:01| new_MemObject: returning 0x83fa918
>2004/11/18 14:34:01| new_StoreEntry: returning 0x83fa8e0
>2004/11/18 14:34:01| storeKeyPrivate: GET http://www.lenta.ru/
>2004/11/18 14:34:01| storeHashInsert: Inserting Entry 0x83fa8e0 key 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| storeReleaseRequest: 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| cbdataLock: 0x83f9850
>2004/11/18 14:34:01| storeClientCopy: A3370F9272448A4C27CD2F89F0888FE3, seen 0, want 0, size 4096, cb 0x8065450, cbdata 0x83f9850
>2004/11/18 14:34:01| cbdataLock: 0x83faa28
>2004/11/18 14:34:01| storeClientCopy2: A3370F9272448A4C27CD2F89F0888FE3
>2004/11/18 14:34:01| storeClientCopy3: Waiting for more
>2004/11/18 14:34:01| cbdataUnlock: 0x83faa28
>2004/11/18 14:34:01| storeLockObject: key 'A3370F9272448A4C27CD2F89F0888FE3' count=2
>2004/11/18 14:34:01| creating rep: 0x83fbad8
>2004/11/18 14:34:01| init-ing hdr: 0x83fbb14 owner: 2
>2004/11/18 14:34:01| 0x83fbb14 lookup for 38
>2004/11/18 14:34:01| 0x83fbb14 lookup for 9
>2004/11/18 14:34:01| 0x83fbb14 lookup for 22
>2004/11/18 14:34:01| errorConvert: %U --> 'http://www.lenta.ru/'
>2004/11/18 14:34:01| errorConvert: %U --> 'http://www.lenta.ru/'
>2004/11/18 14:34:01| errorConvert: %U --> 'http://www.lenta.ru/'
>2004/11/18 14:34:01| errorConvert: %w --> 'webmaster@server.bkpi82.nikiet.ru'
>2004/11/18 14:34:01| errorConvert: %h --> 'server.bkpi82.nikiet.ru'
>2004/11/18 14:34:01| errorConvert: %T --> 'Thu, 18 Nov 2004 11:34:01 GMT'
>2004/11/18 14:34:01| errorConvert: %h --> 'server.bkpi82.nikiet.ru'
>2004/11/18 14:34:01| errorConvert: %s --> 'squid/2.5.STABLE7'
>2004/11/18 14:34:01| errorConvert: %S --> '
><BR clear="all">
><HR noshade size="1px">
><ADDRESS>
>Generated Thu, 18 Nov 2004 11:34:01 GMT by server.bkpi82.nikiet.ru (squid/2.5.STABLE7)
></ADDRESS>
></BODY></HTML>
>'
>2004/11/18 14:34:01| created entry 0x83f9bb0: 'Server: squid/2.5.STABLE7'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 44 at 0
>2004/11/18 14:34:01| created entry 0x83fcc28: 'Mime-Version: 1.0'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 33 at 1
>2004/11/18 14:34:01| created entry 0x83fcc68: 'Date: Thu, 18 Nov 2004 11:34:01 GMT'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 20 at 2
>2004/11/18 14:34:01| created entry 0x83fcca8: 'Content-Type: text/html'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 18 at 3
>2004/11/18 14:34:01| created entry 0x83fcd10: 'Content-Length: 1340'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 14 at 4
>2004/11/18 14:34:01| created entry 0x83fcd50: 'Expires: Thu, 18 Nov 2004 11:34:01 GMT'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 22 at 5
>2004/11/18 14:34:01| created entry 0x83fa8a0: 'X-Squid-Error: ERR_CACHE_ACCESS_DENIED 0'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 59 at 6
>2004/11/18 14:34:01| authenticateFixHeader: headertype:35 authuser:(nil)
>2004/11/18 14:34:01| authenticateFixErrorHeader: Sending type:35 header: 'Basic realm="BKPI-82 Server"'
>2004/11/18 14:34:01| created entry 0x83fd5c0: 'Proxy-Authenticate: Basic realm="BKPI-82 Server"'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 35 at 7
>2004/11/18 14:34:01| packing sline 0x83fbb04 using 0xbffdef40:
>2004/11/18 14:34:01| HTTP/1.0 407 Proxy Authentication Required
>2004/11/18 14:34:01| storeAppend: appending 44 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 44
>2004/11/18 14:34:01| packing hdr: (0x83fbb14)
>2004/11/18 14:34:01| storeAppend: appending 6 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 6
>2004/11/18 14:34:01| storeAppend: appending 2 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 2
>2004/11/18 14:34:01| storeAppend: appending 17 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 17
>2004/11/18 14:34:01| storeAppend: appending 2 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 2
>2004/11/18 14:34:01| storeAppend: appending 12 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 12
>2004/11/18 14:34:01| storeAppend: appending 2 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 2
>2004/11/18 14:34:01| storeAppend: appending 3 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 3
>2004/11/18 14:34:01| storeAppend: appending 2 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 2
>2004/11/18 14:34:01| storeAppend: appending 4 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 4
>2004/11/18 14:34:01| storeAppend: appending 2 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 2
>2004/11/18 14:34:01| storeAppend: appending 29 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 29
>2004/11/18 14:34:01| storeAppend: appending 2 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 2
>2004/11/18 14:34:01| storeAppend: appending 12 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 12
>2004/11/18 14:34:01| storeAppend: appending 2 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 2
>2004/11/18 14:34:01| storeAppend: appending 9 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 9
>2004/11/18 14:34:01| storeAppend: appending 2 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 2
>2004/11/18 14:34:01| storeAppend: appending 14 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 14
>2004/11/18 14:34:01| storeAppend: appending 2 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 2
>2004/11/18 14:34:01| storeAppend: appending 4 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 4
>2004/11/18 14:34:01| storeAppend: appending 2 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 2
>2004/11/18 14:34:01| storeAppend: appending 7 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 7
>2004/11/18 14:34:01| storeAppend: appending 2 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 2
>2004/11/18 14:34:01| storeAppend: appending 29 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 29
>2004/11/18 14:34:01| storeAppend: appending 2 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 2
>2004/11/18 14:34:01| storeAppend: appending 13 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 13
>2004/11/18 14:34:01| storeAppend: appending 2 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 2
>2004/11/18 14:34:01| storeAppend: appending 25 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 25
>2004/11/18 14:34:01| storeAppend: appending 2 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 2
>2004/11/18 14:34:01| storeAppend: appending 18 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 18
>2004/11/18 14:34:01| storeAppend: appending 2 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 2
>2004/11/18 14:34:01| storeAppend: appending 28 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 28
>2004/11/18 14:34:01| storeAppend: appending 2 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 2
>2004/11/18 14:34:01| storeAppend: appending 2 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 2
>2004/11/18 14:34:01| storeAppend: appending 1340 bytes for 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| memAppend: len 1340
>2004/11/18 14:34:01| cleaning hdr: 0x83fa9c4 owner: 2
>2004/11/18 14:34:01| InvokeHandlers: A3370F9272448A4C27CD2F89F0888FE3
>2004/11/18 14:34:01| InvokeHandlers: checking client #0
>2004/11/18 14:34:01| cbdataLock: 0x83faa28
>2004/11/18 14:34:01| storeClientCopy2: A3370F9272448A4C27CD2F89F0888FE3
>2004/11/18 14:34:01| storeClientCopy3: Copying from memory
>2004/11/18 14:34:01| memCopy: offset 0: size 4096
>2004/11/18 14:34:01| cbdataValid: 0x83f9850
>2004/11/18 14:34:01| clientSendMoreData: http://www.lenta.ru/, 1648 bytes
>2004/11/18 14:34:01| clientSendMoreData: FD 19 'http://www.lenta.ru/', out.offset=0 
>2004/11/18 14:34:01| creating rep: 0x83fbad8
>2004/11/18 14:34:01| init-ing hdr: 0x83fbb14 owner: 2
>2004/11/18 14:34:01| 0x83fbb14 lookup for 38
>2004/11/18 14:34:01| 0x83fbb14 lookup for 9
>2004/11/18 14:34:01| 0x83fbb14 lookup for 22
>2004/11/18 14:34:01| cleaning hdr: 0x83fbb14 owner: 2
>2004/11/18 14:34:01| init-ing hdr: 0x83fbb14 owner: 2
>2004/11/18 14:34:01| 0x83fbb14 lookup for 38
>2004/11/18 14:34:01| 0x83fbb14 lookup for 9
>2004/11/18 14:34:01| 0x83fbb14 lookup for 22
>2004/11/18 14:34:01| parsing hdr: (0x83fbb14)
>Server: squid/2.5.STABLE7
>Mime-Version: 1.0
>Date: Thu, 18 Nov 2004 11:34:01 GMT
>Content-Type: text/html
>Content-Length: 1340
>Expires: Thu, 18 Nov 2004 11:34:01 GMT
>X-Squid-Error: ERR_CACHE_ACCESS_DENIED 0
>Proxy-Authenticate: Basic realm="BKPI-82 Server"
>
>2004/11/18 14:34:01| creating entry 0x83fe610: near 'Server: squid/2.5.STABLE7'
>2004/11/18 14:34:01| created entry 0x83fe610: 'Server: squid/2.5.STABLE7'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 44 at 0
>2004/11/18 14:34:01| creating entry 0x83fe698: near 'Mime-Version: 1.0'
>2004/11/18 14:34:01| created entry 0x83fe698: 'Mime-Version: 1.0'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 33 at 1
>2004/11/18 14:34:01| creating entry 0x83fe6d8: near 'Date: Thu, 18 Nov 2004 11:34:01 GMT'
>2004/11/18 14:34:01| created entry 0x83fe6d8: 'Date: Thu, 18 Nov 2004 11:34:01 GMT'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 20 at 2
>2004/11/18 14:34:01| creating entry 0x83fe718: near 'Content-Type: text/html'
>2004/11/18 14:34:01| created entry 0x83fe718: 'Content-Type: text/html'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 18 at 3
>2004/11/18 14:34:01| creating entry 0x83fe758: near 'Content-Length: 1340'
>2004/11/18 14:34:01| created entry 0x83fe758: 'Content-Length: 1340'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 14 at 4
>2004/11/18 14:34:01| creating entry 0x83fe798: near 'Expires: Thu, 18 Nov 2004 11:34:01 GMT'
>2004/11/18 14:34:01| created entry 0x83fe798: 'Expires: Thu, 18 Nov 2004 11:34:01 GMT'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 22 at 5
>2004/11/18 14:34:01| creating entry 0x83fe7d8: near 'X-Squid-Error: ERR_CACHE_ACCESS_DENIED 0'
>2004/11/18 14:34:01| created entry 0x83fe7d8: 'X-Squid-Error: ERR_CACHE_ACCESS_DENIED 0'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 59 at 6
>2004/11/18 14:34:01| creating entry 0x83fe818: near 'Proxy-Authenticate: Basic realm="BKPI-82 Server"'
>2004/11/18 14:34:01| created entry 0x83fe818: 'Proxy-Authenticate: Basic realm="BKPI-82 Server"'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 35 at 7
>2004/11/18 14:34:01| 0x83fbb14 lookup for 38
>2004/11/18 14:34:01| 0x83fbb14 lookup for 9
>2004/11/18 14:34:01| 0x83fbb14 lookup for 22
>2004/11/18 14:34:01| 0x83fbb14 del-by-id 38
>2004/11/18 14:34:01| deleting 'Keep-Alive' fields in hdr 0x83fbb14
>2004/11/18 14:34:01| 0x83fbb14 lookup for 9
>2004/11/18 14:34:01| created entry 0x83fe880: 'X-Cache: MISS from server.bkpi82.nikiet.ru'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 55 at 8
>2004/11/18 14:34:01| created entry 0x83fe8c0: 'X-Cache-Lookup: NONE from server.bkpi82.nikiet.ru:3130'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 56 at 9
>2004/11/18 14:34:01| created entry 0x83fcd90: 'Proxy-Connection: close'
>2004/11/18 14:34:01| 0x83fbb14 adding entry: 38 at 10
>2004/11/18 14:34:01| cbdataLock: 0x8251170
>2004/11/18 14:34:01| aclCheckFast: list: 0x8251170
>2004/11/18 14:34:01| aclMatchAclList: checking all
>2004/11/18 14:34:01| aclMatchAcl: checking 'acl all src 0.0.0.0/0.0.0.0'
>2004/11/18 14:34:01| aclMatchIp: '193.125.101.1' found
>2004/11/18 14:34:01| aclMatchAclList: returning 1
>2004/11/18 14:34:01| cbdataUnlock: 0x8251170
>2004/11/18 14:34:01| cbdataFree: 0x83fcde0
>2004/11/18 14:34:01| cbdataFree: Freeing 0x83fcde0
>2004/11/18 14:34:01| destroying entry 0x83fe610: 'Server: squid/2.5.STABLE7'
>2004/11/18 14:34:01| clientSendMoreData: Appending 1340 bytes after 308 bytes of headers
>2004/11/18 14:34:01| packing sline 0x83fbb04 using 0xbffdee10:
>2004/11/18 14:34:01| HTTP/1.0 407 Proxy Authentication Required
>2004/11/18 14:34:01| packing hdr: (0x83fbb14)
>2004/11/18 14:34:01| destroying rep: 0x83fbad8
>2004/11/18 14:34:01| cleaning hdr: 0x83fbb14 owner: 2
>2004/11/18 14:34:01| destroying entry 0x83fe698: 'Mime-Version: 1.0'
>2004/11/18 14:34:01| destroying entry 0x83fe6d8: 'Date: Thu, 18 Nov 2004 11:34:01 GMT'
>2004/11/18 14:34:01| destroying entry 0x83fe718: 'Content-Type: text/html'
>2004/11/18 14:34:01| destroying entry 0x83fe758: 'Content-Length: 1340'
>2004/11/18 14:34:01| destroying entry 0x83fe798: 'Expires: Thu, 18 Nov 2004 11:34:01 GMT'
>2004/11/18 14:34:01| destroying entry 0x83fe7d8: 'X-Squid-Error: ERR_CACHE_ACCESS_DENIED 0'
>2004/11/18 14:34:01| destroying entry 0x83fe818: 'Proxy-Authenticate: Basic realm="BKPI-82 Server"'
>2004/11/18 14:34:01| destroying entry 0x83fe880: 'X-Cache: MISS from server.bkpi82.nikiet.ru'
>2004/11/18 14:34:01| destroying entry 0x83fe8c0: 'X-Cache-Lookup: NONE from server.bkpi82.nikiet.ru:3130'
>2004/11/18 14:34:01| destroying entry 0x83fcd90: 'Proxy-Connection: close'
>2004/11/18 14:34:01| comm_write: FD 19: sz 1746: hndl 0x8065dc0: data 0x83f9850.
>2004/11/18 14:34:01| cbdataLock: 0x83f9850
>2004/11/18 14:34:01| commSetSelect: FD 19 type 2
>2004/11/18 14:34:01| cbdataUnlock: 0x83faa28
>2004/11/18 14:34:01| storeSwapOut: http://www.lenta.ru/
>2004/11/18 14:34:01| storeSwapOut: store_status = STORE_PENDING
>2004/11/18 14:34:01| storeSwapOut: mem->inmem_lo = 0
>2004/11/18 14:34:01| storeSwapOut: mem->inmem_hi = 1648
>2004/11/18 14:34:01| storeSwapOut: swapout.queue_offset = 0
>2004/11/18 14:34:01| storeSwapOut: lowest_offset = 0
>2004/11/18 14:34:01| storeComplete: 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| storeEntryValidLength: Checking 'A3370F9272448A4C27CD2F89F0888FE3'
>2004/11/18 14:34:01| storeEntryValidLength:     object_len = 1648
>2004/11/18 14:34:01| storeEntryValidLength:         hdr_sz = 0
>2004/11/18 14:34:01| storeEntryValidLength: content_length = 1340
>2004/11/18 14:34:01| storeEntryValidLength: Zero header size: A3370F9272448A4C27CD2F89F0888FE3
>2004/11/18 14:34:01| storeSwapOut: http://www.lenta.ru/
>2004/11/18 14:34:01| storeSwapOut: store_status = STORE_OK
>2004/11/18 14:34:01| storeSwapOut: mem->inmem_lo = 0
>2004/11/18 14:34:01| storeSwapOut: mem->inmem_hi = 1648
>2004/11/18 14:34:01| storeSwapOut: swapout.queue_offset = 0
>2004/11/18 14:34:01| storeSwapOut: lowest_offset = 0
>2004/11/18 14:34:01| InvokeHandlers: A3370F9272448A4C27CD2F89F0888FE3
>2004/11/18 14:34:01| InvokeHandlers: checking client #0
>2004/11/18 14:34:01| storeUnlockObject: key 'A3370F9272448A4C27CD2F89F0888FE3' count=1
>2004/11/18 14:34:01| cbdataFree: 0x83fba80
>2004/11/18 14:34:01| cbdataFree: Freeing 0x83fba80
>2004/11/18 14:34:01| cbdataUnlock: 0x83f9850
>2004/11/18 14:34:01| cbdataUnlock: 0x83f1eb0
>2004/11/18 14:34:01| cbdataFree: 0x83f9a78
>2004/11/18 14:34:01| cbdataFree: 0x83f9a78 has 1 locks, not freeing
>2004/11/18 14:34:01| cbdataUnlock: 0x83f9a78
>2004/11/18 14:34:01| cbdataUnlock: Freeing 0x83f9a78
>2004/11/18 14:34:01| comm_poll: 1+0 FDs ready
>2004/11/18 14:34:01| comm_poll: FD 19 ready for writing
>2004/11/18 14:34:01| commHandleWrite: FD 19: off 0, sz 1746.
>2004/11/18 14:34:01| commHandleWrite: write() returns 1746
>2004/11/18 14:34:01| cbdataValid: 0x83f9850
>2004/11/18 14:34:01| clientWriteComplete: FD 19, sz 1746, err 0, off 1648, len 1648
>2004/11/18 14:34:01| clientWriteComplete: FD 19 transfer is DONE
>2004/11/18 14:34:01| comm_close: FD 19
>2004/11/18 14:34:01| commCallCloseHandlers: FD 19
>2004/11/18 14:34:01| commCallCloseHandlers: ch->handler=0x80630f0
>2004/11/18 14:34:01| cbdataValid: 0x83f1eb0
>2004/11/18 14:34:01| connStateFree: FD 19
>2004/11/18 14:34:01| httpRequestFree: http://www.lenta.ru/
>2004/11/18 14:34:01| httpRequestFree: al.url='http://www.lenta.ru/'
>2004/11/18 14:34:01| packing hdr: (0x83f9d70)
>FATAL: Received Segment Violation...dying.
>2004/11/18 14:34:01| storeDirWriteCleanLogs: Starting...
>2004/11/18 14:34:01| file_open: FD 20
>2004/11/18 14:34:01| WARNING: Closing open FD   20
>2004/11/18 14:34:01| fd_close FD 20 HTTP Socket
>2004/11/18 14:34:01| fd_open FD 20 /var/spool/squid/swap.state.clean
>2004/11/18 14:34:01| storeDirWriteCleanLogs: opened /var/spool/squid/swap.state.clean, FD 20
>2004/11/18 14:34:01| cbdataFree: 0x83f9b10
>2004/11/18 14:34:01| cbdataFree: Freeing 0x83f9b10
>2004/11/18 14:34:01| file_close: FD 17, really closing
>2004/11/18 14:34:01| fd_close FD 17 /var/spool/squid/swap.state
>2004/11/18 14:34:01| Cache Dir #0 log closed on FD 17
>2004/11/18 14:34:01| xrename: renaming /var/spool/squid/swap.state.clean to /var/spool/squid/swap.state
>2004/11/18 14:34:01| file_open: FD 17
>2004/11/18 14:34:01| fd_open FD 17 /var/spool/squid/swap.state.last-clean
>2004/11/18 14:34:01| file_close: FD 17, really closing
>2004/11/18 14:34:01| fd_close FD 17 /var/spool/squid/swap.state.last-clean
>2004/11/18 14:34:01| file_close: FD 20, really closing
>2004/11/18 14:34:01| fd_close FD 20 /var/spool/squid/swap.state.clean
>2004/11/18 14:34:01|   Finished.  Wrote 32154 entries.
>2004/11/18 14:34:01|   Took 0.1 seconds (561563.4 entries/sec).
>CPU Usage: 13.400 seconds = 10.120 user + 3.280 sys
>Maximum Resident Size: 0 KB
>Page faults with physical i/o: 463
>Memory usage for squid via mallinfo():
>	total space in arena:    5197 KB
>	Ordinary blocks:         5083 KB      6 blks
>	Small blocks:               0 KB      7 blks
>	Holding blocks:          3140 KB      1 blks
>	Free Small blocks:          0 KB
>	Free Ordinary blocks:     114 KB
>	Total in use:            8223 KB 158%
>	Total free:               115 KB 2%
>2004/11/18 14:34:04| fd_open FD 3 /var/log/squid/cache.log
>2004/11/18 14:34:04| Starting Squid Cache version 2.5.STABLE7 for i586-alt-linux-gnu...
>2004/11/18 14:34:04| Process ID 5652
>2004/11/18 14:34:04| With 16384 file descriptors available
>2004/11/18 14:34:04| Initializing IP Cache...
>2004/11/18 14:34:04| ipcache_init: Skipping DNS name lookup tests.
>2004/11/18 14:34:04| cachemgrRegister: registered ipcache
>2004/11/18 14:34:04| Initializing FQDN Cache...
>2004/11/18 14:34:04| cachemgrRegister: registered fqdncache
>2004/11/18 14:34:04| etc_hosts: line is '127.0.0.1	localhost localhost.localdomain
>'
>2004/11/18 14:34:04| etc_hosts: address is '127.0.0.1'
>2004/11/18 14:34:04| etc_hosts: got hostname 'localhost'
>2004/11/18 14:34:04| etc_hosts: got hostname 'localhost.localdomain'
>2004/11/18 14:34:04| etc_hosts: line is '193.125.101.1	server server.bkpi82.nikiet.ru
>'
>2004/11/18 14:34:04| etc_hosts: address is '193.125.101.1'
>2004/11/18 14:34:04| etc_hosts: got hostname 'server'
>2004/11/18 14:34:04| etc_hosts: got hostname 'server.bkpi82.nikiet.ru'
>2004/11/18 14:34:04| etc_hosts: line is '193.125.101.100	epiphanov epiphanov.bkpi82.nikiet.ru
>'
>2004/11/18 14:34:04| etc_hosts: address is '193.125.101.100'
>2004/11/18 14:34:04| etc_hosts: got hostname 'epiphanov'
>2004/11/18 14:34:04| etc_hosts: got hostname 'epiphanov.bkpi82.nikiet.ru'
>2004/11/18 14:34:04| etc_hosts: line is '172.22.1.29	potapova potapova.nikiet.ru
>'
>2004/11/18 14:34:04| etc_hosts: address is '172.22.1.29'
>2004/11/18 14:34:04| etc_hosts: got hostname 'potapova'
>2004/11/18 14:34:04| etc_hosts: got hostname 'potapova.nikiet.ru'
>2004/11/18 14:34:04| comm_open: FD 4 is a new socket
>2004/11/18 14:34:04| fd_open FD 4 DNS Socket
>2004/11/18 14:34:04| comm_local_port: FD 4: port 32773
>2004/11/18 14:34:04| DNS Socket created at 0.0.0.0, port 32773, FD 4
>2004/11/18 14:34:04| Adding nameserver 193.125.101.1 from squid.conf
>2004/11/18 14:34:04| idnsAddNameserver: Added nameserver #0: 193.125.101.1
>2004/11/18 14:34:04| Adding nameserver 172.22.0.1 from squid.conf
>2004/11/18 14:34:04| idnsAddNameserver: Added nameserver #1: 172.22.0.1
>2004/11/18 14:34:04| Adding nameserver 172.22.0.24 from squid.conf
>2004/11/18 14:34:04| idnsAddNameserver: Added nameserver #2: 172.22.0.24
>2004/11/18 14:34:04| cachemgrRegister: registered idns
>2004/11/18 14:34:04| authBasicConfigured: returning configured
>2004/11/18 14:34:04| helperOpenServers: Starting 5 'squid_ldap_auth' processes
>2004/11/18 14:34:04| comm_open: FD 5 is a new socket
>2004/11/18 14:34:04| fd_open FD 5 squid_ldap_auth
>2004/11/18 14:34:04| comm_open: FD 6 is a new socket
>2004/11/18 14:34:04| fd_open FD 6 squid_ldap_auth
>2004/11/18 14:34:04| ipcCreate: prfd FD 6
>2004/11/18 14:34:04| ipcCreate: pwfd FD 6
>2004/11/18 14:34:04| ipcCreate: crfd FD 5
>2004/11/18 14:34:04| ipcCreate: cwfd FD 5
>2004/11/18 14:34:04| ipcCreate: FD 6 sockaddr 127.0.0.1:32798
>2004/11/18 14:34:04| ipcCreate: FD 5 sockaddr 127.0.0.1:32797
>2004/11/18 14:34:04| ipcCreate: FD 5 listening...
>2004/11/18 14:34:04| comm_close: FD 5
>2004/11/18 14:34:04| commCallCloseHandlers: FD 5
>2004/11/18 14:34:04| fd_close FD 5 squid_ldap_auth
>2004/11/18 14:34:04| leave_suid: PID 5653 called
>2004/11/18 14:34:04| leave_suid: PID 5653 giving up root priveleges forever
>2004/11/18 14:34:04| ipcCreate: calling accept on FD 5
>2004/11/18 14:34:04| ipcCreate: CHILD accepted new FD 6
>2004/11/18 14:34:04| commSetTimeout: FD 6 timeout -1
>2004/11/18 14:34:04| cbdataLock: 0x8253fa0
>2004/11/18 14:34:04| comm_add_close_handler: FD 6, handler=0x8081fd0, data=0x8254010
>2004/11/18 14:34:04| cbdataLock: 0x8254010
>2004/11/18 14:34:04| comm_open: FD 5 is a new socket
>2004/11/18 14:34:04| fd_open FD 5 squid_ldap_auth
>2004/11/18 14:34:04| comm_open: FD 7 is a new socket
>2004/11/18 14:34:04| fd_open FD 7 squid_ldap_auth
>2004/11/18 14:34:04| ipcCreate: prfd FD 7
>2004/11/18 14:34:04| ipcCreate: pwfd FD 7
>2004/11/18 14:34:04| ipcCreate: crfd FD 5
>2004/11/18 14:34:04| ipcCreate: cwfd FD 5
>2004/11/18 14:34:04| ipcCreate: FD 7 sockaddr 127.0.0.1:32800
>2004/11/18 14:34:04| ipcCreate: FD 5 sockaddr 127.0.0.1:32799
>2004/11/18 14:34:04| ipcCreate: FD 5 listening...
>2004/11/18 14:34:04| comm_close: FD 5
>2004/11/18 14:34:04| commCallCloseHandlers: FD 5
>2004/11/18 14:34:04| fd_close FD 5 squid_ldap_auth
>2004/11/18 14:34:04| leave_suid: PID 5654 called
>2004/11/18 14:34:04| leave_suid: PID 5654 giving up root priveleges forever
>2004/11/18 14:34:04| ipcCreate: calling accept on FD 5
>2004/11/18 14:34:04| ipcCreate: CHILD accepted new FD 7
>2004/11/18 14:34:04| commSetTimeout: FD 7 timeout -1
>2004/11/18 14:34:04| cbdataLock: 0x8253fa0
>2004/11/18 14:34:04| comm_add_close_handler: FD 7, handler=0x8081fd0, data=0x8256078
>2004/11/18 14:34:04| cbdataLock: 0x8256078
>2004/11/18 14:34:04| comm_open: FD 5 is a new socket
>2004/11/18 14:34:04| fd_open FD 5 squid_ldap_auth

Actions: View

Attachments on bug 5536: 654