Re: [squid-users] Squid is responsing very slowly, when opening page for first time.

From: Jiří Červenka <cervenka@dont-contact.us>
Date: Tue, 09 May 2006 09:59:51 +0200

It apllies for first visit of each server. Response for each server
visited for the first time is slow.

Here is relevant part of access.log

1147160886.726 83 172.16.0.14 TCP_MISS/200 10134 GET
http://www.seznam.cz/ - DIRECT/194.228.32.3 text/html
1147160886.926 4 172.16.0.14 TCP_IMS_HIT/304 294 GET
http://1.im.cz/homepage/img/logo-kveten.gif - NONE/- image/gif
1147160886.942 14 172.16.0.14 TCP_IMS_HIT/304 294 GET
http://1.im.cz/ad/1/5081/48642/264624_0.gif - NONE/- image/gif
1147160886.948 6 172.16.0.14 TCP_IMS_HIT/304 294 GET
http://1.im.cz/homepage/img/pipe.gif - NONE/- image/gif
1147160886.960 16 172.16.0.14 TCP_HIT/200 995 GET
http://1.im.cz/szn/img/h/9.gif - NONE/- image/gif
1147160886.960 13 172.16.0.14 TCP_IMS_HIT/304 294 GET
http://1.im.cz/homepage/img/arr_inet.gif - NONE/- image/gif
1147160886.967 17 172.16.0.14 TCP_IMS_HIT/304 294 GET
http://1.im.cz/homepage/img/note-small-ico.gif - NONE/- image/gif
1147160886.970 50 172.16.0.14 TCP_MISS/200 445 GET
http://www.seznam.cz/et - DIRECT/194.228.32.3 text/plain
1147160886.971 4 172.16.0.14 TCP_IMS_HIT/304 294 GET
http://1.im.cz/homepage/img/rising_down.gif - NONE/- image/gif
1147160886.973 4 172.16.0.14 TCP_IMS_HIT/304 294 GET
http://1.im.cz/homepage/img/weather/PB.gif - NONE/- image/gif
1147160886.973 4 172.16.0.14 TCP_IMS_HIT/304 294 GET
http://1.im.cz/homepage/img/weather/SJ.gif - NONE/- image/gif
1147160886.973 6 172.16.0.14 TCP_IMS_HIT/304 294 GET
http://1.im.cz/homepage/img/hledaci-pes-kveten.gif - NONE/- image/gif
1147160886.982 4 172.16.0.14 TCP_IMS_HIT/304 294 GET
http://1.im.cz/homepage/img/ico_lide.gif - NONE/- image/gif
1147160886.983 5 172.16.0.14 TCP_IMS_HIT/304 294 GET
http://1.im.cz/homepage/img/rising_up.gif - NONE/- image/gif
1147160886.983 5 172.16.0.14 TCP_IMS_HIT/304 294 GET
http://1.im.cz/homepage/img/bck_lines.gif - NONE/- image/gif
1147160886.986 6 172.16.0.14 TCP_IMS_HIT/304 294 GET
http://1.im.cz/homepage/img/sport.gif - NONE/- image/gif
1147160886.990 3 172.16.0.14 TCP_IMS_HIT/304 294 GET
http://1.im.cz/homepage/img/bck_line.gif - NONE/- image/gif
1147160886.993 5 172.16.0.14 TCP_IMS_HIT/304 294 GET
http://1.im.cz/homepage/img/ico_rss.gif - NONE/- image/gif
1147160887.016 36 172.16.0.14 TCP_MISS/200 514 GET
http://ad.seznam.cz/miss? - DIRECT/212.80.76.72 image/gif
1147160887.025 32 172.16.0.14 TCP_MISS/200 514 GET
http://ad.seznam.cz/impress? - DIRECT/212.80.76.72 image/gif
1147160887.043 15 172.16.0.14 TCP_MEM_HIT/200 1257 GET
http://seznam.hit.gemius.pl/gemiusaudience.html - NONE/- text/html
1147160887.062 37 172.16.0.14 TCP_MISS/302 524 GET
http://gdecz.hit.gemius.pl/_1147160929764/redot.gif? -
DIRECT/217.11.237.209 text/html
1147160887.080 71 172.16.0.14 TCP_MISS/200 514 GET
http://ad.seznam.cz/miss? - DIRECT/212.80.76.72 image/gif
1147160887.177 68 172.16.0.14 TCP_MISS/200 449 GET
http://gdecz.hit.gemius.pl/_1147160929764/dot.gif? -
DIRECT/217.11.237.209 image/gif
1147160887.191 58 172.16.0.14 TCP_MISS/200 1579 GET
http://seznam.hit.gemius.pl/_1147160930030/reppdot.js? -
DIRECT/212.80.76.201 application/x-javascript

Internal DNS statistic from cachemgr (dns server is bind9 running on
localhost):

Internal DNS Statistics :

The Queue:
                       DELAY SINCE
  ID SIZE SENDS FIRST SEND LAST SEND
------ ---- ----- ---------- ---------
0x53fa 32 1 3.754 3.754

Nameservers:
IP ADDRESS # QUERIES # REPLIES
--------------- --------- ---------
127.0.0.1 437 436

Rcode Matrix:
RCODE ATTEMPT1 ATTEMPT2 ATTEMPT3
    0 51275 1 0
    1 0 0 0
    2 2 1 1
    3 4431 0 0
    4 0 0 0
    5 0 0 0

And maybe this could help:

FQDN Cache Statistics:
FQDNcache Entries: 14
FQDNcache Requests: 2621967
FQDNcache Hits: 580
FQDNcache Negative Hits: 0
FQDNcache Misses: 2621387
Blocking calls to gethostbyaddr(): 0
FQDN Cache Contents:

Address Flg TTL Cnt Hostnames
list of my servers

Ican see no further messages in cache.log.
Thanks for reply.

Jiri

Mark Elsen napsal(a):
>> Hello,
>> I have little "slow response" issue. Squid 2.5.STABLE9, Debian Sarge.
>> When I open web page for the first time, the response from squid is very
>> slow.
>
> Is it only for the first page ?
>
>> It lasts up tu 8 seconds to open desired web page (10 Mbit line, average
>> latency 30-40ms).
>> When I turn cache off, the opening of the same web page lasts less then
>> 1 second.
>> Can someone give advise what to do to speed my squid up.
>>
>
> - Check access.log for this URL, the response time, is one
> of the fields in the access log entry (see FAQ on access log format).
> What is the response time reported in access.log ?
>
> - Is DNS working dequately ? Resolving problems ?
> Check cachemgr -> DNS stats.
>
> - Any futher messages in cache.log ?
>
> M.
>
Received on Tue May 09 2006 - 01:59:34 MDT

This archive was generated by hypermail pre-2.1.9 : Thu Jun 01 2006 - 12:00:02 MDT