[Varnish] #1827: Fetch no body from backend when using HTTP/1.0 and Beresp is uncacheable
Varnish
varnish-bugs at varnish-cache.org
Thu Dec 3 14:16:07 CET 2015
#1827: Fetch no body from backend when using HTTP/1.0 and Beresp is uncacheable
----------------------+--------------------
Reporter: jparedes | Type: defect
Status: new | Priority: normal
Milestone: | Component: build
Version: 4.1.0 | Severity: normal
Keywords: |
----------------------+--------------------
I'm caching requests that originate from an nginx v1.8.0 balancer which
happen to default to HTTP/1.0 for proxying.
When Varnish hits an app node (nginx v1.8.0) and the response is
uncacheable (has a Set-Cookie header, or Expire: -1) Varnish returns no
body. This started to happen as soon as i upgraded from 4.0 to 4.1.
I'm using varnish-4.1.0(revision 3041728) adding just as little as the
backend definition to the default vcl.
From the app server perspective seems Varnish is dropping the connection
early.
Switching requests to HTTP/1.1 from balancer seems to fix this issue, but
i'm curious about what's happening behind the scenes.
'''VLC config'''
{{{
vcl 4.0;
backend default {
.host = "127.0.0.1";
.port = "80";
}
}}}
'''varnishlog'''
{{{
* << Session >> 32795
- Begin sess 0 HTTP/1
- SessOpen 127.0.0.1 32872 :81 127.0.0.1 81 1449147365.342063 15
- Link req 32796 rxreq
- SessClose RESP_CLOSE 0.294
- End
* << BeReq >> 32800
- Begin bereq 32799 fetch
- Timestamp Start: 1449147372.327667 0.000000 0.000000
- BereqMethod GET
- BereqURL /sitios/conectados/inicio/
- BereqProtocol HTTP/1.0
- BereqHeader Host: local.conectados.gob.ar
- BereqHeader X-Real-IP: 127.0.0.1
- BereqHeader User-Agent: curl/7.35.0
- BereqHeader Accept: */*
- BereqHeader X-Forwarded-For: 127.0.0.1
- BereqProtocol HTTP/1.1
- BereqHeader Accept-Encoding: gzip
- BereqHeader X-Varnish: 32800
- VCL_call BACKEND_FETCH
- VCL_return fetch
- BackendOpen 18 boot.default 127.0.0.1 80 127.0.0.1 38847
- Timestamp Bereq: 1449147372.327824 0.000156 0.000156
- Timestamp Beresp: 1449147372.597840 0.270173 0.270016
- BerespProtocol HTTP/1.1
- BerespStatus 200
- BerespReason OK
- BerespHeader Server: nginx/1.8.0
- BerespHeader Date: Thu, 03 Dec 2015 12:56:12 GMT
- BerespHeader Content-Type: text/html
- BerespHeader Transfer-Encoding: chunked
- BerespHeader Connection: keep-alive
- BerespHeader X-Powered-By: PHP/5.5.9-1ubuntu4.14
- TTL RFC 120 10 -1 1449147373 1449147373 1449147372 0 0
- VCL_call BACKEND_RESPONSE
- VCL_return deliver
- Storage malloc s0
- ObjProtocol HTTP/1.1
- ObjStatus 200
- ObjReason OK
- ObjHeader Server: nginx/1.8.0
- ObjHeader Date: Thu, 03 Dec 2015 12:56:12 GMT
- ObjHeader Content-Type: text/html
- ObjHeader X-Powered-By: PHP/5.5.9-1ubuntu4.14
- Fetch_Body 2 chunked stream
- BackendReuse 18 boot.default
- Timestamp BerespBody: 1449147372.599237 0.271570 0.001397
- Length 45187
- BereqAcct 203 0 203 191 45187 45378
- End
* << Request >> 32799
- Begin req 32798 rxreq
- Timestamp Start: 1449147372.327589 0.000000 0.000000
- Timestamp Req: 1449147372.327589 0.000000 0.000000
- ReqStart 127.0.0.1 32881
- ReqMethod GET
- ReqURL /sitios/conectados/inicio/
- ReqProtocol HTTP/1.0
- ReqHeader Host: local.conectados.gob.ar
- ReqHeader X-Real-IP: 127.0.0.1
- ReqHeader Connection: close
- ReqHeader User-Agent: curl/7.35.0
- ReqHeader Accept: */*
- ReqHeader X-Forwarded-For: 127.0.0.1
- VCL_call RECV
- VCL_return hash
- VCL_call HASH
- VCL_return lookup
- VCL_call MISS
- VCL_return fetch
- Link bereq 32800 fetch
- Timestamp Fetch: 1449147372.597970 0.270381 0.270381
- RespProtocol HTTP/1.1
- RespStatus 200
- RespReason OK
- RespHeader Server: nginx/1.8.0
- RespHeader Date: Thu, 03 Dec 2015 12:56:12 GMT
- RespHeader Content-Type: text/html
- RespHeader X-Powered-By: PHP/5.5.9-1ubuntu4.14
- RespHeader X-Varnish: 32799
- RespHeader Age: 0
- RespHeader Via: 1.1 varnish-v4
- VCL_call DELIVER
- VCL_return deliver
- Timestamp Process: 1449147372.597987 0.270398 0.000017
- RespHeader Accept-Ranges: bytes
- Debug "RES_MODE 4"
- RespHeader Connection: close
- Timestamp Resp: 1449147372.599283 0.271693 0.001295
- ReqAcct 153 0 153 227 45187 45414
- End
* << Session >> 32798
- Begin sess 0 HTTP/1
- SessOpen 127.0.0.1 32881 :81 127.0.0.1 81 1449147372.327418 16
- Link req 32799 rxreq
- SessClose RESP_CLOSE 0.272
- End
}}}
'''Nginx app server log''':
{{{
2015/12/03 10:10:09 [debug] 5807#0: *158 http header: "Host:
local.conectados.gob.ar"
2015/12/03 10:10:09 [debug] 5807#0: *158 http header: "X-Real-IP:
127.0.0.1"
2015/12/03 10:10:09 [debug] 5807#0: *158 http header: "User-Agent:
curl/7.35.0"
2015/12/03 10:10:09 [debug] 5807#0: *158 http header: "Accept: */*"
2015/12/03 10:10:09 [debug] 5807#0: *158 http header: "cookie:
login_token=1"
2015/12/03 10:10:09 [debug] 5807#0: *158 http header: "X-Forwarded-For:
127.0.0.1"
2015/12/03 10:10:09 [debug] 5807#0: *158 http header: "X-Varnish: 47"
2015/12/03 10:10:09 [debug] 5807#0: *158 http header done
2015/12/03 10:10:09 [debug] 5807#0: *158 event timer del: 19:
1449148269446
2015/12/03 10:10:09 [debug] 5807#0: *158 generic phase: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 rewrite phase: 1
2015/12/03 10:10:09 [debug] 5807#0: *158 test location: "/"
2015/12/03 10:10:09 [debug] 5807#0: *158 test location: "concursos"
2015/12/03 10:10:09 [debug] 5807#0: *158 test location: "vendor"
2015/12/03 10:10:09 [debug] 5807#0: *158 test location: "usuario"
2015/12/03 10:10:09 [debug] 5807#0: *158 test location: ~ "(.+\.txt)$"
2015/12/03 10:10:09 [debug] 5807#0: *158 test location: ~
"^/js/yui(/?)(.*)$"
2015/12/03 10:10:09 [debug] 5807#0: *158 test location: ~ "\.php$"
2015/12/03 10:10:09 [debug] 5807#0: *158 test location: ~
"^(/?|/404/?|/video(/?|/.*)|/audio(/?|/.*)|/enlace(/?|/.*)|/foto(/?|/.*)|/nota(/?|/.*)|/archivo(/?|/.*))$"
2015/12/03 10:10:09 [debug] 5807#0: *158 test location: ~ "^/rest/(.*)$"
2015/12/03 10:10:09 [debug] 5807#0: *158 using configuration "/"
2015/12/03 10:10:09 [debug] 5807#0: *158 http cl:-1 max:20971520
2015/12/03 10:10:09 [debug] 5807#0: *158 rewrite phase: 3
2015/12/03 10:10:09 [debug] 5807#0: *158 http script complex value
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var:
"/media/repository/work/webapps/conectados_git/webroot/sitios/conectados/inicio/"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: ""
2015/12/03 10:10:09 [debug] 5807#0: *158 http script file op
0000000000000003
"/media/repository/work/webapps/conectados_git/webroot/sitios/conectados/inicio/"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script if
2015/12/03 10:10:09 [debug] 5807#0: *158 http script complex value
2015/12/03 10:10:09 [warn] 5807#0: *158 using uninitialized "rule_0"
variable, client: 127.0.0.1, server: local.conectados.gob.ar, request:
"GET /sitios/conectados/inicio/ HTTP/1.0", host: "local.conectados.gob.ar"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "1"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var: ""
2015/12/03 10:10:09 [debug] 5807#0: *158 http script set $rule_0
2015/12/03 10:10:09 [debug] 5807#0: *158 http script complex value
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var:
"/media/repository/work/webapps/conectados_git/webroot/sitios/conectados/inicio/"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: ""
2015/12/03 10:10:09 [debug] 5807#0: *158 http script file op
0000000000000001
"/media/repository/work/webapps/conectados_git/webroot/sitios/conectados/inicio/"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script if
2015/12/03 10:10:09 [debug] 5807#0: *158 http script complex value
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "2"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var: "1"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script set $rule_0
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var: "21"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script value: "21"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script equal
2015/12/03 10:10:09 [debug] 5807#0: *158 http script if
2015/12/03 10:10:09 [debug] 5807#0: *158 http script regex: "^/(.*)$"
2015/12/03 10:10:09 [notice] 5807#0: *158 "^/(.*)$" matches
"/sitios/conectados/inicio/", client: 127.0.0.1, server:
local.conectados.gob.ar, request: "GET /sitios/conectados/inicio/
HTTP/1.0", host: "local.conectados.gob.ar"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "/index.php"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script args
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "r="
2015/12/03 10:10:09 [debug] 5807#0: *158 http script capture:
"sitios/conectados/inicio/"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "&"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script regex end
2015/12/03 10:10:09 [notice] 5807#0: *158 rewritten data: "/index.php",
args: "r=sitios/conectados/inicio/&", client: 127.0.0.1, server:
local.conectados.gob.ar, request: "GET /sitios/conectados/inicio/
HTTP/1.0", host: "local.conectados.gob.ar"
2015/12/03 10:10:09 [debug] 5807#0: *158 post rewrite phase: 4
2015/12/03 10:10:09 [debug] 5807#0: *158 uri changes: 11
2015/12/03 10:10:09 [debug] 5807#0: *158 test location: "/"
2015/12/03 10:10:09 [debug] 5807#0: *158 test location: "concursos"
2015/12/03 10:10:09 [debug] 5807#0: *158 test location: "vendor"
2015/12/03 10:10:09 [debug] 5807#0: *158 test location: "usuario"
2015/12/03 10:10:09 [debug] 5807#0: *158 test location: ~ "(.+\.txt)$"
2015/12/03 10:10:09 [debug] 5807#0: *158 test location: ~
"^/js/yui(/?)(.*)$"
2015/12/03 10:10:09 [debug] 5807#0: *158 test location: ~ "\.php$"
2015/12/03 10:10:09 [debug] 5807#0: *158 using configuration "\.php$"
2015/12/03 10:10:09 [debug] 5807#0: *158 http cl:-1 max:20971520
2015/12/03 10:10:09 [debug] 5807#0: *158 rewrite phase: 3
2015/12/03 10:10:09 [debug] 5807#0: *158 post rewrite phase: 4
2015/12/03 10:10:09 [debug] 5807#0: *158 generic phase: 5
2015/12/03 10:10:09 [debug] 5807#0: *158 generic phase: 6
2015/12/03 10:10:09 [debug] 5807#0: *158 generic phase: 7
2015/12/03 10:10:09 [debug] 5807#0: *158 access phase: 8
2015/12/03 10:10:09 [debug] 5807#0: *158 access phase: 9
2015/12/03 10:10:09 [debug] 5807#0: *158 access phase: 10
2015/12/03 10:10:09 [debug] 5807#0: *158 post access phase: 11
2015/12/03 10:10:09 [debug] 5807#0: *158 try files phase: 12
2015/12/03 10:10:09 [debug] 5807#0: *158 http init upstream, client timer:
0
2015/12/03 10:10:09 [debug] 5807#0: *158 epoll add event: fd:19 op:3
ev:80002005
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "QUERY_STRING"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var:
"r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "QUERY_STRING:
r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy:
"REQUEST_METHOD"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var: "GET"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "REQUEST_METHOD:
GET"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "CONTENT_TYPE"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "CONTENT_TYPE: "
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy:
"CONTENT_LENGTH"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "CONTENT_LENGTH: "
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "SCRIPT_NAME"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var: "/index.php"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "SCRIPT_NAME:
/index.php"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "REQUEST_URI"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var:
"/sitios/conectados/inicio/"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "REQUEST_URI:
/sitios/conectados/inicio/"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "DOCUMENT_URI"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var: "/index.php"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "DOCUMENT_URI:
/index.php"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "DOCUMENT_ROOT"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var:
"/media/repository/work/webapps/conectados_git/webroot"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "DOCUMENT_ROOT:
/media/repository/work/webapps/conectados_git/webroot"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy:
"SERVER_PROTOCOL"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var: "HTTP/1.0"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "SERVER_PROTOCOL:
HTTP/1.0"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: ""
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy:
"GATEWAY_INTERFACE"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "CGI/1.1"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param:
"GATEWAY_INTERFACE: CGI/1.1"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy:
"SERVER_SOFTWARE"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "nginx/"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var: "1.8.0"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "SERVER_SOFTWARE:
nginx/1.8.0"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "REMOTE_ADDR"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var: "127.0.0.1"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "REMOTE_ADDR:
127.0.0.1"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "REMOTE_PORT"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var: "38891"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "REMOTE_PORT:
38891"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "SERVER_ADDR"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var: "127.0.0.1"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "SERVER_ADDR:
127.0.0.1"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "SERVER_PORT"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var: "80"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "SERVER_PORT: 80"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "SERVER_NAME"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var:
"local.conectados.gob.ar"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "SERVER_NAME:
local.conectados.gob.ar"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy:
"REDIRECT_STATUS"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy: "200"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "REDIRECT_STATUS:
200"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script copy:
"SCRIPT_FILENAME"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var:
"/media/repository/work/webapps/conectados_git/webroot"
2015/12/03 10:10:09 [debug] 5807#0: *158 http script var: "/index.php"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "SCRIPT_FILENAME:
/media/repository/work/webapps/conectados_git/webroot/index.php"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "HTTP_HOST:
local.conectados.gob.ar"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "HTTP_X_REAL_IP:
127.0.0.1"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "HTTP_USER_AGENT:
curl/7.35.0"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "HTTP_ACCEPT: */*"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "HTTP_COOKIE:
login_token=1"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param:
"HTTP_X_FORWARDED_FOR: 127.0.0.1"
2015/12/03 10:10:09 [debug] 5807#0: *158 fastcgi param: "HTTP_X_VARNISH:
47"
2015/12/03 10:10:09 [debug] 5807#0: *158 http cleanup add:
0000000000BB3CC8
2015/12/03 10:10:09 [debug] 5807#0: *158 get rr peer, try: 1
2015/12/03 10:10:09 [debug] 5807#0: *158 socket 20
2015/12/03 10:10:09 [debug] 5807#0: *158 epoll add connection: fd:20
ev:80002005
2015/12/03 10:10:09 [debug] 5807#0: *158 connect to
unix:/var/run/php5-fpm.sock, fd:20 #159
2015/12/03 10:10:09 [debug] 5807#0: *158 connected
2015/12/03 10:10:09 [debug] 5807#0: *158 http upstream connect: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 posix_memalign:
0000000000C28130:128 @16
2015/12/03 10:10:09 [debug] 5807#0: *158 http upstream send request
2015/12/03 10:10:09 [debug] 5807#0: *158 http upstream send request body
2015/12/03 10:10:09 [debug] 5807#0: *158 chain writer buf fl:0 s:760
2015/12/03 10:10:09 [debug] 5807#0: *158 chain writer in: 0000000000BB3D00
2015/12/03 10:10:09 [debug] 5807#0: *158 writev: 760 of 760
2015/12/03 10:10:09 [debug] 5807#0: *158 chain writer out:
0000000000000000
2015/12/03 10:10:09 [debug] 5807#0: *158 event timer add: 20:
150000:1449148359446
2015/12/03 10:10:09 [debug] 5807#0: *158 http finalize request: -4,
"/index.php?r=sitios/conectados/inicio/&" a:1, c:2
2015/12/03 10:10:09 [debug] 5807#0: *158 http request count:2 blk:0
2015/12/03 10:10:09 [debug] 5807#0: *158 post event 0000000000C0F9E8
2015/12/03 10:10:09 [debug] 5807#0: *158 post event 0000000000C0FA50
2015/12/03 10:10:09 [debug] 5807#0: *158 delete posted event
0000000000C0F9E8
2015/12/03 10:10:09 [debug] 5807#0: *158 http run request:
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 http upstream check client, write
event:1, "/index.php"
2015/12/03 10:10:09 [debug] 5807#0: *158 http upstream recv(): -1 (11:
Resource temporarily unavailable)
2015/12/03 10:10:09 [debug] 5807#0: *158 delete posted event
0000000000C0FA50
2015/12/03 10:10:09 [debug] 5807#0: *158 http upstream request:
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 http upstream dummy handler
2015/12/03 10:10:09 [debug] 5807#0: *158 post event 0000000000BFC240
2015/12/03 10:10:09 [debug] 5807#0: *158 post event 0000000000C0FA50
2015/12/03 10:10:09 [debug] 5807#0: *158 delete posted event
0000000000BFC240
2015/12/03 10:10:09 [debug] 5807#0: *158 http upstream request:
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 http upstream process header
2015/12/03 10:10:09 [debug] 5807#0: *158 malloc: 0000000000BB3E90:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 posix_memalign:
0000000000CB4AC0:4096 @16
2015/12/03 10:10:09 [debug] 5807#0: *158 recv: fd:20 4096 of 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 01
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 06
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 00
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 01
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 00
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: BC
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 04
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 00
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record length: 188
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi parser: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi header: "X-Powered-
By: PHP/5.5.9-1ubuntu4.14"
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi parser: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi header: "Set-Cookie:
login_token=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0;
path=/; domain=.local.conectados.gob.ar"
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi parser: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi header: "Content-
type: text/html"
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi parser: 1
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi header done
2015/12/03 10:10:09 [debug] 5807#0: *158 HTTP/1.1 200 OK
Server: nginx/1.8.0
Date: Thu, 03 Dec 2015 13:10:09 GMT
Content-Type: text/html
Connection: close
X-Powered-By: PHP/5.5.9-1ubuntu4.14
Set-Cookie: login_token=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT;
Max-Age=0; path=/; domain=.local.conectados.gob.ar
2015/12/03 10:10:09 [debug] 5807#0: *158 write new buf t:1 f:0
0000000000CB4C60, pos 0000000000CB4C60, size: 282 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 http write filter: l:0 f:0 s:282
2015/12/03 10:10:09 [debug] 5807#0: *158 http cacheable: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 http upstream process upstream
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe read upstream: 1
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe preread: 3900
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 01
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 06
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 00
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 01
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: B0
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 80
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 00
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 00
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record length: 45184
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf #0 0000000000BB3F60
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf 0000000000BB3F60 3888
2015/12/03 10:10:09 [debug] 5807#0: *158 malloc: 0000000000CB5AD0:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 readv: 1, last:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe recv chain: 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf #1 0000000000CB5AD0
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf 0000000000CB5AD0 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 malloc: 0000000000CB6AE0:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 readv: 1, last:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe recv chain: 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf #2 0000000000CB6AE0
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf 0000000000CB6AE0 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 malloc: 0000000000BCA2E0:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 readv: 1, last:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe recv chain: 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf #3 0000000000BCA2E0
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf 0000000000BCA2E0 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 malloc: 0000000000BCB2F0:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 readv: 1, last:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe recv chain: 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf #4 0000000000BCB2F0
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf 0000000000BCB2F0 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 malloc: 0000000000BCC300:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 readv: 1, last:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe recv chain: 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf #5 0000000000BCC300
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf 0000000000BCC300 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 malloc: 0000000000C80990:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 readv: 1, last:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe recv chain: 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf #6 0000000000C80990
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf 0000000000C80990 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 malloc: 0000000000C819A0:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 readv: 1, last:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe recv chain: 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf #7 0000000000C819A0
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf 0000000000C819A0 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 malloc: 0000000000C829B0:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 readv: 1, last:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe recv chain: 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf #8 0000000000C829B0
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf 0000000000C829B0 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe downstream ready
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe buf in s:1 t:1 f:0
0000000000BB3E90, pos 0000000000BB3F60, size: 3888 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe buf in s:1 t:1 f:0
0000000000CB5AD0, pos 0000000000CB5AD0, size: 4096 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe buf in s:1 t:1 f:0
0000000000CB6AE0, pos 0000000000CB6AE0, size: 4096 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe buf in s:1 t:1 f:0
0000000000BCA2E0, pos 0000000000BCA2E0, size: 4096 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe buf in s:1 t:1 f:0
0000000000BCB2F0, pos 0000000000BCB2F0, size: 4096 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe buf in s:1 t:1 f:0
0000000000BCC300, pos 0000000000BCC300, size: 4096 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe buf in s:1 t:1 f:0
0000000000C80990, pos 0000000000C80990, size: 4096 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe buf in s:1 t:1 f:0
0000000000C819A0, pos 0000000000C819A0, size: 4096 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe buf in s:1 t:1 f:0
0000000000C829B0, pos 0000000000C829B0, size: 4096 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe length: -1
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write downstream: 1
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write busy: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write buf ls:1
0000000000BB3F60 3888
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write buf ls:1
0000000000CB5AD0 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write buf ls:1
0000000000CB6AE0 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write: out:0000000000BB3E68,
f:1
2015/12/03 10:10:09 [debug] 5807#0: *158 http output filter
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 http copy filter:
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 http postpone filter
"/index.php?r=sitios/conectados/inicio/&" 0000000000BB3E58
2015/12/03 10:10:09 [debug] 5807#0: *158 write old buf t:1 f:0
0000000000CB4C60, pos 0000000000CB4C60, size: 282 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 write new buf t:1 f:0
0000000000BB3E90, pos 0000000000BB3F60, size: 3888 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 write new buf t:1 f:0
0000000000CB5AD0, pos 0000000000CB5AD0, size: 4096 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 http write filter: l:0 f:1 s:8266
2015/12/03 10:10:09 [debug] 5807#0: *158 http write filter limit 0
2015/12/03 10:10:09 [debug] 5807#0: *158 writev: 8266 of 8266
2015/12/03 10:10:09 [debug] 5807#0: *158 http write filter
0000000000000000
2015/12/03 10:10:09 [debug] 5807#0: *158 http copy filter: 0
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write busy: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write buf ls:1
0000000000CB6AE0 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write buf ls:1
0000000000BCA2E0 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write buf ls:1
0000000000BCB2F0 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write: out:0000000000CB4FF0,
f:1
2015/12/03 10:10:09 [debug] 5807#0: *158 http output filter
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 http copy filter:
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 http postpone filter
"/index.php?r=sitios/conectados/inicio/&" 0000000000CB5560
2015/12/03 10:10:09 [debug] 5807#0: *158 write new buf t:1 f:0
0000000000CB6AE0, pos 0000000000CB6AE0, size: 4096 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 write new buf t:1 f:0
0000000000BCA2E0, pos 0000000000BCA2E0, size: 4096 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 http write filter: l:0 f:1 s:8192
2015/12/03 10:10:09 [debug] 5807#0: *158 http write filter limit 0
2015/12/03 10:10:09 [debug] 5807#0: *158 writev: 8192 of 8192
2015/12/03 10:10:09 [debug] 5807#0: *158 http write filter
0000000000000000
2015/12/03 10:10:09 [debug] 5807#0: *158 http copy filter: 0
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write busy: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write buf ls:1
0000000000BCB2F0 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write buf ls:1
0000000000BCC300 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write buf ls:1
0000000000C80990 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write: out:0000000000CB5170,
f:1
2015/12/03 10:10:09 [debug] 5807#0: *158 http output filter
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 http copy filter:
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 http postpone filter
"/index.php?r=sitios/conectados/inicio/&" 0000000000CB5570
2015/12/03 10:10:09 [debug] 5807#0: *158 write new buf t:1 f:0
0000000000BCB2F0, pos 0000000000BCB2F0, size: 4096 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 write new buf t:1 f:0
0000000000BCC300, pos 0000000000BCC300, size: 4096 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 http write filter: l:0 f:1 s:8192
2015/12/03 10:10:09 [debug] 5807#0: *158 http write filter limit 0
2015/12/03 10:10:09 [debug] 5807#0: *158 writev: 8192 of 8192
2015/12/03 10:10:09 [debug] 5807#0: *158 http write filter
0000000000000000
2015/12/03 10:10:09 [debug] 5807#0: *158 http copy filter: 0
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write busy: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write buf ls:1
0000000000C80990 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write buf ls:1
0000000000C819A0 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write buf ls:1
0000000000C829B0 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write: out:0000000000CB52F0,
f:1
2015/12/03 10:10:09 [debug] 5807#0: *158 http output filter
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 http copy filter:
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 http postpone filter
"/index.php?r=sitios/conectados/inicio/&" 0000000000CB5590
2015/12/03 10:10:09 [debug] 5807#0: *158 write new buf t:1 f:0
0000000000C80990, pos 0000000000C80990, size: 4096 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 write new buf t:1 f:0
0000000000C819A0, pos 0000000000C819A0, size: 4096 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 http write filter: l:0 f:1 s:8192
2015/12/03 10:10:09 [debug] 5807#0: *158 http write filter limit 0
2015/12/03 10:10:09 [debug] 5807#0: *158 writev: -1 of 8192
2015/12/03 10:10:09 [info] 5807#0: *158 writev() failed (32: Broken pipe)
while sending to client, client: 127.0.0.1, server:
local.conectados.gob.ar, request: "GET /sitios/conectados/inicio/
HTTP/1.0", upstream: "fastcgi://unix:/var/run/php5-fpm.sock:", host:
"local.conectados.gob.ar"
2015/12/03 10:10:09 [debug] 5807#0: *158 http write filter
FFFFFFFFFFFFFFFF
2015/12/03 10:10:09 [debug] 5807#0: *158 http copy filter: -1
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe read upstream: 1
2015/12/03 10:10:09 [debug] 5807#0: *158 readv: 9, last:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe recv chain: 8560
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf #9 0000000000C829B0
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf 0000000000C829B0 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf #10 0000000000C819A0
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf 0000000000C819A0 4096
2015/12/03 10:10:09 [debug] 5807#0: *158 readv: 7, last:4096
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe recv chain: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe buf in s:1 t:1 f:0
0000000000C829B0, pos 0000000000C829B0, size: 4096 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe buf in s:1 t:1 f:0
0000000000C819A0, pos 0000000000C819A0, size: 4096 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe buf free s:0 t:1 f:0
0000000000C80990, pos 0000000000C80990, size: 368 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe buf free s:0 t:1 f:0
0000000000BCB2F0, pos 0000000000BCB2F0, size: 0 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe buf free s:0 t:1 f:0
0000000000BCC300, pos 0000000000BCC300, size: 0 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe buf free s:0 t:1 f:0
0000000000CB6AE0, pos 0000000000CB6AE0, size: 0 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe buf free s:0 t:1 f:0
0000000000BCA2E0, pos 0000000000BCA2E0, size: 0 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe buf free s:0 t:1 f:0
0000000000BB3E90, pos 0000000000BB3E90, size: 0 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe buf free s:0 t:1 f:0
0000000000CB5AD0, pos 0000000000CB5AD0, size: 0 file: 0, size: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe length: -1
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf #11 0000000000C80990
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 01
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 06
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 00
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 01
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 00
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 03
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 05
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 00
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record length: 3
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf #11 0000000000C80AE8
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 01
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 03
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 00
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 01
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 00
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 08
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 00
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record byte: 00
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi record length: 8
2015/12/03 10:10:09 [debug] 5807#0: *158 http fastcgi sent end request
2015/12/03 10:10:09 [debug] 5807#0: *158 input buf 0000000000C80AE8 3
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000BCB2F0
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000BCC300
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000CB6AE0
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000BCA2E0
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000BB3E90
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000CB5AD0
2015/12/03 10:10:09 [debug] 5807#0: *158 pipe write downstream: 1
2015/12/03 10:10:09 [debug] 5807#0: *158 event timer del: 20:
1449148359446
2015/12/03 10:10:09 [debug] 5807#0: *158 http upstream exit:
0000000000000000
2015/12/03 10:10:09 [debug] 5807#0: *158 finalize http upstream request: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 finalize http fastcgi request
2015/12/03 10:10:09 [debug] 5807#0: *158 free rr peer 1 0
2015/12/03 10:10:09 [debug] 5807#0: *158 close http upstream connection:
20
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000C28130, unused:
48
2015/12/03 10:10:09 [debug] 5807#0: *158 delete posted event
0000000000C0FA50
2015/12/03 10:10:09 [debug] 5807#0: *158 reusable connection: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 http upstream temp fd: -1
2015/12/03 10:10:09 [debug] 5807#0: *158 http output filter
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 http copy filter:
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 http postpone filter
"/index.php?r=sitios/conectados/inicio/&" 00007FFFF1C4E640
2015/12/03 10:10:09 [debug] 5807#0: *158 http copy filter: -1
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 http finalize request: -1,
"/index.php?r=sitios/conectados/inicio/&" a:1, c:1
2015/12/03 10:10:09 [debug] 5807#0: *158 http terminate request count:1
2015/12/03 10:10:09 [debug] 5807#0: *158 http terminate cleanup count:1
blk:0
2015/12/03 10:10:09 [debug] 5807#0: *158 http posted request:
"/index.php?r=sitios/conectados/inicio/&"
2015/12/03 10:10:09 [debug] 5807#0: *158 http terminate handler count:1
2015/12/03 10:10:09 [debug] 5807#0: *158 http request count:1 blk:0
2015/12/03 10:10:09 [debug] 5807#0: *158 http close request
2015/12/03 10:10:09 [debug] 5807#0: *158 http log handler
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000C829B0
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000C819A0
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000C80990
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000000000
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000000000
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000000000
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000000000
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000000000
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000000000
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000BB1E70, unused: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000BB2E80, unused: 8
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000CB4AC0, unused:
991
2015/12/03 10:10:09 [debug] 5807#0: *158 close http connection: 19
2015/12/03 10:10:09 [debug] 5807#0: *158 reusable connection: 0
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000B4D2A0
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000BB13E0, unused: 8
2015/12/03 10:10:09 [debug] 5807#0: *158 free: 0000000000B4C990, unused:
72
}}}
This request work:
{{{
$ curl -ki 'https://local1.conectados.gob.ar/sitios/conectados/inicio/'
HTTP/1.1 200 OK
Server: nginx/1.8.0
Date: Thu, 03 Dec 2015 13:14:32 GMT
Content-Type: text/html
Transfer-Encoding: chunked
Connection: keep-alive
X-Powered-By: PHP/5.5.9-1ubuntu4.14
X-Varnish: 32802 53
Age: 19
Via: 1.1 varnish-v4
Accept-Ranges: bytes
X-Frame-Options: DENY
<!DOCTYPE html>
<html lang="es">
<head>
<title>CONECTADOS</title>
<meta charset="utf-8">
<meta name="viewport" content="width=device-width, initial-
scale=1.0">
<meta name=
[....] cropped html
}}}
This request doesn't work:
{{{
$curl -ki 'https://local1.conectados.gob.ar/sitios/conectados/inicio/' -H
'cookie: login_token=1'
HTTP/1.1 200 OK
Server: nginx/1.8.0
Date: Thu, 03 Dec 2015 13:13:33 GMT
Content-Type: text/html
Content-Length: 0
Connection: keep-alive
X-Powered-By: PHP/5.5.9-1ubuntu4.14
Set-Cookie: login_token=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT;
Max-Age=0; path=/; domain=.local.conectados.gob.ar
X-Varnish: 49
Age: 0
Via: 1.1 varnish-v4
Accept-Ranges: bytes
X-Frame-Options: DENY}}}
--
Ticket URL: <https://www.varnish-cache.org/trac/ticket/1827>
Varnish <https://varnish-cache.org/>
The Varnish HTTP Accelerator
More information about the varnish-bugs
mailing list