[Varnish] #1592: Assert error in WS_Release(), cache/cache_ws.c line 225:

Varnish varnish-bugs at varnish-cache.org
Sun Sep 7 11:58:23 CEST 2014


#1592: Assert error in WS_Release(), cache/cache_ws.c line 225:
---------------------+--------------------
 Reporter:  olli     |       Owner:
     Type:  defect   |      Status:  new
 Priority:  normal   |   Milestone:
Component:  build    |     Version:  4.0.1
 Severity:  blocker  |  Resolution:
 Keywords:           |
---------------------+--------------------
Description changed by fgsch:

Old description:

> Hi,
>
> I want to switch from varnish 3.0.4 to varnish-4.0.1 but I get the
> following errors in 4.0.1:
>
> @400000005409907619329984 Child (7530) died signal=6
> @40000000540990761933417c Child (7530) Panic message:
> @40000000540990761933417c Assert error in WS_Release(), cache/cache_ws.c
> line 225:
> @400000005409907619334564   Condition(bytes <= ws->e - ws->f) not true.
> @40000000540990761933494c thread = (cache-worker)
> @40000000540990761933494c ident =
> Linux,3.2.0-4-amd64,x86_64,-smalloc,-smalloc,-hcritbit,epoll
> @400000005409907619334d34 Backtrace:
> @400000005409907619334d34   0x433fe5: pan_ic+0xc5
> @400000005409907619334d34   0x44b573: WS_Release+0xb3
> @40000000540990761933511c   0x442e28: VRT_IP_string+0x68
> @400000005409907619336c74   0x7f182a0d328e:
> ./vcl.0aGwBrPz.so(VGC_function_vcl_deliver+0xae) [0x7f182a0d328e]
> @40000000540990761933705c   0x440817: vcl_call_method+0x187
> @40000000540990761933705c   0x441728: VCL_deliver_method+0x48
> @400000005409907619337444   0x437e2e: cnt_deliver+0x1ce
> @400000005409907619337444   0x438531: CNT_Request+0x111
> @40000000540990761933782c   0x41a510: ESI_Deliver+0x7d0
> @40000000540990761933782c   0x43b2a8: V1D_Deliver+0x368
> @40000000540990761933ddbc req = 0x7f181fc9a020 {
> @40000000540990761933e1a4   sp = 0x7f181ec1b520, vxid = 1073872940,  step
> = R_STP_DELIVER,
> @40000000540990761933e58c   req_body = R_BODY_NONE,
> @40000000540990761933e58c   err_code = 503, err_reason = (null),
> @40000000540990761933e58c   restarts = 0, esi_level = 1
> @40000000540990761933e974   sp = 0x7f181ec1b520 {
> @40000000540990761933e974     fd = 29, vxid = 131085,
> @40000000540990761933ed5c     client = 193.27.50.88 36722,
> @40000000540990761933ed5c     step = S_STP_WORKING,
> @4000000054099076193400e4   },
> @4000000054099076193400e4   worker = 0x7f1823d1ec50 {
> @4000000054099076193404cc     ws = 0x7f1823d1ee68 {
> @4000000054099076193404cc       id = "wrk",
> @4000000054099076193404cc       {s,f,r,e} =
> {0x7f1823d1e450,0x7f1823d1e450,(nil),+2048},
> @400000005409907619342024     },
> @400000005409907619342024   VCL::method = DELIVER,
> @40000000540990761934240c   VCL::return = abandon,
> @40000000540990761934240c   },
> @40000000540990761934240c   ws = 0x7f181fc9a1b8 {
> @4000000054099076193427f4     id = "req",
> @4000000054099076193427f4     {s,f,r,e} =
> {0x7f181fc9c010,+56,(nil),+57360},
> @400000005409907619342bdc   },
> @400000005409907619342bdc   http[req] = {
> @400000005409907619342bdc     ws = 0x7f181fc671b8[req]
> @400000005409907619343f64       "GET",
> @400000005409907619343f64
> "/esi/topflop.htn?esiPermId=252&typ=mostclickedSearch&ajax=10&limit=10&divId=USFMostClickedSearch&seite=zertifikate",
> @40000000540990761934434c       "HTTP/1.1",
> @400000005409907619344734       "Accept: */*",
> @400000005409907619344734       "Accept-Language: de-DE",
> @400000005409907619344b1c       "Pragma: no-cache",
> @400000005409907619344b1c       "User-Agent: Mozilla/4.0 (compatible;
> MSIE 8.0; Windows NT 6.1; WOW64; Trident/4.0; GTB7.4; SLCC2; .NET CLR
> 2.0.50727; .NET CLR 3.5.30729; .NET CLR 3.0.30729; Media Center PC 6.0;
> .NET CLR 1.1.4322; .NET4.0C; .NET4.0E)",
> @400000005409907619346e44       "Via: 1.1 komsrv:3128 (KEN!), 1.1
> idssquid17.services.datevnet.de (squid)",
> @40000000540990761934722c       "Cache-Control: no-cache",
> @40000000540990761934722c       "Connection: keep-alive",
> @400000005409907619347614       "Host: www.finanztreff.de",
> @400000005409907619347de4       "X-USF-clientip: 193.27.50.88",
> @400000005409907619347de4       "X-Forwarded-For: 193.27.50.88,
> 193.27.50.88",
> @4000000054099076193481cc       "X-USF-ESI-Level: 1",
> @4000000054099076193481cc       "Accept-Encoding: gzip",
> @4000000054099076193481cc   },
> @4000000054099076193485b4   http[resp] = {
> @4000000054099076193485b4     ws = 0x7f181fc9a1b8[req]
> @40000000540990761934993c       "HTTP/1.1",
> @40000000540990761934993c       "503",
> @400000005409907619349d24       "Backend fetch failed",
> @400000005409907619349d24       "Date: Fri, 05 Sep 2014 10:28:59 GMT",
> @400000005409907619349d24       "Server: Varnish",
> @40000000540990761934a10c       "Content-Type: text/html; charset=utf-8",
> @40000000540990761934a10c       "Retry-After: 5",
> @40000000540990761934a10c       "X-Varnish: 131116",
> @40000000540990761934a4f4       "Age: 0",
> @40000000540990761934acc4       "Via: 1.1 varnish-v4",
> @40000000540990761934b0ac       "X-USF-Cache: MISS",
> @40000000540990761934b0ac   },
> @40000000540990761934b0ac   vcl = {
> @40000000540990761934b0ac     srcname = {
> @40000000540990761934b494       "input",
> @40000000540990761934b494       "Builtin",
> @40000000540990761934b494       "/usr/local/opt/varnish/etc/cookie.inc",
> @40000000540990761934b494       "/usr/local/opt/varnish/etc/pool-
> push-a.inc",
> @40000000540990761934b87c       "/usr/local/opt/varnish/etc/push.inc",
> @40000000540990761934c04c     },
> @40000000540990761934c434   },
> @40000000540990761934c434   obj (REQ) = 0x7f182283cf80 {
> @40000000540990761934c434     vxid = 2147614765,
> @40000000540990761934c434     http[obj] = {
> @40000000540990761934c434       ws = 0x7f18200ab268[]
> @40000000540990761934c81c         "HTTP/1.1",
> @40000000540990761934c81c         "503",
> @40000000540990761934c81c         "Backend fetch failed",
> @40000000540990761934cc04         "Date: Fri, 05 Sep 2014 10:28:59 GMT",
> @40000000540990761934e374         "Server: Varnish",
> @40000000540990761934e374         "Content-Type: text/html;
> charset=utf-8",
> @40000000540990761934e75c         "Retry-After: 5",
> @40000000540990761934e75c     },
> @40000000540990761934e75c     len = 418,
> @40000000540990761934e75c     store = {
> @40000000540990761934eb44       418 {
> @40000000540990761934eb44         0a 3c 3f 78 6d 6c 20 76 65 72 73 69 6f
> 6e 3d 22 |.<?xml version="|
> @40000000540990761934eb44         31 2e 30 22 20 65 6e 63 6f 64 69 6e 67
> 3d 22 75 |1.0" encoding="u|
> @40000000540990761934fecc         74 66 2d 38 22 3f 3e 0a 3c 21 44 4f 43
> 54 59 50 |tf-8"?>.<!DOCTYP|
> @4000000054099076193502b4         45 20 68 74 6d 6c 20 50 55 42 4c 49 43
> 20 22 2d |E html PUBLIC "-|
> @4000000054099076193502b4         [354 more]
> @4000000054099076193502b4       },
> @40000000540990761935069c     },
> @40000000540990761935069c   },
> @40000000540990761935069c },
>
> I can not reproduce bei myself. We make extensive use of ESI.

