Re: [squid-users] Squid 2.5stable9-20050402 always restart (stmem.c error)

* Henrik Nordstrom (hno@squid-cache.org):

[chiming in, as I see this too; see also bug #1279.
This problem seems to affect only diskd. squid version is -STABLE9 plus
all published patches.]

> On Sun, 3 Apr 2005, Irfan D Prastomo wrote:
>
> >dear all,
> >
> >I decided to try squid 2.5-stable9-20050402, and after cleaning all my
> >cache files and rebuilding it again, fired-up squid -DF, I got this error
> >msg after serving packets for about 10 seconds:
> >
> >2005/04/03 05:22:21| assertion failed: stmem.c:93: "current_offset ==
> >target_offset"
> >2005/04/03 05:22:24| Starting Squid Cache version 2.5.STABLE9-20050402 for
> >i386-freebsd5.0...
>
> Can you get a stacktrace of this?

Does this help?

Script started on Sun Apr 3 13:57:56 2005
Your sudo password:
GNU gdb 6.1.1 [FreeBSD]
Copyright 2004 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-marcel-freebsd"...
(gdb) handle SIGPIPE pass nostop noprint
Signal Stop Print Pass to program Description
SIGPIPE No No Yes Broken pipe
(gdb) run -DNYCd3
Starting program: /usr/local/sbin/squid -DNYCd3
2005/04/03 13:58:28| Starting Squid Cache version 2.5.STABLE9 for i386-portbld-freebsd5.4...
2005/04/03 13:58:28| Process ID 48064
2005/04/03 13:58:28| With 3584 file descriptors available
2005/04/03 13:58:28| DNS Socket created at 0.0.0.0, port 49230, FD 8
2005/04/03 13:58:28| Adding nameserver 192.168.1.3 from /etc/resolv.conf
2005/04/03 13:58:28| Unlinkd pipe opened on FD 13
2005/04/03 13:58:28| Swap maxSize 102400 KB, estimated 7876 objects
2005/04/03 13:58:28| Target number of buckets: 393
2005/04/03 13:58:28| Using 8192 Store buckets
2005/04/03 13:58:28| Max Mem size: 8192 KB
2005/04/03 13:58:28| Max Swap size: 102400 KB
2005/04/03 13:58:28| Rebuilding storage in /usr/local/squid/cache (DIRTY)
2005/04/03 13:58:28| Using Least Load store dir selection
2005/04/03 13:58:28| Set Current Directory to /usr/local/squid/cache
2005/04/03 13:58:28| Loaded Icons.
2005/04/03 13:58:28| Accepting HTTP connections at 0.0.0.0, port 3128, FD 15.
2005/04/03 13:58:28| Accepting ICP messages at 0.0.0.0, port 3130, FD 17.
2005/04/03 13:58:28| WCCP Disabled.
2005/04/03 13:58:28| Ready to serve requests.
2005/04/03 13:58:28| Done reading /usr/local/squid/cache swaplog (632 entries)
2005/04/03 13:58:28| Finished rebuilding storage from disk.
2005/04/03 13:58:28| 631 Entries scanned
2005/04/03 13:58:28| 0 Invalid entries.
2005/04/03 13:58:28| 0 With invalid flags.
2005/04/03 13:58:28| 630 Objects loaded.
2005/04/03 13:58:28| 0 Objects expired.
2005/04/03 13:58:28| 1 Objects cancelled.
2005/04/03 13:58:28| 0 Duplicate URLs purged.
2005/04/03 13:58:28| 0 Swapfile clashes avoided.
2005/04/03 13:58:28| Took 0.4 seconds (1572.8 objects/sec).
2005/04/03 13:58:28| Beginning Validation Procedure
2005/04/03 13:58:28| Completed Validation Procedure
2005/04/03 13:58:28| Validated 630 Entries
2005/04/03 13:58:28| store_swap_size = 7364k
2005/04/03 13:58:29| storeLateRelease: released 0 objects
2005/04/03 13:58:46| assertion failed: stmem.c:93: "current_offset == target_offset"

Program received signal SIGABRT, Aborted.
0x2818225b in kill () from /lib/libc.so.5
(gdb) backtrace
#0 0x2818225b in kill () from /lib/libc.so.5
#1 0x28177302 in raise () from /lib/libc.so.5
#2 0x281e9aa7 in abort () from /lib/libc.so.5
#3 0x08066409 in xassert (msg=0x80cb278 "current_offset == target_offset",
    file=0x80cb241 "stmem.c", line=93) at debug.c:270
#4 0x08094454 in stmemFreeDataUpto (mem=0x856a588, target_offset=0)
    at stmem.c:93
#5 0x0809aad8 in storeSwapOutMaintainMemObject (e=0x857ca40)
    at store_swapout.c:155
#6 0x0809ad33 in storeSwapOut (e=0x857ca40) at store_swapout.c:211
#7 0x080980c0 in storeUnregister (sc=0x856a690, e=0x857ca40, data=0x8572010)
    at store_client.c:516
#8 0x0805d45a in httpRequestFree (data=0x8572010) at client_side.c:890
#9 0x0805d619 in connStateFree (fd=14, data=0x8570010) at client_side.c:931
#10 0x0806410f in commCallCloseHandlers (fd=14) at comm.c:573
#11 0x080642b7 in comm_close (fd=14) at comm.c:655
#12 0x0805ff04 in clientWriteComplete (fd=14, bufnotused=0x0, size=0,
    errflag=0, data=0x8572010) at client_side.c:2232
#13 0x0805f6da in clientSendMoreData (data=0x8572010, buf=0x8573000 "", size=0)
    at client_side.c:1978
#14 0x08097438 in storeClientCallback (sc=0x856a690, sz=0)
    at store_client.c:160
#15 0x0809773b in storeClientCopy3 (e=0x857ca40, sc=0x856a690)
    at store_client.c:268
#16 0x080976fb in storeClientCopy2 (e=0x857ca40, sc=0x856a690)
    at store_client.c:255
#17 0x080982de in InvokeHandlers (e=0x857ca40) at store_client.c:591
#18 0x080959f9 in storeComplete (e=0x857ca40) at store.c:732
#19 0x0806d333 in fwdComplete (fwdState=0x8582650) at forward.c:786
#20 0x08078d56 in httpReadReply (fd=18, data=0x856a810) at http.c:663
#21 0x08065779 in comm_poll (msec=64) at comm_select.c:445
#22 0x08085d3d in main (argc=2, argv=0xbfbfec3c) at main.c:739
(gdb) quit
The program is running. Exit anyway? (y or n) y

Script done on Sun Apr 3 13:59:14 2005

> It's also interesting to know the values of current_offset and
> target_offset.

I'd be happy to provide them, but my gdb skills are somewhat limited :(

Regards,

-- 
Thomas-Martin Seck