Re: Некорректный ответ при использовании fastcgi cache background update on
| April 09, 2018 04:28PM |
Registered: 6 years ago Posts: 32 |
> А как используются переменные $handler и $querystring?
Устанавливаются переменные окружения для FCGI-процесса:
fastcgi_param QUERY_STRING $querystring;
fastcgi_param PATH_TRANSLATED $document_root/$handler;
Картина из лога:
Кэш протух, обращение к бэкенду, сохранение в кэш. Ожидаемый ответ, 404.
2018/04/09 21:23:59 [debug] 16867#16867: *1290 using location: @backend "/misssing/?"
…
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http cache key: "http|GET||||/misssing/"
…
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http file cache exists: 0 e:1
2018/04/09 21:23:59 [debug] 16867#16867: *1290 cache file: "/var/www/site/cache/pages/49/1d/2756424aaf76d1f24c982e863d611d49"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http file cache lock u:1 wt:0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream cache: -5
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "SCRIPT_NAME"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "/misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "SCRIPT_NAME: /misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "SCRIPT_FILENAME"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "/var/www/site/cgi-bin/parser.cgi"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "SCRIPT_FILENAME: /var/www/site/cgi-bin/parser.cgi"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "PATH_INFO"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "/misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "PATH_INFO: /misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "QUERY_STRING"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "_action=/misssing/&"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "QUERY_STRING: _action=/misssing/&"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "PATH_TRANSLATED"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "/var/www/site/www"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "_ind.html"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "PATH_TRANSLATED: /var/www/site/www/_ind.html"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "QUERY_STRING"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "QUERY_STRING: "
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "REQUEST_METHOD"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "GET"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "REQUEST_METHOD: GET"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "CONTENT_TYPE"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "CONTENT_TYPE: "
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "CONTENT_LENGTH"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "CONTENT_LENGTH: "
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "SCRIPT_NAME"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "/misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "SCRIPT_NAME: /misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "REQUEST_URI"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "/misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "REQUEST_URI: /misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "DOCUMENT_URI"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "/misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "DOCUMENT_URI: /misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "DOCUMENT_ROOT"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "/var/www/site/www"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "DOCUMENT_ROOT: /var/www/site/www"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "SERVER_PROTOCOL"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "HTTP/1.1"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "SERVER_PROTOCOL: HTTP/1.1"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "REQUEST_SCHEME"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "http"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "REQUEST_SCHEME: http"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: ""
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "GATEWAY_INTERFACE"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "CGI/1.1"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "GATEWAY_INTERFACE: CGI/1.1"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "SERVER_SOFTWARE"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "nginx/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "1.13.9"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "SERVER_SOFTWARE: nginx/1.13.9"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "REMOTE_ADDR"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "2.2.2.2"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "REMOTE_ADDR: 2.2.2.2"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "REMOTE_PORT"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "57628"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "REMOTE_PORT: 57628"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "SERVER_ADDR"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "0.0.0.0"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "SERVER_ADDR: 0.0.0.0"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "SERVER_PORT"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "80"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "SERVER_PORT: 80"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "SERVER_NAME"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "example.com"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "SERVER_NAME: example.com"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "REDIRECT_STATUS"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "200"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "REDIRECT_STATUS: 200"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: ""
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: ""
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "HTTP_HOST: example.com"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "HTTP_USER_AGENT: curl/7.56.1"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "HTTP_ACCEPT: */*"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 posix_memalign: 00005592481987C0:4096 @16
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http cleanup add: 0000559248198790
2018/04/09 21:23:59 [debug] 16867#16867: *1290 init keepalive peer
2018/04/09 21:23:59 [debug] 16867#16867: *1290 get keepalive peer
2018/04/09 21:23:59 [debug] 16867#16867: *1290 get rr peer, try: 1
2018/04/09 21:23:59 [debug] 16867#16867: *1290 get keepalive peer: using connection 00005592492CF030
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream connect: -4
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream send request
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream send request body
2018/04/09 21:23:59 [debug] 16867#16867: *1290 chain writer buf fl:0 s:720
2018/04/09 21:23:59 [debug] 16867#16867: *1290 chain writer in: 00005592481988A0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 writev: 720 of 720
2018/04/09 21:23:59 [debug] 16867#16867: *1290 chain writer out: 0000000000000000
2018/04/09 21:23:59 [debug] 16867#16867: *1290 event timer add: 10: 120000:1523298359132
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http finalize request: -4, "/misssing/?" a:1, c:3
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http request count:3 blk:0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http finalize request: -4, "/misssing/?" a:1, c:2
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http request count:2 blk:0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http run request: "/misssing/?"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream check client, write event:1, "/misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream request: "/misssing/?"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream dummy handler
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream request: "/misssing/?"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream process header
2018/04/09 21:23:59 [debug] 16867#16867: *1290 malloc: 0000559248F36E20:4096
2018/04/09 21:23:59 [debug] 16867#16867: *1290 recv: eof:0, avail:1
2018/04/09 21:23:59 [debug] 16867#16867: *1290 recv: fd:10 3703 of 3703
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi record byte: 01
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi record byte: 06
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi record byte: 00
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi record byte: 01
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi record byte: 1F
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi record byte: F8
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi record byte: 00
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi record byte: 00
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi record length: 8184
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi parser: 0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi header: "Content-Type: text/html; charset=UTF-8"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi parser: 0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi header: "X-Backend: 1"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi parser: 0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi header: "Status: 404"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi parser: 0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi header: "Content-Length: 8242"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi parser: 1
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi header done
2018/04/09 21:23:59 [debug] 16867#16867: *1290 HTTP/1.1 404
Server: nginx
Date: Mon, 09 Apr 2018 18:23:59 GMT
Content-Type: text/html; charset=UTF-8
Transfer-Encoding: chunked
Connection: keep-alive
Vary: Accept-Encoding
X-Backend: 1
Strict-Transport-Security: max-age=31536000
…
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http copy filter: 0 "/misssing/?"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 pipe write downstream done
2018/04/09 21:23:59 [debug] 16867#16867: *1290 event timer: 10, old: 1523298359132, new: 1523298359226
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http file cache update
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http file cache rename: "/var/lib/nginx/fastcgi/1/68/0000000681" to "/var/www/site/cache/pages/49/1d/2756424aaf76d1f24c982e863d611d49"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream exit: 0000000000000000
2018/04/09 21:23:59 [debug] 16867#16867: *1290 finalize http upstream request: 0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 finalize http fastcgi request
2018/04/09 21:23:59 [debug] 16867#16867: *1290 free keepalive peer
2018/04/09 21:23:59 [debug] 16867#16867: *1290 free keepalive peer: saving connection 00005592492CF030
2018/04/09 21:23:59 [debug] 16867#16867: *1290 event timer del: 10: 1523298359132
2018/04/09 21:23:59 [debug] 16867#16867: *1290 free rr peer 1 0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream temp fd: 33
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http output filter "/misssing/?"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http copy filter: "/misssing/?"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http ssi filter "/misssing/?"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 ssi out: 0000559248FF0868 0000000000000000
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http postpone filter "/misssing/?" 0000559248FF08B8
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http copy filter: 0 "/misssing/?"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http finalize request: 0, "/misssing/?" a:0, c:4
2. Корректные ответы из кэша.
2018/04/09 21:25:33 [debug] 16866#16866: *1441 using configuration "/"
…
2018/04/09 21:25:33 [debug] 16866#16866: *1441 http cache key: "http|GET||||/misssing/"
…
2018/04/09 21:25:33 [debug] 16866#16866: *1441 http file cache exists: 0 e:1
2018/04/09 21:25:33 [debug] 16866#16866: *1441 cache file: "/var/www/site/cache/pages/49/1d/2756424aaf76d1f24c982e863d611d49"
2018/04/09 21:25:33 [debug] 16866#16866: *1441 add cleanup: 00005592493D1698
2018/04/09 21:25:33 [debug] 16866#16866: *1441 http file cache fd: 45
2018/04/09 21:25:33 [debug] 16866#16866: *1441 read: 45, 00005592493D1718, 492, 0
2018/04/09 21:25:33 [debug] 16866#16866: *1441 http upstream cache: 0
…
2018/04/09 21:25:33 [debug] 16866#16866: *1441 http file cache send: /var/www/site/cache/pages/49/1d/2756424aaf76d1f24c982e863d611d49
2018/04/09 21:25:33 [debug] 16866#16866: *1441 HTTP/1.1 404
Server: nginx
Date: Mon, 09 Apr 2018 18:25:33 GMT
Content-Type: text/html; charset=UTF-8
Transfer-Encoding: chunked
Connection: keep-alive
Vary: Accept-Encoding
X-Backend: 1
Strict-Transport-Security: max-age=31536000
Content-Encoding: gzip
3. Перезапись кэша.
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http posted request: "/misssing/?"
…
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http cache key: "http|GET||||/misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: ""
2018/04/09 21:29:34 [debug] 16867#16867: *1901 add cleanup: 00005592481A8650
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache exists: 0 e:1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 cache file: "/var/www/site/cache/pages/49/1d/2756424aaf76d1f24c982e863d611d49"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 add cleanup: 00005592481A86A8
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache fd: 70
2018/04/09 21:29:34 [debug] 16867#16867: *1901 read: 70, 00005592490A6A88, 492, 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache expired: 5 1523298539 1523298574
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream cache: 5
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "SCRIPT_NAME"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "/misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "SCRIPT_NAME: /misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "SCRIPT_FILENAME"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "/var/www/site/cgi-bin/parser.cgi"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "SCRIPT_FILENAME: /var/www/site/cgi-bin/parser.cgi"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "PATH_INFO"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "/misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "PATH_INFO: /misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "QUERY_STRING"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: ""
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "QUERY_STRING: "
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "PATH_TRANSLATED"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "/var/www/site/www"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "banner.html"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "PATH_TRANSLATED: /var/www/site/www/banner.html"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "QUERY_STRING"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "QUERY_STRING: "
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "REQUEST_METHOD"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "GET"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "REQUEST_METHOD: GET"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "CONTENT_TYPE"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "CONTENT_TYPE: "
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "CONTENT_LENGTH"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "CONTENT_LENGTH: "
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "SCRIPT_NAME"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "/misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "SCRIPT_NAME: /misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "REQUEST_URI"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "/misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "REQUEST_URI: /misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "DOCUMENT_URI"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "/misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "DOCUMENT_URI: /misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "DOCUMENT_ROOT"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "/var/www/site/www"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "DOCUMENT_ROOT: /var/www/site/www"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "SERVER_PROTOCOL"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "HTTP/1.1"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "SERVER_PROTOCOL: HTTP/1.1"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "REQUEST_SCHEME"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "http"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "REQUEST_SCHEME: http"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: ""
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "GATEWAY_INTERFACE"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "CGI/1.1"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "GATEWAY_INTERFACE: CGI/1.1"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "SERVER_SOFTWARE"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "nginx/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "1.13.9"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "SERVER_SOFTWARE: nginx/1.13.9"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "REMOTE_ADDR"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "1.1.1.1"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "REMOTE_ADDR: 1.1.1.1"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "REMOTE_PORT"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "41336"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "REMOTE_PORT: 41336"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "SERVER_ADDR"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "0.0.0.0"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "SERVER_ADDR: 0.0.0.0"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "SERVER_PORT"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "80"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "SERVER_PORT: 80"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "SERVER_NAME"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "example.com"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "SERVER_NAME: example.com"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "REDIRECT_STATUS"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "200"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "REDIRECT_STATUS: 200"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: ""
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: ""
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "HTTP_HOST: example.com"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "HTTP_CONNECTION: Keep-Alive"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "HTTP_USER_AGENT: Mozilla/5.0"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "HTTP_ACCEPT_ENCODING: gzip,deflate"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "HTTP_ACCEPT_LANGUAGE: ru, uk;q=0.8, be;q=0.8, en;q=0.7, *;q=0.01"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "HTTP_ACCEPT: */*"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http cleanup add: 00005592490A7098
2018/04/09 21:29:34 [debug] 16867#16867: *1901 init keepalive peer
2018/04/09 21:29:34 [debug] 16867#16867: *1901 get keepalive peer
2018/04/09 21:29:34 [debug] 16867#16867: *1901 get rr peer, try: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 get keepalive peer: using connection 00005592492D16A0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream connect: -4
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream send request
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream send request body
2018/04/09 21:29:34 [debug] 16867#16867: *1901 chain writer buf fl:0 s:912
2018/04/09 21:29:34 [debug] 16867#16867: *1901 chain writer in: 00005592490A7110
2018/04/09 21:29:34 [debug] 16867#16867: *1901 writev: 912 of 912
2018/04/09 21:29:34 [debug] 16867#16867: *1901 chain writer out: 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 event timer add: 54: 120000:1523298694012
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http finalize request: -4, "/misssing/?" a:0, c:6
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http request count:6 blk:0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http posted request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer handler: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http output filter "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http ssi filter "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter "/misssing/?" 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter output "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http gzip filter
…
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter wake "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: 0 "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer output filter: 0, "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 event timer: 31, old: 1523298634012, new: 1523298634012
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http posted request: "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer handler: "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http output filter "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http ssi filter "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter "/banner/inside-top/?" 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter output "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http gzip filter
…
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: 0 "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer output filter: 0, "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer done: "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http finalize request: 0, "/banner/inside-top/?" a:1, c:5
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http wake parent request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http posted request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer handler: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http output filter "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http ssi filter "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter "/misssing/?" 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter output "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http gzip filter
…
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter wake "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: 0 "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer output filter: 0, "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 event timer: 31, old: 1523298634012, new: 1523298634012
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http posted request: "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer handler: "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http output filter "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http ssi filter "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter "/banner/inside-right/?" 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter output "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http gzip filter
…
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: 0 "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer output filter: 0, "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer done: "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http finalize request: 0, "/banner/inside-right/?" a:1, c:4
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http wake parent request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http posted request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer handler: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http output filter "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http ssi filter "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter "/misssing/?" 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter output "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http gzip filter
…
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter wake "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: 0 "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer output filter: 0, "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 event timer: 31, old: 1523298634012, new: 1523298634012
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http posted request: "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer handler: "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http output filter "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http ssi filter "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter "/banner/all-bottom/?" 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter output "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http gzip filter
…
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: 0 "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer output filter: 0, "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer done: "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http finalize request: 0, "/banner/all-bottom/?" a:1, c:3
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http wake parent request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http posted request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer handler: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http output filter "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http ssi filter "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter "/misssing/?" 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter output "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http gzip filter
…
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http chunk: 10
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http chunk: 3497
2018/04/09 21:29:34 [debug] 16867#16867: *1901 write old buf t:1 f:0 0000559248D69C40, pos 0000559248D69C40, size: 266 file: 0, size: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 write new buf t:1 f:0 00005592490A72E0, pos 00005592490A72E0, size: 5 file: 0, size: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 write new buf t:0 f:0 0000000000000000, pos 0000559247145818, size: 10 file: 0, size: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 write new buf t:1 f:0 0000559248D6B700, pos 0000559248D6B700, size: 3497 file: 0, size: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 write new buf t:0 f:0 0000000000000000, pos 0000559246F1D4E5, size: 7 file: 0, size: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http write filter: l:1 f:1 s:3785
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http write filter limit 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 writev: 3785 of 3785
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http write filter 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: 0 "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer output filter: 0, "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer done: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http finalize request: 0, "/misssing/?" a:1, c:2
2018/04/09 21:29:34 [debug] 16867#16867: *1901 event timer del: 31: 1523298634012
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http request count:2 blk:0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http run request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http request empty handler
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream dummy handler
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream process header
2018/04/09 21:29:34 [debug] 16867#16867: *1901 malloc: 00005592490A73E0:4096
2018/04/09 21:29:34 [debug] 16867#16867: *1901 posix_memalign: 00005592490A83F0:4096 @16
2018/04/09 21:29:34 [debug] 16867#16867: *1901 recv: eof:0, avail:1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 recv: fd:54 112 of 3703
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 01
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 06
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 01
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 4A
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 06
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record length: 74
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi parser: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi header: "Content-Type: text/html; charset=UTF-8"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi parser: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi header: "Status: 200"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi parser: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi header: "Content-Length: 0"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi parser: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi header done
2018/04/09 21:29:34 [debug] 16867#16867: *1901 charset: "UTF-8" > "UTF-8"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 file cleanup: fd:70
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: ""
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache set header
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http cacheable: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream process upstream
2018/04/09 21:29:34 [debug] 16867#16867: *1901 pipe read upstream: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 pipe preread: 30
2018/04/09 21:29:34 [debug] 16867#16867: *1901 pipe buf free s:0 t:1 f:0 00005592490A73E0, pos 00005592490A75BB, size: 30 file: 0, size: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 pipe length: 8
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 01
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 06
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 01
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record length: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi closed stdout
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 01
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 03
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 01
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 08
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record length: 8
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi sent end request
2018/04/09 21:29:34 [debug] 16867#16867: *1901 pipe write chain
2018/04/09 21:29:34 [debug] 16867#16867: *1901 add cleanup: 00005592490A73B0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 hashed path: /var/lib/nginx/fastcgi/6/89/0000000896
2018/04/09 21:29:34 [debug] 16867#16867: *1901 temp fd:70
2018/04/09 21:29:34 [debug] 16867#16867: *1901 write: 70, 00005592490A73E0, 475, 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 pipe write downstream: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 event timer: 54, old: 1523298694012, new: 1523298694065
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache update
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache rename: "/var/lib/nginx/fastcgi/6/89/0000000896" to "/var/www/site/cache/pages/49/1d/2756424aaf76d1f24c982e863d611d49"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream exit: 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 finalize http upstream request: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 finalize http fastcgi request
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free keepalive peer
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free keepalive peer: saving connection 00005592492D16A0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 event timer del: 54: 1523298694012
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free rr peer 1 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream temp fd: 70
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http finalize request: 0, "/misssing/?" a:0, c:1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 set http keepalive handler
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http close request
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http log handler
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http map started
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "1.1.1.1"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http map: "1.1.1.1" "1"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "1"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 00005592490A73B0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 file cleanup: fd:70
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 00005592481A8650
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 00005592481A75B0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 file cleanup: fd:69
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 00005592481A7558
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache cleanup
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache free, fd: 69
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 00005592481DBCC8
2018/04/09 21:29:34 [debug] 16867#16867: *1901 file cleanup: fd:63
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 00005592481DB888
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache cleanup
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache free, fd: 63
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 0000559248A43488
2018/04/09 21:29:34 [debug] 16867#16867: *1901 file cleanup: fd:56
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 0000559248A43430
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache cleanup
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache free, fd: 56
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 0000559248A41A70
2018/04/09 21:29:34 [debug] 16867#16867: *1901 file cleanup: fd:53
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 00005592489D6C60
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache cleanup
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache free, fd: 53
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 0000559248F26A08
2018/04/09 21:29:34 [debug] 16867#16867: *1901 file cleanup: fd:52
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 0000559248F269B0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache cleanup
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache free, fd: 52
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559248D6B700
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 00005592490A73E0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 00005592493668A0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559248197EE0, unused: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559247D5D570, unused: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559248F25DD0, unused: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559248D696E0, unused: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 000055924897B2D0, unused: 4
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559249368EF0, unused: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559248175140, unused: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559248F35E10, unused: 5
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 00005592489D5CC0, unused: 2
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559248A41A50, unused: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559248A42A60, unused: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 00005592481DA8B0, unused: 2
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 00005592481DB8C0, unused: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 00005592481A67E0, unused: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 00005592481A77F0, unused: 8
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 00005592490A63D0, unused: 8
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 00005592490A83F0, unused: 3051
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 000055924A1A2FA0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 hc free: 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 hc busy: 0000000000000000 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 tcp_nodelay
2018/04/09 21:29:34 [debug] 16867#16867: *1901 reusable connection: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 event timer add: 31: 65000:1523298639065
2018/04/09 21:29:34 [debug] 16867#16867: *1775 event timer del: 34: 1523298574093
2018/04/09 21:29:34 [debug] 16867#16867: *1775 http keepalive handler
2018/04/09 21:29:34 [debug] 16867#16867: *1775 close http connection: 34
2018/04/09 21:29:34 [debug] 16867#16867: *1775 SSL_shutdown: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1775 reusable connection: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1775 free: 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1775 free: 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1775 free: 0000559248A447E0, unused: 16
2018/04/09 21:29:34 [debug] 16867#16867: *1775 free: 00005592491FB120, unused: 400
2018/04/09 21:29:34 [debug] 16866#16866: *1781 event timer del: 38: 1523298574202
2018/04/09 21:29:34 [debug] 16866#16866: *1781 http keepalive handler
2018/04/09 21:29:34 [debug] 16866#16866: *1781 close http connection: 38
После этого в кэше лежит ответ SSI нулевой длины.
Как понимаю, эти поля окружения остаются от основного запроса.
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "PATH_INFO: /misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "SCRIPT_NAME: /misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "REQUEST_URI: /misssing/"
> Если потом эта переменная как-то передаются на
> бэкенд, и на основании их генерируется ответ - то результат будет
> именно такой, как у вас получается, потому что в момент создания
> запроса на бэкенд для обновления кэша у переменной будет значение
> "banner.html".
Кэш обновляется только nginx'ом, приложение его не трогает.
Устанавливаются переменные окружения для FCGI-процесса:
fastcgi_param QUERY_STRING $querystring;
fastcgi_param PATH_TRANSLATED $document_root/$handler;
Картина из лога:
Кэш протух, обращение к бэкенду, сохранение в кэш. Ожидаемый ответ, 404.
2018/04/09 21:23:59 [debug] 16867#16867: *1290 using location: @backend "/misssing/?"
…
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http cache key: "http|GET||||/misssing/"
…
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http file cache exists: 0 e:1
2018/04/09 21:23:59 [debug] 16867#16867: *1290 cache file: "/var/www/site/cache/pages/49/1d/2756424aaf76d1f24c982e863d611d49"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http file cache lock u:1 wt:0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream cache: -5
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "SCRIPT_NAME"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "/misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "SCRIPT_NAME: /misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "SCRIPT_FILENAME"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "/var/www/site/cgi-bin/parser.cgi"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "SCRIPT_FILENAME: /var/www/site/cgi-bin/parser.cgi"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "PATH_INFO"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "/misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "PATH_INFO: /misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "QUERY_STRING"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "_action=/misssing/&"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "QUERY_STRING: _action=/misssing/&"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "PATH_TRANSLATED"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "/var/www/site/www"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "_ind.html"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "PATH_TRANSLATED: /var/www/site/www/_ind.html"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "QUERY_STRING"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "QUERY_STRING: "
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "REQUEST_METHOD"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "GET"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "REQUEST_METHOD: GET"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "CONTENT_TYPE"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "CONTENT_TYPE: "
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "CONTENT_LENGTH"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "CONTENT_LENGTH: "
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "SCRIPT_NAME"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "/misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "SCRIPT_NAME: /misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "REQUEST_URI"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "/misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "REQUEST_URI: /misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "DOCUMENT_URI"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "/misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "DOCUMENT_URI: /misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "DOCUMENT_ROOT"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "/var/www/site/www"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "DOCUMENT_ROOT: /var/www/site/www"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "SERVER_PROTOCOL"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "HTTP/1.1"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "SERVER_PROTOCOL: HTTP/1.1"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "REQUEST_SCHEME"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "http"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "REQUEST_SCHEME: http"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: ""
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "GATEWAY_INTERFACE"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "CGI/1.1"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "GATEWAY_INTERFACE: CGI/1.1"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "SERVER_SOFTWARE"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "nginx/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "1.13.9"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "SERVER_SOFTWARE: nginx/1.13.9"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "REMOTE_ADDR"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "2.2.2.2"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "REMOTE_ADDR: 2.2.2.2"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "REMOTE_PORT"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "57628"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "REMOTE_PORT: 57628"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "SERVER_ADDR"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "0.0.0.0"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "SERVER_ADDR: 0.0.0.0"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "SERVER_PORT"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "80"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "SERVER_PORT: 80"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "SERVER_NAME"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script var: "example.com"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "SERVER_NAME: example.com"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "REDIRECT_STATUS"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: "200"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "REDIRECT_STATUS: 200"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: ""
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http script copy: ""
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "HTTP_HOST: example.com"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "HTTP_USER_AGENT: curl/7.56.1"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 fastcgi param: "HTTP_ACCEPT: */*"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 posix_memalign: 00005592481987C0:4096 @16
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http cleanup add: 0000559248198790
2018/04/09 21:23:59 [debug] 16867#16867: *1290 init keepalive peer
2018/04/09 21:23:59 [debug] 16867#16867: *1290 get keepalive peer
2018/04/09 21:23:59 [debug] 16867#16867: *1290 get rr peer, try: 1
2018/04/09 21:23:59 [debug] 16867#16867: *1290 get keepalive peer: using connection 00005592492CF030
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream connect: -4
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream send request
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream send request body
2018/04/09 21:23:59 [debug] 16867#16867: *1290 chain writer buf fl:0 s:720
2018/04/09 21:23:59 [debug] 16867#16867: *1290 chain writer in: 00005592481988A0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 writev: 720 of 720
2018/04/09 21:23:59 [debug] 16867#16867: *1290 chain writer out: 0000000000000000
2018/04/09 21:23:59 [debug] 16867#16867: *1290 event timer add: 10: 120000:1523298359132
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http finalize request: -4, "/misssing/?" a:1, c:3
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http request count:3 blk:0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http finalize request: -4, "/misssing/?" a:1, c:2
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http request count:2 blk:0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http run request: "/misssing/?"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream check client, write event:1, "/misssing/"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream request: "/misssing/?"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream dummy handler
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream request: "/misssing/?"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream process header
2018/04/09 21:23:59 [debug] 16867#16867: *1290 malloc: 0000559248F36E20:4096
2018/04/09 21:23:59 [debug] 16867#16867: *1290 recv: eof:0, avail:1
2018/04/09 21:23:59 [debug] 16867#16867: *1290 recv: fd:10 3703 of 3703
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi record byte: 01
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi record byte: 06
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi record byte: 00
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi record byte: 01
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi record byte: 1F
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi record byte: F8
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi record byte: 00
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi record byte: 00
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi record length: 8184
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi parser: 0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi header: "Content-Type: text/html; charset=UTF-8"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi parser: 0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi header: "X-Backend: 1"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi parser: 0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi header: "Status: 404"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi parser: 0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi header: "Content-Length: 8242"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi parser: 1
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http fastcgi header done
2018/04/09 21:23:59 [debug] 16867#16867: *1290 HTTP/1.1 404
Server: nginx
Date: Mon, 09 Apr 2018 18:23:59 GMT
Content-Type: text/html; charset=UTF-8
Transfer-Encoding: chunked
Connection: keep-alive
Vary: Accept-Encoding
X-Backend: 1
Strict-Transport-Security: max-age=31536000
…
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http copy filter: 0 "/misssing/?"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 pipe write downstream done
2018/04/09 21:23:59 [debug] 16867#16867: *1290 event timer: 10, old: 1523298359132, new: 1523298359226
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http file cache update
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http file cache rename: "/var/lib/nginx/fastcgi/1/68/0000000681" to "/var/www/site/cache/pages/49/1d/2756424aaf76d1f24c982e863d611d49"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream exit: 0000000000000000
2018/04/09 21:23:59 [debug] 16867#16867: *1290 finalize http upstream request: 0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 finalize http fastcgi request
2018/04/09 21:23:59 [debug] 16867#16867: *1290 free keepalive peer
2018/04/09 21:23:59 [debug] 16867#16867: *1290 free keepalive peer: saving connection 00005592492CF030
2018/04/09 21:23:59 [debug] 16867#16867: *1290 event timer del: 10: 1523298359132
2018/04/09 21:23:59 [debug] 16867#16867: *1290 free rr peer 1 0
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http upstream temp fd: 33
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http output filter "/misssing/?"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http copy filter: "/misssing/?"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http ssi filter "/misssing/?"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 ssi out: 0000559248FF0868 0000000000000000
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http postpone filter "/misssing/?" 0000559248FF08B8
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http copy filter: 0 "/misssing/?"
2018/04/09 21:23:59 [debug] 16867#16867: *1290 http finalize request: 0, "/misssing/?" a:0, c:4
2. Корректные ответы из кэша.
2018/04/09 21:25:33 [debug] 16866#16866: *1441 using configuration "/"
…
2018/04/09 21:25:33 [debug] 16866#16866: *1441 http cache key: "http|GET||||/misssing/"
…
2018/04/09 21:25:33 [debug] 16866#16866: *1441 http file cache exists: 0 e:1
2018/04/09 21:25:33 [debug] 16866#16866: *1441 cache file: "/var/www/site/cache/pages/49/1d/2756424aaf76d1f24c982e863d611d49"
2018/04/09 21:25:33 [debug] 16866#16866: *1441 add cleanup: 00005592493D1698
2018/04/09 21:25:33 [debug] 16866#16866: *1441 http file cache fd: 45
2018/04/09 21:25:33 [debug] 16866#16866: *1441 read: 45, 00005592493D1718, 492, 0
2018/04/09 21:25:33 [debug] 16866#16866: *1441 http upstream cache: 0
…
2018/04/09 21:25:33 [debug] 16866#16866: *1441 http file cache send: /var/www/site/cache/pages/49/1d/2756424aaf76d1f24c982e863d611d49
2018/04/09 21:25:33 [debug] 16866#16866: *1441 HTTP/1.1 404
Server: nginx
Date: Mon, 09 Apr 2018 18:25:33 GMT
Content-Type: text/html; charset=UTF-8
Transfer-Encoding: chunked
Connection: keep-alive
Vary: Accept-Encoding
X-Backend: 1
Strict-Transport-Security: max-age=31536000
Content-Encoding: gzip
3. Перезапись кэша.
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http posted request: "/misssing/?"
…
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http cache key: "http|GET||||/misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: ""
2018/04/09 21:29:34 [debug] 16867#16867: *1901 add cleanup: 00005592481A8650
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache exists: 0 e:1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 cache file: "/var/www/site/cache/pages/49/1d/2756424aaf76d1f24c982e863d611d49"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 add cleanup: 00005592481A86A8
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache fd: 70
2018/04/09 21:29:34 [debug] 16867#16867: *1901 read: 70, 00005592490A6A88, 492, 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache expired: 5 1523298539 1523298574
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream cache: 5
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "SCRIPT_NAME"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "/misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "SCRIPT_NAME: /misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "SCRIPT_FILENAME"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "/var/www/site/cgi-bin/parser.cgi"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "SCRIPT_FILENAME: /var/www/site/cgi-bin/parser.cgi"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "PATH_INFO"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "/misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "PATH_INFO: /misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "QUERY_STRING"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: ""
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "QUERY_STRING: "
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "PATH_TRANSLATED"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "/var/www/site/www"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "banner.html"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "PATH_TRANSLATED: /var/www/site/www/banner.html"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "QUERY_STRING"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "QUERY_STRING: "
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "REQUEST_METHOD"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "GET"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "REQUEST_METHOD: GET"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "CONTENT_TYPE"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "CONTENT_TYPE: "
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "CONTENT_LENGTH"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "CONTENT_LENGTH: "
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "SCRIPT_NAME"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "/misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "SCRIPT_NAME: /misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "REQUEST_URI"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "/misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "REQUEST_URI: /misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "DOCUMENT_URI"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "/misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "DOCUMENT_URI: /misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "DOCUMENT_ROOT"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "/var/www/site/www"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "DOCUMENT_ROOT: /var/www/site/www"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "SERVER_PROTOCOL"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "HTTP/1.1"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "SERVER_PROTOCOL: HTTP/1.1"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "REQUEST_SCHEME"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "http"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "REQUEST_SCHEME: http"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: ""
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "GATEWAY_INTERFACE"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "CGI/1.1"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "GATEWAY_INTERFACE: CGI/1.1"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "SERVER_SOFTWARE"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "nginx/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "1.13.9"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "SERVER_SOFTWARE: nginx/1.13.9"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "REMOTE_ADDR"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "1.1.1.1"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "REMOTE_ADDR: 1.1.1.1"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "REMOTE_PORT"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "41336"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "REMOTE_PORT: 41336"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "SERVER_ADDR"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "0.0.0.0"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "SERVER_ADDR: 0.0.0.0"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "SERVER_PORT"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "80"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "SERVER_PORT: 80"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "SERVER_NAME"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "example.com"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "SERVER_NAME: example.com"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "REDIRECT_STATUS"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: "200"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "REDIRECT_STATUS: 200"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: ""
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script copy: ""
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "HTTP_HOST: example.com"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "HTTP_CONNECTION: Keep-Alive"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "HTTP_USER_AGENT: Mozilla/5.0"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "HTTP_ACCEPT_ENCODING: gzip,deflate"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "HTTP_ACCEPT_LANGUAGE: ru, uk;q=0.8, be;q=0.8, en;q=0.7, *;q=0.01"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "HTTP_ACCEPT: */*"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http cleanup add: 00005592490A7098
2018/04/09 21:29:34 [debug] 16867#16867: *1901 init keepalive peer
2018/04/09 21:29:34 [debug] 16867#16867: *1901 get keepalive peer
2018/04/09 21:29:34 [debug] 16867#16867: *1901 get rr peer, try: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 get keepalive peer: using connection 00005592492D16A0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream connect: -4
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream send request
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream send request body
2018/04/09 21:29:34 [debug] 16867#16867: *1901 chain writer buf fl:0 s:912
2018/04/09 21:29:34 [debug] 16867#16867: *1901 chain writer in: 00005592490A7110
2018/04/09 21:29:34 [debug] 16867#16867: *1901 writev: 912 of 912
2018/04/09 21:29:34 [debug] 16867#16867: *1901 chain writer out: 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 event timer add: 54: 120000:1523298694012
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http finalize request: -4, "/misssing/?" a:0, c:6
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http request count:6 blk:0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http posted request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer handler: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http output filter "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http ssi filter "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter "/misssing/?" 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter output "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http gzip filter
…
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter wake "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: 0 "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer output filter: 0, "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 event timer: 31, old: 1523298634012, new: 1523298634012
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http posted request: "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer handler: "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http output filter "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http ssi filter "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter "/banner/inside-top/?" 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter output "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http gzip filter
…
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: 0 "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer output filter: 0, "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer done: "/banner/inside-top/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http finalize request: 0, "/banner/inside-top/?" a:1, c:5
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http wake parent request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http posted request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer handler: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http output filter "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http ssi filter "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter "/misssing/?" 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter output "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http gzip filter
…
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter wake "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: 0 "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer output filter: 0, "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 event timer: 31, old: 1523298634012, new: 1523298634012
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http posted request: "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer handler: "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http output filter "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http ssi filter "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter "/banner/inside-right/?" 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter output "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http gzip filter
…
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: 0 "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer output filter: 0, "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer done: "/banner/inside-right/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http finalize request: 0, "/banner/inside-right/?" a:1, c:4
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http wake parent request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http posted request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer handler: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http output filter "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http ssi filter "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter "/misssing/?" 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter output "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http gzip filter
…
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter wake "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: 0 "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer output filter: 0, "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 event timer: 31, old: 1523298634012, new: 1523298634012
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http posted request: "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer handler: "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http output filter "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http ssi filter "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter "/banner/all-bottom/?" 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter output "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http gzip filter
…
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: 0 "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer output filter: 0, "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer done: "/banner/all-bottom/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http finalize request: 0, "/banner/all-bottom/?" a:1, c:3
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http wake parent request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http posted request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer handler: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http output filter "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http ssi filter "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter "/misssing/?" 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http postpone filter output "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http gzip filter
…
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http chunk: 10
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http chunk: 3497
2018/04/09 21:29:34 [debug] 16867#16867: *1901 write old buf t:1 f:0 0000559248D69C40, pos 0000559248D69C40, size: 266 file: 0, size: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 write new buf t:1 f:0 00005592490A72E0, pos 00005592490A72E0, size: 5 file: 0, size: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 write new buf t:0 f:0 0000000000000000, pos 0000559247145818, size: 10 file: 0, size: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 write new buf t:1 f:0 0000559248D6B700, pos 0000559248D6B700, size: 3497 file: 0, size: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 write new buf t:0 f:0 0000000000000000, pos 0000559246F1D4E5, size: 7 file: 0, size: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http write filter: l:1 f:1 s:3785
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http write filter limit 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 writev: 3785 of 3785
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http write filter 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http copy filter: 0 "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer output filter: 0, "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http writer done: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http finalize request: 0, "/misssing/?" a:1, c:2
2018/04/09 21:29:34 [debug] 16867#16867: *1901 event timer del: 31: 1523298634012
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http request count:2 blk:0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http run request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http request empty handler
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream dummy handler
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream request: "/misssing/?"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream process header
2018/04/09 21:29:34 [debug] 16867#16867: *1901 malloc: 00005592490A73E0:4096
2018/04/09 21:29:34 [debug] 16867#16867: *1901 posix_memalign: 00005592490A83F0:4096 @16
2018/04/09 21:29:34 [debug] 16867#16867: *1901 recv: eof:0, avail:1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 recv: fd:54 112 of 3703
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 01
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 06
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 01
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 4A
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 06
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record length: 74
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi parser: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi header: "Content-Type: text/html; charset=UTF-8"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi parser: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi header: "Status: 200"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi parser: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi header: "Content-Length: 0"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi parser: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi header done
2018/04/09 21:29:34 [debug] 16867#16867: *1901 charset: "UTF-8" > "UTF-8"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 file cleanup: fd:70
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: ""
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache set header
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http cacheable: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream process upstream
2018/04/09 21:29:34 [debug] 16867#16867: *1901 pipe read upstream: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 pipe preread: 30
2018/04/09 21:29:34 [debug] 16867#16867: *1901 pipe buf free s:0 t:1 f:0 00005592490A73E0, pos 00005592490A75BB, size: 30 file: 0, size: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 pipe length: 8
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 01
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 06
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 01
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record length: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi closed stdout
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 01
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 03
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 01
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 08
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record byte: 00
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi record length: 8
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http fastcgi sent end request
2018/04/09 21:29:34 [debug] 16867#16867: *1901 pipe write chain
2018/04/09 21:29:34 [debug] 16867#16867: *1901 add cleanup: 00005592490A73B0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 hashed path: /var/lib/nginx/fastcgi/6/89/0000000896
2018/04/09 21:29:34 [debug] 16867#16867: *1901 temp fd:70
2018/04/09 21:29:34 [debug] 16867#16867: *1901 write: 70, 00005592490A73E0, 475, 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 pipe write downstream: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 event timer: 54, old: 1523298694012, new: 1523298694065
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache update
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache rename: "/var/lib/nginx/fastcgi/6/89/0000000896" to "/var/www/site/cache/pages/49/1d/2756424aaf76d1f24c982e863d611d49"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream exit: 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 finalize http upstream request: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 finalize http fastcgi request
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free keepalive peer
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free keepalive peer: saving connection 00005592492D16A0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 event timer del: 54: 1523298694012
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free rr peer 1 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http upstream temp fd: 70
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http finalize request: 0, "/misssing/?" a:0, c:1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 set http keepalive handler
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http close request
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http log handler
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http map started
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "1.1.1.1"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http map: "1.1.1.1" "1"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http script var: "1"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 00005592490A73B0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 file cleanup: fd:70
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 00005592481A8650
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 00005592481A75B0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 file cleanup: fd:69
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 00005592481A7558
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache cleanup
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache free, fd: 69
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 00005592481DBCC8
2018/04/09 21:29:34 [debug] 16867#16867: *1901 file cleanup: fd:63
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 00005592481DB888
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache cleanup
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache free, fd: 63
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 0000559248A43488
2018/04/09 21:29:34 [debug] 16867#16867: *1901 file cleanup: fd:56
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 0000559248A43430
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache cleanup
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache free, fd: 56
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 0000559248A41A70
2018/04/09 21:29:34 [debug] 16867#16867: *1901 file cleanup: fd:53
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 00005592489D6C60
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache cleanup
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache free, fd: 53
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 0000559248F26A08
2018/04/09 21:29:34 [debug] 16867#16867: *1901 file cleanup: fd:52
2018/04/09 21:29:34 [debug] 16867#16867: *1901 run cleanup: 0000559248F269B0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache cleanup
2018/04/09 21:29:34 [debug] 16867#16867: *1901 http file cache free, fd: 52
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559248D6B700
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 00005592490A73E0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 00005592493668A0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559248197EE0, unused: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559247D5D570, unused: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559248F25DD0, unused: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559248D696E0, unused: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 000055924897B2D0, unused: 4
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559249368EF0, unused: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559248175140, unused: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559248F35E10, unused: 5
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 00005592489D5CC0, unused: 2
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559248A41A50, unused: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 0000559248A42A60, unused: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 00005592481DA8B0, unused: 2
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 00005592481DB8C0, unused: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 00005592481A67E0, unused: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 00005592481A77F0, unused: 8
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 00005592490A63D0, unused: 8
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 00005592490A83F0, unused: 3051
2018/04/09 21:29:34 [debug] 16867#16867: *1901 free: 000055924A1A2FA0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 hc free: 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1901 hc busy: 0000000000000000 0
2018/04/09 21:29:34 [debug] 16867#16867: *1901 tcp_nodelay
2018/04/09 21:29:34 [debug] 16867#16867: *1901 reusable connection: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1901 event timer add: 31: 65000:1523298639065
2018/04/09 21:29:34 [debug] 16867#16867: *1775 event timer del: 34: 1523298574093
2018/04/09 21:29:34 [debug] 16867#16867: *1775 http keepalive handler
2018/04/09 21:29:34 [debug] 16867#16867: *1775 close http connection: 34
2018/04/09 21:29:34 [debug] 16867#16867: *1775 SSL_shutdown: 1
2018/04/09 21:29:34 [debug] 16867#16867: *1775 reusable connection: 0
2018/04/09 21:29:34 [debug] 16867#16867: *1775 free: 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1775 free: 0000000000000000
2018/04/09 21:29:34 [debug] 16867#16867: *1775 free: 0000559248A447E0, unused: 16
2018/04/09 21:29:34 [debug] 16867#16867: *1775 free: 00005592491FB120, unused: 400
2018/04/09 21:29:34 [debug] 16866#16866: *1781 event timer del: 38: 1523298574202
2018/04/09 21:29:34 [debug] 16866#16866: *1781 http keepalive handler
2018/04/09 21:29:34 [debug] 16866#16866: *1781 close http connection: 38
После этого в кэше лежит ответ SSI нулевой длины.
Как понимаю, эти поля окружения остаются от основного запроса.
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "PATH_INFO: /misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "SCRIPT_NAME: /misssing/"
2018/04/09 21:29:34 [debug] 16867#16867: *1901 fastcgi param: "REQUEST_URI: /misssing/"
> Если потом эта переменная как-то передаются на
> бэкенд, и на основании их генерируется ответ - то результат будет
> именно такой, как у вас получается, потому что в момент создания
> запроса на бэкенд для обновления кэша у переменной будет значение
> "banner.html".
Кэш обновляется только nginx'ом, приложение его не трогает.
Sorry, only registered users may post in this forum.
Online Users
Guests:
176
Record Number of Users:
8
on April 13, 2023
Record Number of Guests:
421
on December 02, 2018
This forum is powered by Phorum.
 |
 |
 |
 |
|