New description:

 Hi,

 I want to switch from varnish 3.0.4 to varnish-4.0.1 but I get the
 following errors in 4.0.1:

 {{{
 @400000005409907619329984 Child (7530) died signal=6
 @40000000540990761933417c Child (7530) Panic message:
 @40000000540990761933417c Assert error in WS_Release(), cache/cache_ws.c
 line 225:
 @400000005409907619334564   Condition(bytes <= ws->e - ws->f) not true.
 @40000000540990761933494c thread = (cache-worker)
 @40000000540990761933494c ident =
 Linux,3.2.0-4-amd64,x86_64,-smalloc,-smalloc,-hcritbit,epoll
 @400000005409907619334d34 Backtrace:
 @400000005409907619334d34   0x433fe5: pan_ic+0xc5
 @400000005409907619334d34   0x44b573: WS_Release+0xb3
 @40000000540990761933511c   0x442e28: VRT_IP_string+0x68
 @400000005409907619336c74   0x7f182a0d328e:
 ./vcl.0aGwBrPz.so(VGC_function_vcl_deliver+0xae) [0x7f182a0d328e]
 @40000000540990761933705c   0x440817: vcl_call_method+0x187
 @40000000540990761933705c   0x441728: VCL_deliver_method+0x48
 @400000005409907619337444   0x437e2e: cnt_deliver+0x1ce
 @400000005409907619337444   0x438531: CNT_Request+0x111
 @40000000540990761933782c   0x41a510: ESI_Deliver+0x7d0
 @40000000540990761933782c   0x43b2a8: V1D_Deliver+0x368
 @40000000540990761933ddbc req = 0x7f181fc9a020 {
 @40000000540990761933e1a4   sp = 0x7f181ec1b520, vxid = 1073872940,  step
 = R_STP_DELIVER,
 @40000000540990761933e58c   req_body = R_BODY_NONE,
 @40000000540990761933e58c   err_code = 503, err_reason = (null),
 @40000000540990761933e58c   restarts = 0, esi_level = 1
 @40000000540990761933e974   sp = 0x7f181ec1b520 {
 @40000000540990761933e974     fd = 29, vxid = 131085,
 @40000000540990761933ed5c     client = 193.27.50.88 36722,
 @40000000540990761933ed5c     step = S_STP_WORKING,
 @4000000054099076193400e4   },
 @4000000054099076193400e4   worker = 0x7f1823d1ec50 {
 @4000000054099076193404cc     ws = 0x7f1823d1ee68 {
 @4000000054099076193404cc       id = "wrk",
 @4000000054099076193404cc       {s,f,r,e} =
 {0x7f1823d1e450,0x7f1823d1e450,(nil),+2048},
 @400000005409907619342024     },
 @400000005409907619342024   VCL::method = DELIVER,
 @40000000540990761934240c   VCL::return = abandon,
 @40000000540990761934240c   },
 @40000000540990761934240c   ws = 0x7f181fc9a1b8 {
 @4000000054099076193427f4     id = "req",
 @4000000054099076193427f4     {s,f,r,e} =
 {0x7f181fc9c010,+56,(nil),+57360},
 @400000005409907619342bdc   },
 @400000005409907619342bdc   http[req] = {
 @400000005409907619342bdc     ws = 0x7f181fc671b8[req]
 @400000005409907619343f64       "GET",
 @400000005409907619343f64
 "/esi/topflop.htn?esiPermId=252&typ=mostclickedSearch&ajax=10&limit=10&divId=USFMostClickedSearch&seite=zertifikate",
 @40000000540990761934434c       "HTTP/1.1",
 @400000005409907619344734       "Accept: */*",
 @400000005409907619344734       "Accept-Language: de-DE",
 @400000005409907619344b1c       "Pragma: no-cache",
 @400000005409907619344b1c       "User-Agent: Mozilla/4.0 (compatible; MSIE
 8.0; Windows NT 6.1; WOW64; Trident/4.0; GTB7.4; SLCC2; .NET CLR
 2.0.50727; .NET CLR 3.5.30729; .NET CLR 3.0.30729; Media Center PC 6.0;
 .NET CLR 1.1.4322; .NET4.0C; .NET4.0E)",
 @400000005409907619346e44       "Via: 1.1 komsrv:3128 (KEN!), 1.1
 idssquid17.services.datevnet.de (squid)",
 @40000000540990761934722c       "Cache-Control: no-cache",
 @40000000540990761934722c       "Connection: keep-alive",
 @400000005409907619347614       "Host: www.finanztreff.de",
 @400000005409907619347de4       "X-USF-clientip: 193.27.50.88",
 @400000005409907619347de4       "X-Forwarded-For: 193.27.50.88,
 193.27.50.88",
 @4000000054099076193481cc       "X-USF-ESI-Level: 1",
 @4000000054099076193481cc       "Accept-Encoding: gzip",
 @4000000054099076193481cc   },
 @4000000054099076193485b4   http[resp] = {
 @4000000054099076193485b4     ws = 0x7f181fc9a1b8[req]
 @40000000540990761934993c       "HTTP/1.1",
 @40000000540990761934993c       "503",
 @400000005409907619349d24       "Backend fetch failed",
 @400000005409907619349d24       "Date: Fri, 05 Sep 2014 10:28:59 GMT",
 @400000005409907619349d24       "Server: Varnish",
 @40000000540990761934a10c       "Content-Type: text/html; charset=utf-8",
 @40000000540990761934a10c       "Retry-After: 5",
 @40000000540990761934a10c       "X-Varnish: 131116",
 @40000000540990761934a4f4       "Age: 0",
 @40000000540990761934acc4       "Via: 1.1 varnish-v4",
 @40000000540990761934b0ac       "X-USF-Cache: MISS",
 @40000000540990761934b0ac   },
 @40000000540990761934b0ac   vcl = {
 @40000000540990761934b0ac     srcname = {
 @40000000540990761934b494       "input",
 @40000000540990761934b494       "Builtin",
 @40000000540990761934b494       "/usr/local/opt/varnish/etc/cookie.inc",
 @40000000540990761934b494       "/usr/local/opt/varnish/etc/pool-
 push-a.inc",
 @40000000540990761934b87c       "/usr/local/opt/varnish/etc/push.inc",
 @40000000540990761934c04c     },
 @40000000540990761934c434   },
 @40000000540990761934c434   obj (REQ) = 0x7f182283cf80 {
 @40000000540990761934c434     vxid = 2147614765,
 @40000000540990761934c434     http[obj] = {
 @40000000540990761934c434       ws = 0x7f18200ab268[]
 @40000000540990761934c81c         "HTTP/1.1",
 @40000000540990761934c81c         "503",
 @40000000540990761934c81c         "Backend fetch failed",
 @40000000540990761934cc04         "Date: Fri, 05 Sep 2014 10:28:59 GMT",
 @40000000540990761934e374         "Server: Varnish",
 @40000000540990761934e374         "Content-Type: text/html;
 charset=utf-8",
 @40000000540990761934e75c         "Retry-After: 5",
 @40000000540990761934e75c     },
 @40000000540990761934e75c     len = 418,
 @40000000540990761934e75c     store = {
 @40000000540990761934eb44       418 {
 @40000000540990761934eb44         0a 3c 3f 78 6d 6c 20 76 65 72 73 69 6f
 6e 3d 22 |.<?xml version="|
 @40000000540990761934eb44         31 2e 30 22 20 65 6e 63 6f 64 69 6e 67
 3d 22 75 |1.0" encoding="u|
 @40000000540990761934fecc         74 66 2d 38 22 3f 3e 0a 3c 21 44 4f 43
 54 59 50 |tf-8"?>.<!DOCTYP|
 @4000000054099076193502b4         45 20 68 74 6d 6c 20 50 55 42 4c 49 43
 20 22 2d |E html PUBLIC "-|
 @4000000054099076193502b4         [354 more]
 @4000000054099076193502b4       },
 @40000000540990761935069c     },
 @40000000540990761935069c   },
 @40000000540990761935069c },
 }}}

 I can not reproduce bei myself. We make extensive use of ESI.

--

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



More information about the varnish-bugs mailing list