<div dir="ltr">Hi guys!.<div><br></div><div>we are using varnish 3.0.5 and have an issue with Time taken to serve the request parameter (%D)</div><div><br></div><div>we realized that the varnishncsa log is written before the download is complete: example</div>

<div><br></div><div><br></div><div>the download:</div><div><div><font face="courier new, monospace">imac-de-cesar:~ csepulveda$ wget --limit-rate=100k <a href="http://xxx.xxx.com/assets/img/promo-win.jpg">http://xxx.xxx.com/assets/img/promo-win.jpg</a></font></div>

<div><font face="courier new, monospace">--2014-08-08 09:55:27--  <a href="http://xxxx.xxxx.com/assets/img/promo-win.jpg">http://xxxx.xxxx.com/assets/img/promo-win.jpg</a></font></div><div><font face="courier new, monospace">Resolving xxx.xxx.com... xx.xx.xx.xx.</font></div>

<div><font face="courier new, monospace">Connecting to </font><span style="font-family:'courier new',monospace">xxx</span><font face="courier new, monospace">.</font><span style="font-family:'courier new',monospace"> xxx</span><font face="courier new, monospace">.com|xx.xx.xx.xx|:80... connected.</font></div>

<div><font face="courier new, monospace">HTTP request sent, awaiting response... 200 OK</font></div><div><font face="courier new, monospace">Length: 383810 (375K) [image/jpeg]</font></div><div><font face="courier new, monospace">Saving to: ‘promo-win.jpg.2’</font></div>

<div><font face="courier new, monospace"><br></font></div><div><font face="courier new, monospace">100%[=========================================================================>] 383,810      101KB/s   in 3.7s</font></div>

<div><font face="courier new, monospace"><br></font></div><div><font face="courier new, monospace">2014-08-08 09:55:31 (101 KB/s) - ‘promo-win.jpg.2’ saved [383810/383810]</font></div></div><div><br></div><div><br></div>
<div>
The log:</div><div><font face="courier new, monospace">xx.xx.xx.xx "xx.xx.xx.xx" - [08/Aug/2014:13:55:33 +0000] "GET <a href="http://xxx.xxxx.com/assets/img/promo-win.jpg">http://xxx.xxxx.com/assets/img/promo-win.jpg</a> HTTP/1.0" 200 383810 "-" "Wget/UNKNOWN (darwin12.2.0)" 0.401839018 miss 0.785520</font></div>

<div><font face="courier new, monospace"><br></font></div><div>The download take 3.7 seconds but varnishncsa show <span style="font-family:'courier new',monospace">0.785520.</span>watching this with tail -f we see the log is writen when the download go on 5 or 8 percent.</div>

<div><span style="font-family:'courier new',monospace"><br></span></div>The varnishncsa format is this:<div><font face="courier new, monospace">FORMAT="%h \"%{X-Forwarded-For}i\" %u %t \"%r\" %s %b \"%{Referer}i\" \"%{User-agent}i\" %{Varnish:time_firstbyte}x %{Varnish:handling}x %D"</font><br>

</div><div><span style="font-family:'courier new',monospace"><br></span></div>what we are doing wrong?<br>can you help us?<br><br>Thanks!.<div><br clear="all"><div><div dir="ltr">--<br>César Sepúlveda<br>Jefe de Plataforma<div>

Mediastream Chile<br><br>Email: <a href="mailto:csepulveda@mediastre.am" target="_blank">csepulveda@mediastre.am</a><br><span style="font-family:arial;font-size:small">Teléfono: +56 2 </span><span style="font-family:arial;font-size:small">24029750</span><br>

</div></div></div>
</div></div>