[squid-users] 3.2.0.17-20120420-r11555 cache problem on FreeBSD 9.0

From: Kiril Dimitrov <kickbul_at_gmail.com>
Date: Thu, 26 Apr 2012 09:56:58 +0300

I seemingly randomly get the following entries in cache.log :

2012/04/26 09:48:07 kid1| WARNING: 1 swapin MD5 mismatches
2012/04/26 09:48:07 kid1| Could not parse headers from on disk object
2012/04/26 09:48:07 kid1| WARNING: An error inside Squid has caused an
HTTP reply without Date:. Please report this:
2012/04/26 09:48:07 kid1| StoreEntry->key: 046717E59AE72DE6AAD977A3A61FFED3
2012/04/26 09:48:07 kid1| StoreEntry->next: 0
2012/04/26 09:48:07 kid1| StoreEntry->mem_obj: 0x80974ae00
2012/04/26 09:48:07 kid1| StoreEntry->timestamp: -1
2012/04/26 09:48:07 kid1| StoreEntry->lastref: 1335422887
2012/04/26 09:48:07 kid1| StoreEntry->expires: -1
2012/04/26 09:48:07 kid1| StoreEntry->lastmod: -1
2012/04/26 09:48:07 kid1| StoreEntry->swap_file_sz: 0
2012/04/26 09:48:07 kid1| StoreEntry->refcount: 1
2012/04/26 09:48:07 kid1| StoreEntry->flags:
CACHABLE,PRIVATE,FWD_HDR_WAIT,VALIDATED
2012/04/26 09:48:07 kid1| StoreEntry->swap_dirn: -1
2012/04/26 09:48:07 kid1| StoreEntry->swap_filen: -1
2012/04/26 09:48:07 kid1| StoreEntry->lock_count: 2
2012/04/26 09:48:07 kid1| StoreEntry->mem_status: 0
2012/04/26 09:48:07 kid1| StoreEntry->ping_status: 2
2012/04/26 09:48:07 kid1| StoreEntry->store_status: 1
2012/04/26 09:48:07 kid1| StoreEntry->swap_status: 0
2012/04/26 09:48:07 kid1| assertion failed: store.cc:1868: "isEmpty()"
2012/04/26 09:48:11 kid1| Starting Squid Cache version
3.2.0.17-20120420-r11555 for amd64-unknown-freebsd9.0...
2012/04/26 09:48:11 kid1| Process ID 90536
2012/04/26 09:48:11 kid1| Process Roles: worker
2012/04/26 09:48:11 kid1| With 30000 file descriptors available
2012/04/26 09:48:11 kid1| Initializing IP Cache...
2012/04/26 09:48:11 kid1| DNS Socket created at 0.0.0.0, FD 6
2012/04/26 09:48:11 kid1| Adding nameserver 127.0.0.1 from /etc/resolv.conf
2012/04/26 09:48:11 kid1| helperOpenServers: Starting 10/450
'squidGuard' processes
2012/04/26 09:48:11 kid1| helperOpenServers: Starting 10/200
'upload_stop.sh' processes
2012/04/26 09:48:11 kid1| Logfile: opening log
stdio:/usr/local/squid/logs/access.log
2012/04/26 09:48:11 kid1| Logfile: opening log
stdio:/usr/local/squid/logs/store.log
2012/04/26 09:48:11 kid1| Swap maxSize 51200000 + 5242880 KB,
estimated 4341760 objects
2012/04/26 09:48:11 kid1| Target number of buckets: 217088
2012/04/26 09:48:11 kid1| Using 262144 Store buckets
2012/04/26 09:48:11 kid1| Max Mem size: 5242880 KB
2012/04/26 09:48:11 kid1| Max Swap size: 51200000 KB
2012/04/26 09:48:11 kid1| Rebuilding storage in
/usr/local/squid/cache/ (dirty log)
2012/04/26 09:48:11 kid1| Using Least Load store dir selection
2012/04/26 09:48:11 kid1| Set Current Directory to /usr/local/squid/cache/
2012/04/26 09:48:11 kid1| Loaded Icons.
2012/04/26 09:48:11 kid1| HTCP Disabled.
2012/04/26 09:48:11 kid1| Configuring Parent 192.168.1.124/9080/0
2012/04/26 09:48:11 kid1| Squid plugin modules loaded: 0
2012/04/26 09:48:11 kid1| Adaptation support is off.
2012/04/26 09:48:11 kid1| Accepting HTTP Socket connections at
local=0.0.0.0:3128 remote=[::] FD 51 flags=9
2012/04/26 09:48:11 kid1| Store rebuilding is 0.85% complete
2012/04/26 09:48:14 kid1| Done reading /usr/local/squid/cache/ swaplog
(470297 entries)
2012/04/26 09:48:14 kid1| Finished rebuilding storage from disk.
2012/04/26 09:48:14 kid1| 470291 Entries scanned
2012/04/26 09:48:14 kid1| 0 Invalid entries.
2012/04/26 09:48:14 kid1| 0 With invalid flags.
2012/04/26 09:48:14 kid1| 470285 Objects loaded.
2012/04/26 09:48:14 kid1| 0 Objects expired.
2012/04/26 09:48:14 kid1| 6 Objects cancelled.
2012/04/26 09:48:14 kid1| 0 Duplicate URLs purged.
2012/04/26 09:48:14 kid1| 0 Swapfile clashes avoided.
2012/04/26 09:48:14 kid1| Took 3.28 seconds (143238.17 objects/sec).
2012/04/26 09:48:14 kid1| Beginning Validation Procedure
2012/04/26 09:48:14 kid1| 262144 Entries Validated so far.
2012/04/26 09:48:14 kid1| Completed Validation Procedure
2012/04/26 09:48:14 kid1| Validated 470284 Entries
2012/04/26 09:48:14 kid1| store_swap_size = 12653092.00 KB
2012/04/26 09:48:15 kid1| storeLateRelease: released 0 objects
[: -: bad number

which is to say the least annoying
Looks like broken cache but I wanted to get a second opinion before I delete it.
Received on Thu Apr 26 2012 - 06:57:04 MDT

This archive was generated by hypermail 2.2.0 : Thu Apr 26 2012 - 12:00:04 MDT