[squid-users] squid restarts itself

From: <wh_at_msdrd.com>
Date: Fri, 02 Jan 2009 13:18:47 -0700

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.

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 Fri Jan 02 2009 - 20:18:56 MST

This archive was generated by hypermail 2.2.0 : Sun Jan 04 2009 - 12:00:02 MST