[squid-users] assertion failed

Hi,

My squid 3.2.2 - R is failing after a reconfigure.
Not using SMP.

here is what I find in the logs :
............

2012/10/24 14:54:42 kid1| assertion failed: comm.cc:163:
"!fd_table[conn->fd].closing()"
............

2012/10/24 14:58:29 kid1| StoreEntry->ping_status: 2
2012/10/24 14:58:29 kid1| StoreEntry->store_status: 1
2012/10/24 14:58:29 kid1| StoreEntry->swap_status: 0
2012/10/24 14:58:29 kid1| assertion failed: store.cc:1854: "isEmpty()"
2012/10/24 14:58:33 kid1| Starting Squid Cache version
3.2.2-20121011-r11676 for x86_64-unknown-linux-gnu...
2012/10/24 14:58:33 kid1| Process ID 569
2012/10/24 14:58:33 kid1| Process Roles: worker
2012/10/24 14:58:33 kid1| With 65535 file descriptors available
2012/10/24 14:58:33 kid1| Initializing IP Cache...
2012/10/24 14:58:33 kid1| DNS Socket created at 0.0.0.0, FD 11
2012/10/24 14:58:33 kid1| Adding nameserver 194.2.0.20 from /etc/resolv.conf
2012/10/24 14:58:33 kid1| Adding nameserver 194.2.0.50 from /etc/resolv.conf
2012/10/24 14:58:33 kid1| Adding domain d097.cp from /etc/resolv.conf
2012/10/24 14:58:33 kid1| helperOpenServers: Starting 10/1000
'squidGuard' processes
2012/10/24 14:58:33 kid1| Logfile: opening log stdio:/var/log/squid/access.log
2012/10/24 14:58:33 kid1| Logfile: opening log
stdio:/var/log/squid/pirates_XFF.log
2012/10/24 14:58:33 kid1| Logfile: opening log
stdio:/var/log/squid/pirates_VIA.log
2012/10/24 14:58:33 kid1| Store logging disabled
2012/10/24 14:58:33 kid1| Swap maxSize 23019520 + 2097152 KB,
estimated 1932051 objects
2012/10/24 14:58:33 kid1| Target number of buckets: 96602
2012/10/24 14:58:33 kid1| Using 131072 Store buckets
2012/10/24 14:58:33 kid1| Max Mem size: 2097152 KB
2012/10/24 14:58:33 kid1| Max Swap size: 23019520 KB
2012/10/24 14:58:33 kid1| Rebuilding storage in /var/cache/squid-mem (dirty log)
2012/10/24 14:58:33 kid1| Rebuilding storage in /var/cache/squid (dirty log)
2012/10/24 14:58:33 kid1| Using Least Load store dir selection
2012/10/24 14:58:33 kid1| Set Current Directory to /var/cache/squid
2012/10/24 14:58:33 kid1| Loaded Icons.
2012/10/24 14:58:33 kid1| HTCP Disabled.
2012/10/24 14:58:33 kid1| Sending SNMP messages from 0.0.0.0:3401
2012/10/24 14:58:33 kid1| Configuring Parent proxyav.bercy.cp/3128/0
2012/10/24 14:58:33 kid1| Squid plugin modules loaded: 0
2012/10/24 14:58:33 kid1| Accepting HTTP Socket connections at
local=0.0.0.0:3128 remote=[::] FD 39 flags=9
2012/10/24 14:58:33 kid1| Accepting ICP messages on 0.0.0.0:3130
2012/10/24 14:58:33 kid1| Sending ICP messages from 0.0.0.0:3130
2012/10/24 14:58:33 kid1| Accepting SNMP messages on 0.0.0.0:3401
2012/10/24 14:58:33 kid1| Store rebuilding is 0.62% complete
2012/10/24 14:58:33 kid1| Done reading /var/cache/squid swaplog (57155 entries)
2012/10/24 14:58:34 kid1| WARNING: HTTP header contains NULL
characters {Accept: */*^M
Content-Type: application/x-www-form-urlencoded}
NULL
{Accept: */*^M
Content-Type: application/x-www-form-urlencoded
2012/10/24 14:58:34 kid1| Starting new redirector helpers...
2012/10/24 14:58:34 kid1| helperOpenServers: Starting 5/1000
'squidGuard' processes
2012/10/24 14:58:34 kid1| Starting new redirector helpers...
2012/10/24 14:58:34 kid1| helperOpenServers: Starting 5/1000
'squidGuard' processes
2012/10/24 14:58:34 kid1| Starting new redirector helpers...
2012/10/24 14:58:34 kid1| helperOpenServers: Starting 5/1000
'squidGuard' processes
2012/10/24 14:58:34 kid1| Starting new redirector helpers...
2012/10/24 14:58:34 kid1| helperOpenServers: Starting 5/1000
'squidGuard' processes
2012/10/24 14:58:34 kid1| Starting new redirector helpers...
2012/10/24 14:58:34 kid1| helperOpenServers: Starting 5/1000
'squidGuard' processes
2012/10/24 14:58:34 kid1| Starting new redirector helpers...
2012/10/24 14:58:34 kid1| helperOpenServers: Starting 5/1000
'squidGuard' processes
2012/10/24 14:58:34 kid1| Starting new redirector helpers...
2012/10/24 14:58:34 kid1| helperOpenServers: Starting 5/1000
'squidGuard' processes
2012/10/24 14:58:34 kid1| Starting new redirector helpers...
2012/10/24 14:58:34 kid1| helperOpenServers: Starting 5/1000
'squidGuard' processes
2012/10/24 14:58:34 kid1| Starting new redirector helpers...
2012/10/24 14:58:34 kid1| helperOpenServers: Starting 5/1000
'squidGuard' processes
2012/10/24 14:58:37 kid1| Done reading /var/cache/squid-mem swaplog
(648235 entries)
2012/10/24 14:58:37 kid1| Finished rebuilding storage from disk.
2012/10/24 14:58:37 kid1| 435593 Entries scanned
2012/10/24 14:58:37 kid1| 1 Invalid entries.
2012/10/24 14:58:37 kid1| 0 With invalid flags.
2012/10/24 14:58:37 kid1| 165731 Objects loaded.
2012/10/24 14:58:37 kid1| 0 Objects expired.
2012/10/24 14:58:37 kid1| 268805 Objects cancelled.
2012/10/24 14:58:37 kid1| 912 Duplicate URLs purged.
2012/10/24 14:58:37 kid1| 145 Swapfile clashes avoided.
2012/10/24 14:58:37 kid1| Took 4.08 seconds (40609.22 objects/sec).
2012/10/24 14:58:37 kid1| Beginning Validation Procedure
2012/10/24 14:58:37 kid1| Completed Validation Procedure
2012/10/24 14:58:37 kid1| Validated 165731 Entries
2012/10/24 14:58:37 kid1| store_swap_size = 663316.00 KB
2012/10/24 14:58:37 kid1| storeLateRelease: released 60 objects
2012/10/24 14:58:39 kid1| Failed to select source for '[null_entry]'
2012/10/24 14:58:39 kid1| always_direct = 0
2012/10/24 14:58:39 kid1| never_direct = 0
2012/10/24 14:58:39 kid1| timedout = 0
2012/10/24 14:58:39 kid1| WARNING: 1 swapin MD5 mismatches
2012/10/24 14:58:39 kid1| Could not parse headers from on disk object
2012/10/24 14:58:39 kid1| WARNING: An error inside Squid has caused an
HTTP reply without Date:. Please report this:
2012/10/24 14:58:39 kid1| StoreEntry->key: 04B85DD220904A643F71639EF2F21621
2012/10/24 14:58:39 kid1| StoreEntry->next: 0x2f36008
2012/10/24 14:58:39 kid1| StoreEntry->mem_obj: 0x3bb18b0
2012/10/24 14:58:39 kid1| StoreEntry->timestamp: -1
2012/10/24 14:58:39 kid1| StoreEntry->lastref: 1351083519
2012/10/24 14:58:39 kid1| StoreEntry->expires: -1
2012/10/24 14:58:39 kid1| StoreEntry->lastmod: -1
2012/10/24 14:58:39 kid1| StoreEntry->swap_file_sz: 0
2012/10/24 14:58:39 kid1| StoreEntry->refcount: 1
2012/10/24 14:58:39 kid1| StoreEntry->flags:
CACHABLE,DISPATCHED,PRIVATE,FWD_HDR_WAIT,VALIDATED
2012/10/24 14:58:39 kid1| StoreEntry->swap_dirn: -1
2012/10/24 14:58:39 kid1| StoreEntry->swap_filen: -1
2012/10/24 14:58:39 kid1| StoreEntry->lock_count: 3
2012/10/24 14:58:39 kid1| StoreEntry->mem_status: 0
2012/10/24 14:58:39 kid1| StoreEntry->ping_status: 2
2012/10/24 14:58:39 kid1| StoreEntry->store_status: 1
2012/10/24 14:58:39 kid1| StoreEntry->swap_status: 0
2012/10/24 14:58:39 kid1| assertion failed: store.cc:1854: "isEmpty()"
.....

Any Idea what is happening? Or how to debug this issue?

The only fix I found for now is cleaning the entire cache and starting
new with empty cache_dir.

Thanks a lot
Alex
Received on Wed Oct 24 2012 - 13:43:33 MDT