[squid-users] 2.4 Stable1 assertion failed: store_dir_diskd.c:1636

From: Alejandro A. Ramirez <ales@dont-contact.us>
Date: Tue, 27 Mar 2001 14:06:30 -0600

Hi,

    Im using FreeBSD4.2, in an IBM Netfinity 5000 w/2 PIII 600, 1GB RAM, and
1 RAID0 array and 6 * 18 GB HDs, I have downloaded the HEAD branch from
today "squid-2.4-200103270000", and it seems that I have hitted a bug, just
overloading the cache for a few minutes with more than 300 req/s, it crashed
with the following message:

assertion failed: store_dir_diskd.c:1636: "0 <= *shm_offset && *shm_offset <
SHMBUFS * SHMBUF_BLKSZ"

Below you can find the Backtrace of this crash:

P.S. If you need something else (testing, access, etc) , just let me know (I
can provide this because this is a testing server).

Greetings...
Ales

Script started on Tue Mar 27 12:20:28 2001
GNU gdb 4.18
Copyright 1998 Free Software Foundation, Inc.
GDB is free software, covered by the GNU General Public License, and you are
welcome to change it and/or distribute copies of it under certain
conditions.
Type "show copying" to see the conditions.
There is absolutely no warranty for GDB. Type "show warranty" for details.
This GDB was configured as "i386-unknown-freebsd"...
(gdb) handle SIGPIPE noprint nostop pass
Signal Stop Print Pass to program Description
SIGPIPE No No Yes Broken pipe
(gdb) r -CNd1
Starting program: /usr/local/sbin/squid -CNd1
2001/03/27 12:20:39| Starting Squid Cache version 2.4.STABLE1 for
i386-unknown-freebsd4.2...
2001/03/27 12:20:39| Process ID 11391
2001/03/27 12:20:39| With 32768 file descriptors available
2001/03/27 12:20:39| Performing DNS Tests...
2001/03/27 12:20:39| Successful DNS name lookup tests...
2001/03/27 12:20:39| DNS Socket created on FD 7
2001/03/27 12:20:39| Adding nameserver 127.0.0.1 from /etc/resolv.conf
2001/03/27 12:20:39| Unlinkd pipe opened on FD 12
2001/03/27 12:20:39| Swap maxSize 43008000 KB, estimated 3308307 objects
2001/03/27 12:20:39| Target number of buckets: 165415
2001/03/27 12:20:39| Using 262144 Store buckets
2001/03/27 12:20:39| Max Mem size: 131072 KB
2001/03/27 12:20:39| Max Swap size: 43008000 KB
2001/03/27 12:20:39| Store logging disabled
2001/03/27 12:20:39| Rebuilding storage in /cache0/0 (DIRTY)
2001/03/27 12:20:39| Rebuilding storage in /cache1/0/0 (DIRTY)
2001/03/27 12:20:39| Rebuilding storage in /cache1/1/0 (DIRTY)
2001/03/27 12:20:39| Rebuilding storage in /cache1/2/0 (DIRTY)
2001/03/27 12:20:39| Rebuilding storage in /cache1/3/0 (DIRTY)
2001/03/27 12:20:39| Rebuilding storage in /cache1/4/0 (DIRTY)
2001/03/27 12:20:39| Rebuilding storage in /cache1/5/0 (DIRTY)
2001/03/27 12:20:39| Using Round Robin store dir selection
2001/03/27 12:20:39| Set Current Directory to /cache0/0
2001/03/27 12:20:39| Loaded Icons.
2001/03/27 12:20:39| Accepting HTTP connections at 0.0.0.0, port 80, FD 31.
2001/03/27 12:20:39| Accepting SNMP messages on port 1610, FD 33.
2001/03/27 12:20:39| Accepting WCCP messages on port 2048, FD 34.
2001/03/27 12:20:39| Ready to serve requests.
2001/03/27 12:20:44| Store rebuilding is 1.1% complete
2001/03/27 12:20:59| Store rebuilding is 4.7% complete
2001/03/27 12:21:07| parseHttpRequest: Unsupported method 'GNUTELLA'
2001/03/27 12:21:07| clientReadRequest: FD 266 Invalid Request
2001/03/27 12:21:17| Store rebuilding is 8.2% complete
2001/03/27 12:21:32| Store rebuilding is 10.5% complete
2001/03/27 12:21:50| Store rebuilding is 12.7% complete
2001/03/27 12:21:56| Request header is too large (12287 bytes)
2001/03/27 12:21:56| Config 'request_header_max_size'= 10240 bytes.
2001/03/27 12:22:09| Store rebuilding is 15.0% complete
2001/03/27 12:22:28| Store rebuilding is 17.3% complete
2001/03/27 12:22:48| Store rebuilding is 19.6% complete
...
...
...
2001/03/27 12:32:45| Store rebuilding is 90.6% complete
2001/03/27 12:33:04| Store rebuilding is 92.9% complete
2001/03/27 12:33:22| Store rebuilding is 95.2% complete
2001/03/27 12:33:33| Done reading /cache1/2/0 swaplog (349700 entries)
2001/03/27 12:33:35| Done reading /cache1/0/0 swaplog (350556 entries)
2001/03/27 12:33:38| Store rebuilding is 97.5% complete
2001/03/27 12:33:43| Done reading /cache1/4/0 swaplog (355743 entries)
2001/03/27 12:33:45| Done reading /cache1/5/0 swaplog (357281 entries)
2001/03/27 12:33:49| Done reading /cache1/1/0 swaplog (362229 entries)
2001/03/27 12:33:50| Done reading /cache0/0 swaplog (363160 entries)
2001/03/27 12:33:51| Done reading /cache1/3/0 swaplog (368753 entries)
2001/03/27 12:33:51| Finished rebuilding storage from disk.
2001/03/27 12:33:51| 2492508 Entries scanned
2001/03/27 12:33:51| 0 Invalid entries.
2001/03/27 12:33:51| 0 With invalid flags.
2001/03/27 12:33:51| 2477831 Objects loaded.
2001/03/27 12:33:51| 0 Objects expired.
2001/03/27 12:33:51| 14368 Objects cancelled.
2001/03/27 12:33:51| 647 Duplicate URLs purged.
2001/03/27 12:33:51| 14 Swapfile clashes avoided.
2001/03/27 12:33:51| Took 791.7 seconds (3129.8 objects/sec).
2001/03/27 12:33:51| Beginning Validation Procedure
2001/03/27 12:33:55| 262144 Entries Validated so far.
2001/03/27 12:33:58| 524288 Entries Validated so far.
2001/03/27 12:34:02| 786432 Entries Validated so far.
2001/03/27 12:34:06| 1048576 Entries Validated so far.
2001/03/27 12:34:10| 1310720 Entries Validated so far.
2001/03/27 12:34:14| 1572864 Entries Validated so far.
2001/03/27 12:34:18| 1835008 Entries Validated so far.
2001/03/27 12:34:22| 2097152 Entries Validated so far.
2001/03/27 12:34:26| 2359296 Entries Validated so far.
2001/03/27 12:34:28| Completed Validation Procedure
2001/03/27 12:34:28| Validated 2477311 Entries
2001/03/27 12:34:28| store_swap_size = 38701107k
2001/03/27 12:34:36| storeLateRelease: released 4069 objects
2001/03/27 12:34:39| WARNING: 1 swapin MD5 mismatches
2001/03/27 12:35:20| WARNING: failed to unpack meta data
2001/03/27 12:37:08| WARNING: failed to unpack meta data
2001/03/27 12:39:15| WARNING: 10 swapin MD5 mismatches
2001/03/27 12:42:23| clientReadRequest: FD 1787 Invalid Request
2001/03/27 12:44:04| Request header is too large (12287 bytes)
2001/03/27 12:44:04| Config 'request_header_max_size'= 10240 bytes.
2001/03/27 12:45:30| WARNING: failed to unpack meta data
2001/03/27 12:52:09| WARNING: failed to unpack meta data
2001/03/27 12:53:56| Request header is too large (12287 bytes)
2001/03/27 12:53:56| Config 'request_header_max_size'= 10240 bytes.
2001/03/27 12:57:05| idnsCheckQueue: ID 25c3: giving up after 59 tries and
303.6 seconds
...
...
...
2001/03/27 13:35:02| idnsCheckQueue: ID 6574: giving up after 58 tries and
305.2 seconds
2001/03/27 13:35:05| idnsCheckQueue: ID 65b3: giving up after 57 tries and
300.7 seconds
2001/03/27 13:35:06| commConnectDnsHandle: Bad dns_error_message
2001/03/27 13:35:08| idnsCheckQueue: ID 65c0: giving up after 57 tries and
301.7 seconds
2001/03/27 13:35:15| idnsCheckQueue: ID 65e6: giving up after 57 tries and
301.9 seconds
2001/03/27 13:35:34| Request header is too large (12287 bytes)
2001/03/27 13:35:34| Config 'request_header_max_size'= 10240 bytes.
squid in free(): warning: modified (chunk-) pointer.
2001/03/27 13:35:38| assertion failed: store_dir_diskd.c:1636: "0 <=
*shm_offset && *shm_offset < SHMBUFS
 * SHMBUF_BLKSZ"

