strange behaviour?

From: Holger Reif <Holger.Reif@dont-contact.us>
Date: Thu, 19 Feb 1998 23:38:39 +0100

Hi,

I'm unable to get a lot of stuff out of the / through the
cache. I'm running 1.18 but the problem wasn't mentioned in
the changlog for 1.19 or 1.20 thus I think an upgrade without
understanding the problem doen't help anything.

Lets take a sample URL and look what happens. The URL is

        http://ilmenau.futurenet.de/img/werbung/ami.gif

First, I can stat this connecting directly to the server:
/--------------------------------\
ilmenau:/etc # telnet ilmenau 80
Trying 195.145.101.248...
Connected to ilmenau.futurenet.de.
Escape character is '^]'.
HEAD http://ilmenau.futurenet.de/img/werbung/ami.gif HTTP/1.0
 
HTTP/1.1 200 OK
Date: Thu, 19 Feb 1998 21:52:26 GMT
Server: Apache/1.2.5
Last-Modified: Thu, 12 Feb 1998 11:26:44 GMT
ETag: "28bba-eac-34e2dc74"
Content-Length: 3756
Accept-Ranges: bytes
Connection: close
Content-Type: image/gif
 
Connection closed by foreign host.
ilmenau:/etc #
\-------------------------------/

If I try connecting via the squid - no luck!
/--------------------------------\
ilmenau:/etc # telnet ilmenau 8080
Trying 195.145.101.248...
Connected to ilmenau.futurenet.de.
Escape character is '^]'.
HEAD http://ilmenau.futurenet.de/img/werbung/ami.gif HTTP/1.0
 
HTTP/1.0 404 Not found
Date: Thu, 19 Feb 1998 21:56:03 GMT
Server: Apache/1.0.0
Content-type: text/html
 
Connection closed by foreign host.
\-------------------------------/
What's rather strange, it even reports that the server would
have been an 1.0.0 Apache and the MIME is text/html!

Here is what the access.log says:
/--------------------------------\
887925255.177 6222 195.145.101.248 TCP_MISS/404 110 HEAD http://ilmenau.futurenet.de/img/werbung/ami.gif - DIRECT/ilmenau.futurenet.de text/html
\-------------------------------/

It's consistent with what the result is.

It seems to me squid got somehow confused where his files are (cf. wrong header)?

But what really strange come is the following:
/--------------------------------\
887923811.692 37054 195.145.101.248 TCP_MISS/200 240 HEAD http://ilmenau.futurenet.de/img/werbung/ami.gif - DIRECT/ilmenau.futurenet.de image/gif
887923873.109 1414 141.24.32.26 TCP_MISS/404 263 GET http://ilmenau.futurenet.de/img/werbung/ami.gif - DIRECT/ilmenau.futurenet.de text/html
887924852.247 1700 141.24.32.26 TCP_MISS/404 263 GET http://ilmenau.futurenet.de/img/werbung/ami.gif - DIRECT/ilmenau.futurenet.de text/html
887925255.177 6222 195.145.101.248 TCP_MISS/404 110 HEAD http://ilmenau.futurenet.de/img/werbung/ami.gif - DIRECT/ilmenau.futurenet.de text/html
\-------------------------------/

It seems, I was during the testing time able to get a valid
response for a HEAD request!!! Before: errors, after errors.

The squid itself never asked during the testing time the web-server.

I searched a bit through the whole log files and occacionally found
the webserve rwhich was queuried for the URL (fotunately one I control too)

Instead of ilmenau.futurenet.de squid asked jane.prakinf.tu-ilmenau.de!!!

Is there something wrong with the IP cache of squid??? The DNS of the
maschine was up w/o any problems.

BTW squid was able to retrieve the image (by chance?) in the meantime
once, but failed to reload it (first the correct server and shortly after
the wrong server was asked.)
/--------------------------------\
887926808.425 217 141.24.32.26 TCP_MISS/200 3996 GET http://ilmenau.futurenet.de/img/werbung/ami.gif - DIRECT/ilmenau.futurenet.de image/gif
887927174.972 573 141.24.32.26 TCP_CLIENT_REFRESH/404 263 GET http://ilmenau.futurenet.de/img/werbung/ami.gif - DIRECT/ilmenau.futurenet.de text/html
\--------------------------------/

Just for the record, here are the latest lines from store.log
/--------------------------------\
887925253.516 RELEASE 404 887924960 -2 -2 text/html 0/153 GET http://ilmenau.futurenet.de/img/werbung/ami.gif
887925255.177 RELEASE 404 887925363 -2 -2 text/html 0/0 HEAD http://ilmenau.futurenet.de/img/werbung/ami.gif
887926808.451 SWAPOUT 200 887926808 887282804 -2 image/gif 3756/3756 GET http://ilmenau.futurenet.de/img/werbung/ami.gif
887927174.967 RELEASE 200 887926808 887282804 -2 image/gif 3756/3756 GET http://ilmenau.futurenet.de/img/werbung/ami.gif
\--------------------------------/

The cache size is left default, the usage is with 21 Meg for the
whole cache rather low. I only changed (besides acl) the following:

        cache_mem 8
        cache_swap 300

I'm totally confused and I'm looking for advice where to go now.
I know there is a debug option, but I don't have an idea what
should be turned on...

Any advice or ideas?

-- 
read you later  -  Holger Reif
----------------------------------------  Signaturprojekt Deutsche Einheit
TU Ilmenau - Informatik - Telematik                      (Verdamp lang her)
Holger.Reif@PrakInf.TU-Ilmenau.DE         Alt wie ein Baum werden, um ueber
http://Remus.PrakInf.TU-Ilmenau.DE/Reif/  alle 7 Bruecken gehen zu koennen
Received on Thu Feb 19 1998 - 14:54:21 MST

This archive was generated by hypermail pre-2.1.9 : Tue Dec 09 2003 - 16:38:55 MST