[squid-users] Squid process dies abnormally

From: Adeoye Oke <adeoye@dont-contact.us>
Date: Wed, 11 Feb 2004 07:56:04 +0100

Hello List,

My squid server runs on a PC with a P4 1.8Ghz machine with 512MB of
SDRAM and has a separate 40GB IDE 7200 rpm Hard drive of which only 20GB
for cache, and an additional 2GB on the main hard drive. It runs RH
Linux 9. I observed that it just stopped responding to requests, and
upon inspection of the cache logs this is what I found

 
------------------------------------------------------------------------
---------------------------------------------------------
2004/02/10 18:09:08| Starting Squid Cache version 2.5.STABLE3 for
i686-pc-linux-gnu...
2004/02/10 18:09:08| Process ID 860
2004/02/10 18:09:08| With 8192 file descriptors available
2004/02/10 18:09:08| DNS Socket created at 0.0.0.0, port 1026, FD 4
2004/02/10 18:09:08| Adding nameserver 66.178.2.25 from /etc/resolv.conf
2004/02/10 18:09:08| Adding nameserver 192.115.72.154 from
/etc/resolv.conf
2004/02/10 18:09:08| Adding nameserver 192.115.72.170 from
/etc/resolv.conf
2004/02/10 18:09:08| Unlinkd pipe opened on FD 9
2004/02/10 18:09:08| Swap maxSize 22528000 KB, estimated 1732923 objects
2004/02/10 18:09:08| Target number of buckets: 86646
2004/02/10 18:09:08| Using 131072 Store buckets
2004/02/10 18:09:08| Max Mem size: 65536 KB
2004/02/10 18:09:08| Max Swap size: 22528000 KB
2004/02/10 18:09:08| Store logging disabled
2004/02/10 18:09:08| Rebuilding storage in /cache (DIRTY)
2004/02/10 18:09:08| Rebuilding storage in /cache2 (DIRTY)
2004/02/10 18:09:08| Using Least Load store dir selection
2004/02/10 18:09:08| Set Current Directory to /var/spool/squid
2004/02/10 18:09:08| Loaded Icons.
2004/02/10 18:09:08| Accepting HTTP connections at 0.0.0.0, port 3128,
FD 11.
2004/02/10 18:09:08| Accepting ICP messages at 0.0.0.0, port 3130, FD
12.
2004/02/10 18:09:08| WCCP Disabled.
2004/02/10 18:09:08| Configuring Parent 148.223.251.99/80/7
2004/02/10 18:09:08| Configuring Parent 213.154.72.162/80/7
2004/02/10 18:09:08| Configuring Parent 216.43.106.33/80/7
2004/02/10 18:09:08| Configuring Parent 65.117.182.225/80/7
2004/02/10 18:09:08| Configuring Parent 213.212.201.99/80/7
2004/02/10 18:09:08| Configuring Parent 200.61.183.162/80/7
2004/02/10 18:09:08| Configuring Parent 218.188.8.182/80/7
2004/02/10 18:09:08| Configuring Parent 200.189.75.236/8080/7
2004/02/10 18:09:08| Ready to serve requests.
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeAufsDirRebuildFromDirectory: failed to get
meta data
2004/02/10 18:09:08| storeSwapMetaUnpack: bad type (-87)!
FATAL: Received Segment Violation...dying.
2004/02/10 18:09:08| Not currently OK to rewrite swap log.
2004/02/10 18:09:08| storeDirWriteCleanLogs: Operation aborted.
CPU Usage: 0.080 seconds = 0.010 user + 0.070 sys
Maximum Resident Size: 0 KB
Page faults with physical i/o: 485
Memory usage for squid via mallinfo():
 total space in arena: 1900 KB
 Ordinary blocks: 1899 KB 2 blks
 Small blocks: 0 KB 0 blks
 Holding blocks: 2088 KB 2 blks
 Free Small blocks: 0 KB
 Free Ordinary blocks: 0 KB
 Total in use: 3987 KB 210%
 Total free: 0 KB 0%
