[squid-users] random request blocking and request completion times logged in whole seconds ??

From: Joshua Groom <Joshua.Groom@dont-contact.us>
Date: Wed, 18 Dec 2002 19:51:10 +1300

I'm getting two very odd symptoms.

One is that in the squid logs show request completion times almost always in multiples of 1000 milliseconds.

The other is that sometimes a request will be blocked by squid until another request comes in from another client and 'resets' squid. The second request seems to be served before the original request, but moments later the original request is served as well.

Here is a small sample of the logs.

1040188334.520 66000 10.34.14.11 TCP_HIT/000 0 GET http://www.theregister.co.uk/Themes/3/Style.css jwg1 NONE/- -
1040188338.520 1000 10.34.14.11 TCP_MISS/200 3967 GET http://www.google.com/intl/xx-hacker/ jwg1 TIMEOUT_FIRST_UP_PARENT/javascript-parent text/html
1040188342.520 1000 10.34.14.11 TCP_MISS/200 3967 GET http://www.google.com/intl/xx-hacker/ jwg1 FIRST_UP_PARENT/javascript-parent text/html
1040188342.520 0 10.34.14.11 TCP_MISS/304 234 GET http://www.google.com/intl/xx-hacker/images/hp0.gif jwg1 FIRST_UP_PARENT/javascript-parent text/html
1040188343.520 1000 10.34.14.11 TCP_MISS/304 234 GET http://www.google.com/intl/xx-hacker/images/hp1.gif jwg1 FIRST_UP_PARENT/javascript-parent text/html
1040188343.520 1000 10.34.14.11 TCP_MISS/304 234 GET http://www.google.com/intl/xx-hacker/images/hp2.gif jwg1 FIRST_UP_PARENT/javascript-parent text/html
1040188343.520 1000 10.34.14.11 TCP_MISS/304 234 GET http://www.google.com/intl/xx-hacker/images/hp3.gif jwg1 FIRST_UP_PARENT/javascript-parent text/html
1040189337.000 1000 10.34.14.11 TCP_MISS/200 3967 GET http://www.google.com/intl/xx-hacker/ - DEFAULT_PACP_MISS/304 234 GET http://www.google.com/intl/xx-hacker/images/hp2.gif - DEFAULT_PARENT/primary-parent text/html

I am running squid 2.4 STABLE7 on Solaris 9 (64 bit).
Previously I was running 2.2 on Solaris 6 (32 bit)
Before that it was an older version of squid on sol 6 (and I didn't have this problem)

In both the first two the above problems were evident but in the oldest logs I can dredge up (from the 'older' version of squid) the time stamps and request completion times were normal.

Joshua Groom, Systems Programmer
Open Systems - IRD
EMAIL: Joshua.Groom@ird.govt.nz
DDI: 8031479
EXT: 88479
Received on Tue Dec 17 2002 - 23:50:02 MST

This archive was generated by hypermail pre-2.1.9 : Tue Dec 09 2003 - 17:12:07 MST