Skip to content

Instantly share code, notes, and snippets.

@oschaaf

oschaaf/error.log

Created April 13, 2016 11:49
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save oschaaf/281b7a0fed9954dd960adac55e96f2cd to your computer and use it in GitHub Desktop.
Save oschaaf/281b7a0fed9954dd960adac55e96f2cd to your computer and use it in GitHub Desktop.
Download ZIP
nginx 1.9.14 rate limiting + http/2
Raw
error.log
2016/04/13 13:45:42 [debug] 27207#0: *2 SSL_write: 13
2016/04/13 13:45:42 [debug] 27207#0: *2 http2 frame sent: 0000000001E6DEC8 sid:0 bl:0 len:4
2016/04/13 13:45:42 [debug] 27207#0: *2 free: 0000000001E6DC70, unused: 3320
2016/04/13 13:45:42 [debug] 27207#0: *2 free: 0000000001EEF080
2016/04/13 13:45:42 [debug] 27207#0: *2 reusable connection: 1
2016/04/13 13:45:42 [debug] 27207#0: *2 event timer del: 9: 1460548122266
2016/04/13 13:45:42 [debug] 27207#0: *2 event timer add: 9: 180000:1460548122683
2016/04/13 13:45:42 [debug] 27207#0: timer delta: 417
2016/04/13 13:45:42 [debug] 27207#0: worker cycle
2016/04/13 13:45:42 [debug] 27207#0: epoll timer: 180000
2016/04/13 13:46:22 [debug] 27207#0: epoll: fd:9 ev:0001 d:0000000001E87211
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 idle handler
2016/04/13 13:46:22 [debug] 27207#0: *2 reusable connection: 0
2016/04/13 13:46:22 [debug] 27207#0: *2 posix_memalign: 0000000001E6DC70:4096 @16
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 read handler
2016/04/13 13:46:22 [debug] 27207#0: *2 SSL_read: 61
2016/04/13 13:46:22 [debug] 27207#0: *2 SSL_read: 17
2016/04/13 13:46:22 [debug] 27207#0: *2 SSL_read: -1
2016/04/13 13:46:22 [debug] 27207#0: *2 SSL_get_error: 2
2016/04/13 13:46:22 [debug] 27207#0: *2 process http2 frame type:1 f:24 l:52 sid:551
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 HEADERS frame sid:551 on 0 excl:0 weight:256
2016/04/13 13:46:22 [debug] 27207#0: *2 posix_memalign: 0000000001E5CF60:1024 @16
2016/04/13 13:46:22 [debug] 27207#0: *2 posix_memalign: 0000000001EFD740:4096 @16
2016/04/13 13:46:22 [debug] 27207#0: *2 posix_memalign: 0000000001EFE750:4096 @16
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 3
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 84
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 7
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 hpack encoded string length: 4
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 hpack encoded string length: 20
2016/04/13 13:46:22 [debug] 27207#0: *2 http uri: "/mod_pagespeed_test/test.php"
2016/04/13 13:46:22 [debug] 27207#0: *2 http args: ""
2016/04/13 13:46:22 [debug] 27207#0: *2 http exten: "php"
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 69
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 http header: "content-length: 15"
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 63
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 http header: "pragma: no-cache"
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 62
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 http header: "cache-control: no-cache"
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 82
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 http header: "accept: text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,*/*;q=0.8"
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 68
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 http header: "origin: https://192.168.137.10:4430"
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 81
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 http header: "upgrade-insecure-requests: 1"
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 80
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 http header: "user-agent: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/49.0.2623.112 Safari/537.36"
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 67
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 http header: "content-type: application/x-www-form-urlencoded"
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 70
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 http header: "referer: https://192.168.137.10:4430/mod_pagespeed_test/test.php"
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 66
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 http header: "accept-encoding: gzip, deflate, br"
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 78
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 http header: "accept-language: en-US,en;q=0.8,nl;q=0.6"
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 77
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 76
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 75
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 74
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 73
2016/04/13 13:46:22 [debug] 27207#0: *2 posix_memalign: 0000000001F011C0:1024 @16
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 get indexed header name: 72
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 http request line: "POST /mod_pagespeed_test/test.php HTTP/2.0"
2016/04/13 13:46:22 [debug] 27207#0: *2 rewrite phase: 0
2016/04/13 13:46:22 [debug] 27207#0: *2 test location: "/50x.html"
2016/04/13 13:46:22 [debug] 27207#0: *2 test location: ~ "\.php$"
2016/04/13 13:46:22 [debug] 27207#0: *2 using configuration "\.php$"
2016/04/13 13:46:22 [debug] 27207#0: *2 http cl:15 max:1048576
2016/04/13 13:46:22 [debug] 27207#0: *2 rewrite phase: 2
2016/04/13 13:46:22 [debug] 27207#0: *2 post rewrite phase: 3
2016/04/13 13:46:22 [debug] 27207#0: *2 generic phase: 4
2016/04/13 13:46:22 [debug] 27207#0: *2 http script var: "���"
2016/04/13 13:46:22 [debug] 27207#0: shmtx lock
2016/04/13 13:46:22 [debug] 27207#0: shmtx unlock
2016/04/13 13:46:22 [debug] 27207#0: *2 limit_req[0]: -2 0.000
2016/04/13 13:46:22 [debug] 27207#0: *2 http script var: "localhost"
2016/04/13 13:46:22 [debug] 27207#0: shmtx lock
2016/04/13 13:46:22 [debug] 27207#0: shmtx unlock
2016/04/13 13:46:22 [debug] 27207#0: *2 limit_req[1]: 0 0.000
2016/04/13 13:46:22 [debug] 27207#0: shmtx lock
2016/04/13 13:46:22 [debug] 27207#0: shmtx unlock
2016/04/13 13:46:22 [debug] 27207#0: *2 generic phase: 5
2016/04/13 13:46:22 [debug] 27207#0: *2 access phase: 6
2016/04/13 13:46:22 [debug] 27207#0: *2 access phase: 7
2016/04/13 13:46:22 [debug] 27207#0: *2 post access phase: 8
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 send WINDOW_UPDATE frame sid:551, window:2147483647
2016/04/13 13:46:22 [debug] 27207#0: *2 event timer add: 9: 60000:1460548042682
2016/04/13 13:46:22 [debug] 27207#0: *2 http finalize request: -4, "/mod_pagespeed_test/test.php?" a:1, c:2
2016/04/13 13:46:22 [debug] 27207#0: *2 http request count:2 blk:0
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 frame complete pos:0000000001F0286D end:0000000001F0287E
2016/04/13 13:46:22 [debug] 27207#0: *2 process http2 frame type:6 f:0 l:8 sid:0
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 PING frame, flags: 0
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 frame complete pos:0000000001F0287E end:0000000001F0287E
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 frame out: 0000000001E6DF78 sid:0 bl:0 len:8
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 frame out: 0000000001E6DEC8 sid:0 bl:0 len:4
2016/04/13 13:46:22 [debug] 27207#0: *2 malloc: 0000000001EEF080:16384
2016/04/13 13:46:22 [debug] 27207#0: *2 SSL buf copy: 13
2016/04/13 13:46:22 [debug] 27207#0: *2 SSL buf copy: 17
2016/04/13 13:46:22 [debug] 27207#0: *2 SSL to write: 30
2016/04/13 13:46:22 [debug] 27207#0: *2 SSL_write: 30
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 frame sent: 0000000001E6DEC8 sid:0 bl:0 len:4
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 frame sent: 0000000001E6DF78 sid:0 bl:0 len:8
2016/04/13 13:46:22 [debug] 27207#0: *2 event timer del: 9: 1460548122683
2016/04/13 13:46:22 [debug] 27207#0: timer delta: 39999
2016/04/13 13:46:22 [debug] 27207#0: worker cycle
2016/04/13 13:46:22 [debug] 27207#0: epoll timer: 60000
2016/04/13 13:46:22 [debug] 27207#0: epoll: fd:9 ev:0001 d:0000000001E87211
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 read handler
2016/04/13 13:46:22 [debug] 27207#0: *2 SSL_read: 24
2016/04/13 13:46:22 [debug] 27207#0: *2 SSL_read: -1
2016/04/13 13:46:22 [debug] 27207#0: *2 SSL_get_error: 2
2016/04/13 13:46:22 [debug] 27207#0: *2 process http2 frame type:0 f:1 l:15 sid:551
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 DATA frame
2016/04/13 13:46:22 [debug] 27207#0: *2 event timer del: 9: 1460548042682
2016/04/13 13:46:22 [debug] 27207#0: *2 http body new buf t:1 f:0 0000000001EFF518, pos 0000000001EFF518, size: 15 file: 0, size: 0
2016/04/13 13:46:22 [debug] 27207#0: *2 http init upstream, client timer: 0
2016/04/13 13:46:22 [debug] 27207#0: *2 posix_memalign: 0000000001EF3090:4096 @16
2016/04/13 13:46:22 [debug] 27207#0: *2 http script copy: "SCRIPT_FILENAME"
2016/04/13 13:46:22 [debug] 27207#0: *2 http script var: "/home/oschaaf/code/google/mps-issue-1175//src/install/mod_pagespeed_test/test.php"
2016/04/13 13:46:22 [debug] 27207#0: *2 fastcgi param: "SCRIPT_FILENAME: /home/oschaaf/code/google/mps-issue-1175//src/install/mod_pagespeed_test/test.php"
2016/04/13 13:46:22 [debug] 27207#0: *2 http script copy: "QUERY_STRING"
2016/04/13 13:46:22 [debug] 27207#0: *2 fastcgi param: "QUERY_STRING: "
2016/04/13 13:46:22 [debug] 27207#0: *2 http script copy: "REQUEST_METHOD"
2016/04/13 13:46:22 [debug] 27207#0: *2 http script var: "POST"
2016/04/13 13:46:22 [debug] 27207#0: *2 fastcgi param: "REQUEST_METHOD: POST"
2016/04/13 13:46:22 [debug] 27207#0: *2 http script copy: "CONTENT_TYPE"
2016/04/13 13:46:22 [debug] 27207#0: *2 http script var: "application/x-www-form-urlencoded"
2016/04/13 13:46:22 [debug] 27207#0: *2 fastcgi param: "CONTENT_TYPE: application/x-www-form-urlencoded"
2016/04/13 13:46:22 [debug] 27207#0: *2 http script copy: "CONTENT_LENGTH"
2016/04/13 13:46:22 [debug] 27207#0: *2 http script var: "15"
2016/04/13 13:46:22 [debug] 27207#0: *2 fastcgi param: "CONTENT_LENGTH: 15"
2016/04/13 13:46:22 [debug] 27207#0: *2 fastcgi param: "HTTP_HOST: 192.168.137.10:4430"
2016/04/13 13:46:22 [debug] 27207#0: *2 fastcgi param: "HTTP_CONTENT_LENGTH: 15"
2016/04/13 13:46:22 [debug] 27207#0: *2 fastcgi param: "HTTP_PRAGMA: no-cache"
2016/04/13 13:46:22 [debug] 27207#0: *2 fastcgi param: "HTTP_CACHE_CONTROL: no-cache"
2016/04/13 13:46:22 [debug] 27207#0: *2 fastcgi param: "HTTP_ACCEPT: text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,*/*;q=0.8"
2016/04/13 13:46:22 [debug] 27207#0: *2 fastcgi param: "HTTP_ORIGIN: https://192.168.137.10:4430"
2016/04/13 13:46:22 [debug] 27207#0: *2 fastcgi param: "HTTP_UPGRADE_INSECURE_REQUESTS: 1"
2016/04/13 13:46:22 [debug] 27207#0: *2 fastcgi param: "HTTP_USER_AGENT: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/49.0.2623.112 Safari/537.36"
2016/04/13 13:46:22 [debug] 27207#0: *2 fastcgi param: "HTTP_CONTENT_TYPE: application/x-www-form-urlencoded"
2016/04/13 13:46:22 [debug] 27207#0: *2 fastcgi param: "HTTP_REFERER: https://192.168.137.10:4430/mod_pagespeed_test/test.php"
2016/04/13 13:46:22 [debug] 27207#0: *2 fastcgi param: "HTTP_ACCEPT_ENCODING: gzip, deflate, br"
2016/04/13 13:46:22 [debug] 27207#0: *2 fastcgi param: "HTTP_ACCEPT_LANGUAGE: en-US,en;q=0.8,nl;q=0.6"
2016/04/13 13:46:22 [debug] 27207#0: *2 fastcgi param: "HTTP_COOKIE: __utma=249880774.1383284618.1457435953.1457435953.1457435953.1; __utmz=249880774.1457435953.1.1.utmcsr=(direct)|utmccn=(direct)|utmcmd=(none); COMMERCE_SITE_PreviousCatalogPage=http://www.overland.com:80/default.aspx; _ga=GA1.4.1383284618.1457435953; __ar_v4=A2DMCTE7MBFGDNK5YEWLMH%3A20160307%3A6%7C3546UXGADRAB7D4T22VG7L%3A20160307%3A179%7CVINBB5DOHFDPZAD42RWWE6%3A20160307%3A179%7C6BHUC3GFKFCJLNRPPUHMNS%3A20160307%3A156%7CRDW3YUET4NBAFGEQUT65ET%3A20160307%3A17; _hp2_id.3446894088=7920717152696490.3996779594.2723900530"
2016/04/13 13:46:22 [debug] 27207#0: *2 http cleanup add: 0000000001EF3650
2016/04/13 13:46:22 [debug] 27207#0: *2 get rr peer, try: 1
2016/04/13 13:46:22 [debug] 27207#0: *2 stream socket 10
2016/04/13 13:46:22 [debug] 27207#0: *2 epoll add connection: fd:10 ev:80002005
2016/04/13 13:46:22 [debug] 27207#0: *2 connect to 127.0.0.1:9000, fd:10 #79
2016/04/13 13:46:22 [debug] 27207#0: *2 http upstream connect: -2
2016/04/13 13:46:22 [debug] 27207#0: *2 posix_memalign: 0000000001E65AC0:128 @16
2016/04/13 13:46:22 [debug] 27207#0: *2 event timer add: 10: 60000:1460548042683
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 frame complete pos:0000000001F02848 end:0000000001F02848
2016/04/13 13:46:22 [debug] 27207#0: timer delta: 1
2016/04/13 13:46:22 [debug] 27207#0: worker cycle
2016/04/13 13:46:22 [debug] 27207#0: epoll timer: 60000
2016/04/13 13:46:22 [debug] 27207#0: epoll: fd:10 ev:0004 d:0000000001E872F0
2016/04/13 13:46:22 [debug] 27207#0: *2 http upstream request: "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:22 [debug] 27207#0: *2 http upstream send request handler
2016/04/13 13:46:22 [debug] 27207#0: *2 http upstream send request
2016/04/13 13:46:22 [debug] 27207#0: *2 http upstream send request body
2016/04/13 13:46:22 [debug] 27207#0: *2 chain writer buf fl:0 s:1376
2016/04/13 13:46:22 [debug] 27207#0: *2 chain writer buf fl:0 s:15
2016/04/13 13:46:22 [debug] 27207#0: *2 chain writer buf fl:0 s:9
2016/04/13 13:46:22 [debug] 27207#0: *2 chain writer in: 0000000001EF36B8
2016/04/13 13:46:22 [debug] 27207#0: *2 writev: 1400 of 1400
2016/04/13 13:46:22 [debug] 27207#0: *2 chain writer out: 0000000000000000
2016/04/13 13:46:22 [debug] 27207#0: *2 event timer del: 10: 1460548042683
2016/04/13 13:46:22 [debug] 27207#0: *2 event timer add: 10: 60000:1460548042683
2016/04/13 13:46:22 [debug] 27207#0: timer delta: 0
2016/04/13 13:46:22 [debug] 27207#0: worker cycle
2016/04/13 13:46:22 [debug] 27207#0: epoll timer: 60000
2016/04/13 13:46:22 [debug] 27207#0: epoll: fd:10 ev:2005 d:0000000001E872F0
2016/04/13 13:46:22 [debug] 27207#0: *2 http upstream request: "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:22 [debug] 27207#0: *2 http upstream process header
2016/04/13 13:46:22 [debug] 27207#0: *2 malloc: 0000000001F42840:4096
2016/04/13 13:46:22 [debug] 27207#0: *2 recv: fd:10 528 of 4096
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi record byte: 01
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi record byte: 06
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi record byte: 00
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi record byte: 01
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi record byte: 01
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi record byte: F3
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi record byte: 05
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi record byte: 00
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi record length: 499
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi parser: 0
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi header: "X-Powered-By: PHP/5.5.9-1ubuntu4.14"
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi parser: 0
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi header: "Content-type: text/html"
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi parser: 1
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi header done
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 header filter
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 output header: ":status: 200"
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 output header: "server: nginx/1.9.14"
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 output header: "date: Wed, 13 Apr 2016 11:46:22 GMT"
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 output header: "content-type: text/html"
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 output header: "x-powered-by: PHP/5.5.9-1ubuntu4.14"
2016/04/13 13:46:22 [debug] 27207#0: *2 http2:551 create HEADERS frame 0000000001EF3B50: len:73
2016/04/13 13:46:22 [debug] 27207#0: *2 http cleanup add: 0000000001EF3C38
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 frame out: 0000000001EF3B50 sid:551 bl:1 len:73
2016/04/13 13:46:22 [debug] 27207#0: *2 SSL buf copy: 9
2016/04/13 13:46:22 [debug] 27207#0: *2 SSL buf copy: 73
2016/04/13 13:46:22 [debug] 27207#0: *2 http2:551 HEADERS frame 0000000001EF3B50 was sent
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 frame sent: 0000000001EF3B50 sid:551 bl:1 len:73
2016/04/13 13:46:22 [debug] 27207#0: *2 http cacheable: 0
2016/04/13 13:46:22 [debug] 27207#0: *2 http upstream process upstream
2016/04/13 13:46:22 [debug] 27207#0: *2 pipe read upstream: 1
2016/04/13 13:46:22 [debug] 27207#0: *2 pipe preread: 456
2016/04/13 13:46:22 [debug] 27207#0: *2 readv: 1, last:3568
2016/04/13 13:46:22 [debug] 27207#0: *2 pipe recv chain: 0
2016/04/13 13:46:22 [debug] 27207#0: *2 pipe buf free s:0 t:1 f:0 0000000001F42840, pos 0000000001F42888, size: 456 file: 0, size: 0
2016/04/13 13:46:22 [debug] 27207#0: *2 pipe length: -1
2016/04/13 13:46:22 [debug] 27207#0: *2 input buf #0 0000000001F42888
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi record byte: 01
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi record byte: 03
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi record byte: 00
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi record byte: 01
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi record byte: 00
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi record byte: 08
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi record byte: 00
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi record byte: 00
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi record length: 8
2016/04/13 13:46:22 [debug] 27207#0: *2 http fastcgi sent end request
2016/04/13 13:46:22 [debug] 27207#0: *2 input buf 0000000001F42888 435
2016/04/13 13:46:22 [debug] 27207#0: *2 pipe write downstream: 1
2016/04/13 13:46:22 [debug] 27207#0: *2 pipe write downstream flush in
2016/04/13 13:46:22 [debug] 27207#0: *2 http output filter "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:22 [debug] 27207#0: *2 http copy filter: "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:22 [debug] 27207#0: *2 http ssi filter "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:22 [debug] 27207#0: *2 saved: 0 state: 0
2016/04/13 13:46:22 [debug] 27207#0: *2 parse: -2, looked: 0 0000000001F42888-0000000001F42A3B
2016/04/13 13:46:22 [debug] 27207#0: *2 saved: 0
2016/04/13 13:46:22 [debug] 27207#0: *2 ssi out: 0000000001EF3E00 0000000001F42888
2016/04/13 13:46:22 [debug] 27207#0: *2 http postpone filter "/mod_pagespeed_test/test.php?" 0000000001EF36B8
2016/04/13 13:46:22 [debug] 27207#0: *2 write new buf t:1 f:0 0000000001F42840, pos 0000000001F42888, size: 435 file: 0, size: 0
2016/04/13 13:46:22 [debug] 27207#0: *2 http write filter: l:0 f:0 s:435
2016/04/13 13:46:22 [debug] 27207#0: *2 http copy filter: 0 "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:22 [debug] 27207#0: *2 pipe write downstream done
2016/04/13 13:46:22 [debug] 27207#0: *2 event timer del: 10: 1460548042683
2016/04/13 13:46:22 [debug] 27207#0: *2 http upstream exit: 0000000000000000
2016/04/13 13:46:22 [debug] 27207#0: *2 finalize http upstream request: 0
2016/04/13 13:46:22 [debug] 27207#0: *2 finalize http fastcgi request
2016/04/13 13:46:22 [debug] 27207#0: *2 free rr peer 1 0
2016/04/13 13:46:22 [debug] 27207#0: *2 close http upstream connection: 10
2016/04/13 13:46:22 [debug] 27207#0: *2 free: 0000000001E65AC0, unused: 48
2016/04/13 13:46:22 [debug] 27207#0: *2 reusable connection: 0
2016/04/13 13:46:22 [debug] 27207#0: *2 http upstream temp fd: -1
2016/04/13 13:46:22 [debug] 27207#0: *2 http output filter "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:22 [debug] 27207#0: *2 http copy filter: "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:22 [debug] 27207#0: *2 http ssi filter "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:22 [debug] 27207#0: *2 ssi out: 0000000001EF3EC0 0000000000000000
2016/04/13 13:46:22 [debug] 27207#0: *2 http postpone filter "/mod_pagespeed_test/test.php?" 0000000001EF3F10
2016/04/13 13:46:22 [debug] 27207#0: *2 write old buf t:1 f:0 0000000001F42840, pos 0000000001F42888, size: 435 file: 0, size: 0
2016/04/13 13:46:22 [debug] 27207#0: *2 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
2016/04/13 13:46:22 [debug] 27207#0: *2 http write filter: l:1 f:0 s:435
2016/04/13 13:46:22 [debug] 27207#0: *2 http write filter limit 0
2016/04/13 13:46:22 [debug] 27207#0: *2 http2:551 create DATA frame 0000000001EF3B50: len:435 flags:1
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 frame out: 0000000001EF3B50 sid:551 bl:0 len:435
2016/04/13 13:46:22 [debug] 27207#0: *2 SSL buf copy: 9
2016/04/13 13:46:22 [debug] 27207#0: *2 SSL buf copy: 435
2016/04/13 13:46:22 [debug] 27207#0: *2 SSL to write: 526
2016/04/13 13:46:22 [debug] 27207#0: *2 SSL_write: 526
2016/04/13 13:46:22 [debug] 27207#0: *2 http2:551 DATA frame 0000000001EF3B50 was sent
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 frame sent: 0000000001EF3B50 sid:551 bl:0 len:435
2016/04/13 13:46:22 [debug] 27207#0: *2 http write filter 0000000000000000
2016/04/13 13:46:22 [debug] 27207#0: *2 http copy filter: 0 "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:22 [debug] 27207#0: *2 http finalize request: 0, "/mod_pagespeed_test/test.php?" a:1, c:1
2016/04/13 13:46:22 [debug] 27207#0: *2 http request count:1 blk:0
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 close stream 551, queued 0, processing 1
2016/04/13 13:46:22 [debug] 27207#0: *2 http close request
2016/04/13 13:46:22 [debug] 27207#0: *2 http log handler
2016/04/13 13:46:22 [debug] 27207#0: *2 free: 0000000001F42840
2016/04/13 13:46:22 [debug] 27207#0: *2 free: 0000000001EFD740, unused: 0
2016/04/13 13:46:22 [debug] 27207#0: *2 free: 0000000001EFE750, unused: 8
2016/04/13 13:46:22 [debug] 27207#0: *2 free: 0000000001EF3090, unused: 92
2016/04/13 13:46:22 [debug] 27207#0: *2 free: 0000000001E5CF60, unused: 17
2016/04/13 13:46:22 [debug] 27207#0: *2 free: 0000000001F011C0, unused: 779
2016/04/13 13:46:22 [debug] 27207#0: *2 post event 0000000001EBF120
2016/04/13 13:46:22 [debug] 27207#0: timer delta: 0
2016/04/13 13:46:22 [debug] 27207#0: posted event 0000000001EBF120
2016/04/13 13:46:22 [debug] 27207#0: *2 delete posted event 0000000001EBF120
2016/04/13 13:46:22 [debug] 27207#0: *2 http2 handle connection handler
2016/04/13 13:46:22 [debug] 27207#0: *2 free: 0000000001E6DC70, unused: 3144
2016/04/13 13:46:22 [debug] 27207#0: *2 free: 0000000001EEF080
2016/04/13 13:46:22 [debug] 27207#0: *2 reusable connection: 1
2016/04/13 13:46:22 [debug] 27207#0: *2 event timer add: 9: 180000:1460548162683
2016/04/13 13:46:22 [debug] 27207#0: worker cycle
2016/04/13 13:46:22 [debug] 27207#0: epoll timer: 180000
2016/04/13 13:46:23 [debug] 27207#0: epoll: fd:9 ev:0001 d:0000000001E87211
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 idle handler
2016/04/13 13:46:23 [debug] 27207#0: *2 reusable connection: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 posix_memalign: 0000000001E6DC70:4096 @16
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 read handler
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL_read: 61
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL_read: -1
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL_get_error: 2
2016/04/13 13:46:23 [debug] 27207#0: *2 process http2 frame type:1 f:24 l:52 sid:553
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 HEADERS frame sid:553 on 0 excl:0 weight:256
2016/04/13 13:46:23 [debug] 27207#0: *2 posix_memalign: 0000000001E5CF60:1024 @16
2016/04/13 13:46:23 [debug] 27207#0: *2 posix_memalign: 0000000001EFD740:4096 @16
2016/04/13 13:46:23 [debug] 27207#0: *2 posix_memalign: 0000000001EFE750:4096 @16
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 3
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 84
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 7
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 hpack encoded string length: 4
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 hpack encoded string length: 20
2016/04/13 13:46:23 [debug] 27207#0: *2 http uri: "/mod_pagespeed_test/test.php"
2016/04/13 13:46:23 [debug] 27207#0: *2 http args: ""
2016/04/13 13:46:23 [debug] 27207#0: *2 http exten: "php"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 69
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "content-length: 15"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 63
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "pragma: no-cache"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 62
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "cache-control: no-cache"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 82
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "accept: text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,*/*;q=0.8"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 68
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "origin: https://192.168.137.10:4430"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 81
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "upgrade-insecure-requests: 1"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 80
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "user-agent: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/49.0.2623.112 Safari/537.36"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 67
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "content-type: application/x-www-form-urlencoded"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 70
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "referer: https://192.168.137.10:4430/mod_pagespeed_test/test.php"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 66
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "accept-encoding: gzip, deflate, br"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 78
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "accept-language: en-US,en;q=0.8,nl;q=0.6"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 77
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 76
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 75
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 74
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 73
2016/04/13 13:46:23 [debug] 27207#0: *2 posix_memalign: 0000000001F011C0:1024 @16
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 72
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http request line: "POST /mod_pagespeed_test/test.php HTTP/2.0"
2016/04/13 13:46:23 [debug] 27207#0: *2 rewrite phase: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 test location: "/50x.html"
2016/04/13 13:46:23 [debug] 27207#0: *2 test location: ~ "\.php$"
2016/04/13 13:46:23 [debug] 27207#0: *2 using configuration "\.php$"
2016/04/13 13:46:23 [debug] 27207#0: *2 http cl:15 max:1048576
2016/04/13 13:46:23 [debug] 27207#0: *2 rewrite phase: 2
2016/04/13 13:46:23 [debug] 27207#0: *2 post rewrite phase: 3
2016/04/13 13:46:23 [debug] 27207#0: *2 generic phase: 4
2016/04/13 13:46:23 [debug] 27207#0: *2 http script var: "���"
2016/04/13 13:46:23 [debug] 27207#0: shmtx lock
2016/04/13 13:46:23 [debug] 27207#0: shmtx unlock
2016/04/13 13:46:23 [debug] 27207#0: *2 limit_req[0]: -2 0.606
2016/04/13 13:46:23 [debug] 27207#0: *2 http script var: "localhost"
2016/04/13 13:46:23 [debug] 27207#0: shmtx lock
2016/04/13 13:46:23 [debug] 27207#0: shmtx unlock
2016/04/13 13:46:23 [debug] 27207#0: *2 limit_req[1]: 0 0.000
2016/04/13 13:46:23 [debug] 27207#0: shmtx lock
2016/04/13 13:46:23 [debug] 27207#0: shmtx unlock
2016/04/13 13:46:23 [debug] 27207#0: *2 generic phase: 5
2016/04/13 13:46:23 [debug] 27207#0: *2 access phase: 6
2016/04/13 13:46:23 [debug] 27207#0: *2 access phase: 7
2016/04/13 13:46:23 [debug] 27207#0: *2 post access phase: 8
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 send WINDOW_UPDATE frame sid:553, window:2147483647
2016/04/13 13:46:23 [debug] 27207#0: *2 event timer add: 9: 60000:1460548043076
2016/04/13 13:46:23 [debug] 27207#0: *2 http finalize request: -4, "/mod_pagespeed_test/test.php?" a:1, c:2
2016/04/13 13:46:23 [debug] 27207#0: *2 http request count:2 blk:0
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 frame complete pos:0000000001F0286D end:0000000001F0286D
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 frame out: 0000000001E6DEC8 sid:0 bl:0 len:4
2016/04/13 13:46:23 [debug] 27207#0: *2 malloc: 0000000001EEF080:16384
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL buf copy: 13
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL to write: 13
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL_write: 13
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 frame sent: 0000000001E6DEC8 sid:0 bl:0 len:4
2016/04/13 13:46:23 [debug] 27207#0: *2 event timer del: 9: 1460548162683
2016/04/13 13:46:23 [debug] 27207#0: timer delta: 393
2016/04/13 13:46:23 [debug] 27207#0: worker cycle
2016/04/13 13:46:23 [debug] 27207#0: epoll timer: 60000
2016/04/13 13:46:23 [debug] 27207#0: epoll: fd:9 ev:0001 d:0000000001E87211
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 read handler
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL_read: 24
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL_read: -1
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL_get_error: 2
2016/04/13 13:46:23 [debug] 27207#0: *2 process http2 frame type:0 f:1 l:15 sid:553
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 DATA frame
2016/04/13 13:46:23 [debug] 27207#0: *2 event timer del: 9: 1460548043076
2016/04/13 13:46:23 [debug] 27207#0: *2 http body new buf t:1 f:0 0000000001EFF518, pos 0000000001EFF518, size: 15 file: 0, size: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 http init upstream, client timer: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 posix_memalign: 0000000001EF3090:4096 @16
2016/04/13 13:46:23 [debug] 27207#0: *2 http script copy: "SCRIPT_FILENAME"
2016/04/13 13:46:23 [debug] 27207#0: *2 http script var: "/home/oschaaf/code/google/mps-issue-1175//src/install/mod_pagespeed_test/test.php"
2016/04/13 13:46:23 [debug] 27207#0: *2 fastcgi param: "SCRIPT_FILENAME: /home/oschaaf/code/google/mps-issue-1175//src/install/mod_pagespeed_test/test.php"
2016/04/13 13:46:23 [debug] 27207#0: *2 http script copy: "QUERY_STRING"
2016/04/13 13:46:23 [debug] 27207#0: *2 fastcgi param: "QUERY_STRING: "
2016/04/13 13:46:23 [debug] 27207#0: *2 http script copy: "REQUEST_METHOD"
2016/04/13 13:46:23 [debug] 27207#0: *2 http script var: "POST"
2016/04/13 13:46:23 [debug] 27207#0: *2 fastcgi param: "REQUEST_METHOD: POST"
2016/04/13 13:46:23 [debug] 27207#0: *2 http script copy: "CONTENT_TYPE"
2016/04/13 13:46:23 [debug] 27207#0: *2 http script var: "application/x-www-form-urlencoded"
2016/04/13 13:46:23 [debug] 27207#0: *2 fastcgi param: "CONTENT_TYPE: application/x-www-form-urlencoded"
2016/04/13 13:46:23 [debug] 27207#0: *2 http script copy: "CONTENT_LENGTH"
2016/04/13 13:46:23 [debug] 27207#0: *2 http script var: "15"
2016/04/13 13:46:23 [debug] 27207#0: *2 fastcgi param: "CONTENT_LENGTH: 15"
2016/04/13 13:46:23 [debug] 27207#0: *2 fastcgi param: "HTTP_HOST: 192.168.137.10:4430"
2016/04/13 13:46:23 [debug] 27207#0: *2 fastcgi param: "HTTP_CONTENT_LENGTH: 15"
2016/04/13 13:46:23 [debug] 27207#0: *2 fastcgi param: "HTTP_PRAGMA: no-cache"
2016/04/13 13:46:23 [debug] 27207#0: *2 fastcgi param: "HTTP_CACHE_CONTROL: no-cache"
2016/04/13 13:46:23 [debug] 27207#0: *2 fastcgi param: "HTTP_ACCEPT: text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,*/*;q=0.8"
2016/04/13 13:46:23 [debug] 27207#0: *2 fastcgi param: "HTTP_ORIGIN: https://192.168.137.10:4430"
2016/04/13 13:46:23 [debug] 27207#0: *2 fastcgi param: "HTTP_UPGRADE_INSECURE_REQUESTS: 1"
2016/04/13 13:46:23 [debug] 27207#0: *2 fastcgi param: "HTTP_USER_AGENT: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/49.0.2623.112 Safari/537.36"
2016/04/13 13:46:23 [debug] 27207#0: *2 fastcgi param: "HTTP_CONTENT_TYPE: application/x-www-form-urlencoded"
2016/04/13 13:46:23 [debug] 27207#0: *2 fastcgi param: "HTTP_REFERER: https://192.168.137.10:4430/mod_pagespeed_test/test.php"
2016/04/13 13:46:23 [debug] 27207#0: *2 fastcgi param: "HTTP_ACCEPT_ENCODING: gzip, deflate, br"
2016/04/13 13:46:23 [debug] 27207#0: *2 fastcgi param: "HTTP_ACCEPT_LANGUAGE: en-US,en;q=0.8,nl;q=0.6"
2016/04/13 13:46:23 [debug] 27207#0: *2 fastcgi param: "HTTP_COOKIE: __utma=249880774.1383284618.1457435953.1457435953.1457435953.1; __utmz=249880774.1457435953.1.1.utmcsr=(direct)|utmccn=(direct)|utmcmd=(none); COMMERCE_SITE_PreviousCatalogPage=http://www.overland.com:80/default.aspx; _ga=GA1.4.1383284618.1457435953; __ar_v4=A2DMCTE7MBFGDNK5YEWLMH%3A20160307%3A6%7C3546UXGADRAB7D4T22VG7L%3A20160307%3A179%7CVINBB5DOHFDPZAD42RWWE6%3A20160307%3A179%7C6BHUC3GFKFCJLNRPPUHMNS%3A20160307%3A156%7CRDW3YUET4NBAFGEQUT65ET%3A20160307%3A17; _hp2_id.3446894088=7920717152696490.3996779594.2723900530"
2016/04/13 13:46:23 [debug] 27207#0: *2 http cleanup add: 0000000001EF3650
2016/04/13 13:46:23 [debug] 27207#0: *2 get rr peer, try: 1
2016/04/13 13:46:23 [debug] 27207#0: *2 stream socket 10
2016/04/13 13:46:23 [debug] 27207#0: *2 epoll add connection: fd:10 ev:80002005
2016/04/13 13:46:23 [debug] 27207#0: *2 connect to 127.0.0.1:9000, fd:10 #80
2016/04/13 13:46:23 [debug] 27207#0: *2 http upstream connect: -2
2016/04/13 13:46:23 [debug] 27207#0: *2 posix_memalign: 0000000001E65AC0:128 @16
2016/04/13 13:46:23 [debug] 27207#0: *2 event timer add: 10: 60000:1460548043077
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 frame complete pos:0000000001F02848 end:0000000001F02848
2016/04/13 13:46:23 [debug] 27207#0: timer delta: 1
2016/04/13 13:46:23 [debug] 27207#0: worker cycle
2016/04/13 13:46:23 [debug] 27207#0: epoll timer: 60000
2016/04/13 13:46:23 [debug] 27207#0: epoll: fd:10 ev:0004 d:0000000001E872F1
2016/04/13 13:46:23 [debug] 27207#0: *2 http upstream request: "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:23 [debug] 27207#0: *2 http upstream send request handler
2016/04/13 13:46:23 [debug] 27207#0: *2 http upstream send request
2016/04/13 13:46:23 [debug] 27207#0: *2 http upstream send request body
2016/04/13 13:46:23 [debug] 27207#0: *2 chain writer buf fl:0 s:1376
2016/04/13 13:46:23 [debug] 27207#0: *2 chain writer buf fl:0 s:15
2016/04/13 13:46:23 [debug] 27207#0: *2 chain writer buf fl:0 s:9
2016/04/13 13:46:23 [debug] 27207#0: *2 chain writer in: 0000000001EF36B8
2016/04/13 13:46:23 [debug] 27207#0: *2 writev: 1400 of 1400
2016/04/13 13:46:23 [debug] 27207#0: *2 chain writer out: 0000000000000000
2016/04/13 13:46:23 [debug] 27207#0: *2 event timer del: 10: 1460548043077
2016/04/13 13:46:23 [debug] 27207#0: *2 event timer add: 10: 60000:1460548043078
2016/04/13 13:46:23 [debug] 27207#0: timer delta: 1
2016/04/13 13:46:23 [debug] 27207#0: worker cycle
2016/04/13 13:46:23 [debug] 27207#0: epoll timer: 60000
2016/04/13 13:46:23 [debug] 27207#0: epoll: fd:10 ev:2005 d:0000000001E872F1
2016/04/13 13:46:23 [debug] 27207#0: *2 http upstream request: "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:23 [debug] 27207#0: *2 http upstream process header
2016/04/13 13:46:23 [debug] 27207#0: *2 malloc: 0000000001F42840:4096
2016/04/13 13:46:23 [debug] 27207#0: *2 recv: fd:10 528 of 4096
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi record byte: 01
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi record byte: 06
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi record byte: 00
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi record byte: 01
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi record byte: 01
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi record byte: F3
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi record byte: 05
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi record byte: 00
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi record length: 499
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi parser: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi header: "X-Powered-By: PHP/5.5.9-1ubuntu4.14"
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi parser: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi header: "Content-type: text/html"
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi parser: 1
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi header done
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 header filter
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 output header: ":status: 200"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 output header: "server: nginx/1.9.14"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 output header: "date: Wed, 13 Apr 2016 11:46:23 GMT"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 output header: "content-type: text/html"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 output header: "x-powered-by: PHP/5.5.9-1ubuntu4.14"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2:553 create HEADERS frame 0000000001EF3B50: len:73
2016/04/13 13:46:23 [debug] 27207#0: *2 http cleanup add: 0000000001EF3C38
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 frame out: 0000000001EF3B50 sid:553 bl:1 len:73
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL buf copy: 9
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL buf copy: 73
2016/04/13 13:46:23 [debug] 27207#0: *2 http2:553 HEADERS frame 0000000001EF3B50 was sent
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 frame sent: 0000000001EF3B50 sid:553 bl:1 len:73
2016/04/13 13:46:23 [debug] 27207#0: *2 http cacheable: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 http upstream process upstream
2016/04/13 13:46:23 [debug] 27207#0: *2 pipe read upstream: 1
2016/04/13 13:46:23 [debug] 27207#0: *2 pipe preread: 456
2016/04/13 13:46:23 [debug] 27207#0: *2 readv: 1, last:3568
2016/04/13 13:46:23 [debug] 27207#0: *2 pipe recv chain: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 pipe buf free s:0 t:1 f:0 0000000001F42840, pos 0000000001F42888, size: 456 file: 0, size: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 pipe length: -1
2016/04/13 13:46:23 [debug] 27207#0: *2 input buf #0 0000000001F42888
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi record byte: 01
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi record byte: 03
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi record byte: 00
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi record byte: 01
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi record byte: 00
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi record byte: 08
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi record byte: 00
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi record byte: 00
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi record length: 8
2016/04/13 13:46:23 [debug] 27207#0: *2 http fastcgi sent end request
2016/04/13 13:46:23 [debug] 27207#0: *2 input buf 0000000001F42888 435
2016/04/13 13:46:23 [debug] 27207#0: *2 pipe write downstream: 1
2016/04/13 13:46:23 [debug] 27207#0: *2 pipe write downstream flush in
2016/04/13 13:46:23 [debug] 27207#0: *2 http output filter "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:23 [debug] 27207#0: *2 http copy filter: "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:23 [debug] 27207#0: *2 http ssi filter "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:23 [debug] 27207#0: *2 saved: 0 state: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 parse: -2, looked: 0 0000000001F42888-0000000001F42A3B
2016/04/13 13:46:23 [debug] 27207#0: *2 saved: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 ssi out: 0000000001EF3E00 0000000001F42888
2016/04/13 13:46:23 [debug] 27207#0: *2 http postpone filter "/mod_pagespeed_test/test.php?" 0000000001EF36B8
2016/04/13 13:46:23 [debug] 27207#0: *2 write new buf t:1 f:0 0000000001F42840, pos 0000000001F42888, size: 435 file: 0, size: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 http write filter: l:0 f:0 s:435
2016/04/13 13:46:23 [debug] 27207#0: *2 http copy filter: 0 "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:23 [debug] 27207#0: *2 pipe write downstream done
2016/04/13 13:46:23 [debug] 27207#0: *2 event timer del: 10: 1460548043078
2016/04/13 13:46:23 [debug] 27207#0: *2 http upstream exit: 0000000000000000
2016/04/13 13:46:23 [debug] 27207#0: *2 finalize http upstream request: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 finalize http fastcgi request
2016/04/13 13:46:23 [debug] 27207#0: *2 free rr peer 1 0
2016/04/13 13:46:23 [debug] 27207#0: *2 close http upstream connection: 10
2016/04/13 13:46:23 [debug] 27207#0: *2 free: 0000000001E65AC0, unused: 48
2016/04/13 13:46:23 [debug] 27207#0: *2 reusable connection: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 http upstream temp fd: -1
2016/04/13 13:46:23 [debug] 27207#0: *2 http output filter "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:23 [debug] 27207#0: *2 http copy filter: "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:23 [debug] 27207#0: *2 http ssi filter "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:23 [debug] 27207#0: *2 ssi out: 0000000001EF3EC0 0000000000000000
2016/04/13 13:46:23 [debug] 27207#0: *2 http postpone filter "/mod_pagespeed_test/test.php?" 0000000001EF3F10
2016/04/13 13:46:23 [debug] 27207#0: *2 write old buf t:1 f:0 0000000001F42840, pos 0000000001F42888, size: 435 file: 0, size: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 http write filter: l:1 f:0 s:435
2016/04/13 13:46:23 [debug] 27207#0: *2 http write filter limit 0
2016/04/13 13:46:23 [debug] 27207#0: *2 http2:553 create DATA frame 0000000001EF3B50: len:435 flags:1
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 frame out: 0000000001EF3B50 sid:553 bl:0 len:435
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL buf copy: 9
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL buf copy: 435
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL to write: 526
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL_write: 526
2016/04/13 13:46:23 [debug] 27207#0: *2 http2:553 DATA frame 0000000001EF3B50 was sent
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 frame sent: 0000000001EF3B50 sid:553 bl:0 len:435
2016/04/13 13:46:23 [debug] 27207#0: *2 http write filter 0000000000000000
2016/04/13 13:46:23 [debug] 27207#0: *2 http copy filter: 0 "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:23 [debug] 27207#0: *2 http finalize request: 0, "/mod_pagespeed_test/test.php?" a:1, c:1
2016/04/13 13:46:23 [debug] 27207#0: *2 http request count:1 blk:0
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 close stream 553, queued 0, processing 1
2016/04/13 13:46:23 [debug] 27207#0: *2 http close request
2016/04/13 13:46:23 [debug] 27207#0: *2 http log handler
2016/04/13 13:46:23 [debug] 27207#0: *2 free: 0000000001F42840
2016/04/13 13:46:23 [debug] 27207#0: *2 free: 0000000001EFD740, unused: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 free: 0000000001EFE750, unused: 8
2016/04/13 13:46:23 [debug] 27207#0: *2 free: 0000000001EF3090, unused: 92
2016/04/13 13:46:23 [debug] 27207#0: *2 free: 0000000001E5CF60, unused: 17
2016/04/13 13:46:23 [debug] 27207#0: *2 free: 0000000001F011C0, unused: 779
2016/04/13 13:46:23 [debug] 27207#0: *2 post event 0000000001EBF120
2016/04/13 13:46:23 [debug] 27207#0: timer delta: 0
2016/04/13 13:46:23 [debug] 27207#0: posted event 0000000001EBF120
2016/04/13 13:46:23 [debug] 27207#0: *2 delete posted event 0000000001EBF120
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 handle connection handler
2016/04/13 13:46:23 [debug] 27207#0: *2 free: 0000000001E6DC70, unused: 3320
2016/04/13 13:46:23 [debug] 27207#0: *2 free: 0000000001EEF080
2016/04/13 13:46:23 [debug] 27207#0: *2 reusable connection: 1
2016/04/13 13:46:23 [debug] 27207#0: *2 event timer add: 9: 180000:1460548163078
2016/04/13 13:46:23 [debug] 27207#0: worker cycle
2016/04/13 13:46:23 [debug] 27207#0: epoll timer: 180000
2016/04/13 13:46:23 [debug] 27207#0: epoll: fd:9 ev:0001 d:0000000001E87211
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 idle handler
2016/04/13 13:46:23 [debug] 27207#0: *2 reusable connection: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 posix_memalign: 0000000001E6DC70:4096 @16
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 read handler
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL_read: 61
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL_read: -1
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL_get_error: 2
2016/04/13 13:46:23 [debug] 27207#0: *2 process http2 frame type:1 f:24 l:52 sid:555
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 HEADERS frame sid:555 on 0 excl:0 weight:256
2016/04/13 13:46:23 [debug] 27207#0: *2 posix_memalign: 0000000001E5CF60:1024 @16
2016/04/13 13:46:23 [debug] 27207#0: *2 posix_memalign: 0000000001EFD740:4096 @16
2016/04/13 13:46:23 [debug] 27207#0: *2 posix_memalign: 0000000001EFE750:4096 @16
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 3
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 84
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 7
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 hpack encoded string length: 4
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 hpack encoded string length: 20
2016/04/13 13:46:23 [debug] 27207#0: *2 http uri: "/mod_pagespeed_test/test.php"
2016/04/13 13:46:23 [debug] 27207#0: *2 http args: ""
2016/04/13 13:46:23 [debug] 27207#0: *2 http exten: "php"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 69
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "content-length: 15"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 63
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "pragma: no-cache"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 62
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "cache-control: no-cache"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 82
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "accept: text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,*/*;q=0.8"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 68
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "origin: https://192.168.137.10:4430"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 81
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "upgrade-insecure-requests: 1"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 80
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "user-agent: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/49.0.2623.112 Safari/537.36"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 67
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "content-type: application/x-www-form-urlencoded"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 70
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "referer: https://192.168.137.10:4430/mod_pagespeed_test/test.php"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 66
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "accept-encoding: gzip, deflate, br"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 78
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http header: "accept-language: en-US,en;q=0.8,nl;q=0.6"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 77
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 76
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 75
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 74
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 73
2016/04/13 13:46:23 [debug] 27207#0: *2 posix_memalign: 0000000001F011C0:1024 @16
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 get indexed header name: 72
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 http request line: "POST /mod_pagespeed_test/test.php HTTP/2.0"
2016/04/13 13:46:23 [debug] 27207#0: *2 rewrite phase: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 test location: "/50x.html"
2016/04/13 13:46:23 [debug] 27207#0: *2 test location: ~ "\.php$"
2016/04/13 13:46:23 [debug] 27207#0: *2 using configuration "\.php$"
2016/04/13 13:46:23 [debug] 27207#0: *2 http cl:15 max:1048576
2016/04/13 13:46:23 [debug] 27207#0: *2 rewrite phase: 2
2016/04/13 13:46:23 [debug] 27207#0: *2 post rewrite phase: 3
2016/04/13 13:46:23 [debug] 27207#0: *2 generic phase: 4
2016/04/13 13:46:23 [debug] 27207#0: *2 http script var: "���"
2016/04/13 13:46:23 [debug] 27207#0: shmtx lock
2016/04/13 13:46:23 [debug] 27207#0: shmtx unlock
2016/04/13 13:46:23 [debug] 27207#0: *2 limit_req[0]: -3 1.210
2016/04/13 13:46:23 [error] 27207#0: *2 limiting requests, excess: 1.210 by zone "perip", client: 192.168.137.1, server: localhost, request: "POST /mod_pagespeed_test/test.php HTTP/2.0", host: "192.168.137.10:4430", referrer: "https://192.168.137.10:4430/mod_pagespeed_test/test.php"
2016/04/13 13:46:23 [debug] 27207#0: *2 http finalize request: 503, "/mod_pagespeed_test/test.php?" a:1, c:1
2016/04/13 13:46:23 [debug] 27207#0: *2 http special response: 503, "/mod_pagespeed_test/test.php?"
2016/04/13 13:46:23 [debug] 27207#0: *2 internal redirect: "/50x.html?"
2016/04/13 13:46:23 [debug] 27207#0: *2 rewrite phase: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 test location: "/50x.html"
2016/04/13 13:46:23 [debug] 27207#0: *2 using configuration "=/50x.html"
2016/04/13 13:46:23 [debug] 27207#0: *2 http cl:15 max:1048576
2016/04/13 13:46:23 [debug] 27207#0: *2 rewrite phase: 2
2016/04/13 13:46:23 [debug] 27207#0: *2 post rewrite phase: 3
2016/04/13 13:46:23 [debug] 27207#0: *2 generic phase: 4
2016/04/13 13:46:23 [debug] 27207#0: *2 generic phase: 5
2016/04/13 13:46:23 [debug] 27207#0: *2 access phase: 6
2016/04/13 13:46:23 [debug] 27207#0: *2 access phase: 7
2016/04/13 13:46:23 [debug] 27207#0: *2 post access phase: 8
2016/04/13 13:46:23 [debug] 27207#0: *2 content phase: 9
2016/04/13 13:46:23 [debug] 27207#0: *2 content phase: 10
2016/04/13 13:46:23 [debug] 27207#0: *2 content phase: 11
2016/04/13 13:46:23 [debug] 27207#0: *2 http filename: "/home/oschaaf/nginx-tmpbuild/html/50x.html"
2016/04/13 13:46:23 [debug] 27207#0: *2 add cleanup: 0000000001EFEF18
2016/04/13 13:46:23 [debug] 27207#0: *2 http static fd: 10
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 header filter
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 output header: ":status: 503"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 output header: "server: nginx/1.9.14"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 output header: "date: Wed, 13 Apr 2016 11:46:23 GMT"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 output header: "content-type: text/html"
2016/04/13 13:46:23 [debug] 27207#0: *2 http2:555 create HEADERS frame 0000000001EFF2F8: len:49
2016/04/13 13:46:23 [debug] 27207#0: *2 http cleanup add: 0000000001EFF400
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 frame out: 0000000001EFF2F8 sid:555 bl:1 len:49
2016/04/13 13:46:23 [debug] 27207#0: *2 malloc: 0000000001EEF080:16384
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL buf copy: 9
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL buf copy: 49
2016/04/13 13:46:23 [debug] 27207#0: *2 http2:555 HEADERS frame 0000000001EFF2F8 was sent
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 frame sent: 0000000001EFF2F8 sid:555 bl:1 len:49
2016/04/13 13:46:23 [debug] 27207#0: *2 http output filter "/50x.html?"
2016/04/13 13:46:23 [debug] 27207#0: *2 http copy filter: "/50x.html?"
2016/04/13 13:46:23 [debug] 27207#0: *2 read: 10, 0000000001EFF4E0, 537, 0
2016/04/13 13:46:23 [debug] 27207#0: *2 http ssi filter "/50x.html?"
2016/04/13 13:46:23 [debug] 27207#0: *2 saved: 0 state: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 parse: -2, looked: 0 0000000001EFF4E0-0000000001EFF6F9
2016/04/13 13:46:23 [debug] 27207#0: *2 saved: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 posix_memalign: 0000000001EF3090:4096 @16
2016/04/13 13:46:23 [debug] 27207#0: *2 ssi out: 0000000001EF30B0 0000000001EFF4E0
2016/04/13 13:46:23 [debug] 27207#0: *2 http postpone filter "/50x.html?" 0000000001EFF720
2016/04/13 13:46:23 [debug] 27207#0: *2 write new buf t:1 f:0 0000000001EFF4E0, pos 0000000001EFF4E0, size: 537 file: 0, size: 537
2016/04/13 13:46:23 [debug] 27207#0: *2 http write filter: l:1 f:0 s:537
2016/04/13 13:46:23 [debug] 27207#0: *2 http write filter limit 0
2016/04/13 13:46:23 [debug] 27207#0: *2 http2:555 create DATA frame 0000000001EFF2F8: len:537 flags:1
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 frame out: 0000000001EFF2F8 sid:555 bl:0 len:537
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL buf copy: 9
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL buf copy: 537
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL to write: 604
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL_write: 604
2016/04/13 13:46:23 [debug] 27207#0: *2 http2:555 DATA frame 0000000001EFF2F8 was sent
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 frame sent: 0000000001EFF2F8 sid:555 bl:0 len:537
2016/04/13 13:46:23 [debug] 27207#0: *2 http write filter 0000000000000000
2016/04/13 13:46:23 [debug] 27207#0: *2 http copy filter: 0 "/50x.html?"
2016/04/13 13:46:23 [debug] 27207#0: *2 http finalize request: 0, "/50x.html?" a:1, c:2
2016/04/13 13:46:23 [debug] 27207#0: *2 http request count:2 blk:0
2016/04/13 13:46:23 [debug] 27207#0: *2 http finalize request: -4, "/50x.html?" a:1, c:1
2016/04/13 13:46:23 [debug] 27207#0: *2 http request count:1 blk:0
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 close stream 555, queued 0, processing 1
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 send RST_STREAM frame sid:555, status:0
2016/04/13 13:46:23 [debug] 27207#0: *2 http close request
2016/04/13 13:46:23 [debug] 27207#0: *2 http log handler
2016/04/13 13:46:23 [debug] 27207#0: *2 run cleanup: 0000000001EFEF18
2016/04/13 13:46:23 [debug] 27207#0: *2 file cleanup: fd:10
2016/04/13 13:46:23 [debug] 27207#0: *2 free: 0000000001EFD740, unused: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 free: 0000000001EFE750, unused: 0
2016/04/13 13:46:23 [debug] 27207#0: *2 free: 0000000001EF3090, unused: 3756
2016/04/13 13:46:23 [debug] 27207#0: *2 free: 0000000001E5CF60, unused: 17
2016/04/13 13:46:23 [debug] 27207#0: *2 free: 0000000001F011C0, unused: 779
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 frame complete pos:0000000001F0286D end:0000000001F0286D
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 frame out: 0000000001E6DEC8 sid:0 bl:0 len:4
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL buf copy: 13
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL to write: 13
2016/04/13 13:46:23 [debug] 27207#0: *2 SSL_write: 13
2016/04/13 13:46:23 [debug] 27207#0: *2 http2 frame sent: 0000000001E6DEC8 sid:0 bl:0 len:4
2016/04/13 13:46:23 [debug] 27207#0: *2 free: 0000000001E6DC70, unused: 3320
2016/04/13 13:46:23 [debug] 27207#0: *2 free: 0000000001EEF080
2016/04/13 13:46:23 [debug] 27207#0: *2 reusable connection: 1
2016/04/13 13:46:23 [debug] 27207#0: *2 event timer del: 9: 1460548163078
2016/04/13 13:46:23 [debug] 27207#0: *2 event timer add: 9: 180000:1460548163472
2016/04/13 13:46:23 [debug] 27207#0: timer delta: 394
2016/04/13 13:46:23 [debug] 27207#0: worker cycle
2016/04/13 13:46:23 [debug] 27207#0: epoll timer: 180000
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment