Re: [squid-users] Squid stops responding-LTSP and WinXP clients

From: Avinash Rao <avinash.aol_at_gmail.com>
Date: Thu, 1 Oct 2009 11:17:25 +0530

On Tue, Sep 29, 2009 at 7:24 PM, Avinash Rao <avinash.aol_at_gmail.com> wrote:
> The file is too huge, the email bounced back, I am pasting few records
> which has different entries.
>
>
> 2009/09/29 16:43:02| statHistCopy: Dest=0x85baa8, Orig=0x86fd68
> 2009/09/29 16:43:02| statHistCopy: capacity 300 300
> 2009/09/29 16:43:02| statHistCopy: min 0.000000 0.000000
> 2009/09/29 16:43:02| statHistCopy: max 60000000.000000 60000000.000000
> 2009/09/29 16:43:02| statHistCopy: scale 16.750554 16.750554
> 2009/09/29 16:43:02| statHistCopy: copying 1200 bytes to 0x3f7c2a0
> from 0x126d130
> 2009/09/29 16:43:02| statHistCopy: Dest=0x85bae0, Orig=0x86fda0
> 2009/09/29 16:43:02| statHistCopy: capacity 300 300
> 2009/09/29 16:43:02| statHistCopy: min 0.000000 0.000000
> 2009/09/29 16:43:02| statHistCopy: max 60000000.000000 60000000.000000
> 2009/09/29 16:43:02| statHistCopy: scale 16.750554 16.750554
> 2009/09/29 16:43:02| statHistCopy: copying 1200 bytes to 0x112dd40
> from 0x126d5f0
> 2009/09/29 16:43:02| statHistCopy: Dest=0x85bb30, Orig=0x86fdf0
> 2009/09/29 16:43:02| statHistCopy: capacity 300 300
> 2009/09/29 16:43:02| statHistCopy: min 0.000000 0.000000
> 2009/09/29 16:43:02| statHistCopy: max 600000.000000 600000.000000
> 2009/09/29 16:43:02| statHistCopy: scale 22.548445 22.548445
> 2009/09/29 16:43:02| statHistCopy: copying 1200 bytes to 0x41435c0
> from 0x126dab0
> 2009/09/29 16:43:02| statHistCopy: Dest=0x85bbc0, Orig=0x86fe80
> 2009/09/29 16:43:02| statHistCopy: capacity 7 7
> 2009/09/29 16:43:02| statHistCopy: min -1.000000 -1.000000
> 2009/09/29 16:43:02| statHistCopy: max 6.000000 6.000000
> 2009/09/29 16:43:02| statHistCopy: scale 1.000000 1.000000
> 2009/09/29 16:43:02| statHistCopy: copying 28 bytes to 0x420a450 from 0x126df70
> 2009/09/29 16:43:02| statHistCopy: Dest=0x85bc28, Orig=0x86fee8
> 2009/09/29 16:43:02| statHistCopy: capacity 18 18
> 2009/09/29 16:43:02| statHistCopy: min -1.000000 -1.000000
> 2009/09/29 16:43:02| statHistCopy: max 17.000000 17.000000
> 2009/09/29 16:43:02| statHistCopy: scale 1.000000 1.000000
> 2009/09/29 16:43:02| statHistCopy: copying 72 bytes to 0x3fa2de0 from 0x126dfa0
> 2009/09/29 16:43:02| statHistCopy: Dest=0x85bc98, Orig=0x86ff58
> 2009/09/29 16:43:02| statHistCopy: capacity 13 13
> 2009/09/29 16:43:02| statHistCopy: min -1.000000 -1.000000
> 2009/09/29 16:43:02| statHistCopy: max 12.000000 12.000000
> 2009/09/29 16:43:02| statHistCopy: scale 1.000000 1.000000
> 2009/09/29 16:43:02| statHistCopy: copying 52 bytes to 0x4159f30 from 0x126e040
> 2009/09/29 16:43:02| statHistCopy: Dest=0x85bcd0, Orig=0x86ff90
> 2009/09/29 16:43:02| statHistCopy: capacity 256 256
> 2009/09/29 16:43:02| statHistCopy: min 0.000000 0.000000
> 2009/09/29 16:43:02| statHistCopy: max 255.000000 255.000000
> 2009/09/29 16:43:02| statHistCopy: scale 1.003922 1.003922
> 2009/09/29 16:43:02| statHistCopy: copying 1024 bytes to 0x11354b0
> from 0x126e080
> 2009/09/29 16:43:02| comm_select: timeout 851
> 2009/09/29 16:43:02| comm_select: time out
> 2009/09/29 16:43:02| comm_select: timeout 730
> 2009/09/29 16:43:02| commSetEvents(fd=14)
> 2009/09/29 16:43:02| commSetEvents(fd=20)
> 2009/09/29 16:43:02| comm_call_handlers(): got fd=14 read_event=1
> write_event=0 F->read_handler=0x41e3a0 F->write_handler=(nil)
> 2009/09/29 16:43:02| comm_call_handlers(): Calling read handler on fd=14
> 2009/09/29 16:43:02| clientReadRequest: FD 14: reading request...
> 2009/09/29 16:43:02| clientReadRequest: FD 14 closed?
> 2009/09/29 16:43:02| cbdataLock: 0x3f62e78
> 2009/09/29 16:43:02| cbdataValid: 0x3f62e78
> 2009/09/29 16:43:02| cbdataUnlock: 0x3f62e78
> 2009/09/29 16:43:02| commSetSelect: FD 14 type 1
> 2009/09/29 16:43:02| commSetEvents(fd=14)
> 2009/09/29 16:43:02| comm_call_handlers(): got fd=20 read_event=1
> write_event=0 F->read_handler=0x41e3a0 F->write_handler=(nil)
> 2009/09/29 16:43:02| comm_call_handlers(): Calling read handler on fd=20
> 2009/09/29 16:43:02| clientReadRequest: FD 20: reading request...
> 2009/09/29 16:43:02| clientReadRequest: FD 20 closed?
> 2009/09/29 16:43:02| cbdataLock: 0x3f4eb68
> 2009/09/29 16:43:02| cbdataValid: 0x3f4eb68
> 2009/09/29 16:43:02| cbdataUnlock: 0x3f4eb68
> 2009/09/29 16:43:02| commSetSelect: FD 20 type 1
> 2009/09/29 16:43:02| commSetEvents(fd=20)
> 2009/09/29 16:43:02| comm_select: timeout 730
> 2009/09/29 16:43:02| comm_call_handlers(): got fd=14 read_event=1
> write_event=0 F->read_handler=0x41e3a0 F->write_handler=(nil)
> 2009/09/29 16:43:02| commSetEvents(fd=14)
> 2009/09/29 16:43:02| comm_call_handlers(): got fd=20 read_event=1
> write_event=0 F->read_handler=0x41e3a0 F->write_handler=(nil)
> 2009/09/29 16:43:02| commSetEvents(fd=20)
> 2009/09/29 16:43:02| comm_select: timeout 730
> 2009/09/29 16:43:03| comm_select: time out
> 2009/09/29 16:43:03| eventRun: RUN ID 669501
> 2009/09/29 16:43:03| eventRun: Running 'MaintainSwapSpace', id 669498
> 2009/09/29 16:43:03| eventAdd: Adding 'MaintainSwapSpace', in 1.000000 seconds
> 2009/09/29 16:43:03| comm_select: timeout 10
> 2009/09/29 16:43:03| comm_select: time out
> 2009/09/29 16:43:03| comm_select: timeout 1
> 2009/09/29 16:43:03| comm_select: time out
> 2009/09/29 16:43:03| eventRun: RUN ID 669502
> 2009/09/29 16:43:03| eventRun: Running 'idnsCheckQueue', id 669499
> 2009/09/29 16:43:03| idnsCheckQueue: ID 0xbb9f timeout
> 2009/09/29 16:43:03| idnsCheckQueue: ID bb9f: giving up after 5 tries
> and 156.0 seconds
> 2009/09/29 16:43:03| cbdataValid: 0x41ebbe8
> 2009/09/29 16:43:03| cbdataUnlock: 0x41ebbe8
> 2009/09/29 16:43:03| cbdataFree: 0x41ebbe8
> 2009/09/29 16:43:03| cbdataFree: Freeing 0x41ebbe8
> 2009/09/29 16:43:03| ipcacheParse: Lookup failed 'Timeout' for 'www.tagged.com'
> 2009/09/29 16:43:03| cbdataValid: 0x2bc2ca8
> 2009/09/29 16:43:03| cbdataUnlock: 0x2bc2ca8
> 2009/09/29 16:43:03| cbdataUnlock: Freeing 0x2bc2ca8
> 2009/09/29 16:43:03| cbdataValid: 0x4152a58
> 2009/09/29 16:43:03| cbdataUnlock: 0x4152a58
> 2009/09/29 16:43:03| cbdataFree: 0x4152a58
> 2009/09/29 16:43:03| cbdataFree: Freeing 0x4152a58
> 2009/09/29 16:43:03| ipcacheParse: Lookup failed 'Timeout' for 'www.tagged.com'
> 2009/09/29 16:43:03| ipcacheRelease: Releasing entry for 'www.tagged.com'
> 2009/09/29 16:43:03| cbdataValid: 0x35ab678
> 2009/09/29 16:43:03| commConnectDnsHandle: Unknown host: www.tagged.com
> 2009/09/29 16:43:03| comm_remove_close_handler: FD 16,
> handler=0x427100, data=0x35ab678
> 2009/09/29 16:43:03| cbdataUnlock: 0x35ab678
> 2009/09/29 16:43:03| commSetTimeout: FD 16 timeout -1
> 2009/09/29 16:43:03| commConnectFree: FD 16
> 2009/09/29 16:43:03| cbdataFree: 0x35ab678
> 2009/09/29 16:43:03| cbdataFree: 0x35ab678 has 1 locks, not freeing
> 2009/09/29 16:43:03| cbdataValid: 0x3f6a338
> 2009/09/29 16:43:03| fwdConnectDone: Unknown host: www.tagged.com
> 2009/09/29 16:43:03| fwdFail: ERR_DNS_FAIL "Service Unavailable"
> 2009/09/29 16:43:03| cbdataFree: 0x35a78b8
> 2009/09/29 16:43:03| cbdataFree: Freeing 0x35a78b8
> 2009/09/29 16:43:03| comm_close: FD 16
> 2009/09/29 16:43:03| commCallCloseHandlers: FD 16
> 2009/09/29 16:43:03| commCallCloseHandlers: ch->handler=0x4321e0
> 2009/09/29 16:43:03| cbdataValid: 0x3f6a338
> 2009/09/29 16:43:03| fwdServerClosed: FD 16
> http://www.tagged.com/profile.html?uid=5410338954
> 2009/09/29 16:43:03| fwdStateFree: 0x3f6a338
> 2009/09/29 16:43:03| storeLockObject: key
> 'DA09B04EDD7F9404E8E6528CF00D001C' count=4
> 2009/09/29 16:43:03| creating rep: 0x40af550
> 2009/09/29 16:43:03| init-ing hdr: 0x40af5b8 owner: 3
> 2009/09/29 16:43:03| 0x40af5b8 lookup for 41
> 2009/09/29 16:43:03| 0x40af5b8 lookup for 9
> 2009/09/29 16:43:03| 0x40af5b8 lookup for 41
> 2009/09/29 16:43:03| 0x40af5b8 lookup for 9
> 2009/09/29 16:43:03| 0x40af5b8 lookup for 25
> 2009/09/29 16:43:03| errorConvert: %U --> 'http://www.tagged.com/profile.html?'
> 2009/09/29 16:43:03| errorConvert: %U --> 'http://www.tagged.com/profile.html?'
> 2009/09/29 16:43:03| errorConvert: %H --> 'www.tagged.com'
> 2009/09/29 16:43:03| errorConvert: %z --> 'Timeout'
> 2009/09/29 16:43:03| errorConvert: %w --> 'webmaster'
> 2009/09/29 16:43:03| errorConvert: %w --> 'webmaster'
> 2009/09/29 16:43:03| errorConvert: %T --> 'Tue, 29 Sep 2009 11:13:03 GMT'
> 2009/09/29 16:43:03| errorConvert: %h --> 'sunbox'
> 2009/09/29 16:43:03| errorConvert: %s --> 'squid/2.6.STABLE18'
> 2009/09/29 16:43:03| errorConvert: %S --> '
> 2009/09/29 16:43:03| created entry 0x41f9960: 'Server: squid/2.6.STABLE18'
> 2009/09/29 16:43:03| 0x40af5b8 adding entry: 47 at 0
> 2009/09/29 16:43:03| created entry 0x3b24850: 'Date: Tue, 29 Sep 2009
> 11:13:03 GMT'
> 2009/09/29 16:43:03| 0x40af5b8 adding entry: 23 at 1
> 2009/09/29 16:43:03| created entry 0x382d630: 'Content-Type: text/html'
> 2009/09/29 16:43:03| 0x40af5b8 adding entry: 18 at 2
> 2009/09/29 16:43:03| created entry 0x3a026b0: 'Content-Length: 1158'
> 2009/09/29 16:43:03| 0x40af5b8 adding entry: 14 at 3
> 2009/09/29 16:43:03| created entry 0x39844e0: 'Expires: Tue, 29 Sep
> 2009 11:13:03 GMT'
> 2009/09/29 16:43:03| 0x40af5b8 adding entry: 25 at 4
> 2009/09/29 16:43:03| created entry 0x42117b0: 'X-Squid-Error: ERR_DNS_FAIL 0'
> 2009/09/29 16:43:03| 0x40af5b8 adding entry: 61 at 5
> 2009/09/29 16:43:03| authenticateFixHeader: headertype:0 authuser:(nil)
> 2009/09/29 16:43:03| cleaning hdr: 0x4234e58 owner: 3
> 2009/09/29 16:43:03| packing sline 0x4234e40 using 0x7fffdc1c6bf0:
> 2009/09/29 16:43:03| HTTP/1.0 503 Service Unavailable
>
> 2009/09/29 16:43:03| storeAppend: appending 34 bytes for
> 'DA09B04EDD7F9404E8E6528CF00D001C'
> 2009/09/29 16:43:03| memAppend: len 34
> 2009/09/29 16:43:03| packing hdr: (0x4234e58)
> 2009/09/29 16:43:03| storeAppend: appending 6 bytes for
>

Were you able to make out anything
Received on Thu Oct 01 2009 - 05:47:32 MDT

This archive was generated by hypermail 2.2.0 : Thu Oct 01 2009 - 12:00:05 MDT