------------------------------------------------------------------------
-----------------

Subsequently it kept giving the same error anytime I tried restarting it

------------------------------------------------------------------------
-----------------
2004/02/10 22:14:08| Starting Squid Cache version 2.5.STABLE3 for
i686-pc-linux-gnu...
2004/02/10 22:14:08| Process ID 1392
2004/02/10 22:14:08| With 8192 file descriptors available
2004/02/10 22:14:08| DNS Socket created at 0.0.0.0, port 1029, FD 4
2004/02/10 22:14:08| Adding nameserver 66.178.2.25 from /etc/resolv.conf
2004/02/10 22:14:08| Adding nameserver 192.115.72.154 from
/etc/resolv.conf
2004/02/10 22:14:08| Adding nameserver 192.115.72.170 from
/etc/resolv.conf
2004/02/10 22:14:08| Unlinkd pipe opened on FD 9
2004/02/10 22:14:08| Swap maxSize 22528000 KB, estimated 1732923 objects
2004/02/10 22:14:08| Target number of buckets: 86646
2004/02/10 22:14:08| Using 131072 Store buckets
2004/02/10 22:14:08| Max Mem size: 65536 KB
2004/02/10 22:14:08| Max Swap size: 22528000 KB
2004/02/10 22:14:08| Store logging disabled
2004/02/10 22:14:08| Rebuilding storage in /cache (DIRTY)
2004/02/10 22:14:08| Rebuilding storage in /cache2 (DIRTY)
2004/02/10 22:14:08| Using Least Load store dir selection
2004/02/10 22:14:08| Set Current Directory to /var/spool/squid
2004/02/10 22:14:08| Loaded Icons.
2004/02/10 22:14:09| Accepting HTTP connections at 0.0.0.0, port 3128,
FD 11.
2004/02/10 22:14:09| Accepting ICP messages at 0.0.0.0, port 3130, FD
12.
2004/02/10 22:14:09| WCCP Disabled.
2004/02/10 22:14:09| Configuring Parent 148.223.251.99/80/7
2004/02/10 22:14:09| Configuring Parent 213.154.72.162/80/7
2004/02/10 22:14:09| Configuring Parent 216.43.106.33/80/7
2004/02/10 22:14:09| Configuring Parent 65.117.182.225/80/7
2004/02/10 22:14:09| Configuring Parent 213.212.201.99/80/7
2004/02/10 22:14:09| Configuring Parent 200.61.183.162/80/7
2004/02/10 22:14:09| Configuring Parent 218.188.8.182/80/7
2004/02/10 22:14:09| Configuring Parent 200.189.75.236/8080/7
2004/02/10 22:14:09| Ready to serve requests.
2004/02/10 22:14:09| storeSwapMetaUnpack: bad type (-87)!
FATAL: Received Segment Violation...dying.
2004/02/10 22:14:09| Not currently OK to rewrite swap log.
2004/02/10 22:14:09| storeDirWriteCleanLogs: Operation aborted.
CPU Usage: 0.070 seconds = 0.030 user + 0.040 sys
Maximum Resident Size: 0 KB
Page faults with physical i/o: 481
Memory usage for squid via mallinfo():
 total space in arena: 1892 KB
 Ordinary blocks: 1888 KB 1 blks
 Small blocks: 0 KB 3 blks
 Holding blocks: 2088 KB 2 blks
 Free Small blocks: 0 KB
 Free Ordinary blocks: 3 KB
 Total in use: 3976 KB 210%
 Total free: 3 KB 0%
------------------------------------------------------------------------
-----------------

I then cleared out the cache and rebuilt it all over and the problem
cleared up. Pls could anyone tell me what could have caused this, as I
would like to avoid such occurrences in the future. I know it has
something to do with the cache store, but what exactly is it?

