Ticket #1329: nginx_background_stale_request.txt

File nginx_background_stale_request.txt, 34.0 KB (added by nixelsolutions@…, 9 years ago)

Request debug log

Line 
12017/07/21 08:44:35 [debug] 12535#12535: accept on 0.0.0.0:80, ready: 0
22017/07/21 08:44:35 [debug] 12532#12532: accept on 0.0.0.0:80, ready: 0
32017/07/21 08:44:35 [debug] 12533#12533: accept on 0.0.0.0:80, ready: 0
42017/07/21 08:44:35 [debug] 12530#12530: accept on 0.0.0.0:80, ready: 0
52017/07/21 08:44:35 [debug] 12531#12531: accept on 0.0.0.0:80, ready: 0
62017/07/21 08:44:35 [debug] 12534#12534: accept on 0.0.0.0:80, ready: 0
72017/07/21 08:44:35 [debug] 12535#12535: posix_memalign: 00007F93E802E580:512 @16
82017/07/21 08:44:35 [debug] 12532#12532: accept() not ready (11: Resource temporarily unavailable)
92017/07/21 08:44:35 [debug] 12530#12530: accept() not ready (11: Resource temporarily unavailable)
102017/07/21 08:44:35 [debug] 12531#12531: accept() not ready (11: Resource temporarily unavailable)
112017/07/21 08:44:35 [debug] 12535#12535: *2 accept: 172.31.26.51:54606 fd:27
122017/07/21 08:44:35 [debug] 12533#12533: accept() not ready (11: Resource temporarily unavailable)
132017/07/21 08:44:35 [debug] 12534#12534: accept() not ready (11: Resource temporarily unavailable)
142017/07/21 08:44:35 [debug] 12535#12535: *2 event timer add: 27: 60000:1500626735081
152017/07/21 08:44:35 [debug] 12535#12535: *2 reusable connection: 1
162017/07/21 08:44:35 [debug] 12535#12535: *2 epoll add event: fd:27 op:1 ev:80002001
172017/07/21 08:44:35 [debug] 12535#12535: *2 http wait request handler
182017/07/21 08:44:35 [debug] 12535#12535: *2 malloc: 00007F93E7F30C70:1024
192017/07/21 08:44:35 [debug] 12535#12535: *2 recv: eof:0, avail:1
202017/07/21 08:44:35 [debug] 12535#12535: *2 recv: fd:27 201 of 1024
212017/07/21 08:44:35 [debug] 12535#12535: *2 reusable connection: 0
222017/07/21 08:44:35 [debug] 12535#12535: *2 posix_memalign: 00007F93E801E360:4096 @16
232017/07/21 08:44:35 [debug] 12535#12535: *2 posix_memalign: 00007F93E7F3DAF0:4096 @16
242017/07/21 08:44:35 [debug] 12535#12535: *2 http process request line
252017/07/21 08:44:35 [debug] 12535#12535: *2 http request line: "GET /test.php HTTP/1.0"
262017/07/21 08:44:35 [debug] 12535#12535: *2 http uri: "/test.php"
272017/07/21 08:44:35 [debug] 12535#12535: *2 http args: ""
282017/07/21 08:44:35 [debug] 12535#12535: *2 http exten: "php"
292017/07/21 08:44:35 [debug] 12535#12535: *2 http process request header line
302017/07/21 08:44:35 [debug] 12535#12535: *2 http header: "X-Real-IP: 172.31.2.139"
312017/07/21 08:44:35 [debug] 12535#12535: *2 http header: "X-Forwarded-For: 172.31.2.139"
322017/07/21 08:44:35 [debug] 12535#12535: *2 http header: "X-Forwarded-Proto: https"
332017/07/21 08:44:35 [debug] 12535#12535: *2 http header: "Host: 86332-86332-stg.thecore.link"
342017/07/21 08:44:35 [debug] 12535#12535: *2 http header: "Connection: close"
352017/07/21 08:44:35 [debug] 12535#12535: *2 http header: "User-Agent: curl/7.51.0"
362017/07/21 08:44:35 [debug] 12535#12535: *2 http header: "Accept: */*"
372017/07/21 08:44:35 [debug] 12535#12535: *2 http header done
382017/07/21 08:44:35 [debug] 12535#12535: *2 event timer del: 27: 1500626735081
392017/07/21 08:44:35 [debug] 12535#12535: *2 generic phase: 0
402017/07/21 08:44:35 [debug] 12535#12535: *2 add cleanup: 00007F93E801F078
412017/07/21 08:44:35 [debug] 12535#12535: *2 rewrite phase: 1
422017/07/21 08:44:35 [debug] 12535#12535: *2 http script value: "0"
432017/07/21 08:44:35 [debug] 12535#12535: *2 http script set $skip_cache
442017/07/21 08:44:35 [debug] 12535#12535: *2 http script value: "0"
452017/07/21 08:44:35 [debug] 12535#12535: *2 http script set $skip_cache_m
462017/07/21 08:44:35 [debug] 12535#12535: *2 http script value: "0"
472017/07/21 08:44:35 [debug] 12535#12535: *2 http script set $in_cart
482017/07/21 08:44:35 [debug] 12535#12535: *2 http script value: "1"
492017/07/21 08:44:35 [debug] 12535#12535: *2 http script set $in_cart_advanced_cache
502017/07/21 08:44:35 [debug] 12535#12535: *2 http script value: ""
512017/07/21 08:44:35 [debug] 12535#12535: *2 http script set $rt_session
522017/07/21 08:44:35 [debug] 12535#12535: *2 http script var
532017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "GET"
542017/07/21 08:44:35 [debug] 12535#12535: *2 http script value: "POST"
552017/07/21 08:44:35 [debug] 12535#12535: *2 http script equal
562017/07/21 08:44:35 [debug] 12535#12535: *2 http script equal: no
572017/07/21 08:44:35 [debug] 12535#12535: *2 http script if
582017/07/21 08:44:35 [debug] 12535#12535: *2 http script if: false
592017/07/21 08:44:35 [debug] 12535#12535: *2 http script var
602017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "/test.php"
612017/07/21 08:44:35 [debug] 12535#12535: *2 http script regex: "/wp-admin.*|/checkout.*|/checkout.*|/account.*|/myaccount.*|/%ce%bf-%ce%bb%ce%bf%ce%b3%ce%b1%cf%81%ce%b9%ce%b1%cf%83%ce%bc%cf%8c%cf%82-%ce%bc%ce%bf%cf%85/addond.*|/wc-api.*|/logout.*|/lost-password.*/xmlrpc.php|/wp-(app|cron|login|register|mail).php|wp-.*.php|/feed/|index.php|wp-comments-popup.php|wp-links-opml.php|sitemap(_index)?.xml|a-z0-9_-]+-sitemap([0-9]+)?.xml"
622017/07/21 08:44:35 [notice] 12535#12535: *2 "/wp-admin.*|/checkout.*|/checkout.*|/account.*|/myaccount.*|/%ce%bf-%ce%bb%ce%bf%ce%b3%ce%b1%cf%81%ce%b9%ce%b1%cf%83%ce%bc%cf%8c%cf%82-%ce%bc%ce%bf%cf%85/addond.*|/wc-api.*|/logout.*|/lost-password.*/xmlrpc.php|/wp-(app|cron|login|register|mail).php|wp-.*.php|/feed/|index.php|wp-comments-popup.php|wp-links-opml.php|sitemap(_index)?.xml|a-z0-9_-]+-sitemap([0-9]+)?.xml" does not match "/test.php", client: 172.31.2.139, server: localhost, request: "GET /test.php HTTP/1.0", host: "86332-86332-stg.thecore.link"
632017/07/21 08:44:35 [debug] 12535#12535: *2 http script if
642017/07/21 08:44:35 [debug] 12535#12535: *2 http script if: false
652017/07/21 08:44:35 [debug] 12535#12535: *2 http script var
662017/07/21 08:44:35 [debug] 12535#12535: *2 http script regex: "wordpress_no_cache|wp-postpass"
672017/07/21 08:44:35 [notice] 12535#12535: *2 "wordpress_no_cache|wp-postpass" does not match "", client: 172.31.2.139, server: localhost, request: "GET /test.php HTTP/1.0", host: "86332-86332-stg.thecore.link"
682017/07/21 08:44:35 [debug] 12535#12535: *2 http script if
692017/07/21 08:44:35 [debug] 12535#12535: *2 http script if: false
702017/07/21 08:44:35 [debug] 12535#12535: *2 http script var
712017/07/21 08:44:35 [debug] 12535#12535: *2 http script value: ""
722017/07/21 08:44:35 [debug] 12535#12535: *2 http script not equal
732017/07/21 08:44:35 [debug] 12535#12535: *2 http script not equal: no
742017/07/21 08:44:35 [debug] 12535#12535: *2 http script if
752017/07/21 08:44:35 [debug] 12535#12535: *2 http script if: false
762017/07/21 08:44:35 [debug] 12535#12535: *2 http script var
772017/07/21 08:44:35 [debug] 12535#12535: *2 http script value: "WOO_MYACCOUNT_ARG_VALUE"
782017/07/21 08:44:35 [debug] 12535#12535: *2 http script equal
792017/07/21 08:44:35 [debug] 12535#12535: *2 http script equal: no
802017/07/21 08:44:35 [debug] 12535#12535: *2 http script if
812017/07/21 08:44:35 [debug] 12535#12535: *2 http script if: false
822017/07/21 08:44:35 [debug] 12535#12535: *2 http script var
832017/07/21 08:44:35 [debug] 12535#12535: *2 http script value: "WOO_CHECKOUT_ARG_VALUE"
842017/07/21 08:44:35 [debug] 12535#12535: *2 http script equal
852017/07/21 08:44:35 [debug] 12535#12535: *2 http script equal: no
862017/07/21 08:44:35 [debug] 12535#12535: *2 http script if
872017/07/21 08:44:35 [debug] 12535#12535: *2 http script if: false
882017/07/21 08:44:35 [debug] 12535#12535: *2 http script var
892017/07/21 08:44:35 [debug] 12535#12535: *2 http script value: "WOO_CART_ARG_VALUE"
902017/07/21 08:44:35 [debug] 12535#12535: *2 http script equal
912017/07/21 08:44:35 [debug] 12535#12535: *2 http script equal: no
922017/07/21 08:44:35 [debug] 12535#12535: *2 http script if
932017/07/21 08:44:35 [debug] 12535#12535: *2 http script if: false
942017/07/21 08:44:35 [debug] 12535#12535: *2 http script var
952017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "/test.php"
962017/07/21 08:44:35 [debug] 12535#12535: *2 http script regex: "/cart.*|/%ce%ba%ce%b1%ce%bb%ce%ac%ce%b8%ce%b9.*"
972017/07/21 08:44:35 [notice] 12535#12535: *2 "/cart.*|/%ce%ba%ce%b1%ce%bb%ce%ac%ce%b8%ce%b9.*" does not match "/test.php", client: 172.31.2.139, server: localhost, request: "GET /test.php HTTP/1.0", host: "86332-86332-stg.thecore.link"
982017/07/21 08:44:35 [debug] 12535#12535: *2 http script if
992017/07/21 08:44:35 [debug] 12535#12535: *2 http script if: false
1002017/07/21 08:44:35 [debug] 12535#12535: *2 http script var
1012017/07/21 08:44:35 [debug] 12535#12535: *2 http script if
1022017/07/21 08:44:35 [debug] 12535#12535: *2 http script if: false
1032017/07/21 08:44:35 [debug] 12535#12535: *2 http script var
1042017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "0"
1052017/07/21 08:44:35 [debug] 12535#12535: *2 http script value: "11"
1062017/07/21 08:44:35 [debug] 12535#12535: *2 http script equal
1072017/07/21 08:44:35 [debug] 12535#12535: *2 http script equal: no
1082017/07/21 08:44:35 [debug] 12535#12535: *2 http script if
1092017/07/21 08:44:35 [debug] 12535#12535: *2 http script if: false
1102017/07/21 08:44:35 [debug] 12535#12535: *2 http script var
1112017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "1"
1122017/07/21 08:44:35 [debug] 12535#12535: *2 http script value: "11"
1132017/07/21 08:44:35 [debug] 12535#12535: *2 http script equal
1142017/07/21 08:44:35 [debug] 12535#12535: *2 http script equal: no
1152017/07/21 08:44:35 [debug] 12535#12535: *2 http script if
1162017/07/21 08:44:35 [debug] 12535#12535: *2 http script if: false
1172017/07/21 08:44:35 [debug] 12535#12535: *2 http script var
1182017/07/21 08:44:35 [debug] 12535#12535: *2 http script regex: "wordpress_logged_in_([0-9a-zA-Z]+)=(.*);?"
1192017/07/21 08:44:35 [notice] 12535#12535: *2 "wordpress_logged_in_([0-9a-zA-Z]+)=(.*);?" does not match "", client: 172.31.2.139, server: localhost, request: "GET /test.php HTTP/1.0", host: "86332-86332-stg.thecore.link"
1202017/07/21 08:44:35 [debug] 12535#12535: *2 http script if
1212017/07/21 08:44:35 [debug] 12535#12535: *2 http script if: false
1222017/07/21 08:44:35 [debug] 12535#12535: *2 http script var
1232017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "https"
1242017/07/21 08:44:35 [debug] 12535#12535: *2 http script value: "https"
1252017/07/21 08:44:35 [debug] 12535#12535: *2 http script not equal
1262017/07/21 08:44:35 [debug] 12535#12535: *2 http script not equal: no
1272017/07/21 08:44:35 [debug] 12535#12535: *2 http script if
1282017/07/21 08:44:35 [debug] 12535#12535: *2 http script if: false
1292017/07/21 08:44:35 [debug] 12535#12535: *2 http script regex: "^/%CF%80%CF%81%CE%BF%CF%8A%CF%8C%CE%BD/(.*)$"
1302017/07/21 08:44:35 [notice] 12535#12535: *2 "^/%CF%80%CF%81%CE%BF%CF%8A%CF%8C%CE%BD/(.*)$" does not match "/test.php", client: 172.31.2.139, server: localhost, request: "GET /test.php HTTP/1.0", host: "86332-86332-stg.thecore.link"
1312017/07/21 08:44:35 [debug] 12535#12535: *2 http script regex: "^/προϊόν/(.*)$"
1322017/07/21 08:44:35 [notice] 12535#12535: *2 "^/προϊόν/(.*)$" does not match "/test.php", client: 172.31.2.139, server: localhost, request: "GET /test.php HTTP/1.0", host: "86332-86332-stg.thecore.link"
1332017/07/21 08:44:35 [debug] 12535#12535: *2 test location: "/"
1342017/07/21 08:44:35 [debug] 12535#12535: *2 test location: "papaki-env.php"
1352017/07/21 08:44:35 [debug] 12535#12535: *2 test location: "robots.txt"
1362017/07/21 08:44:35 [debug] 12535#12535: *2 test location: "wp-config.php"
1372017/07/21 08:44:35 [debug] 12535#12535: *2 test location: ~ "/\."
1382017/07/21 08:44:35 [debug] 12535#12535: *2 test location: ~ "^/phpmyadmin/.+\.php$"
1392017/07/21 08:44:35 [debug] 12535#12535: *2 test location: ~ "^/phpmyadmin/(.+\.(jpg|jpeg|gif|css|png|js|ico|html|xml|txt))$"
1402017/07/21 08:44:35 [debug] 12535#12535: *2 test location: ~ "\.php$"
1412017/07/21 08:44:35 [debug] 12535#12535: *2 using configuration "\.php$"
1422017/07/21 08:44:35 [debug] 12535#12535: *2 http cl:-1 max:104857600
1432017/07/21 08:44:35 [debug] 12535#12535: *2 rewrite phase: 3
1442017/07/21 08:44:35 [debug] 12535#12535: *2 rewrite phase: 4
1452017/07/21 08:44:35 [debug] 12535#12535: *2 http script var
1462017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "0"
1472017/07/21 08:44:35 [debug] 12535#12535: *2 http script value: "0"
1482017/07/21 08:44:35 [debug] 12535#12535: *2 http script equal
1492017/07/21 08:44:35 [debug] 12535#12535: *2 http script if
1502017/07/21 08:44:35 [debug] 12535#12535: *2 post rewrite phase: 5
1512017/07/21 08:44:35 [debug] 12535#12535: *2 generic phase: 6
1522017/07/21 08:44:35 [debug] 12535#12535: *2 generic phase: 7
1532017/07/21 08:44:35 [debug] 12535#12535: *2 generic phase: 8
1542017/07/21 08:44:35 [debug] 12535#12535: *2 access phase: 9
1552017/07/21 08:44:35 [debug] 12535#12535: *2 access phase: 10
1562017/07/21 08:44:35 [debug] 12535#12535: *2 access phase: 11
1572017/07/21 08:44:35 [debug] 12535#12535: *2 access phase: 12
1582017/07/21 08:44:35 [debug] 12535#12535: *2 post access phase: 13
1592017/07/21 08:44:35 [debug] 12535#12535: *2 try files phase: 14
1602017/07/21 08:44:35 [debug] 12535#12535: *2 http init upstream, client timer: 0
1612017/07/21 08:44:35 [debug] 12535#12535: *2 epoll add event: fd:27 op:3 ev:80002005
1622017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "http"
1632017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "GET"
1642017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "86332-86332-stg.thecore.link"
1652017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "/test.php"
1662017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: ""
1672017/07/21 08:44:35 [debug] 12535#12535: *2 http cache key: "httpGET86332-86332-stg.thecore.link/test.php"
1682017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "0"
1692017/07/21 08:44:35 [debug] 12535#12535: *2 add cleanup: 00007F93E7F3EAC0
1702017/07/21 08:44:35 [debug] 12535#12535: *2 http file cache exists: 0 e:1
1712017/07/21 08:44:35 [debug] 12535#12535: *2 posix_memalign: 00007F93E800F280:4096 @16
1722017/07/21 08:44:35 [debug] 12535#12535: *2 cache file: "/dev/shm/nginx/4/55/3ecfc32a6a28e8e9a177aff5ac3cd554"
1732017/07/21 08:44:35 [debug] 12535#12535: *2 add cleanup: 00007F93E7F3EAD8
1742017/07/21 08:44:35 [debug] 12535#12535: *2 http file cache fd: 29
1752017/07/21 08:44:35 [debug] 12535#12535: *2 read: 29, 00007F93E800F340, 437, 0
1762017/07/21 08:44:35 [debug] 12535#12535: *2 http file cache expired: 4 1500626505 1500626675
1772017/07/21 08:44:35 [debug] 12535#12535: *2 http upstream cache: 4
1782017/07/21 08:44:35 [debug] 12535#12535: *2 http fastcgi record byte: 01
1792017/07/21 08:44:35 [debug] 12535#12535: *2 http fastcgi record byte: 06
1802017/07/21 08:44:35 [debug] 12535#12535: *2 http fastcgi record byte: 00
1812017/07/21 08:44:35 [debug] 12535#12535: *2 http fastcgi record byte: 01
1822017/07/21 08:44:35 [debug] 12535#12535: *2 http fastcgi record byte: 00
1832017/07/21 08:44:35 [debug] 12535#12535: *2 http fastcgi record byte: 43
1842017/07/21 08:44:35 [debug] 12535#12535: *2 http fastcgi record byte: 05
1852017/07/21 08:44:35 [debug] 12535#12535: *2 http fastcgi record byte: 00
1862017/07/21 08:44:35 [debug] 12535#12535: *2 http fastcgi record length: 67
1872017/07/21 08:44:35 [debug] 12535#12535: *2 http fastcgi parser: 0
1882017/07/21 08:44:35 [debug] 12535#12535: *2 http fastcgi header: "Content-type: text/html; charset=UTF-8"
1892017/07/21 08:44:35 [debug] 12535#12535: *2 http fastcgi parser: 1
1902017/07/21 08:44:35 [debug] 12535#12535: *2 http fastcgi header done
1912017/07/21 08:44:35 [debug] 12535#12535: *2 http file cache send: /dev/shm/nginx/4/55/3ecfc32a6a28e8e9a177aff5ac3cd554
1922017/07/21 08:44:35 [debug] 12535#12535: *2 uploadprogress error-tracker error: 0
1932017/07/21 08:44:35 [debug] 12535#12535: *2 headers more header filter, uri "/test.php"
1942017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "STALE"
1952017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "0"
1962017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "0"
1972017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "/test.php"
1982017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: ""
1992017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "0"
2002017/07/21 08:44:35 [debug] 12535#12535: *2 xslt filter header
2012017/07/21 08:44:35 [debug] 12535#12535: *2 HTTP/1.1 200 OK
202Server: nginx
203Date: Fri, 21 Jul 2017 08:44:35 GMT
204Content-Type: text/html; charset=UTF-8
205Connection: close
206Vary: Accept-Encoding
207TC-Cache: STALE
208TC-S-Cache: 0
209TC-S-Cache-M: 0
210TC-Cache-U: /test.php
211TC-Cache-IC: 0
212
2132017/07/21 08:44:35 [debug] 12535#12535: *2 write new buf t:1 f:0 00007F93E800F848, pos 00007F93E800F848, size: 241 file: 0, size: 0
2142017/07/21 08:44:35 [debug] 12535#12535: *2 http write filter: l:0 f:0 s:241
2152017/07/21 08:44:35 [debug] 12535#12535: *2 http output filter "/test.php?"
2162017/07/21 08:44:35 [debug] 12535#12535: *2 http copy filter: "/test.php?"
2172017/07/21 08:44:35 [debug] 12535#12535: *2 image filter
2182017/07/21 08:44:35 [debug] 12535#12535: *2 xslt filter body
2192017/07/21 08:44:35 [debug] 12535#12535: *2 http postpone filter "/test.php?" 00007FFCFCC54E90
2202017/07/21 08:44:35 [debug] 12535#12535: *2 write old buf t:1 f:0 00007F93E800F848, pos 00007F93E800F848, size: 241 file: 0, size: 0
2212017/07/21 08:44:35 [debug] 12535#12535: *2 write new buf t:0 f:1 0000000000000000, pos 0000000000000000, size: 0 file: 437, size: 25
2222017/07/21 08:44:35 [debug] 12535#12535: *2 http write filter: l:1 f:0 s:266
2232017/07/21 08:44:35 [debug] 12535#12535: *2 http write filter limit 0
2242017/07/21 08:44:35 [debug] 12535#12535: *2 tcp_nopush
2252017/07/21 08:44:35 [debug] 12535#12535: *2 writev: 241 of 241
2262017/07/21 08:44:35 [debug] 12535#12535: *2 sendfile: @437 25
2272017/07/21 08:44:35 [debug] 12535#12535: *2 sendfile: 25 of 25 @437
2282017/07/21 08:44:35 [debug] 12535#12535: *2 http write filter 0000000000000000
2292017/07/21 08:44:35 [debug] 12535#12535: *2 http copy filter: 0 "/test.php?"
2302017/07/21 08:44:35 [debug] 12535#12535: *2 posix_memalign: 00007F93E7F556D0:4096 @16
2312017/07/21 08:44:35 [debug] 12535#12535: *2 http subrequest "/test.php?"
2322017/07/21 08:44:35 [debug] 12535#12535: *2 http finalize request: 0, "/test.php?" a:1, c:3
2332017/07/21 08:44:35 [debug] 12535#12535: *2 http request count:3 blk:0
2342017/07/21 08:44:35 [debug] 12535#12535: *2 http finalize request: -4, "/test.php?" a:1, c:2
2352017/07/21 08:44:35 [debug] 12535#12535: *2 http request count:2 blk:0
2362017/07/21 08:44:35 [debug] 12535#12535: *2 http posted request: "/test.php?"
2372017/07/21 08:44:35 [debug] 12535#12535: *2 http init upstream, client timer: 0
2382017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "http"
2392017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "GET"
2402017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "86332-86332-stg.thecore.link"
2412017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "/test.php"
2422017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: ""
2432017/07/21 08:44:35 [debug] 12535#12535: *2 http cache key: "httpGET86332-86332-stg.thecore.link/test.php"
2442017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "0"
2452017/07/21 08:44:35 [debug] 12535#12535: *2 add cleanup: 00007F93E7F56368
2462017/07/21 08:44:35 [debug] 12535#12535: *2 http file cache exists: 0 e:1
2472017/07/21 08:44:35 [debug] 12535#12535: *2 cache file: "/dev/shm/nginx/4/55/3ecfc32a6a28e8e9a177aff5ac3cd554"
2482017/07/21 08:44:35 [debug] 12535#12535: *2 add cleanup: 00007F93E7F563B8
2492017/07/21 08:44:35 [debug] 12535#12535: *2 http file cache fd: 31
2502017/07/21 08:44:35 [debug] 12535#12535: *2 read: 31, 00007F93E7F56438, 437, 0
2512017/07/21 08:44:35 [debug] 12535#12535: *2 http file cache expired: 5 1500626505 1500626675
2522017/07/21 08:44:35 [debug] 12535#12535: *2 http upstream cache: 5
2532017/07/21 08:44:35 [debug] 12535#12535: *2 posix_memalign: 00007F93E7F566E0:4096 @16
2542017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "SCRIPT_FILENAME"
2552017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "/var/www/shared/documentRoot"
2562017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "/test.php"
2572017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "SCRIPT_FILENAME: /var/www/shared/documentRoot/test.php"
2582017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "QUERY_STRING"
2592017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "QUERY_STRING: "
2602017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "REQUEST_METHOD"
2612017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "GET"
2622017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "REQUEST_METHOD: GET"
2632017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "CONTENT_TYPE"
2642017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "CONTENT_TYPE: "
2652017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "CONTENT_LENGTH"
2662017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "CONTENT_LENGTH: "
2672017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "SCRIPT_NAME"
2682017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "/test.php"
2692017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "SCRIPT_NAME: /test.php"
2702017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "REQUEST_URI"
2712017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "/test.php"
2722017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "REQUEST_URI: /test.php"
2732017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "DOCUMENT_URI"
2742017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "/test.php"
2752017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "DOCUMENT_URI: /test.php"
2762017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "DOCUMENT_ROOT"
2772017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "/var/www/shared/documentRoot"
2782017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "DOCUMENT_ROOT: /var/www/shared/documentRoot"
2792017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "SERVER_PROTOCOL"
2802017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "HTTP/1.0"
2812017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "SERVER_PROTOCOL: HTTP/1.0"
2822017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "REQUEST_SCHEME"
2832017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "http"
2842017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "REQUEST_SCHEME: http"
2852017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: ""
2862017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "GATEWAY_INTERFACE"
2872017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "CGI/1.1"
2882017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "GATEWAY_INTERFACE: CGI/1.1"
2892017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "SERVER_SOFTWARE"
2902017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "nginx/"
2912017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "1.13.3"
2922017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "SERVER_SOFTWARE: nginx/1.13.3"
2932017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "REMOTE_ADDR"
2942017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "172.31.2.139"
2952017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "REMOTE_ADDR: 172.31.2.139"
2962017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "REMOTE_PORT"
2972017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: ""
2982017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "REMOTE_PORT: "
2992017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "SERVER_ADDR"
3002017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "172.17.0.39"
3012017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "SERVER_ADDR: 172.17.0.39"
3022017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "SERVER_PORT"
3032017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "80"
3042017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "SERVER_PORT: 80"
3052017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "SERVER_NAME"
3062017/07/21 08:44:35 [debug] 12535#12535: *2 http script var: "localhost"
3072017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "SERVER_NAME: localhost"
3082017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "REDIRECT_STATUS"
3092017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: "200"
3102017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "REDIRECT_STATUS: 200"
3112017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: ""
3122017/07/21 08:44:35 [debug] 12535#12535: *2 http script copy: ""
3132017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "HTTP_X_REAL_IP: 172.31.2.139"
3142017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "HTTP_X_FORWARDED_FOR: 172.31.2.139"
3152017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "HTTP_X_FORWARDED_PROTO: https"
3162017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "HTTP_HOST: 86332-86332-stg.thecore.link"
3172017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "HTTP_CONNECTION: close"
3182017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "HTTP_USER_AGENT: curl/7.51.0"
3192017/07/21 08:44:35 [debug] 12535#12535: *2 fastcgi param: "HTTP_ACCEPT: */*"
3202017/07/21 08:44:35 [debug] 12535#12535: *2 http cleanup add: 00007F93E7F566B0
3212017/07/21 08:44:35 [debug] 12535#12535: *2 get rr peer, try: 2
3222017/07/21 08:44:35 [debug] 12535#12535: *2 get rr peer, current: 00007F93E7FB9CF8 -1
3232017/07/21 08:44:35 [debug] 12535#12535: *2 stream socket 33
3242017/07/21 08:44:35 [debug] 12535#12535: *2 epoll add connection: fd:33 ev:80002005
3252017/07/21 08:44:35 [debug] 12535#12535: *2 connect to 127.0.0.1:81, fd:33 #3
3262017/07/21 08:44:35 [debug] 12535#12535: *2 http upstream connect: -2
3272017/07/21 08:44:35 [debug] 12535#12535: *2 posix_memalign: 00007F93E80036F0:128 @16
3282017/07/21 08:44:35 [debug] 12535#12535: *2 event timer add: 33: 60000:1500626735081
3292017/07/21 08:44:35 [debug] 12535#12535: *2 http finalize request: -4, "/test.php?" a:0, c:2
3302017/07/21 08:44:35 [debug] 12535#12535: *2 http request count:2 blk:0
3312017/07/21 08:44:35 [debug] 12535#12535: *2 http run request: "/test.php?"
3322017/07/21 08:44:35 [debug] 12535#12535: *2 http request empty handler
3332017/07/21 08:44:35 [debug] 12535#12535: *2 http upstream request: "/test.php?"
3342017/07/21 08:44:35 [debug] 12535#12535: *2 http upstream send request handler
3352017/07/21 08:44:35 [debug] 12535#12535: *2 http upstream send request
3362017/07/21 08:44:35 [debug] 12535#12535: *2 http upstream send request body
3372017/07/21 08:44:35 [debug] 12535#12535: *2 chain writer buf fl:0 s:688
3382017/07/21 08:44:35 [debug] 12535#12535: *2 chain writer in: 00007F93E800F940
3392017/07/21 08:44:35 [debug] 12535#12535: *2 writev: 688 of 688
3402017/07/21 08:44:35 [debug] 12535#12535: *2 chain writer out: 0000000000000000
3412017/07/21 08:44:35 [debug] 12535#12535: *2 event timer del: 33: 1500626735081
3422017/07/21 08:44:35 [debug] 12535#12535: *2 event timer add: 33: 3000000:1500629675082
3432017/07/21 08:44:40 [debug] 12535#12535: *2 http upstream request: "/test.php?"
3442017/07/21 08:44:40 [debug] 12535#12535: *2 http upstream process header
3452017/07/21 08:44:40 [debug] 12535#12535: *2 malloc: 00007F93E7F36400:16384
3462017/07/21 08:44:40 [debug] 12535#12535: *2 recv: eof:0, avail:1
3472017/07/21 08:44:40 [debug] 12535#12535: *2 recv: fd:33 96 of 15997
3482017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi record byte: 01
3492017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi record byte: 06
3502017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi record byte: 00
3512017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi record byte: 01
3522017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi record byte: 00
3532017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi record byte: 43
3542017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi record byte: 05
3552017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi record byte: 00
3562017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi record length: 67
3572017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi parser: 0
3582017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi header: "Content-type: text/html; charset=UTF-8"
3592017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi parser: 1
3602017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi header done
3612017/07/21 08:44:40 [debug] 12535#12535: *2 uploadprogress error-tracker error: 0
3622017/07/21 08:44:40 [debug] 12535#12535: *2 headers more header filter, uri "/test.php"
3632017/07/21 08:44:40 [debug] 12535#12535: *2 xslt filter header
3642017/07/21 08:44:40 [debug] 12535#12535: *2 charset: "UTF-8" > "UTF-8"
3652017/07/21 08:44:40 [debug] 12535#12535: *2 file cleanup: fd:31
3662017/07/21 08:44:40 [debug] 12535#12535: *2 http script var: "0"
3672017/07/21 08:44:40 [debug] 12535#12535: *2 http file cache set header
3682017/07/21 08:44:40 [debug] 12535#12535: *2 http cacheable: 1
3692017/07/21 08:44:40 [debug] 12535#12535: *2 http upstream process upstream
3702017/07/21 08:44:40 [debug] 12535#12535: *2 pipe read upstream: 0
3712017/07/21 08:44:40 [debug] 12535#12535: *2 pipe preread: 46
3722017/07/21 08:44:40 [debug] 12535#12535: *2 pipe buf free s:0 t:1 f:0 00007F93E7F36400, pos 00007F93E7F365B5, size: 46 file: 0, size: 0
3732017/07/21 08:44:40 [debug] 12535#12535: *2 pipe length: -1
3742017/07/21 08:44:40 [debug] 12535#12535: *2 pipe write chain
3752017/07/21 08:44:40 [debug] 12535#12535: *2 add cleanup: 00007F93E7F56DF8
3762017/07/21 08:44:40 [debug] 12535#12535: *2 hashed path: /var/lib/nginx/fastcgi/1/00/0000000001
3772017/07/21 08:44:40 [debug] 12535#12535: *2 temp fd:31
3782017/07/21 08:44:40 [debug] 12535#12535: *2 write: 31, 00007F93E7F36400, 437, 0
3792017/07/21 08:44:40 [debug] 12535#12535: *2 pipe write downstream: 1
3802017/07/21 08:44:40 [debug] 12535#12535: *2 pipe read upstream: 0
3812017/07/21 08:44:40 [debug] 12535#12535: *2 pipe buf free s:0 t:1 f:0 00007F93E7F36400, pos 00007F93E7F365B5, size: 46 file: 0, size: 0
3822017/07/21 08:44:40 [debug] 12535#12535: *2 pipe length: -1
3832017/07/21 08:44:40 [debug] 12535#12535: *2 event timer del: 33: 1500629675082
3842017/07/21 08:44:40 [debug] 12535#12535: *2 event timer add: 33: 3000000:1500629680085
3852017/07/21 08:44:40 [debug] 12535#12535: *2 http upstream downstream error
3862017/07/21 08:44:40 [debug] 12535#12535: *2 http upstream request: "/test.php?"
3872017/07/21 08:44:40 [debug] 12535#12535: *2 http upstream dummy handler
3882017/07/21 08:44:40 [debug] 12535#12535: *2 http upstream request: "/test.php?"
3892017/07/21 08:44:40 [debug] 12535#12535: *2 http upstream process upstream
3902017/07/21 08:44:40 [debug] 12535#12535: *2 pipe read upstream: 1
3912017/07/21 08:44:40 [debug] 12535#12535: *2 readv: eof:1, avail:1
3922017/07/21 08:44:40 [debug] 12535#12535: *2 readv: 1, last:15901
3932017/07/21 08:44:40 [debug] 12535#12535: *2 pipe recv chain: 0
3942017/07/21 08:44:40 [debug] 12535#12535: *2 pipe buf free s:0 t:1 f:0 00007F93E7F36400, pos 00007F93E7F365B5, size: 46 file: 0, size: 0
3952017/07/21 08:44:40 [debug] 12535#12535: *2 pipe length: -1
3962017/07/21 08:44:40 [debug] 12535#12535: *2 input buf #0 00007F93E7F365B5
3972017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi record byte: 01
3982017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi record byte: 03
3992017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi record byte: 00
4002017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi record byte: 01
4012017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi record byte: 00
4022017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi record byte: 08
4032017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi record byte: 00
4042017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi record byte: 00
4052017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi record length: 8
4062017/07/21 08:44:40 [debug] 12535#12535: *2 http fastcgi sent end request
4072017/07/21 08:44:40 [debug] 12535#12535: *2 input buf 00007F93E7F365B5 25
4082017/07/21 08:44:40 [debug] 12535#12535: *2 pipe write chain
4092017/07/21 08:44:40 [debug] 12535#12535: *2 write: 31, 00007F93E7F365B5, 25, 437
4102017/07/21 08:44:40 [debug] 12535#12535: *2 pipe write downstream: 1
4112017/07/21 08:44:40 [debug] 12535#12535: *2 event timer: 33, old: 1500629680085, new: 1500629680085
4122017/07/21 08:44:40 [debug] 12535#12535: *2 http file cache update
4132017/07/21 08:44:40 [debug] 12535#12535: *2 http file cache rename: "/var/lib/nginx/fastcgi/1/00/0000000001" to "/dev/shm/nginx/4/55/3ecfc32a6a28e8e9a177aff5ac3cd554"
4142017/07/21 08:44:40 [debug] 12535#12535: *2 malloc: 00007F93E800CC10:64
4152017/07/21 08:44:40 [debug] 12535#12535: *2 malloc: 00007F93E80107D0:462
4162017/07/21 08:44:40 [debug] 12535#12535: *2 http upstream exit: 0000000000000000
4172017/07/21 08:44:40 [debug] 12535#12535: *2 finalize http upstream request: 0
4182017/07/21 08:44:40 [debug] 12535#12535: *2 finalize http fastcgi request
4192017/07/21 08:44:40 [debug] 12535#12535: *2 free rr peer 2 0
4202017/07/21 08:44:40 [debug] 12535#12535: *2 close http upstream connection: 33
4212017/07/21 08:44:40 [debug] 12535#12535: *2 free: 00007F93E80036F0, unused: 48
4222017/07/21 08:44:40 [debug] 12535#12535: *2 event timer del: 33: 1500629680085
4232017/07/21 08:44:40 [debug] 12535#12535: *2 reusable connection: 0
4242017/07/21 08:44:40 [debug] 12535#12535: *2 http upstream temp fd: 31
4252017/07/21 08:44:40 [debug] 12535#12535: *2 http finalize request: 0, "/test.php?" a:0, c:1
4262017/07/21 08:44:40 [debug] 12535#12535: *2 http request count:1 blk:0
4272017/07/21 08:44:40 [debug] 12535#12535: *2 http close request
4282017/07/21 08:44:40 [debug] 12535#12535: *2 http log handler
4292017/07/21 08:44:40 [debug] 12535#12535: *2 http map started
4302017/07/21 08:44:40 [debug] 12535#12535: *2 http script var: "curl/7.51.0"
4312017/07/21 08:44:40 [debug] 12535#12535: *2 http map: "curl/7.51.0" "1"
4322017/07/21 08:44:40 [debug] 12535#12535: *2 http script var: "1"
4332017/07/21 08:44:40 [debug] 12535#12535: *2 http script var: "1"
4342017/07/21 08:44:40 [debug] 12535#12535: *2 run cleanup: 00007F93E7F56DF8
4352017/07/21 08:44:40 [debug] 12535#12535: *2 file cleanup: fd:31
4362017/07/21 08:44:40 [debug] 12535#12535: *2 run cleanup: 00007F93E7F56368
4372017/07/21 08:44:40 [debug] 12535#12535: *2 run cleanup: 00007F93E7F3EAD8
4382017/07/21 08:44:40 [debug] 12535#12535: *2 file cleanup: fd:29
4392017/07/21 08:44:40 [debug] 12535#12535: *2 run cleanup: 00007F93E7F3EAC0
4402017/07/21 08:44:40 [debug] 12535#12535: *2 http file cache cleanup
4412017/07/21 08:44:40 [debug] 12535#12535: *2 http file cache free, fd: 29
4422017/07/21 08:44:40 [debug] 12535#12535: *2 run cleanup: 00007F93E801F078
4432017/07/21 08:44:40 [debug] 12535#12535: *2 free: 00007F93E7F36400
4442017/07/21 08:44:40 [debug] 12535#12535: *2 free: 00007F93E801E360, unused: 1
4452017/07/21 08:44:40 [debug] 12535#12535: *2 free: 00007F93E7F3DAF0, unused: 0
4462017/07/21 08:44:40 [debug] 12535#12535: *2 free: 00007F93E800F280, unused: 0
4472017/07/21 08:44:40 [debug] 12535#12535: *2 free: 00007F93E7F556D0, unused: 8
4482017/07/21 08:44:40 [debug] 12535#12535: *2 free: 00007F93E7F566E0, unused: 1721
4492017/07/21 08:44:40 [debug] 12535#12535: *2 close http connection: 27
4502017/07/21 08:44:40 [debug] 12535#12535: *2 reusable connection: 0
4512017/07/21 08:44:40 [debug] 12535#12535: *2 free: 00007F93E7F30C70
4522017/07/21 08:44:40 [debug] 12535#12535: *2 free: 00007F93E802E580, unused: 104