RE: [squid-users] Strange squid problemm

> -----Original Message-----
> From: hno@hem.passagen.se [mailto:hno@hem.passagen.se] On
> Behalf Of Henrik Nordstrom
> Sent: Wednesday, October 17, 2001 4:19 AM
> To: Elman Efendiyev
> Cc: squid-users@squid-cache.org
> Subject: Re: [squid-users] Strange squid problemm
>
>
> Elman Efendiyev wrote:
>
> > 2001/10/17 02:15:50| The request GET
> > http://mmedia.ozon.ru/graphics/img_ass/468/ozon_bibl2.gif
> is ALLOWED,
> > because it matched 'earlnet' 2001/10/17 02:15:50|
> clientReadRequest:
> > FD 15: no data to process ((11) Resource temporarily unavailable)
>
> Fine. This basically tells that Squid has read and processed
> the whole request.

Do you mean this isnt error and this is ok? If so why "((11) Resource
temporarily unavailable)". Maybe you can direct me to the point in
documentation about this message?
I saw some discussions in the archive of list about it as about error
message but not found explain or solution to fix it.

> > 2001/10/17 02:15:50| fwdServerClosed: FD 16
> >
> http://u022.22.spylog.com/cnt?p=1&rn=0.05679123829377347>
&r=http%3A//ww
> > w.
> > dropzone.ru/&pg=http%3A//www.dropzone.ru/guestbook.shtml
> > 2001/10/17 02:15:55| commHandleWrite: FD 18: write failure:
> (32) Broken
> > pipe.
>
> This isn't as good. Broken pipe indicates the other end of FD
> 18 aborted the connection while Squid was sending data there.
> Now, this snippet alone does not tell if it was the client or
> the server.

Here log from starting squid to the point where I catch "freeze"
problemm:

