Re: [squid-users] squid restarts itself

> Hello again and Happy New Year to all.
>
> Today I decided to review the cache.log file to see how things were
> running after receiving some complaints from users that there hasnt'
> been Internet a couple of times. I noticed that squid is restarting
> itself every once in a while. I dont know what' going on with squid or
> my configuration but, I'm getting a lot of errors and is not working
> properly. Please help me figure out what's wrong. Thank you in advanced
> for your help.

Correct your squid is crashing. The symptom has been shown with several
bugs, some of which are fixed by the most recent Squid 3.0 releases.
Please try an upgrade and see if the problem persists.

Amos

>
> Heres my squid.conf file:
> # Port Squid listens on
> http_port 192.168.2.1:3128 transparent
>
> # Access-lists (ACLs) will permit or deny hosts to access the proxy
> #acl lan-access src 192.168.1.0/255.255.255.0
> acl lan-access src 192.168.2.0/255.255.255.0
> acl localhost src 127.0.0.1
> acl all src 0.0.0.0/0.0.0.0
>
>
> # Access rule
> http_access allow localhost
> http_access allow lan-access
> http_access deny all
>
> maximum_object_size 100 MB
>
> cache_mem 100 MB
>
> access_log /var/log/squid/access.log
> cache_log /var/log/squid/cache.log
> cache_store_log /var/log/squid/store.log
>
> cache_dir ufs /var/log/squid/cache 100000 255 255
>
> htcp_port 0
> icp_port 0
>
> extension_methods SEARCH NICK
>
> ---------------------------------------------------------------------
>
> Now, here's part of the cache.log file:
>
> 2008/12/19 12:32:53| Done reading /var/log/squid/cache swaplog (426776
> entries)
> 2008/12/19 12:32:53| Finished rebuilding storage from disk.
> 2008/12/19 12:32:53| 419973 Entries scanned
> 2008/12/19 12:32:53| 0 Invalid entries.
> 2008/12/19 12:32:53| 0 With invalid flags.
> 2008/12/19 12:32:53| 418997 Objects loaded.
> 2008/12/19 12:32:53| 0 Objects expired.
> 2008/12/19 12:32:53| 640 Objects cancelled.
> 2008/12/19 12:32:53| 5918 Duplicate URLs purged.
> 2008/12/19 12:32:53| 192 Swapfile clashes avoided.
> 2008/12/19 12:32:53| Took 46.1 seconds (9096.9 objects/sec).
> 2008/12/19 12:32:53| Beginning Validation Procedure
> 2008/12/19 12:32:54| 262144 Entries Validated so far.
> 2008/12/19 12:32:54| storeLateRelease: released 45 objects
> 2008/12/19 12:32:56| Completed Validation Procedure
> 2008/12/19 12:32:56| Validated 826549 Entries
> 2008/12/19 12:32:56| store_swap_size = 24545080
> 2008/12/19 12:49:23| clientParseRequestMethod: Unsupported method in
> request '^C'
> 2008/12/19 12:49:23| clientProcessRequest: Invalid Request
> 2008/12/19 12:53:19| WARNING: unparseable HTTP header field {POST
> /mortalfm/ HTTP/1.0}
> 2008/12/19 13:01:32| WARNING: 1 swapin MD5 mismatches
> 2008/12/19 13:08:19| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:08:35| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:08:55| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:09:23| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:09:36| clientNatLookup: NF getsockopt(SO_ORIGINAL_DST)
> failed: (2) No such file or directory
> 2008/12/19 13:09:44| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:10:00| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:10:37| clientNatLookup: NF getsockopt(SO_ORIGINAL_DST)
> failed: (2) No such file or directory
> 2008/12/19 13:10:37| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:11:02| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:11:18| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:11:34| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:11:50| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:12:06| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:12:22| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:12:38| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:12:54| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:13:22| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:13:38| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:13:55| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:14:13| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:14:36| clientNatLookup: NF getsockopt(SO_ORIGINAL_DST)
> failed: (2) No such file or directory
> 2008/12/19 13:14:36| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:14:52| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:15:23| client_side.cc(2699) WARNING! Your cache is running
> out of filedescriptors
> 2008/12/19 13:33:35| clientParseRequestMethod: Unsupported method in
> request '^C'
> 2008/12/19 13:33:35| clientProcessRequest: Invalid Request
> 2008/12/19 14:53:30| clientParseRequestMethod: Unsupported method in
> request '^C'
> 2008/12/19 14:53:30| clientProcessRequest: Invalid Request
> 2008/12/19 15:56:37| WARNING: 10 swapin MD5 mismatches
> 2008/12/19 16:07:48| clientParseRequestMethod: Unsupported method in
> request '^C'
> 2008/12/19 16:07:48| clientProcessRequest: Invalid Request
>
> .......
>
> 2008/12/19 20:40:55| Accepting transparently proxied HTTP connections at
> 192.168.2.1, port 3128, FD 15.
> 2008/12/19 20:40:55| HTCP Disabled.
> 2008/12/19 20:40:55| WCCP Disabled.
> 2008/12/19 20:40:55| Ready to serve requests.
> 2008/12/19 20:40:56| Store rebuilding is 0.9% complete
> 2008/12/19 20:41:10| WARNING: newer swaplog entry for dirno 0, fileno
> 00000107
> 2008/12/19 20:41:10| WARNING: newer swaplog entry for dirno 0, fileno
> 0000010B
> 2008/12/19 20:41:11| Store rebuilding is 20.4% complete
> 2008/12/19 20:41:12| WARNING: newer swaplog entry for dirno 0, fileno
> 00000120
> 2008/12/19 20:41:15| WARNING: newer swaplog entry for dirno 0, fileno
> 00000133
> 2008/12/19 20:41:15| WARNING: newer swaplog entry for dirno 0, fileno
> 00000136
> 2008/12/19 20:41:16| WARNING: newer swaplog entry for dirno 0, fileno
> 00000138
> 2008/12/19 20:41:16| WARNING: newer swaplog entry for dirno 0, fileno
> 00000139
> 2008/12/19 20:41:16| WARNING: newer swaplog entry for dirno 0, fileno
> 0000013A
> 2008/12/19 20:41:16| WARNING: newer swaplog entry for dirno 0, fileno
> 0000013B
> 2008/12/19 20:41:17| WARNING: newer swaplog entry for dirno 0, fileno
> 0000013F
> 2008/12/19 20:41:19| WARNING: newer swaplog entry for dirno 0, fileno
> 00000166
> 2008/12/19 20:41:20| WARNING: newer swaplog entry for dirno 0, fileno
> 000001AC
> 2008/12/19 20:41:20| WARNING: newer swaplog entry for dirno 0, fileno
> 0000018C
> 2008/12/19 20:41:20| WARNING: newer swaplog entry for dirno 0, fileno
> 000001BF
> 2008/12/19 20:41:21| WARNING: newer swaplog entry for dirno 0, fileno
> 00000225
> 2008/12/19 20:41:21| WARNING: newer swaplog entry for dirno 0, fileno
> 00000227
> 2008/12/19 20:41:21| WARNING: newer swaplog entry for dirno 0, fileno
> 00000254
> 2008/12/19 20:41:22| WARNING: newer swaplog entry for dirno 0, fileno
> 0000026C
> 2008/12/19 20:41:22| WARNING: newer swaplog entry for dirno 0, fileno
> 00000271
> 2008/12/19 20:41:24| WARNING: newer swaplog entry for dirno 0, fileno
> 00000277
> 2008/12/19 20:41:26| Store rebuilding is 46.4% complete
> 2008/12/19 20:41:29| WARNING: newer swaplog entry for dirno 0, fileno
> 000002A7
> 2008/12/19 20:41:29| WARNING: newer swaplog entry for dirno 0, fileno
> 000002A9
> 2008/12/19 20:41:29| WARNING: newer swaplog entry for dirno 0, fileno
> 000002AB
> 2008/12/19 20:41:29| WARNING: newer swaplog entry for dirno 0, fileno
> 000002CF
> 2008/12/19 20:41:30| WARNING: newer swaplog entry for dirno 0, fileno
> 00000399
> 2008/12/19 20:41:31| WARNING: newer swaplog entry for dirno 0, fileno
> 000003A0
> 2008/12/19 20:41:31| WARNING: newer swaplog entry for dirno 0, fileno
> 000003A2
> 2008/12/19 20:41:31| WARNING: newer swaplog entry for dirno 0, fileno
> 000003AE
> 2008/12/19 20:41:31| WARNING: newer swaplog entry for dirno 0, fileno
> 000003AF
> 2008/12/19 20:41:31| WARNING: newer swaplog entry for dirno 0, fileno
> 00000447
> 2008/12/19 20:41:32| WARNING: newer swaplog entry for dirno 0, fileno
> 00000449
> 2008/12/19 20:41:32| WARNING: newer swaplog entry for dirno 0, fileno
> 0000044A
> 2008/12/19 20:41:32| WARNING: newer swaplog entry for dirno 0, fileno
> 00000452
> 2008/12/19 20:41:32| WARNING: newer swaplog entry for dirno 0, fileno
> 0000045B
> 2008/12/19 20:41:32| WARNING: newer swaplog entry for dirno 0, fileno
> 0000048B
> 2008/12/19 20:41:35| WARNING: newer swaplog entry for dirno 0, fileno
> 000004B8
> 2008/12/19 20:41:35| WARNING: newer swaplog entry for dirno 0, fileno
> 000004BA
> 2008/12/19 20:41:36| WARNING: newer swaplog entry for dirno 0, fileno
> 000004B6
> 2008/12/19 20:41:41| WARNING: newer swaplog entry for dirno 0, fileno
> 000004D3
> 2008/12/19 20:41:41| Store rebuilding is 69.5% complete
> 2008/12/19 20:41:41| WARNING: newer swaplog entry for dirno 0, fileno
> 000004D7
>
>
> ........
>
> 2008/12/27 00:23:51| HttpStateData::cacheableReply: unknown http status
> code in reply
> 2008/12/27 00:23:51| ctx: exit levels from 1 down to 0
> 2008/12/27 00:23:51| clientParseRequestMethod: Unsupported method in
> request ''
> 2008/12/27 00:23:51| clientProcessRequest: Invalid Request
> 2008/12/27 00:23:51| ctx: enter level 0:
> 'http://download.romhustler.net/download/ef057be4314d88f0462d97191e00cf1c/4955b70c/neogeo/ncommand.zip'
> 2008/12/27 00:23:51| ctx: enter level 1:
> 'http://download.romhustler.net/download/ef057be4314d88f0462d97191e00cf1c/4955b70c/neogeo/ncommand.zip'
> 2008/12/27 00:23:51| HttpStateData::cacheableReply: unknown http status
> code in reply
> 2008/12/27 00:23:51| ctx: exit levels from 1 down to 0
> 2008/12/27 00:23:51| clientParseRequestMethod: Unsupported method in
> request ''
> 2008/12/27 00:23:51| clientProcessRequest: Invalid Request
> 2008/12/27 00:24:27| clientParseRequestMethod: Unsupported method in
> request ''
> 2008/12/27 00:24:27| clientProcessRequest: Invalid Request
> 2008/12/27 00:24:58| clientParseRequestMethod: Unsupported method in
> request ''
> 2008/12/27 00:24:58| clientProcessRequest: Invalid Request
> 2008/12/27 00:25:17| clientParseRequestMethod: Unsupported method in
> request ''
> 2008/12/27 00:25:17| clientProcessRequest: Invalid Request
> 2008/12/27 00:25:21| ctx: enter level 0:
> 'http://download.romhustler.net/download/ef057be4314d88f0462d97191e00cf1c/4955b70c/neogeo/ncommand.zip'
> 2008/12/27 00:25:21| ctx: enter level 1:
> 'http://download.romhustler.net/download/ef057be4314d88f0462d97191e00cf1c/4955b70c/neogeo/ncommand.zip'
> 2008/12/27 00:25:21| HttpStateData::cacheableReply: unknown http status
> code in reply
> 2008/12/27 00:25:41| ctx: exit levels from 1 down to 0
> 2008/12/27 00:25:41| clientParseRequestMethod: Unsupported method in
> request ''
> 2008/12/27 00:25:41| clientProcessRequest: Invalid Request
> 2008/12/27 00:26:08| clientParseRequestMethod: Unsupported method in
> request ''
> 2008/12/27 00:26:08| clientProcessRequest: Invalid Request
> 2008/12/27 00:27:07| ctx: enter level 0:
> 'http://download.romhustler.net/download/d4e846c702627bdae3ad17ac153ee4a2/4955b654/neogeo/ninjamas.zip'
> 2008/12/27 00:27:07| ctx: enter level 1:
> 'http://download.romhustler.net/download/d4e846c702627bdae3ad17ac153ee4a2/4955b654/neogeo/ninjamas.zip'
> 2008/12/27 00:27:07| HttpStateData::cacheableReply: unknown http status
> code in reply
> 2008/12/27 00:27:07| ctx: exit levels from 1 down to 0
> 2008/12/27 00:27:07| ctx: enter level 0:
> 'http://download.romhustler.net/download/d4e846c702627bdae3ad17ac153ee4a2/4955b654/neogeo/ninjamas.zip'
> 2008/12/27 00:27:07| ctx: enter level 1:
> 'http://download.romhustler.net/download/d4e846c702627bdae3ad17ac153ee4a2/4955b654/neogeo/ninjamas.zip'
> 2008/12/27 00:27:07| HttpStateData::cacheableReply: unknown http status
> code in reply
> 2008/12/27 00:27:08| ctx: exit levels from 1 down to 0
> 2008/12/27 00:27:08| ctx: enter level 0:
> 'http://download.romhustler.net/download/d4e846c702627bdae3ad17ac153ee4a2/4955b654/neogeo/ninjamas.zip'
> 2008/12/27 00:27:08| ctx: enter level 1:
> 'http://download.romhustler.net/download/d4e846c702627bdae3ad17ac153ee4a2/4955b654/neogeo/ninjamas.zip'
> 2008/12/27 00:27:08| HttpStateData::cacheableReply: unknown http status
> code in reply
> 2008/12/27 00:27:08| ctx: exit levels from 1 down to 0
> 2008/12/27 00:27:08| ctx: enter level 0:
> 'http://download.romhustler.net/download/d4e846c702627bdae3ad17ac153ee4a2/4955b654/neogeo/ninjamas.zip'
> 2008/12/27 00:27:08| ctx: enter level 1:
> 'http://download.romhustler.net/download/d4e846c702627bdae3ad17ac153ee4a2/4955b654/neogeo/ninjamas.zip'
> 2008/12/27 00:27:08| HttpStateData::cacheableReply: unknown http status
> code in reply
> 2008/12/27 00:27:08| ctx: exit levels from 1 down to 0
> 2008/12/27 00:27:08| ctx: enter level 0:
> 'http://download.romhustler.net/download/d4e846c702627bdae3ad17ac153ee4a2/4955b654/neogeo/ninjamas.zip'
> 2008/12/27 00:27:08| ctx: enter level 1:
> 'http://download.romhustler.net/download/d4e846c702627bdae3ad17ac153ee4a2/4955b654/neogeo/ninjamas.zip'
> 2008/12/27 00:27:08| HttpStateData::cacheableReply: unknown http status
> code in reply
> 2008/12/27 00:39:26| ctx: exit levels from 1 down to 0
> 2008/12/27 00:39:26| clientParseRequestMethod: Unsupported method in
> request ''
> 2008/12/27 00:39:26| clientProcessRequest: Invalid Request
>
> .....
>
> 2009/01/02 14:02:05| Done reading /var/log/squid/cache swaplog (1026983
> entries)
> 2009/01/02 14:02:05| Finished rebuilding storage from disk.
> 2009/01/02 14:02:05| 976779 Entries scanned
> 2009/01/02 14:02:05| 0 Invalid entries.
> 2009/01/02 14:02:05| 0 With invalid flags.
> 2009/01/02 14:02:05| 976031 Objects loaded.
> 2009/01/02 14:02:05| 0 Objects expired.
> 2009/01/02 14:02:05| 159 Objects cancelled.
> 2009/01/02 14:02:05| 50001 Duplicate URLs purged.
> 2009/01/02 14:02:05| 177 Swapfile clashes avoided.
> 2009/01/02 14:02:05| Took 92.9 seconds (10506.3 objects/sec).
> 2009/01/02 14:02:05| Beginning Validation Procedure
> 2009/01/02 14:02:06| storeLateRelease: released 116 objects
> 2009/01/02 14:02:08| 524288 Entries Validated so far.
> 2009/01/02 14:02:10| 786432 Entries Validated so far.
> 2009/01/02 14:02:16| Completed Validation Procedure
> 2009/01/02 14:02:16| Validated 1852869 Entries
> 2009/01/02 14:02:16| store_swap_size = 63812816
> 2009/01/02 14:02:20| WARNING: 1 swapin MD5 mismatches
> 2009/01/02 14:02:43| clientParseRequestMethod: Unsupported method in
> request 'HTTP/1.0 200 OK
> Date: Fri, 02 Jan 2009 19:02:42 GMT
> Server: Microsoft-IIS/6.0
> P3P: CP="BUS CUR CONo FIN IVDo ONL OUR PHY SAMo TELo"
> xxn: W5
> MSNSERVER: H: BAY132-W5 V: 13.2.260.1209 D: 2008-12-09T21:13:20
> Cache-Control: no-cache
> Pragma: no-cache
> Expires: -1
> Content-Type: application/x-javascript; charset=utf-8
> Content-Length: 19097
> X-Cache: MISS from localhost
> Via: 1.0 localhost (squid/3.0.PRE5)
> Proxy-Connection: keep-alive
> X-Antivirus: avast! 4
> X-Antivirus-Status: Clean
>
> Thanx.
>
>
>
>
Received on Sun Jan 04 2009 - 09:49:52 MST