[SQU] 2.4.PRE-STABLE2 Received Segment Violation...dying

    Im using FreeBSD 4.2, and I installed yesterday the daily snapshot, and
when it got up to 19,700 req/min, it chrashed, Im using diskd and my system
has 2 PIII 600 Mhz and 1GB of RAM, below you will find the backtrace.

    Also I did noticed that it took more than 30 minutes to rebuild the
cache at startup, I noticed that the PRE-STABLE1 version used the diskd
process to rebuild the cache, and it really rebuild it fast enough, about 2
or 3 minutes, and now the diskd process are dead until the main squid
process finishes rebuilding the cache (very, very, very sloooow).

cache# df
Filesystem 1K-blocks Used Avail Capacity Mounted on
/dev/idad0s1a 254063 43991 189747 19% /
/dev/idad0s1h 60521954 5801553 49878645 10% /cache0
/dev/da0s1e 17235871 5893239 9963763 37% /cache1/0
/dev/da1s1e 17235871 5835914 10021088 37% /cache1/1
/dev/da2s1e 17235871 5836556 10020446 37% /cache1/2
/dev/da3s1e 17235871 5798640 10058362 37% /cache1/3
/dev/da4s1e 17235871 5892156 9964846 37% /cache1/4
/dev/da5s1e 17235871 5825177 10031825 37% /cache1/5
/dev/idad0s1e 1016303 17 934982 0% /tmp
/dev/idad0s1g 4065262 572139 3167903 15% /usr
/dev/idad0s1f 2032623 13950 1856064 1% /var
procfs 4 4 0 100% /proc

