Mailing List Archive

Martin Aspeli wrote:
> Hi guys,
>
> I'm having some trouble with Varnish 1.1.1 tarball and 1.1 branch in svn
> (r1922), and trunk as of today.

... and worse, when this happens, Firefox 2 on OSX actually crashes and
needs to be killed manually. :)

Martin

--
Acquisition is a jealous mistress

Martin Aspeli wrote:
> Hi guys,
>
> I'm having some trouble with Varnish 1.1.1 tarball and 1.1 branch in svn
> (r1922), and trunk as of today.
>
> Basically, I use a setup where I have Apache with virtual hosting, in
> front of Varnish (VCL included) in front of Zope/Plone (Plone 3 with
> CacheFu 1.1, in case that means anything to you, it hopefully shouldn't
> matter).
>
> It's hard to debug, but Varnish appears to hang, in that requests never
> complete. Not always, but between five or ten requests, I can make a few
> do this.
>
> I can't spot an obvious pattern, but it's normally either a page or a JS
> or CSS request. It happens sometimes on basic GET requests, and more
> often on POST requests.
>
> Is this familiar to anyone? Can I do anything more useful to debug?

After more testing - I know of at least one, probably two other people
who've had the same problem with the 1.1 branch (and 1.1 final, and
1.1.1 final, and trunk). They, like me, have reverted to 1.0 final,
which seems a lot more stable in this regard. I'm pasting the VCL I use
for 1.0, but it's not much difference.

Are people using 1.1.1 in production? Am I missing something in my
configuration? How can I debug this better?

Cheers,
Martin

Varnish 1.0 config that works:
------------------------------

backend default_backend {
set backend.host = "127.0.0.1";
set backend.port = "8080";
}

acl purge {
"localhost";
}

sub vcl_recv {

if (req.request != "GET" && req.request != "HEAD") {
# PURGE request if zope asks nicely
if (req.request == "PURGE") {
if (!client.ip ~ purge) {
error 405 "Not allowed.";
}
lookup;
}
pipe;
}
if (req.http.Expect) {
pipe;
}

/* Always cache images */
if (req.url ~
"\.(jpg|jpeg|gif|png|tiff|tif|svg|swf|ico|css|js|vsd|doc|ppt|pps|xls|pdf|mp3|mp4|m4a|ogg|mov|avi|wmv|sxw|zip|gz|bz2|tgz|tar|rar|odc|odb|odf|odg|odi|odp|ods|odt|sxc|sxd|sxi|sxw|dmg|torrent|deb|msi|iso|rpm)$")
{
lookup;
}
/* Always cache CSS and javascript */
if (req.url ~ "\.(css|js)$") {
lookup;
}

/* Do not cache other authorised content */
if (req.http.Authenticate || req.http.Authorization) {
pass;
}
# We only care about the "__ac.*" cookies, used for authentication
if (req.http.Cookie && req.http.Cookie ~
"__ac(|_(name|password|persistent))=") {
pass;
}
# XXX Add a check for _ZopeId here as well so we do not cache requests which
# rely on Zope sessions
lookup;
}

# Do the PURGE thing
sub vcl_hit {
if (req.request == "PURGE") {
set obj.ttl = 0s;
error 200 "Purged";
}
}
sub vcl_miss {
if (req.request == "PURGE") {
error 404 "Not in cache";
}
}


sub vcl_fetch {
if (!obj.valid) {
error;
}
if (!obj.cacheable) {
pass;
}
if (resp.http.Set-Cookie) {
pass;
}

if (req.url ~ "(rss_|search_rss)") {
set obj.ttl = 1800s;
}
}

For comparison, 1.1 config that doesn't work:
---------------------------------------------

backend backend_0 {
set backend.host = "127.0.0.1";
set backend.port = "8080";
}



acl purge {
"localhost";
}

sub vcl_recv {


if (req.request == "PURGE") {
if (!client.ip ~ purge) {
error 405 "Not allowed.";
}
lookup;
}

if (req.request != "GET" && req.request != "HEAD") {
# Do not try to cache POST
pipe;
}

if (req.http.Expect) {
pipe;
}

if (req.http.If-None-Match) {
pass;
}

/* Always cache images and binaries */
if (req.url ~
"\.(jpg|jpeg|gif|png|tiff|tif|svg|swf|ico|vsd|doc|ppt|pps|xls|pdf|mp3|mp4|m4a|ogg|mov|avi|wmv|sxw|zip|gz|bz2|tgz|tar|rar|odc|odb|odf|odg|odi|odp|ods|odt|sxc|sxd|sxi|sxw|dmg|torrent|deb|msi|iso|rpm)$")

{
lookup;
}
/* Always cache CSS and javascript */
if (req.url ~ "\.(css|js)$") {
lookup;
}

/* Do not cache other authorised content */
if (req.http.Authenticate || req.http.Authorization) {
pass;
}

/* We only care about the "__ac.*" cookies, used for authentication */
if (req.http.Cookie && (req.http.Cookie ~
"__ac(_(name|password|persistent))?=" || req.http.Cookie ~ "_ZopeId")) {
pass;
}

lookup;
}

/* Deal with purge requests */
sub vcl_hit {
if (req.request == "PURGE") {
set obj.ttl = 0s;
error 200 "Purged";
}
}

sub vcl_miss {
if (req.http.If-Modified-Since) {
pass;
}

if (req.request == "PURGE") {
error 404 "Not in cache";
}
}

sub vcl_fetch {
if (!obj.valid) {
error;
}
if (!obj.cacheable) {
pass;
}
if (obj.http.Set-Cookie) {
pass;
}

if (req.url ~ "(rss_|search_rss)") {
set obj.ttl = 1800s;
}
}

--
Acquisition is a jealous mistress

Martin Aspeli <optilude at gmx.net> writes:
> I'm having some trouble with Varnish 1.1.1 tarball and 1.1 branch in
> svn (r1922), and trunk as of today. [...]

ENOLOGS

DES
--
Dag-Erling Sm?rgrav
Senior Software Developer
Linpro AS - www.linpro.no

Dag-Erling Sm?rgrav <des at ...> writes:

>
> Martin Aspeli <optilude at ...> writes:
> > I'm having some trouble with Varnish 1.1.1 tarball and 1.1 branch in
> > svn (r1922), and trunk as of today. [...]
>
> ENOLOGS

varnishlog produces a *lot* of output, and this problem is intermittent and hard
to co-ordinate. I can generally make it happen by refreshing my site a few dozen
times in rapid succession, but it's difficult to pinpoint exactly which log
entry corresponds to that exact event.

If you don't mind the list spam of a few hundred lines of log output, I can
leave the log running while I try to reproduce the error (and kill it as soon as
it happens), but if there are more exact techniques I could use to debug, that
may be better for all of us.

Martin

Are there anything suspicious in your backends log when this happens ?

--
Poul-Henning Kamp | UNIX since Zilog Zeus 3.20
phk at FreeBSD.ORG | TCP/IP since RFC 956
FreeBSD committer | BSD since 4.3-tahoe
Never attribute to malice what can adequately be explained by incompetence.

Poul-Henning Kamp <phk at ...> writes:

>
>
> Are there anything suspicious in your backends log when this happens ?

Not really, but I'm not sure how you define "suspicious". I'm definitely
unable to reproduce this problem with Varnish 1.0, nor does it happen if I
bypass Varnish.

The backend here, by the way, is Plone 3.0, with CacheFu 1.1. CacheFu may
send PURGE requests from time to time, and will try to set ETags cleverly.
I have tried without CacheFu, though, and the problem seems to persist.

As I said, it's hard to be definitive here, because it's such an intermittent
problem.

Martin

Dag-Erling Sm?rgrav wrote:
> Martin Aspeli <optilude at gmx.net> writes:
>> I'm having some trouble with Varnish 1.1.1 tarball and 1.1 branch in
>> svn (r1922), and trunk as of today. [...]
>
> ENOLOGS

Okay - here is an attempt at fishing out some log entries. Basically,
I'm seeing intermittent dropped requests that eventually time out: It
can be the page itself, or JS, CSS or image resources being fetched
separately.

The logs are with Varnish 1.1, but I the same behaviour (if a bit less
frequently) them with 1.0 as well.

The VCL:

# This is a basic VCL configuration file for varnish. See the vcl(7)
# man page for details on VCL syntax and semantics.
#
# Default backend definition. Set this to point to your content server.

backend default {
set backend.host = "127.0.0.1";
set backend.port = "8000";
}

acl purge {
"localhost";
}

sub vcl_recv {

if (req.request == "PURGE") {
if (!client.ip ~ purge) {
error 405 "Not allowed.";
}
lookup;
}

if (req.request != "GET" && req.request != "HEAD") {
pipe;
}

if (req.http.Expect) {
pipe;
}

if (req.http.If-None-Match) {
pass;
}

/* Always cache images and binaries */
if (req.url ~
"\.(jpg|jpeg|gif|png|tiff|tif|svg|swf|ico|vsd|doc|ppt|pps|xls|pdf|mp3|mp4|m4a|ogg|mov|avi|wmv|sxw|zip|gz|bz2|tgz|tar|rar|odc|odb|odf|odg|odi|odp|ods|odt|sxc|sxd|sxi|sxw|dmg|torrent|deb|msi|iso|rpm)$")
{
lookup;
}

/* Always cache CSS and javascript */
if (req.url ~ "\.(css|js)$") {
lookup;
}

/* Do not cache other authorised content */
if (req.http.Authenticate || req.http.Authorization) {
pass;
}

/* We only care about the "__ac.*" cookies, used for authentication */
if (req.http.Cookie && (req.http.Cookie ~
"__ac(_(name|password|persistent))?=" || req.http.Cookie ~ "_ZopeId")) {
pass;
}

lookup;
}

/* Deal with purge requests */
sub vcl_hit {
if (req.request == "PURGE") {
set obj.ttl = 0s;
error 200 "Purged";
}
deliver;
}

sub vcl_miss {
if (req.http.If-Modified-Since) {
pass;
}
if (req.request == "PURGE") {
error 404 "Not in cache";
}
fetch;
}

sub vcl_fetch {
if (!obj.valid) {
error;
}
if (!obj.cacheable) {
pass;
}
if (obj.http.Set-Cookie) {
pass;
}
if (req.url ~ "(rss_|search_rss)") {
set obj.ttl = 1800s;
}
insert;
}

Some logs (partially annotated as far as I managed to pick out actual
requests and results):

New request
-----------

