Major Bug in 1.1betas (rebuild cache)

From: Christian Balzer <cb@dont-contact.us>
Date: Thu, 10 Oct 1996 08:18:30 +0200 (MET DST)

Hi,

I've had this (identical) rather frustating experience before when
I upgraded from 1.1b3 to 1.1b4, but blamed it on me being to hectic
when restarting the new version. Alas now I think we're looking at
real problem, last night around 04:00am the server (Linux 2.0.21) crashed
(at a time with no system/squid activity, btw) and when I rebooted it
this happened:

---
96/10/10 07:14:54| Starting Squid Cache version 1.1.beta5 for i586-unknown-linux...
96/10/10 07:14:54| With 1024 file descriptors available
96/10/10 07:14:54| Performing DNS Tests...
96/10/10 07:14:54| Successful DNS name lookup tests...
96/10/10 07:14:54| dnsOpenServers: Starting 5 'dnsserver' processes
96/10/10 07:14:54| neighbors_init: Initializing Neighbors...
96/10/10 07:14:54| Adding a neighbor: washuu.swb.de/80/3130
96/10/10 07:14:54| file_map_create: creating space for 2097152 files
96/10/10 07:14:54| Rebuilding storage from disk image in /var/spool/cache
96/10/10 07:14:55| Accepting HTTP connections on FD 15.
96/10/10 07:14:55| Accepting ICP connections on FD 16.
96/10/10 07:14:55| Ready to serve requests.
96/10/10 07:16:19|      4096 Lines read so far.
96/10/10 07:16:54|      8192 Lines read so far.
96/10/10 07:17:10|     12288 Lines read so far.
96/10/10 07:17:20|     16384 Lines read so far.
96/10/10 07:18:44|     20480 Lines read so far.
96/10/10 07:20:42|     24576 Lines read so far.
96/10/10 07:23:00|     28672 Lines read so far.
96/10/10 07:26:13|     32768 Lines read so far.
96/10/10 07:29:03|     36864 Lines read so far.
96/10/10 07:29:49|     40960 Lines read so far.
96/10/10 07:30:15|     45056 Lines read so far.
96/10/10 07:30:30|     49152 Lines read so far.
96/10/10 07:30:43|     53248 Lines read so far.
96/10/10 07:30:45| Finished rebuilding storage from disk image.
96/10/10 07:30:45|     53985 Lines read from previous logfile.
96/10/10 07:30:45|     18412 Objects loaded.
96/10/10 07:30:45|         0 Objects expired.
96/10/10 07:30:45|     17721 Duplicate URLs purged.
96/10/10 07:30:45|        46 Swapfile clashes avoided.
96/10/10 07:30:45|   Took 950 seconds (  19.4 objects/sec).
96/10/10 07:30:45|   store_swap_size = 225234k
96/10/10 07:33:26| file_open: error opening file /var/spool/cache/0C/4B/000004BC: (2) No such file or directory
96/10/10 07:33:26| storeSwapInStart: Failed for 'http://www-cache.swb.de/'
96/10/10 07:33:34| file_open: error opening file /var/spool/cache/04/E5/00003E54: (2) No such file or directory
96/10/10 07:33:34| storeSwapInStart: Failed for 'http://www-cache.swb.de/swb/info.html'
96/10/10 07:42:57| file_open: error opening file /var/spool/cache/02/06/00004062: (2) No such fi e or directory
96/10/10 07:42:57| storeSwapInStart: Failed for 'http://www-cache.swb.de/icons/dir.gif'
96/10/10 07:42:57| file_open: error opening file /var/spool/cache/04/06/00004064: (2) No such file or directory
96/10/10 07:42:57| storeSwapInStart: Failed for 'http://www-cache.swb.de/icons/blank.gif'
---
And yes, these last lines tell the horrible truth, the cache was wiped
_completely_ even though squid thinks differently and the log file
in the /var/spool/cache directory is 340KB in size...
If it sheds any light on this, I did notice the log file to be 500KB in
size when the rebuild started, so maybe this file was corrupted (but not
likely by the crash!) at an earlier point.
Whatever the reason, even with a total bogus log file the files in the
cache should have the final say about what's in there or not...
 
There go 2 weeks of "seeding" the cache with likely requested objects. :(
Mata ne,
<CB>
-- 
  // <CB> aka Christian Balzer, Tannenstr. 23c, D-64342 Seeheim, Germany
\X/  CB@brewhq.swb.de | Voice: +49 6257 83036, Fax/Data: +49 6257 83037
SWB  - The Software Brewery - | Team H, Germany HQ | Anime no Otaku
Received on Wed Oct 09 1996 - 23:19:19 MDT

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