Script started on Tue Mar 6 18:29:42 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/06 18:30:27| Starting Squid Cache version 2.4.PRE-STABLE2 for
i386-unknown-freebsd4.2...
2001/03/06 18:30:27| Process ID 196
2001/03/06 18:30:27| With 32768 file descriptors available
2001/03/06 18:30:27| Performing DNS Tests...
2001/03/06 18:30:27| Successful DNS name lookup tests...
2001/03/06 18:30:27| DNS Socket created on FD 7
2001/03/06 18:30:27| Adding nameserver 127.0.0.1 from /etc/resolv.conf
2001/03/06 18:30:27| Unlinkd pipe opened on FD 12
2001/03/06 18:30:27| Swap maxSize 43008000 KB, estimated 3308307 objects
2001/03/06 18:30:27| Target number of buckets: 165415
2001/03/06 18:30:27| Using 262144 Store buckets
2001/03/06 18:30:27| Max Mem size: 131072 KB
2001/03/06 18:30:27| Max Swap size: 43008000 KB
2001/03/06 18:30:27| Store logging disabled
2001/03/06 18:30:27| Rebuilding storage in /cache0/0 (DIRTY)
2001/03/06 18:30:27| Rebuilding storage in /cache1/0/0 (DIRTY)
2001/03/06 18:30:27| Rebuilding storage in /cache1/1/0 (DIRTY)
2001/03/06 18:30:27| Rebuilding storage in /cache1/2/0 (DIRTY)
2001/03/06 18:30:27| Rebuilding storage in /cache1/3/0 (DIRTY)
2001/03/06 18:30:27| Rebuilding storage in /cache1/4/0 (DIRTY)
2001/03/06 18:30:27| Rebuilding storage in /cache1/5/0 (DIRTY)
2001/03/06 18:30:27| Using Round Robin store dir selection
2001/03/06 18:30:27| Set Current Directory to /cache0/0
2001/03/06 18:30:27| Loaded Icons.
2001/03/06 18:30:27| Accepting HTTP connections at 0.0.0.0, port 80, FD 31.
2001/03/06 18:30:27| Accepting SNMP messages on port 1610, FD 33.
2001/03/06 18:30:27| Accepting WCCP messages on port 2048, FD 34.
2001/03/06 18:30:27| Ready to serve requests.
2001/03/06 18:30:31| Store rebuilding is 0.3% complete
2001/03/06 18:30:46| Store rebuilding is 3.6% complete
2001/03/06 18:31:01| Store rebuilding is 5.9% complete
2001/03/06 18:31:17| Store rebuilding is 7.7% complete
2001/03/06 18:31:35| Store rebuilding is 9.1% complete
2001/03/06 18:31:50| Store rebuilding is 10.5% complete
...
...
Check out the time it took to rebuild the cache !!!!!!
...
...
2001/03/06 19:01:44| Done reading /cache1/1/0 swaplog (1157373 entries)
2001/03/06 19:01:59| Store rebuilding is 98.0% complete
2001/03/06 19:02:02| Done reading /cache1/2/0 swaplog (1168914 entries)
2001/03/06 19:02:14| Store rebuilding is 98.7% complete
2001/03/06 19:02:14| Done reading /cache0/0 swaplog (1178985 entries)
2001/03/06 19:02:18| Done reading /cache1/0/0 swaplog (1182420 entries)
2001/03/06 19:02:28| Done reading /cache1/5/0 swaplog (1196709 entries)
2001/03/06 19:02:29| Store rebuilding is 99.6% complete
2001/03/06 19:02:30| Done reading /cache1/4/0 swaplog (1199998 entries)
2001/03/06 19:02:32| Done reading /cache1/3/0 swaplog (1208515 entries)
2001/03/06 19:02:32| Finished rebuilding storage from disk.
2001/03/06 19:02:32| 5350408 Entries scanned
2001/03/06 19:02:32| 0 Invalid entries.
2001/03/06 19:02:32| 0 With invalid flags.
2001/03/06 19:02:32| 2449836 Objects loaded.
2001/03/06 19:02:32| 0 Objects expired.
2001/03/06 19:02:32| 2834347 Objects cancelled.
2001/03/06 19:02:32| 67103 Duplicate URLs purged.
2001/03/06 19:02:32| 47411 Swapfile clashes avoided.
2001/03/06 19:02:32| Took 1925.7 seconds (1272.2 objects/sec).
2001/03/06 19:02:32| Beginning Validation Procedure
2001/03/06 19:02:35| 262144 Entries Validated so far.
2001/03/06 19:02:39| 524288 Entries Validated so far.
2001/03/06 19:02:42| 786432 Entries Validated so far.
2001/03/06 19:02:45| 1048576 Entries Validated so far.
2001/03/06 19:02:48| 1310720 Entries Validated so far.
2001/03/06 19:02:52| 1572864 Entries Validated so far.
2001/03/06 19:02:55| 1835008 Entries Validated so far.
2001/03/06 19:02:57| 2097152 Entries Validated so far.
2001/03/06 19:03:01| 2359296 Entries Validated so far.
2001/03/06 19:03:01| Completed Validation Procedure
2001/03/06 19:02:35| 262144 Entries Validated so far.
2001/03/06 19:02:39| 524288 Entries Validated so far.
2001/03/06 19:02:42| 786432 Entries Validated so far.
2001/03/06 19:02:45| 1048576 Entries Validated so far.
2001/03/06 19:02:48| 1310720 Entries Validated so far.
2001/03/06 19:02:52| 1572864 Entries Validated so far.
2001/03/06 19:02:55| 1835008 Entries Validated so far.
2001/03/06 19:02:57| 2097152 Entries Validated so far.
2001/03/06 19:03:01| 2359296 Entries Validated so far.
2001/03/06 19:03:01| Completed Validation Procedure
2001/03/06 19:03:01| Validated 2402234 Entries
2001/03/06 19:03:01| store_swap_size = 36681000k
2001/03/06 19:03:25| WARNING: failed to unpack meta data
2001/03/06 19:03:38| WARNING: 1 swapin MD5 mismatches
2001/03/06 19:03:55| storeLateRelease: released 26535 objects
2001/03/06 19:05:11| WARNING: failed to unpack meta data
2001/03/06 19:05:15| WARNING: failed to unpack meta data
2001/03/06 19:05:19| WARNING: failed to unpack meta data
2001/03/06 19:05:22| WARNING: 10 swapin MD5 mismatches
2001/03/06 19:05:55| clientReadRequest: FD 5033 Invalid Request
2001/03/06 19:06:09| WARNING: failed to unpack meta data
2001/03/06 19:06:18| WARNING: failed to unpack meta data
2001/03/06 19:06:28| WARNING: failed to unpack meta data

Program received signal SIGSEGV, Segmentation fault.
0x80aab8b in linklistPush (L=0x10d5a3, p=0x50060000) at tools.c:873
873 while (*L)
(gdb) bt
#0 0x80aab8b in linklistPush (L=0x10d5a3, p=0x50060000) at tools.c:873
#1 0x80b7c90 in storeDiskdShmPut (sd=0x820d800, offset=98304)
    at store_dir_diskd.c:1649
#2 0x80b4a9c in storeDiskdDirCallback (SD=0x820d800) at
store_dir_diskd.c:532
#3 0x80a6e07 in storeDirCallback () at store_dir.c:465
#4 0x80661ca in comm_poll (msec=0) at comm_select.c:330
#5 0x80885ad in main (argc=2, argv=0xbfbffc24) at main.c:720
#6 0x804a529 in _start ()
(gdb) c
Continuing.

Program terminated with signal SIGSEGV, Segmentation fault.
The program no longer exists.
(gdb) q

Script done on Tue Mar 6 19:08:23 2001

P.S. If you need any more testing, debugging or test some patches, just let
me know, I can do it.

Greetings...
Ales

--
To unsubscribe, see http://www.squid-cache.org/mailing-lists.html