[Varnish] #390: varnish #3444 core dumps..
Varnish
varnish-bugs at projects.linpro.no
Wed Nov 26 12:28:07 CET 2008
#390: varnish #3444 core dumps..
----------------------+-----------------------------------------------------
Reporter: mtempels | Owner:
Type: defect | Status: new
Priority: normal | Milestone:
Component: build | Version: trunk
Severity: normal | Resolution:
Keywords: |
----------------------+-----------------------------------------------------
Old description:
> child dies..
>
> various log entries:
>
> Nov 26 11:38:39 vkmag-cache varnishd[21915]: Child (21916) not responding
> to ping, killing it.
> Nov 26 11:38:39 vkmag-cache varnishd[21915]: Child (21916) died signal=6
> (core dumped)
> Nov 26 11:38:39 vkmag-cache varnishd[21915]: Child (21916) Panic message:
> Assert error in WRW_Reserve(), cache_pool.c line 114:
> Condition((w->wfd) == 0) not true. errno = 11 (Resource temporarily
> unavailable) thread = (cache-worker)sp = 0x7f2f8e449008 { fd = 137, id
> = 137, xid = 369732011, client = 213.221.42.174:2504, step =
> STP_DELIVER, handling = DELIVER, ws = 0x7f2f8e449078 { id =
> "sess", {s,f,r,e} = {0x7f2f8e4497c8,,+1223,(nil),+8192}, },
> worker = 0x4eccbbc0 { }, vcl = { srcname = {
> "input", "Default", }, }, obj = 0x7f2f7b248000 {
> refcnt = 1, xid = 369732011, ws = 0x7f2f7b248028 { id = "obj",
> {s,f,r,e} = {0x7f2f7b248358,,+161,(nil),+7336}, }, http = {
> ws = 0x7f2f7b248028 { id = "obj", {s,f,r,e} =
> {0x7f2f7b248358,,+161,(nil),+7336}, }, hd = { "Date:
> Wed, 26 Nov 2008 10:38:39 GMT", "Server: Varnish",
> "Retry-After: 0", "Content-Type: text/html; cha
> Nov 26 11:38:39 vkmag-cache varnishd[21915]: Child cleanup complete
> Nov 26 11:38:40 vkmag-cache varnishd[21915]: child (26105) Started
> Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said Closed
> fds: 3 4 5 8 9 11 12
> Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said Child
> starts
> Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said Ready
> Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said
> Probe("GET /check.php HTTP/1.1
> Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said
> Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said Host:
> 81.23.234.151
> Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said
> Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said
> Connection: close
> Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said
> Nov 26 11:38:40 vkmag-cache last message repeated 2 times
> Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said ", 0.5,
> 1)
> Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said
> Probe("GET /check.php HTTP/1.1
> Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said
> Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said Host:
> 81.23.234.152
> Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said
> Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said
> Connection: close
> Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said
> Nov 26 11:38:40 vkmag-cache last message repeated 2 times
>
> root at vkmag-cache:~/varnish/svn# svn info
> Path: .
> URL: http://varnish.projects.linpro.no/svn
> Repository Root: http://varnish.projects.linpro.no/svn
> Repository UUID: d4fa192b-c00b-0410-8231-f00ffab90ce4
> Revision: 3444
> Node Kind: directory
> Schedule: normal
> Last Changed Author: phk
> Last Changed Rev: 3444
> Last Changed Date: 2008-11-25 17:04:47 +0100 (Tue, 25 Nov 2008)
>
> 21915 ? Ss 0:00 /usr/local/sbin/varnishd -P
> /var/run/varnishd.pid -a 0.0.0.0:80 -f /etc/varnish/default.vcl -T
> 127.0.0.1:6082 -t 120 -w 1,1000,10 -p auto_restart on -s malloc,6G
>
> (gdb) bt
> #0 0x00007f2f971a2095 in raise () from /lib/libc.so.6
> #1 0x00007f2f971a3af0 in abort () from /lib/libc.so.6
> #2 0x000000000041f6a6 in pan_ic (func=0x44c480 "WRW_Reserve",
> file=0x44c498 "cache_pool.c", line=114,
> cond=0x44c4be "(w->wfd) == 0", err=11, xxx=0) at cache_panic.c:325
> #3 0x000000000041fd99 in WRW_Reserve (w=0x4eccbbc0, fd=0x7f2f8e44900c)
> at cache_pool.c:114
> #4 0x0000000000422184 in RES_WriteObj (sp=0x7f2f8e449008) at
> cache_response.c:140
> #5 0x000000000041040f in cnt_deliver (sp=0x7f2f8e449008) at
> cache_center.c:178
> #6 0x00000000004130a9 in CNT_Session (sp=0x7f2f8e449008) at steps.h:42
> #7 0x0000000000420eda in wrk_do_cnt_sess (w=0x4eccbbc0,
> priv=0x7f2f8e449008) at cache_pool.c:396
> #8 0x00000000004209af in wrk_thread (priv=0x7f2f96e0a100) at
> cache_pool.c:310
> #9 0x00007f2f979723f7 in start_thread () from /lib/libpthread.so.0
> #10 0x00007f2f97247b3d in clone () from /lib/libc.so.6
> #11 0x0000000000000000 in ?? ()
New description:
child dies..
various log entries:
{{{
Nov 26 11:38:39 vkmag-cache varnishd[21915]: Child (21916) not responding
to ping, killing it.
Nov 26 11:38:39 vkmag-cache varnishd[21915]: Child (21916) died signal=6
(core dumped)
Nov 26 11:38:39 vkmag-cache varnishd[21915]: Child (21916) Panic message:
Assert error in WRW_Reserve(), cache_pool.c line 114: Condition((w->wfd)
== 0) not true. errno = 11 (Resource temporarily unavailable) thread =
(cache-worker)sp = 0x7f2f8e449008 { fd = 137, id = 137, xid = 369732011,
client = 213.221.42.174:2504, step = STP_DELIVER, handling = DELIVER,
ws = 0x7f2f8e449078 { id = "sess", {s,f,r,e} =
{0x7f2f8e4497c8,,+1223,(nil),+8192}, }, worker = 0x4eccbbc0 { },
vcl = { srcname = { "input", "Default", },
}, obj = 0x7f2f7b248000 { refcnt = 1, xid = 369732011, ws =
0x7f2f7b248028 { id = "obj", {s,f,r,e} =
{0x7f2f7b248358,,+161,(nil),+7336}, }, http = { ws =
0x7f2f7b248028 { id = "obj", {s,f,r,e} =
{0x7f2f7b248358,,+161,(nil),+7336}, }, hd = { "Date:
Wed, 26 Nov 2008 10:38:39 GMT", "Server: Varnish", "Retry-
After: 0", "Content-Type: text/html; cha
Nov 26 11:38:39 vkmag-cache varnishd[21915]: Child cleanup complete
Nov 26 11:38:40 vkmag-cache varnishd[21915]: child (26105) Started
Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said Closed
fds: 3 4 5 8 9 11 12
Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said Child
starts
Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said Ready
Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said Probe("GET
/check.php HTTP/1.1
Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said
Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said Host:
81.23.234.151
Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said
Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said
Connection: close
Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said
Nov 26 11:38:40 vkmag-cache last message repeated 2 times
Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said ", 0.5, 1)
Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said Probe("GET
/check.php HTTP/1.1
Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said
Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said Host:
81.23.234.152
Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said
Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said
Connection: close
Nov 26 11:38:40 vkmag-cache varnishd[21915]: Child (26105) said
Nov 26 11:38:40 vkmag-cache last message repeated 2 times
root at vkmag-cache:~/varnish/svn# svn info
Path: .
URL: http://varnish.projects.linpro.no/svn
Repository Root: http://varnish.projects.linpro.no/svn
Repository UUID: d4fa192b-c00b-0410-8231-f00ffab90ce4
Revision: 3444
Node Kind: directory
Schedule: normal
Last Changed Author: phk
Last Changed Rev: 3444
Last Changed Date: 2008-11-25 17:04:47 +0100 (Tue, 25 Nov 2008)
21915 ? Ss 0:00 /usr/local/sbin/varnishd -P
/var/run/varnishd.pid -a 0.0.0.0:80 -f /etc/varnish/default.vcl -T
127.0.0.1:6082 -t 120 -w 1,1000,10 -p auto_restart on -s malloc,6G
(gdb) bt
#0 0x00007f2f971a2095 in raise () from /lib/libc.so.6
#1 0x00007f2f971a3af0 in abort () from /lib/libc.so.6
#2 0x000000000041f6a6 in pan_ic (func=0x44c480 "WRW_Reserve",
file=0x44c498 "cache_pool.c", line=114,
cond=0x44c4be "(w->wfd) == 0", err=11, xxx=0) at cache_panic.c:325
#3 0x000000000041fd99 in WRW_Reserve (w=0x4eccbbc0, fd=0x7f2f8e44900c) at
cache_pool.c:114
#4 0x0000000000422184 in RES_WriteObj (sp=0x7f2f8e449008) at
cache_response.c:140
#5 0x000000000041040f in cnt_deliver (sp=0x7f2f8e449008) at
cache_center.c:178
#6 0x00000000004130a9 in CNT_Session (sp=0x7f2f8e449008) at steps.h:42
#7 0x0000000000420eda in wrk_do_cnt_sess (w=0x4eccbbc0,
priv=0x7f2f8e449008) at cache_pool.c:396
#8 0x00000000004209af in wrk_thread (priv=0x7f2f96e0a100) at
cache_pool.c:310
#9 0x00007f2f979723f7 in start_thread () from /lib/libpthread.so.0
#10 0x00007f2f97247b3d in clone () from /lib/libc.so.6
#11 0x0000000000000000 in ?? ()
}}}
--
Ticket URL: <http://varnish.projects.linpro.no/ticket/390#comment:2>
Varnish <http://varnish.projects.linpro.no/>
The Varnish HTTP Accelerator
More information about the varnish-bugs
mailing list