Program received signal SIGABRT, Aborted.
0x4816a3bc in kill () from /usr/lib/libc.so.4
(gdb) bt
#0 0x4816a3bc in kill () from /usr/lib/libc.so.4
#1 0x481a6026 in abort () from /usr/lib/libc.so.4
#2 0x806777b in xassert (
    msg=0x80e2ac0 "0 <= *shm_offset && *shm_offset < SHMBUFS *
SHMBUF_BLKSZ",
    file=0x80e18e4 "store_dir_diskd.c", line=1636) at debug.c:250
#3 0x80b7c07 in storeDiskdShmGet (sd=0x820db34, shm_offset=0xbfbbf9ec)
    at store_dir_diskd.c:1636
#4 0x80b8f8f in storeDiskdWrite (SD=0x820db34, sio=0x243110c0,
    buf=0x1ddd2000 " es \" + weekdays[weekday] + \" \" + date + \" de \" +
months[month] + \" del \" + ye
ar);\n// -->\n</script>\n </font> <!-- Fin del Scritp
fecha --></div>\n </td>\n </tr>\n</table
>\n<table width=\"100%\" b"..., size=4096, offset=-1, free_func=0) at
store_io_diskd.c:245
#5 0x80a4430 in storeWrite (sio=0x243110c0,
    buf=0x1ddd2000 " es \" + weekdays[weekday] + \" \" + date + \" de \" +