2001/10/17 02:12:47| Starting Squid Cache version 2.4.STABLE2 for
i686-pc-linux-gnu...
2001/10/17 02:12:47| Process ID 446
2001/10/17 02:12:47| With 1024 file descriptors available
2001/10/17 02:12:47| Performing DNS Tests...
2001/10/17 02:12:47| Successful DNS name lookup tests...
2001/10/17 02:12:47| DNS Socket created on FD 4
2001/10/17 02:12:47| Adding nameserver 212.82.205.193 from
/etc/resolv.conf
2001/10/17 02:12:47| Unlinkd pipe opened on FD 9
2001/10/17 02:12:47| Swap maxSize 102400 KB, estimated 7876 objects
2001/10/17 02:12:47| Target number of buckets: 393
2001/10/17 02:12:47| Using 8192 Store buckets
2001/10/17 02:12:47| Max Mem size: 8192 KB
2001/10/17 02:12:47| Max Swap size: 102400 KB
2001/10/17 02:12:47| Rebuilding storage in /usr/local/squid/cache
(CLEAN)
2001/10/17 02:12:47| Using Least Load store dir selection
2001/10/17 02:12:47| Set Current Directory to /usr/local/squid/cache
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '589'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '514'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '509'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '497'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '485'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '528'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '513'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '535'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '536'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '527'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '521'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '536'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '516'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '518'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '516'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '524'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '514'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '528'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '518'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '529'
2001/10/17 02:12:47| storeCreate: Selected dir '0' for obj size '553'
2001/10/17 02:12:47| Loaded Icons.
2001/10/17 02:12:47| Accepting HTTP connections at 0.0.0.0, port 3128,
FD 10.
2001/10/17 02:12:47| Accepting ICP messages at 0.0.0.0, port 3130, FD
11.
2001/10/17 02:12:47| WCCP Disabled.
2001/10/17 02:12:47| Ready to serve requests.
2001/10/17 02:12:50| Done scanning /usr/local/squid/cache swaplog (0
entries)
2001/10/17 02:12:50| xrename: renaming
/usr/local/squid/cache/swap.state.new to
/usr/local/squid/cache/swap.state
2001/10/17 02:12:50| Finished rebuilding storage from disk.
2001/10/17 02:12:50| 0 Entries scanned
2001/10/17 02:12:50| 0 Invalid entries.
2001/10/17 02:12:50| 0 With invalid flags.
2001/10/17 02:12:50| 0 Objects loaded.
2001/10/17 02:12:50| 0 Objects expired.
2001/10/17 02:12:50| 0 Objects cancelled.
2001/10/17 02:12:50| 0 Duplicate URLs purged.
2001/10/17 02:12:50| 0 Swapfile clashes avoided.
2001/10/17 02:12:50| Took 2.5 seconds ( 0.0 objects/sec).
2001/10/17 02:12:50| Beginning Validation Procedure
2001/10/17 02:12:50| Completed Validation Procedure
2001/10/17 02:12:50| Validated 0 Entries
2001/10/17 02:12:50| store_swap_size = 84k
2001/10/17 02:12:50| storeLateRelease: released 0 objects
2001/10/17 02:14:55| The request GET http://www.dropzone.ru/ is ALLOWED,
because it matched 'earlnet'
2001/10/17 02:15:00| commHandleWrite: FD 13: write failure: (32) Broken
pipe.
2001/10/17 02:15:00| fwdAbort: http://www.dropzone.ru/
2001/10/17 02:15:00| The request GET
http://www.dropzone.ru/_s/logo_dz_med.gif is ALLOWED, because it matched
'earlnet'
2001/10/17 02:15:01| The request GET
http://www.dropzone.ru/_s/dot_black.gif is ALLOWED, because it matched
'earlnet'
2001/10/17 02:15:01| The request GET
http://www.dropzone.ru/_s/dot_empty.gif is ALLOWED, because it matched
'earlnet'
2001/10/17 02:15:01| The request GET
http://www.dropzone.ru/images/stupino/stupino.jpg is ALLOWED, because it
matched 'e
arlnet'
2001/10/17 02:15:01| commHandleWrite: FD 13: write failure: (32) Broken
pipe.
2001/10/17 02:15:01| The request GET
http://counter.rambler.ru/top100.cnt?19770 is ALLOWED, because it
matched 'earlnet'
2001/10/17 02:15:01| storeCreate: Selected dir '0' for obj size '3218'
2001/10/17 02:15:01| fwdServerClosed: FD 14
http://www.dropzone.ru/_s/logo_dz_med.gif
2001/10/17 02:15:02| clientReadRequest: FD 17: no data to process ((11)
Resource temporarily unavailable)
2001/10/17 02:15:02| storeCreate: Selected dir '0' for obj size '452'
2001/10/17 02:15:02| fwdServerClosed: FD 18
http://www.dropzone.ru/_s/dot_empty.gif
2001/10/17 02:15:02| clientReadRequest: FD 17: (104) Connection reset by
peer
2001/10/17 02:15:02| The request GET
http://www.dropzone.ru/images/powerlogo.gif is ALLOWED, because it
matched 'earlnet
'
2001/10/17 02:15:02| The request GET
http://www.dropzone.ru/images/hosted_by.gif is ALLOWED, because it
matched 'earlnet
'
2001/10/17 02:15:02| The request GET
http://cnt.vvv.ru/cgi-bin/cnt?id=144 is ALLOWED, because it matched
'earlnet'
2001/10/17 02:15:02| commHandleWrite: FD 19: write failure: (32) Broken
pipe.
2001/10/17 02:15:02| fwdAbort:
http://www.dropzone.ru/images/stupino/stupino.jpg
2001/10/17 02:15:03| clientReadRequest: FD 14: no data to process ((11)
Resource temporarily unavailable)
2001/10/17 02:15:03| fwdServerClosed: FD 17
http://www.dropzone.ru/images/powerlogo.gif
2001/10/17 02:15:03| The request GET
http://u022.22.spylog.com/cnt?p=0&rn=0.4759337730196914&c=1&wh=1152x864&
px=24&j=Y&s
l=1.3&r=&fr=0&pg=http%3A//www.dropzone.ru/ is ALLOWED, because it
matched 'earlnet'
2001/10/17 02:15:04| fwdServerClosed: FD 24
http://cnt.vvv.ru/cgi-bin/cnt?id=144
2001/10/17 02:15:04| fwdServerClosed: FD 13
http://counter.rambler.ru/top100.cnt?19770
2001/10/17 02:15:04| The request GET
http://www.linkexchange.ru/cgi-bin/erle.cgi?2795?1 is ALLOWED, because
it matched '
earlnet'
2001/10/17 02:15:04| clientReadRequest: FD 18: no data to process ((11)
Resource temporarily unavailable)
2001/10/17 02:15:04| fwdAbort:
http://www.dropzone.ru/images/hosted_by.gif
2001/10/17 02:15:04| The request GET
http://cnt.one.ru/cgi-bin/cnt.cgi?id=73620&t=1 is ALLOWED, because it
matched 'earl
net'
2001/10/17 02:15:04| The request GET
http://www.dropzone.ru/images/apache_pb.gif is ALLOWED, because it
matched 'earlnet
'
2001/10/17 02:15:05| clientReadRequest: FD 18: no data to process ((11)
Resource temporarily unavailable)
2001/10/17 02:15:05| fwdServerClosed: FD 22
http://www.dropzone.ru/images/apache_pb.gif
2001/10/17 02:15:05| storeCreate: Selected dir '0' for obj size '444'
2001/10/17 02:15:05| fwdServerClosed: FD 16
http://www.dropzone.ru/_s/dot_black.gif
2001/10/17 02:15:05| clientReadRequest: FD 14: no data to process ((11)
Resource temporarily unavailable)
2001/10/17 02:15:05| fwdServerClosed: FD 17
http://u022.22.spylog.com/cnt?p=0&rn=0.4759337730196914&c=1&wh=1152x864&
px=2
4&j=Y&sl=1.3&r=&fr=0&pg=http%3A//www.dropzone.ru/
2001/10/17 02:15:09| fwdServerClosed: FD 21
http://cnt.one.ru/cgi-bin/cnt.cgi?id=73620&t=1
2001/10/17 02:15:09| The request GET
http://217.170.71.61/users/004/004236/004236-178.gif is ALLOWED, because
it matched
 'earlnet'
