NaN in varnishlog ReqEnd

[Rob S]

Hi,

I'm running varnish 2.1.5 (SVN 0843d7a), and I'm seeing quite odd
numbers in my ReqEnd.


  601 ReqEnd       c 483016025 1309454855.855156898
1309454865.751157045 -9.895982027 nan nan


The documentation at
https://www.varnish-cache.org/trac/wiki/Varnishlog doesn't really
explain any reason for having a negative fourth number, or for nans in
the fifth and sixth positions.

I think this is connected with ESIs - the page being loaded is one
which includes several ESI fragments.  I'm looking at the ReqEnd line
as I'm trying to identify whether this page is being slow to generate,
or if the http clients are being slow to retrieve the data.

Can anyone offer more information on this?


Rob