[Varnish] #805: Negative ReqEnd accept time (ESI enabled) and hanging request

[Varnish]

#805: Negative ReqEnd accept time (ESI enabled) and hanging request
----------------------+-----------------------------------------------------
 Reporter:  tesdal    |       Owner:  phk  
     Type:  defect    |      Status:  new  
 Priority:  normal    |   Milestone:       
Component:  varnishd  |     Version:  2.1.3
 Severity:  normal    |    Keywords:       
----------------------+-----------------------------------------------------
 We see entries like

    21 ReqEnd       c 315153529 1288175527.482929945 1288175531.779880047
 -4.293623447 nan nan

    14 ReqEnd       c 315196003 1288177021.750665903 1288177023.020342827
 -1.266375780 nan nan

 in Varnish 2.1.3 with ESI enabled.

 HAProxy in front reports timing like 0/0/0/63/101889 and
 0/0/0/8649/1182844. That means it took 63ms and 8649ms before Varnish
 started returning data, but that the connection wasn't closed until after
 101 and 1182 seconds.

 Varnish logs are in:
 http://pastie.textmate.org/private/rperx3qox8nmys71qg9w3a

 ESI is used extensively for the entire site, it's not unique to this URL,
 but this URL is the only place we have noticed the seemingly hanging
 Varnish connections so far.

 Tollef said that he had an idea he wanted to investigate about the cause
 of this.

-- 
Ticket URL: <http://www.varnish-cache.org/trac/ticket/805>
Varnish <http://varnish-cache.org/>
The Varnish HTTP Accelerator