[Varnish] #1596: Panic message:#012Assert error in vbf_fetch_thread()

Varnish varnish-bugs at varnish-cache.org
Mon Sep 15 12:06:42 CEST 2014


#1596: Panic message:#012Assert error in vbf_fetch_thread()
--------------------+----------------------------------
 Reporter:  webi    |       Owner:
     Type:  defect  |      Status:  new
 Priority:  normal  |   Milestone:  Varnish 4.0 release
Component:  build   |     Version:  4.0.1
 Severity:  normal  |  Resolution:
 Keywords:          |
--------------------+----------------------------------
Description changed by lkarsten:

Old description:

> I get several times at irregular intervals fogende error message:
>
> {{{#!comment
> Sep 12 10:27:55 svweb01 varnishd[26392]: Child (26411) died signal=6
> Sep 12 10:27:55 svweb01 varnishd[26392]: Child (26411) Panic
> message:#012Assert error in vbf_fetch_thread(), cache/cache_fetch.c line
> 838:#012  Condition(uu == bo->fetch_obj->len) not true.#012thread =
> (cache-worker)#012ident =
> Linux,3.2.0-4-amd64,x86_64,-smalloc,-smalloc,-hcritbit,epoll#012Backtrace:#012
> 0x433fd5: /usr/sbin/varnishd() [0x433fd5]#012  0x421ff6:
> /usr/sbin/varnishd() [0x421ff6]#012  0x436e90:
> /usr/sbin/varnishd(Pool_Work_Thread+0x370) [0x436e90]#012  0x4498ae:
> /usr/sbin/varnishd() [0x4498ae]#012  0x7f628a027b50: /lib/x86_64-linux-
> gnu/libpthread.so.0(+0x6b50) [0x7f628a027b50]#012  0x7f6289d71e6d:
> /lib/x86_64-linux-gnu/libc.so.6(clone+0x6d) [0x7f6289d71e6d]#012  busyobj
> = 0x7f6244521020 {#012    ws = 0x7f62445210e0 {#012      id = "bo",#012
> {s,f,r,e} = {0x7f6244523000,+968,(nil),+57376},#012    },#012  refcnt =
> 1#012  retries = 0#012  failed = 0#012  state = 3#012    is_do_esi#012
> is_do_pass#012    is_uncacheable#012    is_abandon#012
> is_is_gunzip#012    is_should_close#012    bodystatus = 3 (length),#012
> },#012    ws = 0x7f6244521268 {#012      id = "obj",#012      {s,f,r,e} =
> {0x7f62536bf2d0,+280,(nil),+280},#012    },#012  objcore (FETCH) =
> 0x7f62577c3400 {#012    refcnt = 1#012    flags = 0x104#012    objhead =
> 0x7f6289015390#012  }#012  obj (FETCH) = 0x7f62536bf100 {#012    vxid =
> 2168849705,#012    http[obj] = {#012      ws = 0x7f6244521268[obj]#012
> "HTTP/1.1",#012        "200",#012        "OK",#012        "Date: Fri, 12
> Sep 2014 08:27:54 GMT",#012        "Server: Apache/2.2.22 (Debian)",#012
> "Last-Modified: Sun, 15 Dec 2013 13:12:46 GMT",#012        "ETag:
> "2dad5-178-4ed927385cf80"",#012        "Accept-Ranges: bytes",#012
> "Content-Length: 376",#012        "Vary: User-Agent",#012
> "Content-Type: image/png",#012    },#012    len = 376,#012    store =
> {#012    },#012  },#012  }#012
> Sep 12 10:27:55 svweb01 varnishd[26392]: Child cleanup complete
> Sep 12 10:27:55 svweb01 varnishd[26392]: child (29662) Started
> Sep 12 10:27:55 svweb01 varnishd[26392]: Child (29662) said Child starts
>

> Sep 12 10:29:42 svweb01 varnishd[26392]: Child (29662) died signal=6
> Sep 12 10:29:42 svweb01 varnishd[26392]: Child (29662) Panic
> message:#012Assert error in vbf_fetch_thread(), cache/cache_fetch.c line
> 838:#012  Condition(uu == bo->fetch_obj->len) not true.#012thread =
> (cache-worker)#012ident =
> Linux,3.2.0-4-amd64,x86_64,-smalloc,-smalloc,-hcritbit,epoll#012Backtrace:#012
> 0x433fd5: /usr/sbin/varnishd() [0x433fd5]#012  0x421ff6:
> /usr/sbin/varnishd() [0x421ff6]#012  0x436e90:
> /usr/sbin/varnishd(Pool_Work_Thread+0x370) [0x436e90]#012  0x4498ae:
> /usr/sbin/varnishd() [0x4498ae]#012  0x7f628a027b50: /lib/x86_64-linux-
> gnu/libpthread.so.0(+0x6b50) [0x7f628a027b50]#012  0x7f6289d71e6d:
> /lib/x86_64-linux-gnu/libc.so.6(clone+0x6d) [0x7f6289d71e6d]#012  busyobj
> = 0x7f6263309020 {#012    ws = 0x7f62633090e0 {#012      id = "bo",#012
> {s,f,r,e} = {0x7f626330b000,+12528,(nil),+57376},#012    },#012  refcnt =
> 1#012  retries = 0#012  failed = 0#012  state = 3#012    is_do_esi#012
> is_do_pass#012    is_uncacheable#012    is_abandon#012    is_is_gzip#012
> is_should_close#012    bodystatus = 3 (length),#012    },#012    ws =
> 0x7f6263309268 {#012      id = "obj",#012      {s,f,r,e} =
> {0x7f625f52fee8,+328,(nil),+328},#012    },#012  objcore (FETCH) =
> 0x7f626ab7d080 {#012    refcnt = 1#012    flags = 0x104#012    objhead =
> 0x7f6289015400#012  }#012  obj (FETCH) = 0x7f625f52fd00 {#012    vxid =
> 2158985234,#012    http[obj] = {#012      ws = 0x7f6263309268[obj]#012
> "HTTP/1.1",#012        "200",#012        "OK",#012        "Date: Fri, 12
> Sep 2014 08:29:41 GMT",#012        "Server: Apache/2.2.22 (Debian)",#012
> "Last-Modified: Wed, 10 Sep 2014 04:52:00 GMT",#012        "Accept-
> Ranges: bytes",#012        "Vary: Accept-Encoding,User-Agent",#012
> "Content-Encoding: gzip",#012        "Content-Length: 11847",#012
> "Content-Type: text/css",#012        "ETag: W/"29b5f-
> 108f0-502aecffd4c5f"",#012    },#012    len = 11859,#012    store = {#012
> },#012  },#012  }#012
> Sep 12 10:29:42 svweb01 varnishd[26392]: Child cleanup complete
> Sep 12 10:29:42 svweb01 varnishd[26392]: child (30322) Started
> Sep 12 10:29:42 svweb01 varnishd[26392]: Child (30322) said Child starts
>
> Sep 12 11:09:18 svweb01 varnishd[26392]: Child (29662) died signal=6
> Sep 12 11:09:18 svweb01 varnishd[26392]: Child (30322) Panic
> message:#012Assert error in vbf_fetch_thread(), cache/cache_fetch.c line
> 838:#012  Condition(uu == bo->fetch_obj->len) not true.#012thread =
> (cache-worker)#012ident =
> Linux,3.2.0-4-amd64,x86_64,-smalloc,-smalloc,-hcritbit,epoll#012Backtrace:#012
> 0x433fd5: /usr/sbin/varnishd() [0x433fd5]#012  0x421ff6:
> /usr/sbin/varnishd() [0x421ff6]#012  0x436e90:
> /usr/sbin/varnishd(Pool_Work_Thread+0x370) [0x436e90]#012  0x4498ae:
> /usr/sbin/varnishd() [0x4498ae]#012  0x7f628a027b50: /lib/x86_64-linux-
> gnu/libpthread.so.0(+0x6b50) [0x7f628a027b50]#012  0x7f6289d71e6d:
> /lib/x86_64-linux-gnu/libc.so.6(clone+0x6d) [0x7f6289d71e6d]#012  busyobj
> = 0x7f6219847020 {#012    ws = 0x7f62198470e0 {#012      id = "bo",#012
> {s,f,r,e} = {0x7f6219849000,+6616,(nil),+57376},#012    },#012  refcnt =
> 1#012  retries = 0#012  failed = 0#012  state = 3#012    is_do_esi#012
> is_do_pass#012    is_uncacheable#012    is_abandon#012
> is_is_gunzip#012    bodystatus = 3 (length),#012    },#012    ws =
> 0x7f6219847268 {#012      id = "obj",#012      {s,f,r,e} =
> {0x7f6258aa0bd0,+288,(nil),+288},#012    },#012  objcore (FETCH) =
> 0x7f62373d4200 {#012    refcnt = 1#012    flags = 0x104#012    objhead =
> 0x7f6289015470#012  }#012  obj (FETCH) = 0x7f6258aa0a00 {#012    vxid =
> 2169965050,#012    http[obj] = {#012      ws = 0x7f6219847268[obj]#012
> "HTTP/1.1",#012        "200",#012        "OK",#012        "Date: Fri, 12
> Sep 2014 09:09:17 GMT",#012        "Server: Apache/2.2.22 (Debian)",#012
> "Last-Modified: Tue, 08 Oct 2013 20:21:03 GMT",#012        "ETag:
> "2adbb-1797-4e84081f969c0"",#012        "Accept-Ranges: bytes",#012
> "Content-Length: 6039",#012        "Vary: User-Agent",#012
> "Content-Type: image/png",#012    },#012    len = 6039,#012    store =
> {#012    },#012  },#012  }#012
> Sep 12 11:09:18 svweb01 varnishd[26392]: Child cleanup complete
> Sep 12 11:09:18 svweb01 varnishd[26392]: child (2527) Started
> Sep 12 11:09:18 svweb01 varnishd[26392]: Child (2527) said Child starts
> }}}

New description:

 I get several times at irregular intervals fogende error message:

 {{{
 Panic message:
 Assert error in vbf_fetch_thread(), cache/cache_fetch.c line 838:
   Condition(uu == bo->fetch_obj->len) not true.
 thread = (cache-worker)
 ident = Linux,3.2.0-4-amd64,x86_64,-smalloc,-smalloc,-hcritbit,epoll
 Backtrace:
   0x433fd5: /usr/sbin/varnishd() [0x433fd5]
   0x421ff6: /usr/sbin/varnishd() [0x421ff6]
   0x436e90: /usr/sbin/varnishd(Pool_Work_Thread+0x370) [0x436e90]
   0x4498ae: /usr/sbin/varnishd() [0x4498ae]
   0x7f628a027b50: /lib/x86_64-linux-gnu/libpthread.so.0(+0x6b50)
 [0x7f628a027b50]
   0x7f6289d71e6d: /lib/x86_64-linux-gnu/libc.so.6(clone+0x6d)
 [0x7f6289d71e6d]
   busyobj = 0x7f6219847020 {
     ws = 0x7f62198470e0 {
       id = "bo",
       {s,f,r,e} = {0x7f6219849000,+6616,(nil),+57376},
     },
   refcnt = 1
   retries = 0
   failed = 0
   state = 3
     is_do_esi
     is_do_pass
     is_uncacheable
     is_abandon
     is_is_gunzip
     bodystatus = 3 (length),
     },
     ws = 0x7f6219847268 {
       id = "obj",
       {s,f,r,e} = {0x7f6258aa0bd0,+288,(nil),+288},
     },
   objcore (FETCH) = 0x7f62373d4200 {
     refcnt = 1
     flags = 0x104
     objhead = 0x7f6289015470
   }
   obj (FETCH) = 0x7f6258aa0a00 {
     vxid = 2169965050,
     http[obj] = {
       ws = 0x7f6219847268[obj]
         "HTTP/1.1",
         "200",
         "OK",
         "Date: Fri, 12 Sep 2014 09:09:17 GMT",
         "Server: Apache/2.2.22 (Debian)",
         "Last-Modified: Tue, 08 Oct 2013 20:21:03 GMT",
         "ETag: "2adbb-1797-4e84081f969c0"",
         "Accept-Ranges: bytes",
         "Content-Length: 6039",
         "Vary: User-Agent",
         "Content-Type: image/png",
     },
     len = 6039,
     store = {
     },
   },
   }
 }}}
 {{{#!comment
 Sep 12 10:27:55 svweb01 varnishd[26392]: Child (26411) died signal=6
 Sep 12 10:27:55 svweb01 varnishd[26392]: Child (26411) Panic
 message:#012Assert error in vbf_fetch_thread(), cache/cache_fetch.c line
 838:#012  Condition(uu == bo->fetch_obj->len) not true.#012thread =
 (cache-worker)#012ident =
 Linux,3.2.0-4-amd64,x86_64,-smalloc,-smalloc,-hcritbit,epoll#012Backtrace:#012
 0x433fd5: /usr/sbin/varnishd() [0x433fd5]#012  0x421ff6:
 /usr/sbin/varnishd() [0x421ff6]#012  0x436e90:
 /usr/sbin/varnishd(Pool_Work_Thread+0x370) [0x436e90]#012  0x4498ae:
 /usr/sbin/varnishd() [0x4498ae]#012  0x7f628a027b50: /lib/x86_64-linux-
 gnu/libpthread.so.0(+0x6b50) [0x7f628a027b50]#012  0x7f6289d71e6d:
 /lib/x86_64-linux-gnu/libc.so.6(clone+0x6d) [0x7f6289d71e6d]#012  busyobj
 = 0x7f6244521020 {#012    ws = 0x7f62445210e0 {#012      id = "bo",#012
 {s,f,r,e} = {0x7f6244523000,+968,(nil),+57376},#012    },#012  refcnt =
 1#012  retries = 0#012  failed = 0#012  state = 3#012    is_do_esi#012
 is_do_pass#012    is_uncacheable#012    is_abandon#012    is_is_gunzip#012
 is_should_close#012    bodystatus = 3 (length),#012    },#012    ws =
 0x7f6244521268 {#012      id = "obj",#012      {s,f,r,e} =
 {0x7f62536bf2d0,+280,(nil),+280},#012    },#012  objcore (FETCH) =
 0x7f62577c3400 {#012    refcnt = 1#012    flags = 0x104#012    objhead =
 0x7f6289015390#012  }#012  obj (FETCH) = 0x7f62536bf100 {#012    vxid =
 2168849705,#012    http[obj] = {#012      ws = 0x7f6244521268[obj]#012
 "HTTP/1.1",#012        "200",#012        "OK",#012        "Date: Fri, 12
 Sep 2014 08:27:54 GMT",#012        "Server: Apache/2.2.22 (Debian)",#012
 "Last-Modified: Sun, 15 Dec 2013 13:12:46 GMT",#012        "ETag:
 "2dad5-178-4ed927385cf80"",#012        "Accept-Ranges: bytes",#012
 "Content-Length: 376",#012        "Vary: User-Agent",#012        "Content-
 Type: image/png",#012    },#012    len = 376,#012    store = {#012
 },#012  },#012  }#012
 Sep 12 10:27:55 svweb01 varnishd[26392]: Child cleanup complete
 Sep 12 10:27:55 svweb01 varnishd[26392]: child (29662) Started
 Sep 12 10:27:55 svweb01 varnishd[26392]: Child (29662) said Child starts


 Sep 12 10:29:42 svweb01 varnishd[26392]: Child (29662) died signal=6
 Sep 12 10:29:42 svweb01 varnishd[26392]: Child (29662) Panic
 message:#012Assert error in vbf_fetch_thread(), cache/cache_fetch.c line
 838:#012  Condition(uu == bo->fetch_obj->len) not true.#012thread =
 (cache-worker)#012ident =
 Linux,3.2.0-4-amd64,x86_64,-smalloc,-smalloc,-hcritbit,epoll#012Backtrace:#012
 0x433fd5: /usr/sbin/varnishd() [0x433fd5]#012  0x421ff6:
 /usr/sbin/varnishd() [0x421ff6]#012  0x436e90:
 /usr/sbin/varnishd(Pool_Work_Thread+0x370) [0x436e90]#012  0x4498ae:
 /usr/sbin/varnishd() [0x4498ae]#012  0x7f628a027b50: /lib/x86_64-linux-
 gnu/libpthread.so.0(+0x6b50) [0x7f628a027b50]#012  0x7f6289d71e6d:
 /lib/x86_64-linux-gnu/libc.so.6(clone+0x6d) [0x7f6289d71e6d]#012  busyobj
 = 0x7f6263309020 {#012    ws = 0x7f62633090e0 {#012      id = "bo",#012
 {s,f,r,e} = {0x7f626330b000,+12528,(nil),+57376},#012    },#012  refcnt =
 1#012  retries = 0#012  failed = 0#012  state = 3#012    is_do_esi#012
 is_do_pass#012    is_uncacheable#012    is_abandon#012    is_is_gzip#012
 is_should_close#012    bodystatus = 3 (length),#012    },#012    ws =
 0x7f6263309268 {#012      id = "obj",#012      {s,f,r,e} =
 {0x7f625f52fee8,+328,(nil),+328},#012    },#012  objcore (FETCH) =
 0x7f626ab7d080 {#012    refcnt = 1#012    flags = 0x104#012    objhead =
 0x7f6289015400#012  }#012  obj (FETCH) = 0x7f625f52fd00 {#012    vxid =
 2158985234,#012    http[obj] = {#012      ws = 0x7f6263309268[obj]#012
 "HTTP/1.1",#012        "200",#012        "OK",#012        "Date: Fri, 12
 Sep 2014 08:29:41 GMT",#012        "Server: Apache/2.2.22 (Debian)",#012
 "Last-Modified: Wed, 10 Sep 2014 04:52:00 GMT",#012        "Accept-Ranges:
 bytes",#012        "Vary: Accept-Encoding,User-Agent",#012
 "Content-Encoding: gzip",#012        "Content-Length: 11847",#012
 "Content-Type: text/css",#012        "ETag: W/"29b5f-
 108f0-502aecffd4c5f"",#012    },#012    len = 11859,#012    store = {#012
 },#012  },#012  }#012
 Sep 12 10:29:42 svweb01 varnishd[26392]: Child cleanup complete
 Sep 12 10:29:42 svweb01 varnishd[26392]: child (30322) Started
 Sep 12 10:29:42 svweb01 varnishd[26392]: Child (30322) said Child starts

 Sep 12 11:09:18 svweb01 varnishd[26392]: Child (29662) died signal=6
 Sep 12 11:09:18 svweb01 varnishd[26392]: Child (30322) Panic
 message:#012Assert error in vbf_fetch_thread(), cache/cache_fetch.c line
 838:#012  Condition(uu == bo->fetch_obj->len) not true.#012thread =
 (cache-worker)#012ident =
 Linux,3.2.0-4-amd64,x86_64,-smalloc,-smalloc,-hcritbit,epoll#012Backtrace:#012
 0x433fd5: /usr/sbin/varnishd() [0x433fd5]#012  0x421ff6:
 /usr/sbin/varnishd() [0x421ff6]#012  0x436e90:
 /usr/sbin/varnishd(Pool_Work_Thread+0x370) [0x436e90]#012  0x4498ae:
 /usr/sbin/varnishd() [0x4498ae]#012  0x7f628a027b50: /lib/x86_64-linux-
 gnu/libpthread.so.0(+0x6b50) [0x7f628a027b50]#012  0x7f6289d71e6d:
 /lib/x86_64-linux-gnu/libc.so.6(clone+0x6d) [0x7f6289d71e6d]#012  busyobj
 = 0x7f6219847020 {#012    ws = 0x7f62198470e0 {#012      id = "bo",#012
 {s,f,r,e} = {0x7f6219849000,+6616,(nil),+57376},#012    },#012  refcnt =
 1#012  retries = 0#012  failed = 0#012  state = 3#012    is_do_esi#012
 is_do_pass#012    is_uncacheable#012    is_abandon#012    is_is_gunzip#012
 bodystatus = 3 (length),#012    },#012    ws = 0x7f6219847268 {#012
 id = "obj",#012      {s,f,r,e} = {0x7f6258aa0bd0,+288,(nil),+288},#012
 },#012  objcore (FETCH) = 0x7f62373d4200 {#012    refcnt = 1#012    flags
 = 0x104#012    objhead = 0x7f6289015470#012  }#012  obj (FETCH) =
 0x7f6258aa0a00 {#012    vxid = 2169965050,#012    http[obj] = {#012
 ws = 0x7f6219847268[obj]#012        "HTTP/1.1",#012        "200",#012
 "OK",#012        "Date: Fri, 12 Sep 2014 09:09:17 GMT",#012
 "Server: Apache/2.2.22 (Debian)",#012        "Last-Modified: Tue, 08 Oct
 2013 20:21:03 GMT",#012        "ETag: "2adbb-1797-4e84081f969c0"",#012
 "Accept-Ranges: bytes",#012        "Content-Length: 6039",#012
 "Vary: User-Agent",#012        "Content-Type: image/png",#012    },#012
 len = 6039,#012    store = {#012    },#012  },#012  }#012
 Sep 12 11:09:18 svweb01 varnishd[26392]: Child cleanup complete
 Sep 12 11:09:18 svweb01 varnishd[26392]: child (2527) Started
 Sep 12 11:09:18 svweb01 varnishd[26392]: Child (2527) said Child starts
 }}}

--

-- 
Ticket URL: <https://www.varnish-cache.org/trac/ticket/1596#comment:2>
Varnish <https://varnish-cache.org/>
The Varnish HTTP Accelerator



More information about the varnish-bugs mailing list