months[month] + \" del \" + ye
ar);\n// -->\n</script>\n </font> <!-- Fin del Scritp
fecha --></div>\n </td>\n </tr>\n</table
>\n<table width=\"100%\" b"..., size=4096, offset=-1, free_func=0) at
store_io.c:89
#6 0x80a9161 in storeSwapOut (e=0x24b5bf00) at store_swapout.c:239
#7 0x80a1e0f in storeAppend (e=0x24b5bf00,
    buf=0x8118720 "HTTP/1.1 200 OK\r\nDate: Tue, 27 Mar 2001 19:33:13
GMT\r\nServer: Apache/1.3.14 (Unix)
 FrontPage/4.0.4.3 mod_ssl/2.7.1 OpenSSL/0.9.6\r\nLast-Modified: Tue, 27 Mar
2001 02:24:17 GMT\r\nETag: \
"3557f0-6863-3abff"...,
    len=8760) at store.c:462
---Type <return> to continue, or q <return> to quit---
#8 0x807a028 in httpReadReply (fd=1835, data=0x1b11b000) at http.c:561
#9 0x80666c3 in comm_poll (msec=0) at comm_select.c:432
#10 0x80885ad in main (argc=2, argv=0xbfbffc0c) at main.c:720
#11 0x804a529 in _start ()
(gdb) c
Continuing.

Program terminated with signal SIGABRT, Aborted.
The program no longer exists.
(gdb) quit

Script done on Tue Mar 27 13:45:17 2001
Received on Tue Mar 27 2001 - 13:07:10 MST

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