2001/10/17 02:15:10| fwdServerClosed: FD 19
http://www.linkexchange.ru/cgi-bin/erle.cgi?2795?1
2001/10/17 02:15:11| fwdServerClosed: FD 15
http://217.170.71.61/users/004/004236/004236-178.gif
2001/10/17 02:15:11| clientReadRequest: FD 18: no data to process ((11)
Resource temporarily unavailable)
2001/10/17 02:15:48| The request GET
http://www.dropzone.ru/guestbook.shtml is ALLOWED, because it matched
'earlnet'
2001/10/17 02:15:50| The request GET
http://u022.22.spylog.com/cnt?p=1&rn=0.05679123829377347&r=http%3A//www.
dropzone.ru
/&pg=http%3A//www.dropzone.ru/guestbook.shtml is ALLOWED, because it
matched 'earlnet'
2001/10/17 02:15:50| The request GET
http://www.dropzone.com/graphics/topsites.gif is ALLOWED, because it
matched 'earln
et'
2001/10/17 02:15:50| The request GET
http://mmedia.ozon.ru/graphics/img_ass/468/ozon_bibl2.gif is ALLOWED,
because it ma
tched 'earlnet'
2001/10/17 02:15:50| clientReadRequest: FD 15: no data to process ((11)
Resource temporarily unavailable)
2001/10/17 02:15:50| fwdServerClosed: FD 16
http://u022.22.spylog.com/cnt?p=1&rn=0.05679123829377347&r=http%3A//www.
drop
zone.ru/&pg=http%3A//www.dropzone.ru/guestbook.shtml
2001/10/17 02:15:55| commHandleWrite: FD 18: write failure: (32) Broken
pipe.
2001/10/17 02:15:55| storeCreate: Selected dir '0' for obj size '-1'
2001/10/17 02:15:56| fwdServerClosed: FD 20
http://mmedia.ozon.ru/graphics/img_ass/468/ozon_bibl2.gif
2001/10/17 02:16:02| clientReadRequest: FD 17: no data to process ((11)
Resource temporarily unavailable)
2001/10/17 02:26:07| clientReadRequest: FD 14: (104) Connection reset by
peer
2001/10/17 02:26:07| fwdAbort: http://www.dropzone.ru/guestbook.shtml

Browser "freezes" when downloading url
http://www.dropzone.ru/guestbook.shtml which is Kb text (html). Here I
press escape in browser (that`s why fwdAbort and Connection reset by
peer I think). And I see that before "commHandleWrite: FD 18: write
failure: (32) Broken pipe" was some "clientReadRequest: FD 18: no data
to process ((11) Resource temporarily unavailable)" messages (with same
FD).
Do you have some ideas about "freeze" problemm? (I wouldn't really care
about error messages if they are not related to this problemm, but think
they are)

Sincerely,
Elman Efendiyev
elman@earl.kharkov.ua
Received on Wed Oct 17 2001 - 04:26:27 MDT