0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010676
22 SessionOpen c 127.0.0.1 35852
22 ReqStart c 127.0.0.1 35852 1892976556
22 RxRequest c GET
22 RxURL c //plone-book
22 RxProtocol c HTTP/1.1
22 RxHeader c Host: localhost:8082
22 RxHeader c User-Agent: Mozilla/5.0 (Windows; U; Windows NT
5.1; en-GB; rv:1.8.1.6) Gecko/20070725 Firefox/2.0.0.6
22 RxHeader c Accept:
text/xml,application/xml,application/xhtml+xml,text/html;q=0.9,text/plain;q=0.8,image/png,*/*;q=0.5
22 RxHeader c Accept-Language: en-gb,en;q=0.5
22 RxHeader c Accept-Encoding: gzip,deflate
22 RxHeader c Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
22 RxHeader c Referer: http://example.net/articles
22 RxHeader c Max-Forwards: 10
22 RxHeader c X-Forwarded-For: 8.223.33.128
22 RxHeader c X-Forwarded-Host: example.net
22 RxHeader c X-Forwarded-Server: example.net
22 VCL_call c recv
22 VCL_return c lookup
22 VCL_call c hash
22 VCL_return c hash
22 VCL_call c miss
22 VCL_return c fetch
30 BackendXID b 1892976556
22 Backend c 30 default
30 TxRequest b GET
30 TxURL b
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot//plone-book
30 TxProtocol b HTTP/1.1
30 TxHeader b Host: localhost:8082
30 TxHeader b User-Agent: Mozilla/5.0 (Windows; U; Windows NT
5.1; en-GB; rv:1.8.1.6) Gecko/20070725 Firefox/2.0.0.6
30 TxHeader b Accept:
text/xml,application/xml,application/xhtml+xml,text/html;q=0.9,text/plain;q=0.8,image/png,*/*;q=0.5
30 TxHeader b Accept-Language: en-gb,en;q=0.5
30 TxHeader b Accept-Encoding: gzip,deflate
30 TxHeader b Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
30 TxHeader b Referer: http://example.net/articles
30 TxHeader b Max-Forwards: 10
30 TxHeader b X-Forwarded-For: 8.223.33.128
30 TxHeader b X-Forwarded-Host: example.net
30 TxHeader b X-Forwarded-Server: example.net
30 TxHeader b X-Varnish: 1892976556
30 TxHeader b X-Forwarded-for: 127.0.0.1
30 RxProtocol b HTTP/1.1
30 RxStatus b 200
30 RxResponse b OK
30 RxHeader b Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
30 RxHeader b Date: Wed, 05 Sep 2007 16:44:38 GMT
30 RxHeader b Content-Length: 4784
30 RxHeader b Content-Language: en
30 RxHeader b Content-Encoding: gzip
30 RxHeader b Expires: Sun, 07 Sep 1997 16:44:37 GMT
30 RxHeader b Vary: Accept-Encoding, Accept-Language,
30 RxHeader b Accept-Encoding
30 RxHeader b ETag: ||Optilude Theme|en-gb;en;q=0.5|1|662||||330280
30 RxHeader b X-Caching-Rule-Id: plone-content-types
30 RxHeader b Cache-Control: max-age=0, s-maxage=0, private,
must-revalidate
30 RxHeader b Content-Type: text/html;charset=utf-8
30 RxHeader b X-Header-Set-Id: cache-in-memory
22 ObjProtocol c HTTP/1.1
22 ObjStatus c 200
22 ObjResponse c OK
22 ObjHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
22 ObjHeader c Date: Wed, 05 Sep 2007 16:44:38 GMT
22 ObjHeader c Content-Language: en
22 ObjHeader c Content-Encoding: gzip
22 ObjHeader c Expires: Sun, 07 Sep 1997 16:44:37 GMT
22 ObjHeader c Vary: Accept-Encoding, Accept-Language,
22 ObjHeader c Accept-Encoding
22 ObjHeader c ETag: ||Optilude Theme|en-gb;en;q=0.5|1|662||||330280
22 ObjHeader c X-Caching-Rule-Id: plone-content-types
22 ObjHeader c Cache-Control: max-age=0, s-maxage=0, private,
must-revalidate
22 ObjHeader c Content-Type: text/html;charset=utf-8
22 ObjHeader c X-Header-Set-Id: cache-in-memory
30 BackendReuse b default
22 TTL c 1892976556 RFC 0 1189010678 1189010678 873650677 0 0
22 VCL_call c fetch
22 VCL_return c insert
22 Length c 4784
22 VCL_call c deliver
22 VCL_return c deliver
22 TxProtocol c HTTP/1.1
22 TxStatus c 200
22 TxResponse c OK
22 TxHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
22 TxHeader c Date: Wed, 05 Sep 2007 16:44:38 GMT
22 TxHeader c Content-Language: en
22 TxHeader c Content-Encoding: gzip
22 TxHeader c Expires: Sun, 07 Sep 1997 16:44:37 GMT
22 TxHeader c Vary: Accept-Encoding, Accept-Language,
22 TxHeader c Accept-Encoding
22 TxHeader c ETag: ||Optilude Theme|en-gb;en;q=0.5|1|662||||330280
22 TxHeader c X-Caching-Rule-Id: plone-content-types
22 TxHeader c Cache-Control: max-age=0, s-maxage=0, private,
must-revalidate
22 TxHeader c Content-Type: text/html;charset=utf-8
22 TxHeader c X-Header-Set-Id: cache-in-memory
22 TxHeader c Content-Length: 4784
22 TxHeader c X-Varnish: 1892976556
22 TxHeader c Age: 0
22 TxHeader c Via: 1.1 varnish
22 TxHeader c Connection: keep-alive
22 ReqEnd c 1892976556 1189010677.848659039
1189010678.847302914 0.000082970 0.998533010 0.000110865
0 StatAddr 127.0.0.1 0 411 39 91 0 0 25 29049 280224
0 ExpKill 1892976556 0
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010679
22 HttpError c Received nothing
22 SessionClose c no request
22 StatSess c 127.0.0.1 35852 1 1 1 0 0 1 606 4784
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010682

That request was OK.
Trying a new one:
-----------------



0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010685
22 SessionOpen c 127.0.0.1 35881
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010688
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010691
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010694
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010697
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010700
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010703
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010706
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010709
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010712
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010715
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010718
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010721
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010724
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010727
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010730
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010733
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010736
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010739
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010742
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010745
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010748
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010751
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010754
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010757
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010760
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010763
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010766
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010769
0 ExpKill 1892976547 0
0 ExpKill 1892976548 0
0 WorkThread 0x552591a0 end
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010772
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010775
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010778
0 ExpKill 1892976553 0
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010781
0 ExpKill 1892976554 0
0 ExpKill 1892976555 0
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010784
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189010787

Received Proxy Error from Apache.
Trying a new request:
---------------------------------


