logoutput from r1667

[Denis Ahrens]

On 12.07.2007, at 00:30, Poul-Henning Kamp wrote:

> In message <16367464-71EA-4233-9348-241363049866 at zeno.org>, Denis  
> Ahrens writes
> :
>> Hi
>>
>> here a logoutput from r1667 with default VCL:
>
> Can I get you to check your clock relative to the backend ?

Ok, I (ok, actually it was saite) made a patch to show
the actual problem:

before changeset r1639

RES_WriteObj() was responsible for setting t_resp and the output of  
the "Age:" header

after changeset r1639

RES_BuildHTTP() sometimes uses sp->t_resp uninitialized.

Index: bin/varnishd/cache_response.c
===================================================================
--- bin/varnishd/cache_response.c	(revision 1667)
+++ bin/varnishd/cache_response.c	(working copy)
@@ -134,6 +134,11 @@
  	else
  		http_PrintfHeader(sp->wrk, sp->fd, sp->http,
  		    "X-Varnish: %u", sp->xid);
+	if (!sp->t_resp.tv_sec)
+	{
+		http_PrintfHeader(sp->wrk, sp->fd, sp->http, "DEBUG: reorder  
problem occured: sp->t_resp.tv_sec is 0");
+		clock_gettime(CLOCK_REALTIME, &sp->t_resp);
+	}
  	http_PrintfHeader(sp->wrk, sp->fd, sp->http, "Age: %u",
  	    sp->obj->age + sp->t_resp.tv_sec - sp->obj->entered);
  	http_SetHeader(sp->wrk, sp->fd, sp->http, "Via: 1.1 varnish");

Denis