[Varnish] #1804: PROXYed client requests does not appear in varnishlog

[Varnish]

#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