------------------------------------------------------------------------
-------------------
2004/02/11 07:17:42| Starting Squid Cache version 2.5.STABLE3 for
i686-pc-linux-gnu...
2004/02/11 07:17:42| Process ID 2158
2004/02/11 07:17:42| With 8192 file descriptors available
2004/02/11 07:17:42| DNS Socket created at 0.0.0.0, port 1030, FD 4
2004/02/11 07:17:42| Adding nameserver 66.178.2.25 from /etc/resolv.conf
2004/02/11 07:17:42| Adding nameserver 192.115.72.154 from
/etc/resolv.conf
2004/02/11 07:17:42| Adding nameserver 192.115.72.170 from
/etc/resolv.conf
2004/02/11 07:17:42| Unlinkd pipe opened on FD 9
2004/02/11 07:17:42| Swap maxSize 22528000 KB, estimated 1732923 objects
2004/02/11 07:17:42| Target number of buckets: 86646
2004/02/11 07:17:42| Using 131072 Store buckets
2004/02/11 07:17:42| Max Mem size: 65536 KB
2004/02/11 07:17:42| Max Swap size: 22528000 KB
2004/02/11 07:17:42| Store logging disabled
2004/02/11 07:17:42| Rebuilding storage in /cache (DIRTY)
2004/02/11 07:17:42| Rebuilding storage in /cache2 (DIRTY)
2004/02/11 07:17:42| Using Least Load store dir selection
2004/02/11 07:17:42| Set Current Directory to /var/spool/squid
2004/02/11 07:17:42| Loaded Icons.
2004/02/11 07:17:42| Accepting HTTP connections at 0.0.0.0, port 3128,
FD 10.
2004/02/11 07:17:42| Accepting ICP messages at 0.0.0.0, port 3130, FD
11.
2004/02/11 07:17:42| WCCP Disabled.
2004/02/11 07:17:42| Configuring Parent 148.223.251.99/80/7
2004/02/11 07:17:42| Configuring Parent 213.154.72.162/80/7
2004/02/11 07:17:42| Configuring Parent 216.43.106.33/80/7
2004/02/11 07:17:42| Configuring Parent 65.117.182.225/80/7
2004/02/11 07:17:42| Configuring Parent 213.212.201.99/80/7
2004/02/11 07:17:42| Configuring Parent 200.61.183.162/80/7
2004/02/11 07:17:42| Configuring Parent 218.188.8.182/80/7
2004/02/11 07:17:42| Configuring Parent 200.189.75.236/8080/7
2004/02/11 07:17:42| Ready to serve requests.
2004/02/11 07:17:42| Done scanning /cache swaplog (0 entries)
2004/02/11 07:17:42| Done scanning /cache2 swaplog (0 entries)
2004/02/11 07:17:42| Finished rebuilding storage from disk.
2004/02/11 07:17:42| 0 Entries scanned
2004/02/11 07:17:42| 0 Invalid entries.
2004/02/11 07:17:42| 0 With invalid flags.
2004/02/11 07:17:42| 0 Objects loaded.
2004/02/11 07:17:42| 0 Objects expired.
2004/02/11 07:17:42| 0 Objects cancelled.
2004/02/11 07:17:42| 0 Duplicate URLs purged.
2004/02/11 07:17:42| 0 Swapfile clashes avoided.
2004/02/11 07:17:42| Took 0.7 seconds ( 0.0 objects/sec).
2004/02/11 07:17:42| Beginning Validation Procedure
2004/02/11 07:17:42| Completed Validation Procedure
2004/02/11 07:17:42| Validated 0 Entries
2004/02/11 07:17:42| store_swap_size = 0k
2004/02/11 07:17:43| storeLateRelease: released 0 objects
------------------------------------------------------------------------
---------
Pls all your input will be highly appreciated. Thank you in advance.

Best Regards,
Adeoye Oke
Koochi Communications,
24B A.J. Marinho Drive,
Victoria Island,
Lagos, Nigeria.
Tel: +(234)14619151,14619152
Mob: +(234)803-3337330
Web: http://www.koochicommunications.com
Received on Tue Feb 10 2004 - 23:57:39 MST

This archive was generated by hypermail pre-2.1.9 : Mon Mar 01 2004 - 12:00:02 MST