Mailing List Archive

#1804: PROXYed client requests does not appear in varnishlog
#1804: PROXYed client requests does not appear in varnishlog
--------------------+--------------------
Reporter: trygve | Type: defect
Status: new | Priority: normal
Milestone: | Component: build
Version: | Severity: normal
Keywords: |
--------------------+--------------------
Hi,

I have a setup which consists of haproxy 1.6 with Varnish 4.1.0 as a
backend. haproxy is configured with send-proxy-v2 and Varnish is listening
with -a localhost:6086,PROXY.

Requests that comes in to Varnish on a non-PROXY port gets logged like
usual, but PROXYed client requests does not appear in neither varnishncsa
or varnishlog (unless I add -g raw).


Output from varnishlog:

{{{
* << Session >> 566
- Begin sess 0 PROXY
- SessOpen 127.0.0.1 34182 localhost:6086 127.0.0.1 6086
1445463298.509256 19
- Link req 567 rxreq
- SessClose RESP_CLOSE 0.000
- End
}}}

Output from varnishlog -g raw:

{{{
566 Begin c sess 0 PROXY
566 SessOpen c 127.0.0.1 34182 localhost:6086 127.0.0.1 6086
1445463298.509256 19
566 Link c req 567 rxreq
0 Proxy - 2 2a02:<removed> 54835 2001:<removed> 443
567 Begin c req 566 rxreq
567 Timestamp c Start: 1445463298.509310 0.000000 0.000000
567 Timestamp c Req: 1445463298.509310 0.000000 0.000000
567 ReqStart c 2a02:<removed> 54835
567 ReqMethod c GET
567 ReqURL c /
567 ReqProtocol c HTTP/1.1
567 ReqHeader c User-Agent: curl/7.38.0
567 ReqHeader c Host: example.com
567 ReqHeader c Accept: */*
567 ReqHeader c X-Forwarded-Proto: https
567 ReqHeader c Connection: close
567 ReqHeader c X-Forwarded-For: 2a02:<removed>
567 VCL_call c RECV
567 VCL_acl c NO_MATCH local
567 VCL_return c hash
567 VCL_call c HASH
567 VCL_return c lookup
567 Hit c 131654
567 VCL_call c HIT
567 VCL_return c deliver
567 RespProtocol c HTTP/1.1
567 RespStatus c 200
567 RespReason c OK
567 RespHeader c Date: Wed, 21 Oct 2015 21:34:48 GMT
567 RespHeader c Server: Apache/2.4.7 (Ubuntu)
567 RespHeader c Last-Modified: Thu, 03 Apr 2014 20:04:50 GMT
567 RespHeader c ETag: "469-4f628e9e5742b-gzip"
567 RespHeader c Vary: Accept-Encoding
567 RespHeader c Content-Encoding: gzip
567 RespHeader c Content-Length: 553
567 RespHeader c Content-Type: text/html; charset=UTF-8
567 RespHeader c x-url: example.com/
567 RespHeader c X-Varnish: 567 131654
567 RespHeader c Age: 10
567 RespHeader c Via: 1.1 varnish-v4
567 RespUnset c ETag: "469-4f628e9e5742b-gzip"
567 RespHeader c ETag: W/"469-4f628e9e5742b-gzip"
567 VCL_call c DELIVER
567 RespUnset c x-url: example.com/
567 RespUnset c X-Varnish: 567 131654
567 RespUnset c Via: 1.1 varnish-v4
567 RespHeader c X-Cache: HIT:1
567 RespHeader c X-Age: 10
567 RespUnset c Age: 10
567 RespHeader c Age: 0
567 VCL_return c deliver
567 Timestamp c Process: 1445463298.509411 0.000100 0.000100
567 RespUnset c Content-Encoding: gzip
567 RespHeader c Accept-Ranges: bytes
567 RespUnset c Content-Length: 553
567 RespHeader c Content-Length: 1129
567 Debug c "RES_MODE 42"
567 RespHeader c Connection: close
567 Gzip c U D - 553 1129 80 80 4358
567 Timestamp c Resp: 1445463298.509507 0.000196 0.000096
567 ReqAcct c 178 0 178 328 1129 1457
567 End c
566 SessClose c RESP_CLOSE 0.000
566 End c
}}}

{{{
# cat /etc/apt/sources.list.d/varnish.list
deb http://repo.varnish-cache.org/ubuntu/ precise varnish-4.1
}}}
{{{
# dpkg -l | grep varnish
ii libvarnishapi1 4.1.0-1~precise
amd64 shared libraries for Varnish
ii varnish 4.1.0-1~precise
amd64 state of the art, high-performance web accelerator
}}}

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

_______________________________________________
varnish-bugs mailing list
varnish-bugs@varnish-cache.org
https://www.varnish-cache.org/lists/mailman/listinfo/varnish-bugs
Re: #1804: PROXYed client requests does not appear in varnishlog [ In reply to ]
#1804: PROXYed client requests does not appear in varnishlog
----------------------+--------------------
Reporter: trygve | Owner:
Type: defect | Status: new
Priority: normal | Milestone:
Component: varnishd | Version: trunk
Severity: normal | Resolution:
Keywords: |
----------------------+--------------------
Changes (by daghf):

* version: => trunk
* component: build => varnishd


Comment:

Confirmed in master

--
Ticket URL: <https://www.varnish-cache.org/trac/ticket/1804#comment:1>
Varnish <https://varnish-cache.org/>
The Varnish HTTP Accelerator

_______________________________________________
varnish-bugs mailing list
varnish-bugs@varnish-cache.org
https://www.varnish-cache.org/lists/mailman/listinfo/varnish-bugs
Re: #1804: PROXYed client requests does not appear in varnishlog [ In reply to ]
#1804: PROXYed client requests does not appear in varnishlog
----------------------+-----------------------------------------------
Reporter: trygve | Owner: Martin Blix Grydeland <martin@…>
Type: defect | Status: closed
Priority: normal | Milestone:
Component: varnishd | Version: trunk
Severity: normal | Resolution: fixed
Keywords: |
----------------------+-----------------------------------------------
Changes (by Martin Blix Grydeland <martin@…>):

* owner: => Martin Blix Grydeland <martin@…>
* status: new => closed
* resolution: => fixed


Comment:

In [86b3f9feca54af49ce194f5d6bed9e4dcfa54971]:
{{{
#!CommitTicketReference repository=""
revision="86b3f9feca54af49ce194f5d6bed9e4dcfa54971"
Log proxy related messages on the session, not on the request.

The proxy log records were attempted to be logged on the request' log
buffer, which at that point in time has not yet been set up and does
not posess a VXID. This caused VXID==0 log records to be inserted in
the beginning of the log for the first request on the session when
using proxy protocol, and subsequently the VSL to fail picking them
out as valid request log transactions.

Fix by logging the PROXY handling related messages on the session, in
which they belong.

Fixes: #1804
}}}

--
Ticket URL: <https://www.varnish-cache.org/trac/ticket/1804#comment:2>
Varnish <https://varnish-cache.org/>
The Varnish HTTP Accelerator

_______________________________________________
varnish-bugs mailing list
varnish-bugs@varnish-cache.org
https://www.varnish-cache.org/lists/mailman/listinfo/varnish-bugs
Re: #1804: PROXYed client requests does not appear in varnishlog [ In reply to ]
#1804: PROXYed client requests does not appear in varnishlog
----------------------+-----------------------------------------------
Reporter: trygve | Owner: Martin Blix Grydeland <martin@…>
Type: defect | Status: closed
Priority: normal | Milestone:
Component: varnishd | Version: trunk
Severity: normal | Resolution: fixed
Keywords: |
----------------------+-----------------------------------------------

Comment (by Lasse Karstensen <lkarsten@…>):

In [6b2cc56eb26da79c08c16dea5e897be3dd34fa05]:
{{{
#!CommitTicketReference repository=""
revision="6b2cc56eb26da79c08c16dea5e897be3dd34fa05"
Log proxy related messages on the session, not on the request.

The proxy log records were attempted to be logged on the request' log
buffer, which at that point in time has not yet been set up and does
not posess a VXID. This caused VXID==0 log records to be inserted in
the beginning of the log for the first request on the session when
using proxy protocol, and subsequently the VSL to fail picking them
out as valid request log transactions.

Fix by logging the PROXY handling related messages on the session, in
which they belong.

Fixes: #1804
}}}

--
Ticket URL: <https://www.varnish-cache.org/trac/ticket/1804#comment:3>
Varnish <https://varnish-cache.org/>
The Varnish HTTP Accelerator

_______________________________________________
varnish-bugs mailing list
varnish-bugs@varnish-cache.org
https://www.varnish-cache.org/lists/mailman/listinfo/varnish-bugs