0 WorkThread 0x552591a0 start
24 SessionOpen c 127.0.0.1 37149
24 ReqStart c 127.0.0.1 37149 1892976558
24 RxRequest c GET
24 RxURL c //robots.txt
24 RxProtocol c HTTP/1.1
24 RxHeader c Host: localhost:8082
24 RxHeader c User-Agent: msnbot-media/1.0
(+http://search.msn.com/msnbot.htm)
24 RxHeader c Accept: */*
24 RxHeader c From: msnbot(at)microsoft.com
24 RxHeader c Max-Forwards: 10
24 RxHeader c X-Forwarded-For: 8.223.33.128
24 RxHeader c X-Forwarded-Host: example.net
24 RxHeader c X-Forwarded-Server: example.net
24 VCL_call c recv
24 VCL_return c lookup
24 VCL_call c hash
24 VCL_return c hash
24 VCL_call c miss
24 VCL_return c fetch
26 BackendXID b 1892976558
24 Backend c 26 default
26 TxRequest b GET
26 TxURL b
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot//robots.txt
26 TxProtocol b HTTP/1.1
26 TxHeader b Host: localhost:8082
26 TxHeader b User-Agent: msnbot-media/1.0
(+http://search.msn.com/msnbot.htm)
26 TxHeader b Accept: */*
26 TxHeader b From: msnbot(at)microsoft.com
26 TxHeader b Max-Forwards: 10
26 TxHeader b X-Forwarded-For: 8.223.33.128
26 TxHeader b X-Forwarded-Host: example.net
26 TxHeader b X-Forwarded-Server: example.net
26 TxHeader b X-Varnish: 1892976558
26 TxHeader b X-Forwarded-for: 127.0.0.1
26 RxProtocol b HTTP/1.1
26 RxStatus b 200
26 RxResponse b OK
26 RxHeader b Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
26 RxHeader b Date: Wed, 05 Sep 2007 16:50:39 GMT
26 RxHeader b Last-Modified: Fri, 09 Feb 2007 12:40:57 GMT
26 RxHeader b Content-Length: 549
26 RxHeader b Content-Type: text/plain; charset=iso-8859-15
24 ObjProtocol c HTTP/1.1
24 ObjStatus c 200
24 ObjResponse c OK
24 ObjHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
24 ObjHeader c Date: Wed, 05 Sep 2007 16:50:39 GMT
24 ObjHeader c Last-Modified: Fri, 09 Feb 2007 12:40:57 GMT
24 ObjHeader c Content-Type: text/plain; charset=iso-8859-15
26 BackendReuse b default
24 TTL c 1892976558 RFC 120 1189011039 1189011039 0 0 0
24 VCL_call c fetch
24 VCL_return c insert
24 Length c 549
24 VCL_call c deliver
24 VCL_return c deliver
24 TxProtocol c HTTP/1.1
24 TxStatus c 200
24 TxResponse c OK
24 TxHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
24 TxHeader c Date: Wed, 05 Sep 2007 16:50:39 GMT
24 TxHeader c Last-Modified: Fri, 09 Feb 2007 12:40:57 GMT
24 TxHeader c Content-Type: text/plain; charset=iso-8859-15
24 TxHeader c Content-Length: 549
24 TxHeader c X-Varnish: 1892976558
24 TxHeader c Age: 0
24 TxHeader c Via: 1.1 varnish
24 TxHeader c Connection: keep-alive
24 ReqEnd c 1892976558 1189011039.758268118
1189011039.804734945 0.000107050 0.046402931 0.000063896
0 StatAddr 127.0.0.1 0 772 40 92 0 0 26 29370 280773
24 HttpError c Received nothing
24 SessionClose c no request
24 StatSess c 127.0.0.1 37149 0 1 1 0 0 1 321 549
24 SessionOpen c 127.0.0.1 37150
24 ReqStart c 127.0.0.1 37150 1892976559
24 RxRequest c GET
24 RxURL c //Members/hg/play/41-gambling-335.htm
24 RxProtocol c HTTP/1.1
24 RxHeader c Host: localhost:8082
24 RxHeader c User-Agent: msnbot-media/1.0
(+http://search.msn.com/msnbot.htm)
24 RxHeader c Accept: text/html, text/plain, text/text,
text/javascript, text/x-javascript, text/js, text/x-js, text/jscript,
text/ecmascript, text/xml, xml/xml, image/*, audio/*, video/*,
application/*
24 RxHeader c Accept-Encoding: identity;q=1.0
24 RxHeader c From: msnbot(at)microsoft.com
24 RxHeader c Max-Forwards: 10
24 RxHeader c X-Forwarded-For: 8.223.33.128
24 RxHeader c X-Forwarded-Host: example.net
24 RxHeader c X-Forwarded-Server: example.net
24 VCL_call c recv
24 VCL_return c lookup
24 VCL_call c hash
24 VCL_return c hash
24 VCL_call c miss
24 VCL_return c fetch
26 BackendXID b 1892976559
24 Backend c 26 default
26 TxRequest b GET
26 TxURL b
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot//Members/hg/play/41-gambling-335.htm
26 TxProtocol b HTTP/1.1
26 TxHeader b Host: localhost:8082
26 TxHeader b User-Agent: msnbot-media/1.0
(+http://search.msn.com/msnbot.htm)
26 TxHeader b Accept: text/html, text/plain, text/text,
text/javascript, text/x-javascript, text/js, text/x-js, text/jscript,
text/ecmascript, text/xml, xml/xml, image/*, audio/*, video/*,
application/*
26 TxHeader b Accept-Encoding: identity;q=1.0
26 TxHeader b From: msnbot(at)microsoft.com
26 TxHeader b Max-Forwards: 10
26 TxHeader b X-Forwarded-For: 8.223.33.128
26 TxHeader b X-Forwarded-Host: example.net
26 TxHeader b X-Forwarded-Server: example.net
26 TxHeader b X-Varnish: 1892976559
26 TxHeader b X-Forwarded-for: 127.0.0.1
26 RxProtocol b HTTP/1.1
26 RxStatus b 404
26 RxResponse b Not Found
26 RxHeader b Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
26 RxHeader b Date: Wed, 05 Sep 2007 16:50:40 GMT
26 RxHeader b Bobo-Exception-Line: 672
26 RxHeader b Content-Length: 12306
26 RxHeader b Bobo-Exception-Value: See the server error log for
details
26 RxHeader b Content-Language: en
26 RxHeader b Bobo-Exception-File: HTTPResponse.py
26 RxHeader b Bobo-Exception-Type: NotFound
26 RxHeader b Expires: Sat, 1 Jan 2000 00:00:00 GMT
26 RxHeader b X-Ksscommands: <?xml version="1.0"?> <root
xmlns:kukit="http://www.kukit.org/commands/1.0"> <kukit:commands>
<kukit:command name="error"> <kukit:param
name="type">system</kukit:param> <kukit:param
name="message">NotFound: &amp;lt;h2&amp;gt;Site Err
26 RxHeader b Content-Type: text/html;charset=utf-8
24 ObjProtocol c HTTP/1.1
24 ObjStatus c 404
24 ObjResponse c Not Found
24 ObjHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
24 ObjHeader c Date: Wed, 05 Sep 2007 16:50:40 GMT
24 ObjHeader c Bobo-Exception-Line: 672
24 ObjHeader c Bobo-Exception-Value: See the server error log for
details
24 ObjHeader c Content-Language: en
24 ObjHeader c Bobo-Exception-File: HTTPResponse.py
24 ObjHeader c Bobo-Exception-Type: NotFound
24 ObjHeader c Expires: Sat, 1 Jan 2000 00:00:00 GMT
24 ObjHeader c X-Ksscommands: <?xml version="1.0"?> <root
xmlns:kukit="http://www.kukit.org/commands/1.0"> <kukit:commands>
<kukit:command name="error"> <kukit:param
name="type">system</kukit:param> <kukit:param
name="message">NotFound: &amp;lt;h2&amp;gt;Site Err
24 ObjHeader c Content-Type: text/html;charset=utf-8
26 BackendReuse b default
24 TTL c 1892976559 RFC 120 1189011040 1189011040 946684800 0 0
24 VCL_call c fetch
24 VCL_return c insert
24 Length c 12306
24 VCL_call c deliver
24 VCL_return c deliver
24 TxProtocol c HTTP/1.1
24 TxStatus c 404
24 TxResponse c Not Found
24 TxHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
24 TxHeader c Date: Wed, 05 Sep 2007 16:50:40 GMT
24 TxHeader c Bobo-Exception-Line: 672
24 TxHeader c Bobo-Exception-Value: See the server error log for
details
24 TxHeader c Content-Language: en
24 TxHeader c Bobo-Exception-File: HTTPResponse.py
24 TxHeader c Bobo-Exception-Type: NotFound
24 TxHeader c Expires: Sat, 1 Jan 2000 00:00:00 GMT
24 TxHeader c X-Ksscommands: <?xml version="1.0"?> <root
xmlns:kukit="http://www.kukit.org/commands/1.0"> <kukit:commands>
<kukit:command name="error"> <kukit:param
name="type">system</kukit:param> <kukit:param
name="message">NotFound: &amp;lt;h2&amp;gt;Site Err
24 TxHeader c Content-Type: text/html;charset=utf-8
24 TxHeader c Content-Length: 12306
24 TxHeader c X-Varnish: 1892976559
24 TxHeader c Age: 0
24 TxHeader c Via: 1.1 varnish
24 TxHeader c Connection: keep-alive
24 ReqEnd c 1892976559 1189011040.021153927
1189011040.916707993 0.000094891 0.895455122 0.000098944
0 StatAddr 127.0.0.1 0 773 41 93 0 0 27 31297 293079
24 HttpError c Received nothing
24 SessionClose c no request
24 StatSess c 127.0.0.1 37150 1 1 1 0 0 1 1927 12306
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011042
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011045
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011048
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011051
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011054
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011057
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011060
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011063
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011066
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011069
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011072
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011075
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011078
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011081
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011084
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011087
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011090
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011093
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011096
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011099
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011102
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011105
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011108
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011111
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011114
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011117
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011120
24 SessionOpen c 127.0.0.1 37510
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011123
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011126
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011129
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011132
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011135
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011138
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011141
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011144
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011147
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011150
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011153
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011156
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011159
0 ExpKill 1892976558 0
0 ExpKill 1892976559 0
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011162
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011165
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011168
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011171
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011174
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011177
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011180
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011183
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011186
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011189
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011192
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011195
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011198
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011201
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011204
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011207
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011210
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011213
0 WorkThread 0x5625b1a0 start
31 SessionOpen c 127.0.0.1 37950
31 ReqStart c 127.0.0.1 37950 1892976561
31 RxRequest c GET
31 RxURL c //articles/the-rumours-are-true-a-book-about-plone-3
31 RxProtocol c HTTP/1.1
31 RxHeader c Host: localhost:8082
31 RxHeader c User-Agent: Mozilla/5.0 (X11; U; Linux i686;
en-US; rv:1.8.0.13) Gecko/20070824 Thunderbird/1.5.0.13
31 RxHeader c Accept:
text/xml,application/xml,application/xhtml+xml,text/html;q=0.9,text/plain;q=0.8,image/png,*/*;q=0.5
31 RxHeader c Accept-Language: fr,fr-fr;q=0.8,en-us;q=0.5,en;q=0.3
31 RxHeader c Accept-Encoding: gzip,deflate
31 RxHeader c Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
31 RxHeader c Max-Forwards: 10
31 RxHeader c X-Forwarded-For: 8.223.33.128
31 RxHeader c X-Forwarded-Host: example.net
31 RxHeader c X-Forwarded-Server: example.net
31 VCL_call c recv
31 VCL_return c lookup
31 VCL_call c hash
31 VCL_return c hash
31 VCL_call c miss
31 VCL_return c fetch
34 BackendOpen b default 127.0.0.1 37951 127.0.0.1 8080
34 BackendXID b 1892976561
31 Backend c 34 default
34 TxRequest b GET
34 TxURL b
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot//articles/the-rumours-are-true-a-book-about-plone-3
34 TxProtocol b HTTP/1.1
34 TxHeader b Host: localhost:8082
34 TxHeader b User-Agent: Mozilla/5.0 (X11; U; Linux i686;
en-US; rv:1.8.0.13) Gecko/20070824 Thunderbird/1.5.0.13
34 TxHeader b Accept:
text/xml,application/xml,application/xhtml+xml,text/html;q=0.9,text/plain;q=0.8,image/png,*/*;q=0.5
34 TxHeader b Accept-Language: fr,fr-fr;q=0.8,en-us;q=0.5,en;q=0.3
34 TxHeader b Accept-Encoding: gzip,deflate
34 TxHeader b Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
34 TxHeader b Max-Forwards: 10
34 TxHeader b X-Forwarded-For: 8.223.33.128
34 TxHeader b X-Forwarded-Host: example.net
34 TxHeader b X-Forwarded-Server: example.net
34 TxHeader b X-Varnish: 1892976561
34 TxHeader b X-Forwarded-for: 127.0.0.1
34 RxProtocol b HTTP/1.1
34 RxStatus b 200
34 RxResponse b OK
34 RxHeader b Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
34 RxHeader b Date: Wed, 05 Sep 2007 16:53:35 GMT
34 RxHeader b X-Pagecache: MISS
34 RxHeader b Content-Length: 8085
34 RxHeader b Content-Language: en
34 RxHeader b Content-Encoding: gzip
34 RxHeader b Expires: Sun, 07 Sep 1997 16:53:34 GMT
34 RxHeader b Vary: Accept-Encoding, Accept-Language,
34 RxHeader b Accept-Encoding
34 RxHeader b ETag: ||Optilude
Theme|fr;fr-fr;q=0.8;en-us;q=0.5;en;q=0.3|1|662||||330280
34 RxHeader b X-Caching-Rule-Id: plone-content-types
34 RxHeader b Cache-Control: max-age=0, s-maxage=0, private,
must-revalidate
34 RxHeader b Content-Type: text/html;charset=utf-8
34 RxHeader b X-Header-Set-Id: cache-in-memory
31 ObjProtocol c HTTP/1.1
31 ObjStatus c 200
31 ObjResponse c OK
31 ObjHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
31 ObjHeader c Date: Wed, 05 Sep 2007 16:53:35 GMT
31 ObjHeader c X-Pagecache: MISS
31 ObjHeader c Content-Language: en
31 ObjHeader c Content-Encoding: gzip
31 ObjHeader c Expires: Sun, 07 Sep 1997 16:53:34 GMT
31 ObjHeader c Vary: Accept-Encoding, Accept-Language,
31 ObjHeader c Accept-Encoding
31 ObjHeader c ETag: ||Optilude
Theme|fr;fr-fr;q=0.8;en-us;q=0.5;en;q=0.3|1|662||||330280
31 ObjHeader c X-Caching-Rule-Id: plone-content-types
31 ObjHeader c Cache-Control: max-age=0, s-maxage=0, private,
must-revalidate
31 ObjHeader c Content-Type: text/html;charset=utf-8
31 ObjHeader c X-Header-Set-Id: cache-in-memory
34 BackendReuse b default
31 TTL c 1892976561 RFC 0 1189011215 1189011215 873651214 0 0
31 VCL_call c fetch
31 VCL_return c insert
31 Length c 8085
31 VCL_call c deliver
31 VCL_return c deliver
31 TxProtocol c HTTP/1.1
31 TxStatus c 200
31 TxResponse c OK
31 TxHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
31 TxHeader c Date: Wed, 05 Sep 2007 16:53:35 GMT
31 TxHeader c X-Pagecache: MISS
31 TxHeader c Content-Language: en
31 TxHeader c Content-Encoding: gzip
31 TxHeader c Expires: Sun, 07 Sep 1997 16:53:34 GMT
31 TxHeader c Vary: Accept-Encoding, Accept-Language,
31 TxHeader c Accept-Encoding
31 TxHeader c ETag: ||Optilude
Theme|fr;fr-fr;q=0.8;en-us;q=0.5;en;q=0.3|1|662||||330280
31 TxHeader c X-Caching-Rule-Id: plone-content-types
31 TxHeader c Cache-Control: max-age=0, s-maxage=0, private,
must-revalidate
31 TxHeader c Content-Type: text/html;charset=utf-8
31 TxHeader c X-Header-Set-Id: cache-in-memory
31 TxHeader c Content-Length: 8085
31 TxHeader c X-Varnish: 1892976561
31 TxHeader c Age: 0
31 TxHeader c Via: 1.1 varnish
31 TxHeader c Connection: keep-alive
31 ReqEnd c 1892976561 1189011214.560914040
1189011215.423327923 0.000175953 0.862323999 0.000089884
0 StatAddr 127.0.0.1 0 948 42 94 0 0 28 31943 301164
35 SessionOpen c 127.0.0.1 37963
35 ReqStart c 127.0.0.1 37963 1892976562
35 RxRequest c GET
35 RxURL c //portal_css/Optilude%20Theme/base-cachekey1160.css
35 RxProtocol c HTTP/1.1
35 RxHeader c Host: localhost:8082
35 RxHeader c User-Agent: Mozilla/5.0 (X11; U; Linux i686;
en-US; rv:1.8.0.13) Gecko/20070824 Thunderbird/1.5.0.13
35 RxHeader c Accept: text/css,*/*;q=0.1
35 RxHeader c Accept-Language: fr,fr-fr;q=0.8,en-us;q=0.5,en;q=0.3
35 RxHeader c Accept-Encoding: gzip,deflate
35 RxHeader c Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
35 RxHeader c Referer:
http://example.net/articles/the-rumours-are-true-a-book-about-plone-3
35 RxHeader c Max-Forwards: 10
35 RxHeader c X-Forwarded-For: 8.223.33.128
35 RxHeader c X-Forwarded-Host: example.net
35 RxHeader c X-Forwarded-Server: example.net
35 VCL_call c recv
35 VCL_return c lookup
35 VCL_call c hash
35 VCL_return c hash
35 Hit c 1892976465
35 VCL_call c hit
35 VCL_return c deliver
35 Length c 3386
35 VCL_call c deliver
35 VCL_return c deliver
35 TxProtocol c HTTP/1.1
35 TxStatus c 200
35 TxResponse c OK
35 TxHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
35 TxHeader c Date: Wed, 05 Sep 2007 16:38:54 GMT
35 TxHeader c X-Cache-Headers-Set-By: CachingPolicyManager:
/blog/caching_policy_manager
35 TxHeader c Expires: Thu, 04 Sep 2008 16:38:54 GMT
35 TxHeader c Last-Modified: Wed, 05 Sep 2007 16:38:54 GMT
35 TxHeader c X-Caching-Rule-Id: resource-registries
35 TxHeader c Cache-Control: max-age=31536000,
s-maxage=31536000, public
35 TxHeader c Content-Type: text/css;charset=utf-8
35 TxHeader c X-Header-Set-Id: cache-in-browser-forever
35 TxHeader c Content-Length: 3386
35 TxHeader c X-Varnish: 1892976562 1892976465
35 TxHeader c Age: 882
35 TxHeader c Via: 1.1 varnish
35 TxHeader c Connection: keep-alive
35 ReqEnd c 1892976562 1189011215.596278906
1189011215.596473932 0.000048876 0.000085115 0.000109911
0 StatAddr 127.0.0.1 0 948 43 95 0 0 28 32528 304550
31 HttpError c Received nothing
31 SessionClose c no request
31 StatSess c 127.0.0.1 37950 1 1 1 0 0 1 646 8085
35 HttpError c Received nothing
35 SessionClose c no request
35 StatSess c 127.0.0.1 37963 0 1 1 0 0 0 585 3386
31 SessionOpen c 127.0.0.1 37967
31 ReqStart c 127.0.0.1 37967 1892976563
31 RxRequest c GET
31 RxURL c //portal_css/Optilude%20Theme/nuplone-cachekey0680.css
31 RxProtocol c HTTP/1.1
31 RxHeader c Host: localhost:8082
31 RxHeader c User-Agent: Mozilla/5.0 (X11; U; Linux i686;
en-US; rv:1.8.0.13) Gecko/20070824 Thunderbird/1.5.0.13
31 RxHeader c Accept: text/css,*/*;q=0.1
31 RxHeader c Accept-Language: fr,fr-fr;q=0.8,en-us;q=0.5,en;q=0.3
31 RxHeader c Accept-Encoding: gzip,deflate
31 RxHeader c Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
31 RxHeader c Referer:
http://example.net/articles/the-rumours-are-true-a-book-about-plone-3
31 RxHeader c Max-Forwards: 10
31 RxHeader c X-Forwarded-For: 8.223.33.128
31 RxHeader c X-Forwarded-Host: example.net
31 RxHeader c X-Forwarded-Server: example.net
31 VCL_call c recv
31 VCL_return c lookup
31 VCL_call c hash
31 VCL_return c hash
31 Hit c 1892976472
31 VCL_call c hit
31 VCL_return c deliver
31 Length c 22550
31 VCL_call c deliver
31 VCL_return c deliver
31 TxProtocol c HTTP/1.1
31 TxStatus c 200
31 TxResponse c OK
31 TxHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
31 TxHeader c Date: Wed, 05 Sep 2007 16:38:54 GMT
31 TxHeader c X-Cache-Headers-Set-By: CachingPolicyManager:
/blog/caching_policy_manager
31 TxHeader c Expires: Thu, 04 Sep 2008 16:38:54 GMT
31 TxHeader c Last-Modified: Wed, 05 Sep 2007 16:38:54 GMT
31 TxHeader c X-Caching-Rule-Id: resource-registries
31 TxHeader c Cache-Control: max-age=31536000,
s-maxage=31536000, public
31 TxHeader c Content-Type: text/css;charset=utf-8
31 TxHeader c X-Header-Set-Id: cache-in-browser-forever
31 TxHeader c Content-Length: 22550
31 TxHeader c X-Varnish: 1892976563 1892976472
31 TxHeader c Age: 881
31 TxHeader c Via: 1.1 varnish
31 TxHeader c Connection: keep-alive
31 ReqEnd c 1892976563 1189011215.753976107
1189011215.754245043 0.000068188 0.000093937 0.000174999
0 StatAddr 127.0.0.1 0 948 44 96 0 0 28 33114 327100
31 HttpError c Received nothing
31 SessionClose c no request
31 StatSess c 127.0.0.1 37967 0 1 1 0 0 0 586 22550
31 SessionOpen c 127.0.0.1 37977
0 WorkThread 0x56a5c1a0 start
35 SessionOpen c 127.0.0.1 37978
0 ExpKill 1892976561 0
31 ReqStart c 127.0.0.1 37977 1892976564
31 RxRequest c GET
31 RxURL c //portal_css/Optilude%20Theme/body.gif
31 RxProtocol c HTTP/1.1
31 RxHeader c Host: localhost:8082
31 RxHeader c User-Agent: Mozilla/5.0 (X11; U; Linux i686;
en-US; rv:1.8.0.13) Gecko/20070824 Thunderbird/1.5.0.13
31 RxHeader c Accept: image/png,*/*;q=0.5
31 RxHeader c Accept-Language: fr,fr-fr;q=0.8,en-us;q=0.5,en;q=0.3
31 RxHeader c Accept-Encoding: gzip,deflate
31 RxHeader c Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
31 RxHeader c Referer:
http://example.net/portal_css/Optilude%20Theme/nuplone-cachekey0680.css
31 RxHeader c Max-Forwards: 10
31 RxHeader c X-Forwarded-For: 8.223.33.128
31 RxHeader c X-Forwarded-Host: example.net
31 RxHeader c X-Forwarded-Server: example.net
31 VCL_call c recv
31 VCL_return c lookup
31 VCL_call c hash
31 VCL_return c hash
31 VCL_call c miss
31 VCL_return c fetch
34 BackendXID b 1892976564
31 Backend c 34 default
34 TxRequest b GET
34 TxURL b
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot//portal_css/Optilude%20Theme/body.gif
34 TxProtocol b HTTP/1.1
34 TxHeader b Host: localhost:8082
34 TxHeader b User-Agent: Mozilla/5.0 (X11; U; Linux i686;
en-US; rv:1.8.0.13) Gecko/20070824 Thunderbird/1.5.0.13
34 TxHeader b Accept: image/png,*/*;q=0.5
34 TxHeader b Accept-Language: fr,fr-fr;q=0.8,en-us;q=0.5,en;q=0.3
34 TxHeader b Accept-Encoding: gzip,deflate
34 TxHeader b Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
34 TxHeader b Referer:
http://example.net/portal_css/Optilude%20Theme/nuplone-cachekey0680.css
34 TxHeader b Max-Forwards: 10
34 TxHeader b X-Forwarded-For: 8.223.33.128
34 TxHeader b X-Forwarded-Host: example.net
34 TxHeader b X-Forwarded-Server: example.net
34 TxHeader b X-Varnish: 1892976564
34 TxHeader b X-Forwarded-for: 127.0.0.1
34 RxProtocol b HTTP/1.1
34 RxStatus b 200
34 RxResponse b OK
34 RxHeader b Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
34 RxHeader b Date: Wed, 05 Sep 2007 16:53:36 GMT
34 RxHeader b Last-Modified: Mon, 27 Aug 2007 20:09:52 GMT
34 RxHeader b Content-Length: 61
34 RxHeader b Content-Type: image/gif
31 ObjProtocol c HTTP/1.1
31 ObjStatus c 200
31 ObjResponse c OK
31 ObjHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
31 ObjHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
31 ObjHeader c Last-Modified: Mon, 27 Aug 2007 20:09:52 GMT
31 ObjHeader c Content-Type: image/gif
34 BackendReuse b default
31 TTL c 1892976564 RFC 120 1189011216 1189011216 0 0 0
31 VCL_call c fetch
31 VCL_return c insert
31 Length c 61
31 VCL_call c deliver
31 VCL_return c deliver
31 TxProtocol c HTTP/1.1
31 TxStatus c 200
31 TxResponse c OK
31 TxHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
31 TxHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
31 TxHeader c Last-Modified: Mon, 27 Aug 2007 20:09:52 GMT
31 TxHeader c Content-Type: image/gif
31 TxHeader c Content-Length: 61
31 TxHeader c X-Varnish: 1892976564
31 TxHeader c Age: 0
31 TxHeader c Via: 1.1 varnish
31 TxHeader c Connection: keep-alive
31 ReqEnd c 1892976564 1189011216.214435101
1189011216.320699930 0.000060081 0.106203794 0.000061035
0 StatAddr 127.0.0.1 0 949 45 97 0 0 29 33412 327161
35 ReqStart c 127.0.0.1 37978 1892976565
35 RxRequest c GET
35 RxURL c //logo.gif
35 RxProtocol c HTTP/1.1
35 RxHeader c Host: localhost:8082
35 RxHeader c User-Agent: Mozilla/5.0 (X11; U; Linux i686;
en-US; rv:1.8.0.13) Gecko/20070824 Thunderbird/1.5.0.13
35 RxHeader c Accept: image/png,*/*;q=0.5
35 RxHeader c Accept-Language: fr,fr-fr;q=0.8,en-us;q=0.5,en;q=0.3
35 RxHeader c Accept-Encoding: gzip,deflate
35 RxHeader c Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
35 RxHeader c Referer:
http://example.net/articles/the-rumours-are-true-a-book-about-plone-3
35 RxHeader c Max-Forwards: 10
35 RxHeader c X-Forwarded-For: 8.223.33.128
35 RxHeader c X-Forwarded-Host: example.net
35 RxHeader c X-Forwarded-Server: example.net
35 VCL_call c recv
35 VCL_return c lookup
35 VCL_call c hash
35 VCL_return c hash
35 VCL_call c miss
35 VCL_return c fetch
38 BackendOpen b default 127.0.0.1 37979 127.0.0.1 8080
38 BackendXID b 1892976565
35 Backend c 38 default
38 TxRequest b GET
38 TxURL b
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot//logo.gif
38 TxProtocol b HTTP/1.1
38 TxHeader b Host: localhost:8082
38 TxHeader b User-Agent: Mozilla/5.0 (X11; U; Linux i686;
en-US; rv:1.8.0.13) Gecko/20070824 Thunderbird/1.5.0.13
38 TxHeader b Accept: image/png,*/*;q=0.5
38 TxHeader b Accept-Language: fr,fr-fr;q=0.8,en-us;q=0.5,en;q=0.3
38 TxHeader b Accept-Encoding: gzip,deflate
38 TxHeader b Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
38 TxHeader b Referer:
http://example.net/articles/the-rumours-are-true-a-book-about-plone-3
38 TxHeader b Max-Forwards: 10
38 TxHeader b X-Forwarded-For: 8.223.33.128
38 TxHeader b X-Forwarded-Host: example.net
38 TxHeader b X-Forwarded-Server: example.net
38 TxHeader b X-Varnish: 1892976565
38 TxHeader b X-Forwarded-for: 127.0.0.1
38 RxProtocol b HTTP/1.1
38 RxStatus b 200
38 RxResponse b OK
38 RxHeader b Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
38 RxHeader b Date: Wed, 05 Sep 2007 16:53:36 GMT
38 RxHeader b Last-Modified: Mon, 27 Aug 2007 20:09:52 GMT
38 RxHeader b Content-Length: 631
38 RxHeader b Content-Type: image/gif
35 ObjProtocol c HTTP/1.1
35 ObjStatus c 200
35 ObjResponse c OK
35 ObjHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
35 ObjHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
35 ObjHeader c Last-Modified: Mon, 27 Aug 2007 20:09:52 GMT
35 ObjHeader c Content-Type: image/gif
38 BackendReuse b default
35 TTL c 1892976565 RFC 120 1189011216 1189011216 0 0 0
35 VCL_call c fetch
35 VCL_return c insert
35 Length c 631
35 VCL_call c deliver
35 VCL_return c deliver
35 TxProtocol c HTTP/1.1
35 TxStatus c 200
35 TxResponse c OK
35 TxHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
35 TxHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
35 TxHeader c Last-Modified: Mon, 27 Aug 2007 20:09:52 GMT
35 TxHeader c Content-Type: image/gif
35 TxHeader c Content-Length: 631
35 TxHeader c X-Varnish: 1892976565
35 TxHeader c Age: 0
35 TxHeader c Via: 1.1 varnish
35 TxHeader c Connection: keep-alive
35 ReqEnd c 1892976565 1189011216.232373953
1189011216.323524952 0.000535011 0.088789940 0.002361059
0 StatAddr 127.0.0.1 0 949 46 98 0 0 30 33711 327792
35 HttpError c Received nothing
35 SessionClose c no request
35 StatSess c 127.0.0.1 37978 0 1 1 0 0 1 299 631
31 HttpError c Received nothing
31 SessionClose c no request
31 StatSess c 127.0.0.1 37977 0 1 1 0 0 1 298 61
31 SessionOpen c 127.0.0.1 37984
31 ReqStart c 127.0.0.1 37984 1892976566
31 RxRequest c GET
31 RxURL c //newsitem_icon.gif
31 RxProtocol c HTTP/1.1
31 RxHeader c Host: localhost:8082
31 RxHeader c User-Agent: Mozilla/5.0 (X11; U; Linux i686;
en-US; rv:1.8.0.13) Gecko/20070824 Thunderbird/1.5.0.13
31 RxHeader c Accept: image/png,*/*;q=0.5
31 RxHeader c Accept-Language: fr,fr-fr;q=0.8,en-us;q=0.5,en;q=0.3
31 RxHeader c Accept-Encoding: gzip,deflate
31 RxHeader c Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
31 RxHeader c Referer:
http://example.net/articles/the-rumours-are-true-a-book-about-plone-3
31 RxHeader c Max-Forwards: 10
31 RxHeader c X-Forwarded-For: 8.223.33.128
31 RxHeader c X-Forwarded-Host: example.net
31 RxHeader c X-Forwarded-Server: example.net
31 VCL_call c recv
31 VCL_return c lookup
31 VCL_call c hash
31 VCL_return c hash
31 Hit c 1892976475
31 VCL_call c hit
31 VCL_return c deliver
31 Length c 952
31 VCL_call c deliver
31 VCL_return c deliver
31 TxProtocol c HTTP/1.1
31 TxStatus c 200
31 TxResponse c OK
31 TxHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
31 TxHeader c Date: Wed, 05 Sep 2007 16:38:55 GMT
31 TxHeader c X-Cache-Headers-Set-By: CachingPolicyManager:
/blog/caching_policy_manager
31 TxHeader c Expires: Thu, 06 Sep 2007 16:38:55 GMT
31 TxHeader c Last-Modified: Sat, 11 Feb 2006 09:46:24 GMT
31 TxHeader c X-Caching-Rule-Id: httpcache
31 TxHeader c Cache-Control: max-age=86400, s-maxage=86400,
public, must-revalidate, proxy-revalidate
31 TxHeader c Content-Type: image/gif
31 TxHeader c X-Header-Set-Id: cache-in-browser-24-hours
31 TxHeader c Content-Length: 952
31 TxHeader c X-Varnish: 1892976566 1892976475
31 TxHeader c Age: 881
31 TxHeader c Via: 1.1 varnish
31 TxHeader c Connection: keep-alive
31 ReqEnd c 1892976566 1189011216.511989117
1189011216.512168884 0.000095129 0.000074863 0.000104904
0 StatAddr 127.0.0.1 0 949 47 99 0 0 30 34302 328744
35 SessionOpen c 127.0.0.1 37985
35 ReqStart c 127.0.0.1 37985 1892976567
35 RxRequest c GET
35 RxURL c //image_icon.gif
35 RxProtocol c HTTP/1.1
35 RxHeader c Host: localhost:8082
35 RxHeader c User-Agent: Mozilla/5.0 (X11; U; Linux i686;
en-US; rv:1.8.0.13) Gecko/20070824 Thunderbird/1.5.0.13
35 RxHeader c Accept: image/png,*/*;q=0.5
35 RxHeader c Accept-Language: fr,fr-fr;q=0.8,en-us;q=0.5,en;q=0.3
35 RxHeader c Accept-Encoding: gzip,deflate
35 RxHeader c Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
35 RxHeader c Referer:
http://example.net/articles/the-rumours-are-true-a-book-about-plone-3
35 RxHeader c Max-Forwards: 10
35 RxHeader c X-Forwarded-For: 8.223.33.128
35 RxHeader c X-Forwarded-Host: example.net
35 RxHeader c X-Forwarded-Server: example.net
35 VCL_call c recv
35 VCL_return c lookup
35 VCL_call c hash
35 VCL_return c hash
35 Hit c 1892976476
35 VCL_call c hit
35 VCL_return c deliver
35 Length c 931
35 VCL_call c deliver
35 VCL_return c deliver
35 TxProtocol c HTTP/1.1
35 TxStatus c 200
35 TxResponse c OK
35 TxHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
35 TxHeader c Date: Wed, 05 Sep 2007 16:38:55 GMT
35 TxHeader c X-Cache-Headers-Set-By: CachingPolicyManager:
/blog/caching_policy_manager
35 TxHeader c Expires: Thu, 06 Sep 2007 16:38:55 GMT
35 TxHeader c Last-Modified: Sat, 11 Feb 2006 09:46:24 GMT
35 TxHeader c X-Caching-Rule-Id: httpcache
35 TxHeader c Cache-Control: max-age=86400, s-maxage=86400,
public, must-revalidate, proxy-revalidate
35 TxHeader c Content-Type: image/gif
35 TxHeader c X-Header-Set-Id: cache-in-browser-24-hours
35 TxHeader c Content-Length: 931
35 TxHeader c X-Varnish: 1892976567 1892976476
35 TxHeader c Age: 881
35 TxHeader c Via: 1.1 varnish
35 TxHeader c Connection: keep-alive
35 ReqEnd c 1892976567 1189011216.522527933
1189011216.522680044 0.000089884 0.000070095 0.000082016
0 StatAddr 127.0.0.1 0 949 48 100 0 0 30 34893 329675
31 HttpError c Received nothing
31 SessionClose c no request
31 StatSess c 127.0.0.1 37984 0 1 1 0 0 0 591 952
35 HttpError c Received nothing
35 SessionClose c no request
35 StatSess c 127.0.0.1 37985 0 1 1 0 0 0 591 931
31 SessionOpen c 127.0.0.1 37987
31 ReqStart c 127.0.0.1 37987 1892976568
31 RxRequest c GET
31 RxURL c //arrowLeft.gif
31 RxProtocol c HTTP/1.1
31 RxHeader c Host: localhost:8082
31 RxHeader c User-Agent: Mozilla/5.0 (X11; U; Linux i686;
en-US; rv:1.8.0.13) Gecko/20070824 Thunderbird/1.5.0.13
31 RxHeader c Accept: image/png,*/*;q=0.5
31 RxHeader c Accept-Language: fr,fr-fr;q=0.8,en-us;q=0.5,en;q=0.3
31 RxHeader c Accept-Encoding: gzip,deflate
31 RxHeader c Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
31 RxHeader c Referer:
http://example.net/articles/the-rumours-are-true-a-book-about-plone-3
31 RxHeader c Max-Forwards: 10
31 RxHeader c X-Forwarded-For: 8.223.33.128
31 RxHeader c X-Forwarded-Host: example.net
31 RxHeader c X-Forwarded-Server: example.net
31 VCL_call c recv
31 VCL_return c lookup
31 VCL_call c hash
31 VCL_return c hash
31 Hit c 1892976519
31 VCL_call c hit
31 VCL_return c deliver
31 Length c 99
31 VCL_call c deliver
31 VCL_return c deliver
31 TxProtocol c HTTP/1.1
31 TxStatus c 200
31 TxResponse c OK
31 TxHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
31 TxHeader c Date: Wed, 05 Sep 2007 16:39:01 GMT
31 TxHeader c X-Cache-Headers-Set-By: CachingPolicyManager:
/blog/caching_policy_manager
31 TxHeader c Expires: Thu, 06 Sep 2007 16:39:01 GMT
31 TxHeader c Last-Modified: Sat, 11 Feb 2006 09:46:24 GMT
31 TxHeader c X-Caching-Rule-Id: httpcache
31 TxHeader c Cache-Control: max-age=86400, s-maxage=86400,
public, must-revalidate, proxy-revalidate
31 TxHeader c Content-Type: image/gif
31 TxHeader c X-Header-Set-Id: cache-in-browser-24-hours
31 TxHeader c Content-Length: 99
31 TxHeader c X-Varnish: 1892976568 1892976519
31 TxHeader c Age: 875
31 TxHeader c Via: 1.1 varnish
31 TxHeader c Connection: keep-alive
31 ReqEnd c 1892976568 1189011216.688266039
1189011216.688390970 0.000116110 0.000061989 0.000062943
0 StatAddr 127.0.0.1 0 949 49 101 0 0 30 35483 329774
35 SessionOpen c 127.0.0.1 37988
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011216
31 HttpError c Received nothing
31 SessionClose c no request
31 StatSess c 127.0.0.1 37987 0 1 1 0 0 0 590 99
35 ReqStart c 127.0.0.1 37988 1892976569
35 RxRequest c GET
35 RxURL c //arrowRight.gif
35 RxProtocol c HTTP/1.1
35 RxHeader c Host: localhost:8082
35 RxHeader c User-Agent: Mozilla/5.0 (X11; U; Linux i686;
en-US; rv:1.8.0.13) Gecko/20070824 Thunderbird/1.5.0.13
35 RxHeader c Accept: image/png,*/*;q=0.5
35 RxHeader c Accept-Language: fr,fr-fr;q=0.8,en-us;q=0.5,en;q=0.3
35 RxHeader c Accept-Encoding: gzip,deflate
35 RxHeader c Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
35 RxHeader c Referer:
http://example.net/articles/the-rumours-are-true-a-book-about-plone-3
35 RxHeader c Max-Forwards: 10
35 RxHeader c X-Forwarded-For: 8.223.33.128
35 RxHeader c X-Forwarded-Host: example.net
35 RxHeader c X-Forwarded-Server: example.net
35 VCL_call c recv
35 VCL_return c lookup
35 VCL_call c hash
35 VCL_return c hash
35 VCL_call c miss
35 VCL_return c fetch
38 BackendXID b 1892976569
35 Backend c 38 default
38 TxRequest b GET
38 TxURL b
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot//arrowRight.gif
38 TxProtocol b HTTP/1.1
38 TxHeader b Host: localhost:8082
38 TxHeader b User-Agent: Mozilla/5.0 (X11; U; Linux i686;
en-US; rv:1.8.0.13) Gecko/20070824 Thunderbird/1.5.0.13
38 TxHeader b Accept: image/png,*/*;q=0.5
38 TxHeader b Accept-Language: fr,fr-fr;q=0.8,en-us;q=0.5,en;q=0.3
38 TxHeader b Accept-Encoding: gzip,deflate
38 TxHeader b Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
38 TxHeader b Referer:
http://example.net/articles/the-rumours-are-true-a-book-about-plone-3
38 TxHeader b Max-Forwards: 10
38 TxHeader b X-Forwarded-For: 8.223.33.128
38 TxHeader b X-Forwarded-Host: example.net
38 TxHeader b X-Forwarded-Server: example.net
38 TxHeader b X-Varnish: 1892976569
38 TxHeader b X-Forwarded-for: 127.0.0.1
38 RxProtocol b HTTP/1.1
38 RxStatus b 200
38 RxResponse b OK
38 RxHeader b Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
38 RxHeader b Date: Wed, 05 Sep 2007 16:53:36 GMT
38 RxHeader b Content-Length: 99
38 RxHeader b X-Cache-Headers-Set-By: CachingPolicyManager:
/blog/caching_policy_manager
38 RxHeader b Expires: Thu, 06 Sep 2007 16:53:36 GMT
38 RxHeader b Last-Modified: Sat, 11 Feb 2006 09:46:24 GMT
38 RxHeader b X-Caching-Rule-Id: httpcache
38 RxHeader b Cache-Control: max-age=86400, s-maxage=86400,
public, must-revalidate, proxy-revalidate
38 RxHeader b Content-Type: image/gif
38 RxHeader b X-Header-Set-Id: cache-in-browser-24-hours
35 ObjProtocol c HTTP/1.1
35 ObjStatus c 200
35 ObjResponse c OK
35 ObjHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
35 ObjHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
35 ObjHeader c X-Cache-Headers-Set-By: CachingPolicyManager:
/blog/caching_policy_manager
35 ObjHeader c Expires: Thu, 06 Sep 2007 16:53:36 GMT
35 ObjHeader c Last-Modified: Sat, 11 Feb 2006 09:46:24 GMT
35 ObjHeader c X-Caching-Rule-Id: httpcache
35 ObjHeader c Cache-Control: max-age=86400, s-maxage=86400,
public, must-revalidate, proxy-revalidate
35 ObjHeader c Content-Type: image/gif
35 ObjHeader c X-Header-Set-Id: cache-in-browser-24-hours
38 BackendReuse b default
35 TTL c 1892976569 RFC 86399 1189011216 1189011216
1189097616 86400 0
35 VCL_call c fetch
35 VCL_return c insert
35 Length c 99
35 VCL_call c deliver
35 VCL_return c deliver
35 TxProtocol c HTTP/1.1
35 TxStatus c 200
35 TxResponse c OK
35 TxHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
35 TxHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
35 TxHeader c X-Cache-Headers-Set-By: CachingPolicyManager:
/blog/caching_policy_manager
35 TxHeader c Expires: Thu, 06 Sep 2007 16:53:36 GMT
35 TxHeader c Last-Modified: Sat, 11 Feb 2006 09:46:24 GMT
35 TxHeader c X-Caching-Rule-Id: httpcache
35 TxHeader c Cache-Control: max-age=86400, s-maxage=86400,
public, must-revalidate, proxy-revalidate
35 TxHeader c Content-Type: image/gif
35 TxHeader c X-Header-Set-Id: cache-in-browser-24-hours
35 TxHeader c Content-Length: 99
35 TxHeader c X-Varnish: 1892976569
35 TxHeader c Age: 0
35 TxHeader c Via: 1.1 varnish
35 TxHeader c Connection: keep-alive
35 ReqEnd c 1892976569 1189011216.714818954
1189011216.796236038 0.000118017 0.081356049 0.000061035
0 StatAddr 127.0.0.1 0 949 50 102 0 0 31 36060 329873
31 SessionOpen c 127.0.0.1 37993
31 VCL_acl c MATCH purge "localhost"
31 ReqStart c 127.0.0.1 37993 1892976570
31 RxRequest c PURGE
31 RxURL c
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot/arrowRight.gif/%28dynamic%20view%29
31 RxProtocol c HTTP/1.1
31 RxHeader c Host: 127.0.0.1:8082
31 RxHeader c Accept-Encoding: identity
31 VCL_call c recv
31 VCL_return c lookup
31 VCL_call c hash
31 VCL_return c hash
31 VCL_call c miss
0 Debug "VCL_error(404, Not in cache)"
31 VCL_return c error
31 Length c 425
31 TxProtocol c HTTP/1.0
31 TxStatus c 404
31 TxResponse c Not Found
31 TxHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
31 TxHeader c Server: Varnish
31 TxHeader c Retry-After: 0
31 TxHeader c Content-Type: text/html; charset=utf-8
31 TxHeader c Content-Length: 425
31 TxHeader c X-Varnish: 1892976570
31 TxHeader c Age: nan
31 TxHeader c Via: 1.1 varnish
31 TxHeader c Connection: keep-alive
31 ReqEnd c 1892976570 1189011216.798091888
1189011216.798286915 0.002460003 nan nan
0 StatAddr 127.0.0.1 0 949 51 103 0 0 31 36292 330298
31 VCL_acl c MATCH purge "localhost"
31 ReqStart c 127.0.0.1 37993 1892976571
31 RxRequest c PURGE
31 RxURL c
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot/arrowRight.gif/
31 RxProtocol c HTTP/1.1
31 RxHeader c Host: 127.0.0.1:8082
31 RxHeader c Accept-Encoding: identity
31 VCL_call c recv
31 VCL_return c lookup
31 VCL_call c hash
31 VCL_return c hash
31 VCL_call c miss
0 Debug "VCL_error(404, Not in cache)"
31 VCL_return c error
31 Length c 425
31 TxProtocol c HTTP/1.0
31 TxStatus c 404
31 TxResponse c Not Found
31 TxHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
31 TxHeader c Server: Varnish
31 TxHeader c Retry-After: 0
31 TxHeader c Content-Type: text/html; charset=utf-8
31 TxHeader c Content-Length: 425
31 TxHeader c X-Varnish: 1892976571
31 TxHeader c Age: nan
31 TxHeader c Via: 1.1 varnish
31 TxHeader c Connection: keep-alive
31 ReqEnd c 1892976571 1189011216.805568933
1189011216.805711031 0.007282019 nan nan
0 StatAddr 127.0.0.1 0 949 51 104 0 0 31 36524 330723
31 VCL_acl c MATCH purge "localhost"
31 ReqStart c 127.0.0.1 37993 1892976572
31 RxRequest c PURGE
31 RxURL c
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot/arrowRight.gif
31 RxProtocol c HTTP/1.1
31 RxHeader c Host: 127.0.0.1:8082
31 RxHeader c Accept-Encoding: identity
31 VCL_call c recv
31 VCL_return c lookup
31 VCL_call c hash
31 VCL_return c hash
31 VCL_call c miss
0 Debug "VCL_error(404, Not in cache)"
31 VCL_return c error
31 Length c 425
31 TxProtocol c HTTP/1.0
31 TxStatus c 404
31 TxResponse c Not Found
31 TxHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
31 TxHeader c Server: Varnish
31 TxHeader c Retry-After: 0
31 TxHeader c Content-Type: text/html; charset=utf-8
31 TxHeader c Content-Length: 425
31 TxHeader c X-Varnish: 1892976572
31 TxHeader c Age: nan
31 TxHeader c Via: 1.1 varnish
31 TxHeader c Connection: keep-alive
31 ReqEnd c 1892976572 1189011216.812565088
1189011216.812725067 0.006854057 nan nan
0 StatAddr 127.0.0.1 0 949 51 105 0 0 31 36756 331148
31 VCL_acl c MATCH purge "localhost"
31 ReqStart c 127.0.0.1 37993 1892976573
31 RxRequest c PURGE
31 RxURL c
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot/arrowRight.gif/folder_contents
31 RxProtocol c HTTP/1.1
31 RxHeader c Host: 127.0.0.1:8082
31 RxHeader c Accept-Encoding: identity
31 VCL_call c recv
31 VCL_return c lookup
31 VCL_call c hash
31 VCL_return c hash
31 VCL_call c miss
0 Debug "VCL_error(404, Not in cache)"
31 VCL_return c error
31 Length c 425
31 TxProtocol c HTTP/1.0
31 TxStatus c 404
31 TxResponse c Not Found
31 TxHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
31 TxHeader c Server: Varnish
31 TxHeader c Retry-After: 0
31 TxHeader c Content-Type: text/html; charset=utf-8
31 TxHeader c Content-Length: 425
31 TxHeader c X-Varnish: 1892976573
31 TxHeader c Age: nan
31 TxHeader c Via: 1.1 varnish
31 TxHeader c Connection: keep-alive
31 ReqEnd c 1892976573 1189011216.819152117
1189011216.819294930 0.006427050 nan nan
0 StatAddr 127.0.0.1 0 949 51 106 0 0 31 36988 331573
31 VCL_acl c MATCH purge "localhost"
31 ReqStart c 127.0.0.1 37993 1892976574
31 RxRequest c PURGE
31 RxURL c
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot/arrowRight.gif/RSS
31 RxProtocol c HTTP/1.1
31 RxHeader c Host: 127.0.0.1:8082
31 RxHeader c Accept-Encoding: identity
31 VCL_call c recv
31 VCL_return c lookup
31 VCL_call c hash
31 VCL_return c hash
31 VCL_call c miss
0 Debug "VCL_error(404, Not in cache)"
31 VCL_return c error
31 Length c 425
31 TxProtocol c HTTP/1.0
31 TxStatus c 404
31 TxResponse c Not Found
31 TxHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
31 TxHeader c Server: Varnish
31 TxHeader c Retry-After: 0
31 TxHeader c Content-Type: text/html; charset=utf-8
31 TxHeader c Content-Length: 425
31 TxHeader c X-Varnish: 1892976574
31 TxHeader c Age: nan
31 TxHeader c Via: 1.1 varnish
31 TxHeader c Connection: keep-alive
31 ReqEnd c 1892976574 1189011216.826606989
1189011216.826745033 0.007312059 nan nan
0 StatAddr 127.0.0.1 0 949 51 107 0 0 31 37220 331998
31 VCL_acl c MATCH purge "localhost"
31 ReqStart c 127.0.0.1 37993 1892976575
31 RxRequest c PURGE
31 RxURL c
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot/arrowRight.gif/view
31 RxProtocol c HTTP/1.1
31 RxHeader c Host: 127.0.0.1:8082
31 RxHeader c Accept-Encoding: identity
31 VCL_call c recv
31 VCL_return c lookup
31 VCL_call c hash
31 VCL_return c hash
31 VCL_call c miss
0 Debug "VCL_error(404, Not in cache)"
31 VCL_return c error
31 Length c 425
31 TxProtocol c HTTP/1.0
31 TxStatus c 404
31 TxResponse c Not Found
31 TxHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
31 TxHeader c Server: Varnish
31 TxHeader c Retry-After: 0
31 TxHeader c Content-Type: text/html; charset=utf-8
31 TxHeader c Content-Length: 425
31 TxHeader c X-Varnish: 1892976575
31 TxHeader c Age: nan
31 TxHeader c Via: 1.1 varnish
31 TxHeader c Connection: keep-alive
31 ReqEnd c 1892976575 1189011216.833184958
1189011216.833395958 0.006439924 nan nan
0 StatAddr 127.0.0.1 0 949 51 108 0 0 31 37452 332423
39 SessionOpen c 127.0.0.1 37994
39 ReqStart c 127.0.0.1 37994 1892976576
39 RxRequest c GET
39 RxURL c //spinner.gif
39 RxProtocol c HTTP/1.1
39 RxHeader c Host: localhost:8082
39 RxHeader c User-Agent: Mozilla/5.0 (X11; U; Linux i686;
en-US; rv:1.8.0.13) Gecko/20070824 Thunderbird/1.5.0.13
39 RxHeader c Accept: image/png,*/*;q=0.5
39 RxHeader c Accept-Language: fr,fr-fr;q=0.8,en-us;q=0.5,en;q=0.3
39 RxHeader c Accept-Encoding: gzip,deflate
39 RxHeader c Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
39 RxHeader c Referer:
http://example.net/articles/the-rumours-are-true-a-book-about-plone-3
39 RxHeader c Max-Forwards: 10
39 RxHeader c X-Forwarded-For: 8.223.33.128
39 RxHeader c X-Forwarded-Host: example.net
39 RxHeader c X-Forwarded-Server: example.net
39 VCL_call c recv
39 VCL_return c lookup
39 VCL_call c hash
39 VCL_return c hash
39 Hit c 1892976502
39 VCL_call c hit
39 VCL_return c deliver
39 Length c 2037
39 VCL_call c deliver
39 VCL_return c deliver
39 TxProtocol c HTTP/1.1
39 TxStatus c 200
39 TxResponse c OK
39 TxHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
39 TxHeader c Date: Wed, 05 Sep 2007 16:38:56 GMT
39 TxHeader c X-Cache-Headers-Set-By: CachingPolicyManager:
/blog/caching_policy_manager
39 TxHeader c Expires: Thu, 06 Sep 2007 16:38:56 GMT
39 TxHeader c Last-Modified: Mon, 18 Jun 2007 08:28:23 GMT
39 TxHeader c X-Caching-Rule-Id: httpcache
39 TxHeader c Cache-Control: max-age=86400, s-maxage=86400,
public, must-revalidate, proxy-revalidate
39 TxHeader c Content-Type: image/gif
39 TxHeader c X-Header-Set-Id: cache-in-browser-24-hours
39 TxHeader c Content-Length: 2037
39 TxHeader c X-Varnish: 1892976576 1892976502
39 TxHeader c Age: 880
39 TxHeader c Via: 1.1 varnish
39 TxHeader c Connection: keep-alive
39 ReqEnd c 1892976576 1189011216.841237068
1189011216.841363907 0.002560139 0.000052929 0.000073910
0 StatAddr 127.0.0.1 0 949 52 109 0 0 31 38044 334460
31 VCL_acl c MATCH purge "localhost"
31 ReqStart c 127.0.0.1 37993 1892976577
31 RxRequest c PURGE
31 RxURL c
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot/arrowRight.gif/%28dynamic%20view%29
31 RxProtocol c HTTP/1.1
31 RxHeader c Host: 127.0.0.1:8082
31 RxHeader c Accept-Encoding: identity
31 VCL_call c recv
31 VCL_return c lookup
31 VCL_call c hash
31 VCL_return c hash
31 VCL_call c miss
0 Debug "VCL_error(404, Not in cache)"
31 VCL_return c error
31 Length c 425
31 TxProtocol c HTTP/1.0
31 TxStatus c 404
31 TxResponse c Not Found
31 TxHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
31 TxHeader c Server: Varnish
31 TxHeader c Retry-After: 0
31 TxHeader c Content-Type: text/html; charset=utf-8
31 TxHeader c Content-Length: 425
31 TxHeader c X-Varnish: 1892976577
31 TxHeader c Age: nan
31 TxHeader c Via: 1.1 varnish
31 TxHeader c Connection: keep-alive
31 ReqEnd c 1892976577 1189011216.842278004
1189011216.842431068 0.008882046 nan nan
0 StatAddr 127.0.0.1 0 949 52 110 0 0 31 38276 334885
31 VCL_acl c MATCH purge "localhost"
31 ReqStart c 127.0.0.1 37993 1892976578
31 RxRequest c PURGE
31 RxURL c
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot/arrowRight.gif/
31 RxProtocol c HTTP/1.1
31 RxHeader c Host: 127.0.0.1:8082
31 RxHeader c Accept-Encoding: identity
31 VCL_call c recv
31 VCL_return c lookup
31 VCL_call c hash
31 VCL_return c hash
31 VCL_call c miss
0 Debug "VCL_error(404, Not in cache)"
31 VCL_return c error
31 Length c 425
31 TxProtocol c HTTP/1.0
31 TxStatus c 404
31 TxResponse c Not Found
31 TxHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
31 TxHeader c Server: Varnish
31 TxHeader c Retry-After: 0
31 TxHeader c Content-Type: text/html; charset=utf-8
31 TxHeader c Content-Length: 425
31 TxHeader c X-Varnish: 1892976578
31 TxHeader c Age: nan
31 TxHeader c Via: 1.1 varnish
31 TxHeader c Connection: keep-alive
31 ReqEnd c 1892976578 1189011216.849272013
1189011216.849400043 0.006840944 nan nan
0 StatAddr 127.0.0.1 0 949 52 111 0 0 31 38508 335310
31 VCL_acl c MATCH purge "localhost"
31 ReqStart c 127.0.0.1 37993 1892976579
31 RxRequest c PURGE
31 RxURL c
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot/arrowRight.gif
31 RxProtocol c HTTP/1.1
31 RxHeader c Host: 127.0.0.1:8082
31 RxHeader c Accept-Encoding: identity
31 VCL_call c recv
31 VCL_return c lookup
31 VCL_call c hash
31 VCL_return c hash
31 VCL_call c miss
0 Debug "VCL_error(404, Not in cache)"
31 VCL_return c error
31 Length c 425
31 TxProtocol c HTTP/1.0
31 TxStatus c 404
31 TxResponse c Not Found
31 TxHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
31 TxHeader c Server: Varnish
31 TxHeader c Retry-After: 0
31 TxHeader c Content-Type: text/html; charset=utf-8
31 TxHeader c Content-Length: 425
31 TxHeader c X-Varnish: 1892976579
31 TxHeader c Age: nan
31 TxHeader c Via: 1.1 varnish
31 TxHeader c Connection: keep-alive
31 ReqEnd c 1892976579 1189011216.856280088
1189011216.856405973 0.006880045 nan nan
0 StatAddr 127.0.0.1 0 949 52 112 0 0 31 38740 335735
35 HttpError c Received nothing
35 SessionClose c no request
35 StatSess c 127.0.0.1 37988 0 1 1 0 0 1 577 99
31 VCL_acl c MATCH purge "localhost"
31 ReqStart c 127.0.0.1 37993 1892976580
31 RxRequest c PURGE
31 RxURL c
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot/arrowRight.gif/folder_contents
31 RxProtocol c HTTP/1.1
31 RxHeader c Host: 127.0.0.1:8082
31 RxHeader c Accept-Encoding: identity
31 VCL_call c recv
31 VCL_return c lookup
31 VCL_call c hash
31 VCL_return c hash
31 VCL_call c miss
0 Debug "VCL_error(404, Not in cache)"
31 VCL_return c error
31 Length c 425
31 TxProtocol c HTTP/1.0
31 TxStatus c 404
31 TxResponse c Not Found
31 TxHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
31 TxHeader c Server: Varnish
31 TxHeader c Retry-After: 0
31 TxHeader c Content-Type: text/html; charset=utf-8
31 TxHeader c Content-Length: 425
31 TxHeader c X-Varnish: 1892976580
31 TxHeader c Age: nan
31 TxHeader c Via: 1.1 varnish
31 TxHeader c Connection: keep-alive
31 ReqEnd c 1892976580 1189011216.863306046
1189011216.863451004 0.006900072 nan nan
0 StatAddr 127.0.0.1 0 949 52 113 0 0 31 38972 336160
31 VCL_acl c MATCH purge "localhost"
31 ReqStart c 127.0.0.1 37993 1892976581
31 RxRequest c PURGE
31 RxURL c
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot/arrowRight.gif/RSS
31 RxProtocol c HTTP/1.1
31 RxHeader c Host: 127.0.0.1:8082
31 RxHeader c Accept-Encoding: identity
31 VCL_call c recv
31 VCL_return c lookup
31 VCL_call c hash
31 VCL_return c hash
31 VCL_call c miss
0 Debug "VCL_error(404, Not in cache)"
31 VCL_return c error
31 Length c 425
31 TxProtocol c HTTP/1.0
31 TxStatus c 404
31 TxResponse c Not Found
31 TxHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
31 TxHeader c Server: Varnish
31 TxHeader c Retry-After: 0
31 TxHeader c Content-Type: text/html; charset=utf-8
31 TxHeader c Content-Length: 425
31 TxHeader c X-Varnish: 1892976581
31 TxHeader c Age: nan
31 TxHeader c Via: 1.1 varnish
31 TxHeader c Connection: keep-alive
31 ReqEnd c 1892976581 1189011216.870321035
1189011216.870450020 0.006870031 nan nan
0 StatAddr 127.0.0.1 0 949 52 114 0 0 31 39204 336585
31 VCL_acl c MATCH purge "localhost"
31 ReqStart c 127.0.0.1 37993 1892976582
31 RxRequest c PURGE
31 RxURL c
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot/arrowRight.gif/view
31 RxProtocol c HTTP/1.1
31 RxHeader c Host: 127.0.0.1:8082
31 RxHeader c Accept-Encoding: identity
31 VCL_call c recv
31 VCL_return c lookup
31 VCL_call c hash
31 VCL_return c hash
31 VCL_call c miss
0 Debug "VCL_error(404, Not in cache)"
31 VCL_return c error
31 Length c 425
31 TxProtocol c HTTP/1.0
31 TxStatus c 404
31 TxResponse c Not Found
31 TxHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
31 TxHeader c Server: Varnish
31 TxHeader c Retry-After: 0
31 TxHeader c Content-Type: text/html; charset=utf-8
31 TxHeader c Content-Length: 425
31 TxHeader c X-Varnish: 1892976582
31 TxHeader c Age: nan
31 TxHeader c Via: 1.1 varnish
31 TxHeader c Connection: keep-alive
31 ReqEnd c 1892976582 1189011216.877748966
1189011216.877892017 0.007298946 nan nan
0 StatAddr 127.0.0.1 0 949 52 115 0 0 31 39436 337010
39 HttpError c Received nothing
39 SessionClose c no request
39 StatSess c 127.0.0.1 37994 0 1 1 0 0 0 592 2037
35 SessionOpen c 127.0.0.1 37995
35 ReqStart c 127.0.0.1 37995 1892976583
35 RxRequest c GET
35 RxURL c //portal_css/Optilude%20Theme/breadCrumbDivider.gif
35 RxProtocol c HTTP/1.1
35 RxHeader c Host: localhost:8082
35 RxHeader c User-Agent: Mozilla/5.0 (X11; U; Linux i686;
en-US; rv:1.8.0.13) Gecko/20070824 Thunderbird/1.5.0.13
35 RxHeader c Accept: image/png,*/*;q=0.5
35 RxHeader c Accept-Language: fr,fr-fr;q=0.8,en-us;q=0.5,en;q=0.3
35 RxHeader c Accept-Encoding: gzip,deflate
35 RxHeader c Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
35 RxHeader c Referer:
http://example.net/portal_css/Optilude%20Theme/nuplone-cachekey0680.css
35 RxHeader c Max-Forwards: 10
35 RxHeader c X-Forwarded-For: 8.223.33.128
35 RxHeader c X-Forwarded-Host: example.net
35 RxHeader c X-Forwarded-Server: example.net
35 VCL_call c recv
35 VCL_return c lookup
35 VCL_call c hash
35 VCL_return c hash
35 VCL_call c miss
35 VCL_return c fetch
38 BackendXID b 1892976583
35 Backend c 38 default
38 TxRequest b GET
38 TxURL b
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot//portal_css/Optilude%20Theme/breadCrumbDivider.gif
38 TxProtocol b HTTP/1.1
38 TxHeader b Host: localhost:8082
38 TxHeader b User-Agent: Mozilla/5.0 (X11; U; Linux i686;
en-US; rv:1.8.0.13) Gecko/20070824 Thunderbird/1.5.0.13
38 TxHeader b Accept: image/png,*/*;q=0.5
38 TxHeader b Accept-Language: fr,fr-fr;q=0.8,en-us;q=0.5,en;q=0.3
38 TxHeader b Accept-Encoding: gzip,deflate
38 TxHeader b Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
38 TxHeader b Referer:
http://example.net/portal_css/Optilude%20Theme/nuplone-cachekey0680.css
38 TxHeader b Max-Forwards: 10
38 TxHeader b X-Forwarded-For: 8.223.33.128
38 TxHeader b X-Forwarded-Host: example.net
38 TxHeader b X-Forwarded-Server: example.net
38 TxHeader b X-Varnish: 1892976583
38 TxHeader b X-Forwarded-for: 127.0.0.1
38 RxProtocol b HTTP/1.1
38 RxStatus b 200
38 RxResponse b OK
38 RxHeader b Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
38 RxHeader b Date: Wed, 05 Sep 2007 16:53:36 GMT
38 RxHeader b Last-Modified: Mon, 27 Aug 2007 20:09:52 GMT
38 RxHeader b Content-Length: 42
38 RxHeader b Content-Type: image/gif
35 ObjProtocol c HTTP/1.1
35 ObjStatus c 200
35 ObjResponse c OK
35 ObjHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
35 ObjHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
35 ObjHeader c Last-Modified: Mon, 27 Aug 2007 20:09:52 GMT
35 ObjHeader c Content-Type: image/gif
38 BackendReuse b default
35 TTL c 1892976583 RFC 120 1189011216 1189011216 0 0 0
35 VCL_call c fetch
35 VCL_return c insert
35 Length c 42
35 VCL_call c deliver
35 VCL_return c deliver
35 TxProtocol c HTTP/1.1
35 TxStatus c 200
35 TxResponse c OK
35 TxHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
35 TxHeader c Date: Wed, 05 Sep 2007 16:53:36 GMT
35 TxHeader c Last-Modified: Mon, 27 Aug 2007 20:09:52 GMT
35 TxHeader c Content-Type: image/gif
35 TxHeader c Content-Length: 42
35 TxHeader c X-Varnish: 1892976583
35 TxHeader c Age: 0
35 TxHeader c Via: 1.1 varnish
35 TxHeader c Connection: keep-alive
35 ReqEnd c 1892976583 1189011216.949131966
1189011216.997659922 0.000112057 0.048439026 0.000088930
0 StatAddr 127.0.0.1 0 949 53 116 0 0 32 39734 337052
39 SessionOpen c 127.0.0.1 37998
39 ReqStart c 127.0.0.1 37998 1892976584
39 RxRequest c GET
39 RxURL c //portal_css/Optilude%20Theme/searchField.png
39 RxProtocol c HTTP/1.1
39 RxHeader c Host: localhost:8082
39 RxHeader c User-Agent: Mozilla/5.0 (X11; U; Linux i686;
en-US; rv:1.8.0.13) Gecko/20070824 Thunderbird/1.5.0.13
39 RxHeader c Accept: image/png,*/*;q=0.5
39 RxHeader c Accept-Language: fr,fr-fr;q=0.8,en-us;q=0.5,en;q=0.3
39 RxHeader c Accept-Encoding: gzip,deflate
39 RxHeader c Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
39 RxHeader c Referer:
http://example.net/portal_css/Optilude%20Theme/nuplone-cachekey0680.css
39 RxHeader c Max-Forwards: 10
39 RxHeader c X-Forwarded-For: 8.223.33.128
39 RxHeader c X-Forwarded-Host: example.net
39 RxHeader c X-Forwarded-Server: example.net
39 VCL_call c recv
39 VCL_return c lookup
39 VCL_call c hash
39 VCL_return c hash
39 VCL_call c miss
39 VCL_return c fetch
38 BackendXID b 1892976584
39 Backend c 38 default
38 TxRequest b GET
38 TxURL b
/VirtualHostBase/http/example.net:80/blog/VirtualHostRoot//portal_css/Optilude%20Theme/searchField.png
38 TxProtocol b HTTP/1.1
38 TxHeader b Host: localhost:8082
38 TxHeader b User-Agent: Mozilla/5.0 (X11; U; Linux i686;
en-US; rv:1.8.0.13) Gecko/20070824 Thunderbird/1.5.0.13
38 TxHeader b Accept: image/png,*/*;q=0.5
38 TxHeader b Accept-Language: fr,fr-fr;q=0.8,en-us;q=0.5,en;q=0.3
38 TxHeader b Accept-Encoding: gzip,deflate
38 TxHeader b Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
38 TxHeader b Referer:
http://example.net/portal_css/Optilude%20Theme/nuplone-cachekey0680.css
38 TxHeader b Max-Forwards: 10
38 TxHeader b X-Forwarded-For: 8.223.33.128
38 TxHeader b X-Forwarded-Host: example.net
38 TxHeader b X-Forwarded-Server: example.net
38 TxHeader b X-Varnish: 1892976584
38 TxHeader b X-Forwarded-for: 127.0.0.1
38 RxProtocol b HTTP/1.1
38 RxStatus b 200
38 RxResponse b OK
38 RxHeader b Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
38 RxHeader b Date: Wed, 05 Sep 2007 16:53:37 GMT
38 RxHeader b Last-Modified: Mon, 27 Aug 2007 20:09:52 GMT
38 RxHeader b Content-Length: 4306
38 RxHeader b Content-Type: image/png
39 ObjProtocol c HTTP/1.1
39 ObjStatus c 200
39 ObjResponse c OK
39 ObjHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
39 ObjHeader c Date: Wed, 05 Sep 2007 16:53:37 GMT
39 ObjHeader c Last-Modified: Mon, 27 Aug 2007 20:09:52 GMT
39 ObjHeader c Content-Type: image/png
38 BackendReuse b default
39 TTL c 1892976584 RFC 120 1189011217 1189011217 0 0 0
39 VCL_call c fetch
39 VCL_return c insert
39 Length c 4306
39 VCL_call c deliver
39 VCL_return c deliver
39 TxProtocol c HTTP/1.1
39 TxStatus c 200
39 TxResponse c OK
39 TxHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.4,
linux2) ZServer/1.1 Plone/3.0
39 TxHeader c Date: Wed, 05 Sep 2007 16:53:37 GMT
39 TxHeader c Last-Modified: Mon, 27 Aug 2007 20:09:52 GMT
39 TxHeader c Content-Type: image/png
39 TxHeader c Content-Length: 4306
39 TxHeader c X-Varnish: 1892976584
39 TxHeader c Age: 0
39 TxHeader c Via: 1.1 varnish
39 TxHeader c Connection: keep-alive
39 ReqEnd c 1892976584 1189011217.015980005
1189011217.065607071 0.000149012 0.049546003 0.000081062
0 StatAddr 127.0.0.1 0 949 54 117 0 0 33 40034 341358
35 HttpError c Received nothing
35 SessionClose c no request
35 StatSess c 127.0.0.1 37995 0 1 1 0 0 1 298 42
39 HttpError c Received nothing
39 SessionClose c no request
39 StatSess c 127.0.0.1 37998 0 1 1 0 0 1 300 4306
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011219
31 SessionClose c timeout
31 StatSess c 127.0.0.1 37993 0 1 12 0 0 0 2784 5100
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011222
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011225
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189011228


Lots of pings... then new error.



--
Acquisition is a jealous mistress

Here is a much shorter log entry that exhibits the same behaviour. This
is with trunk and 1.1.1 final (the log entry is 1.1.1 final)

$
./bin/varnishlog 0 CLI Rd ping 0 CLI Wr 0
200 PONG 1189354743
0 WorkThread 0xb0303c90 start
12 SessionOpen c 127.0.0.1 64496
12 ReqStart c 127.0.0.1 64496 1765608679
12 RxRequest c GET
12 RxURL c /optilux
12 RxProtocol c HTTP/1.1
12 RxHeader c Accept: */*
12 RxHeader c Accept-Language: en
12 RxHeader c Accept-Encoding: gzip, deflate
12 RxHeader c Cookie: __ac="NjE2NDZkNjk2ZTo2MTY0NmQ2OTZl";
wstyle=Normal%20Text
12 RxHeader c User-Agent: Mozilla/5.0 (Macintosh; U; Intel Mac
OS X; en) AppleWebKit/419.3 (KHTML, like Gecko) Safari/419.3
12 RxHeader c Connection: keep-alive
12 RxHeader c Host: localhost:8081
12 VCL_call c recv
12 VCL_return c pass
12 VCL_call c pass
12 VCL_return c pass
15 BackendOpen b default 127.0.0.1 64497 127.0.0.1 8080
15 BackendXID b 1765608679
12 Backend c 15 default
15 TxRequest b GET
15 TxURL b /optilux
15 TxProtocol b HTTP/1.1
15 TxHeader b Accept: */*
15 TxHeader b Accept-Language: en
15 TxHeader b Accept-Encoding: gzip, deflate
15 TxHeader b Cookie: __ac="NjE2NDZkNjk2ZTo2MTY0NmQ2OTZl";
wstyle=Normal%20Text
15 TxHeader b User-Agent: Mozilla/5.0 (Macintosh; U; Intel Mac
OS X; en) AppleWebKit/419.3 (KHTML, like Gecko) Safari/419.3
15 TxHeader b Host: localhost:8081
15 TxHeader b X-Varnish: 1765608679
15 TxHeader b X-Forwarded-for: 127.0.0.1
15 RxProtocol b HTTP/1.1
15 RxStatus b 200
15 RxResponse b OK
15 RxHeader b Server: Zope/(Zope 2.10.4-final, python 2.4.3,
darwin) ZServer/1.1 Plone/3.0
15 RxHeader b Date: Sun, 09 Sep 2007 16:19:04 GMT
15 RxHeader b X-Pagecache: NO-ETAG
15 RxHeader b Content-Length: 6690
15 RxHeader b Content-Language: en-gb
15 RxHeader b Content-Encoding: gzip
15 RxHeader b Expires: Thu, 11 Sep 1997 16:19:04 GMT
15 RxHeader b Vary: Accept-Encoding, Accept-Language,
15 RxHeader b Accept-Encoding
15 RxHeader b ETag: |admin|Optilux Theme|en|1|310||||330376
15 RxHeader b X-Caching-Rule-Id: plone-content-types
15 RxHeader b Cache-Control: max-age=0, s-maxage=0, private,
must-revalidate
15 RxHeader b Content-Type: text/html;charset=utf-8
15 RxHeader b X-Header-Set-Id: cache-with-etag
12 ObjProtocol c HTTP/1.1
12 ObjStatus c 200
12 ObjResponse c OK
12 ObjHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.3,
darwin) ZServer/1.1 Plone/3.0
12 ObjHeader c Date: Sun, 09 Sep 2007 16:19:04 GMT
12 ObjHeader c X-Pagecache: NO-ETAG
12 ObjHeader c Content-Language: en-gb
12 ObjHeader c Content-Encoding: gzip
12 ObjHeader c Expires: Thu, 11 Sep 1997 16:19:04 GMT
12 ObjHeader c Vary: Accept-Encoding, Accept-Language,
12 ObjHeader c Accept-Encoding
12 ObjHeader c ETag: |admin|Optilux Theme|en|1|310||||330376
12 ObjHeader c X-Caching-Rule-Id: plone-content-types
12 ObjHeader c Cache-Control: max-age=0, s-maxage=0, private,
must-revalidate
12 ObjHeader c Content-Type: text/html;charset=utf-8
12 ObjHeader c X-Header-Set-Id: cache-with-etag
15 BackendReuse b default
12 TTL c 1765608679 RFC 0 1189354744 1189354744 873994744 0 0
12 VCL_call c fetch
12 VCL_return c insert
12 Length c 6690
12 VCL_call c deliver
12 VCL_return c deliver
12 TxProtocol c HTTP/1.1
12 TxStatus c 200
12 TxResponse c OK
12 TxHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.3,
darwin) ZServer/1.1 Plone/3.0
12 TxHeader c Date: Sun, 09 Sep 2007 16:19:04 GMT
12 TxHeader c X-Pagecache: NO-ETAG
12 TxHeader c Content-Language: en-gb
12 TxHeader c Content-Encoding: gzip
12 TxHeader c Expires: Thu, 11 Sep 1997 16:19:04 GMT
12 TxHeader c Vary: Accept-Encoding, Accept-Language,
12 TxHeader c Accept-Encoding
12 TxHeader c ETag: |admin|Optilux Theme|en|1|310||||330376
12 TxHeader c X-Caching-Rule-Id: plone-content-types
12 TxHeader c Cache-Control: max-age=0, s-maxage=0, private,
must-revalidate
12 TxHeader c Content-Type: text/html;charset=utf-8
12 TxHeader c X-Header-Set-Id: cache-with-etag
12 TxHeader c Content-Length: 6690
12 TxHeader c X-Varnish: 1765608679
12 TxHeader c Age: 0
12 TxHeader c Via: 1.1 varnish
12 TxHeader c Connection: keep-alive
12 ReqEnd c 1765608679 1189354744.079793930
1189354744.435858965 0.000501871 0.355978012 0.000087023
0 StatAddr 127.0.0.1 0 0 1 1 0 0 1 623 6690
0 WorkThread 0xb0384c90 start
16 SessionOpen c 127.0.0.1 64501
0 WorkThread 0xb0405c90 start
17 SessionOpen c 127.0.0.1 64502
0 WorkThread 0xb0486c90 start
18 SessionOpen c 127.0.0.1 64503
12 ReqStart c 127.0.0.1 64496 1765608680
12 RxRequest c GET
12 RxURL c
/optilux/portal_kss/Optilux%20Theme/at-cachekey9321.kss
12 RxProtocol c HTTP/1.1
12 RxHeader c Accept: */*
12 RxHeader c Accept-Language: en
12 RxHeader c Accept-Encoding: gzip, deflate
12 RxHeader c Cookie: __ac="NjE2NDZkNjk2ZTo2MTY0NmQ2OTZl";
wstyle=Normal%20Text
12 RxHeader c Referer: http://localhost:8081/optilux
12 RxHeader c User-Agent: Mozilla/5.0 (Macintosh; U; Intel Mac
OS X; en) AppleWebKit/419.3 (KHTML, like Gecko) Safari/419.3
12 RxHeader c If-Modified-Since: Sun, 09 Sep 2007 16:17:57 GMT
12 RxHeader c Connection: keep-alive
12 RxHeader c Host: localhost:8081
12 VCL_call c recv
12 VCL_return c pass
12 VCL_call c pass
12 VCL_return c pass
15 BackendXID b 1765608680
12 Backend c 15 default
15 TxRequest b GET
15 TxURL b
/optilux/portal_kss/Optilux%20Theme/at-cachekey9321.kss
15 TxProtocol b HTTP/1.1
15 TxHeader b Accept: */*
15 TxHeader b Accept-Language: en
15 TxHeader b Accept-Encoding: gzip, deflate
15 TxHeader b Cookie: __ac="NjE2NDZkNjk2ZTo2MTY0NmQ2OTZl";
wstyle=Normal%20Text
15 TxHeader b Referer: http://localhost:8081/optilux
15 TxHeader b User-Agent: Mozilla/5.0 (Macintosh; U; Intel Mac
OS X; en) AppleWebKit/419.3 (KHTML, like Gecko) Safari/419.3
15 TxHeader b If-Modified-Since: Sun, 09 Sep 2007 16:17:57 GMT
15 TxHeader b Host: localhost:8081
15 TxHeader b X-Varnish: 1765608680
15 TxHeader b X-Forwarded-for: 127.0.0.1
15 RxProtocol b HTTP/1.1
15 RxStatus b 200
15 RxResponse b OK
15 RxHeader b Server: Zope/(Zope 2.10.4-final, python 2.4.3,
darwin) ZServer/1.1 Plone/3.0
15 RxHeader b Date: Sun, 09 Sep 2007 16:19:04 GMT
15 RxHeader b Content-Length: 9912
15 RxHeader b X-Cache-Headers-Set-By: CachingPolicyManager:
/optilux/caching_policy_manager
15 RxHeader b Accept-Ranges: bytes
15 RxHeader b Expires: Thu, 11 Sep 1997 16:19:04 GMT
15 RxHeader b Last-Modified: Sun, 09 Sep 2007 16:19:04 GMT
15 RxHeader b X-Caching-Rule-Id: downloads
15 RxHeader b Cache-Control: max-age=0, s-maxage=86400,
must-revalidate, proxy-revalidate
15 RxHeader b Content-Type: text/css;charset=utf-8
15 RxHeader b X-Header-Set-Id: cache-in-proxy-24-hours
12 ObjProtocol c HTTP/1.1
12 ObjStatus c 200
12 ObjResponse c OK
12 ObjHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.3,
darwin) ZServer/1.1 Plone/3.0
12 ObjHeader c Date: Sun, 09 Sep 2007 16:19:04 GMT
12 ObjHeader c X-Cache-Headers-Set-By: CachingPolicyManager:
/optilux/caching_policy_manager
12 ObjHeader c Expires: Thu, 11 Sep 1997 16:19:04 GMT
12 ObjHeader c Last-Modified: Sun, 09 Sep 2007 16:19:04 GMT
12 ObjHeader c X-Caching-Rule-Id: downloads
12 ObjHeader c Cache-Control: max-age=0, s-maxage=86400,
must-revalidate, proxy-revalidate
12 ObjHeader c Content-Type: text/css;charset=utf-8
12 ObjHeader c X-Header-Set-Id: cache-in-proxy-24-hours
15 BackendReuse b default
12 TTL c 1765608680 RFC 86400 1189354744 1189354744
873994744 86400 0
12 VCL_call c fetch
12 VCL_return c insert
12 Length c 9912
12 VCL_call c deliver
12 VCL_return c deliver
12 TxProtocol c HTTP/1.1
12 TxStatus c 200
12 TxResponse c OK
12 TxHeader c Server: Zope/(Zope 2.10.4-final, python 2.4.3,
darwin) ZServer/1.1 Plone/3.0
12 TxHeader c Date: Sun, 09 Sep 2007 16:19:04 GMT
12 TxHeader c X-Cache-Headers-Set-By: CachingPolicyManager:
/optilux/caching_policy_manager
12 TxHeader c Expires: Thu, 11 Sep 1997 16:19:04 GMT
12 TxHeader c Last-Modified: Sun, 09 Sep 2007 16:19:04 GMT
12 TxHeader c X-Caching-Rule-Id: downloads
12 TxHeader c Cache-Control: max-age=0, s-maxage=86400,
must-revalidate, proxy-revalidate
12 TxHeader c Content-Type: text/css;charset=utf-8
12 TxHeader c X-Header-Set-Id: cache-in-proxy-24-hours
12 TxHeader c Content-Length: 9912
12 TxHeader c X-Varnish: 1765608680
12 TxHeader c Age: 0
12 TxHeader c Via: 1.1 varnish
12 TxHeader c Connection: keep-alive
12 ReqEnd c 1765608680 1189354744.538324118
1189354744.564362049 0.102465153 0.025976896 0.000061035
0 StatAddr 127.0.0.1 0 0 1 2 0 0 2 1204 16602
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189354746
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189354749
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189354752
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1189354755

I'm not really familiar with this log format, but it seems to me a
request is being made with keep-alive that never completes? During the
last pings, Safari is hanging (and appears to have crashed). It comes
back to life if I kill varnishd. Bad browser behaviour maybe, but still
nothing like this happens when I access the backend directly.

FWIW, this problems seems most pronounced in Safari, but I have seen it
with Firefox and IE as well.

I've seen http://varnish.projects.linpro.no/ticket/55 which may be
related, but there's no mention of where it's fixed (trunk? 1.1
branch?). In any case, I saw the problem with trunk as well.

Martin