Skip to content

Instantly share code, notes, and snippets.

@oschaaf

oschaaf/error.log

Created Apr 13, 2016
Embed
What would you like to do?
nginx 1.9.14 POST to non-existing url on http/2
2016/04/13 13:52:18 [debug] 27207#0: *82 post event 0000000001EBF120
2016/04/13 13:52:18 [debug] 27207#0: posted event 0000000001EBF120
2016/04/13 13:52:18 [debug] 27207#0: *82 delete posted event 0000000001EBF120
2016/04/13 13:52:18 [debug] 27207#0: *82 http2 handle connection handler
2016/04/13 13:52:18 [debug] 27207#0: *82 free: 0000000001E6DC70, unused: 3496
2016/04/13 13:52:18 [debug] 27207#0: *82 free: 0000000001EEF080
2016/04/13 13:52:18 [debug] 27207#0: *82 reusable connection: 1
2016/04/13 13:52:18 [debug] 27207#0: *82 event timer add: 9: 180000:1460548518022
2016/04/13 13:52:18 [debug] 27207#0: worker cycle
2016/04/13 13:52:18 [debug] 27207#0: epoll timer: 180000
2016/04/13 13:52:20 [debug] 27207#0: epoll: fd:9 ev:0001 d:0000000001E87211
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 idle handler
2016/04/13 13:52:20 [debug] 27207#0: *82 reusable connection: 0
2016/04/13 13:52:20 [debug] 27207#0: *82 posix_memalign: 0000000001E6DC70:4096 @16
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 read handler
2016/04/13 13:52:20 [debug] 27207#0: *82 SSL_read: 88
2016/04/13 13:52:20 [debug] 27207#0: *82 SSL_read: -1
2016/04/13 13:52:20 [debug] 27207#0: *82 SSL_get_error: 2
2016/04/13 13:52:20 [debug] 27207#0: *82 process http2 frame type:1 f:24 l:79 sid:19
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 HEADERS frame sid:19 on 0 excl:0 weight:256
2016/04/13 13:52:20 [debug] 27207#0: *82 posix_memalign: 0000000001E5CF60:1024 @16
2016/04/13 13:52:20 [debug] 27207#0: *82 posix_memalign: 0000000001F00770:512 @16
2016/04/13 13:52:20 [debug] 27207#0: *82 posix_memalign: 0000000001F00980:4096 @16
2016/04/13 13:52:20 [debug] 27207#0: *82 posix_memalign: 0000000001EFD740:4096 @16
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 3
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 81
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 7
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 hpack encoded string length: 4
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 hpack encoded string length: 47
2016/04/13 13:52:20 [debug] 27207#0: *82 http uri: "/mod_pagespeed_test/A.proxy_pass2.css.pagespeed.cf.boNLCPRApP.css"
2016/04/13 13:52:20 [debug] 27207#0: *82 http args: ""
2016/04/13 13:52:20 [debug] 27207#0: *82 http exten: "css"
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 80
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 http header: "content-length: 15"
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 79
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 http header: "pragma: no-cache"
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 78
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 http header: "cache-control: no-cache"
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 77
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 http header: "accept: text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,*/*;q=0.8"
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 76
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 http header: "origin: https://192.168.137.10:4430"
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 75
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 http header: "upgrade-insecure-requests: 1"
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 74
2016/04/13 13:52:20 [debug] 27207#0: *82 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:52:20 [debug] 27207#0: *82 http2 get indexed header name: 73
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 http header: "content-type: application/x-www-form-urlencoded"
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 72
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 http header: "referer: https://192.168.137.10:4430/mod_pagespeed_test/test.php"
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 71
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 http header: "accept-encoding: gzip, deflate, br"
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 70
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 http header: "accept-language: en-US,en;q=0.8,nl;q=0.6"
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 69
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 68
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 67
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 66
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 65
2016/04/13 13:52:20 [debug] 27207#0: *82 posix_memalign: 0000000001EFE750:1024 @16
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 get indexed header name: 64
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 http request line: "POST /mod_pagespeed_test/A.proxy_pass2.css.pagespeed.cf.boNLCPRApP.css HTTP/2.0"
2016/04/13 13:52:20 [debug] 27207#0: *82 rewrite phase: 0
2016/04/13 13:52:20 [debug] 27207#0: *82 test location: "/50x.html"
2016/04/13 13:52:20 [debug] 27207#0: *82 test location: ~ "\.php$"
2016/04/13 13:52:20 [debug] 27207#0: *82 using configuration ""
2016/04/13 13:52:20 [debug] 27207#0: *82 http cl:15 max:1048576
2016/04/13 13:52:20 [debug] 27207#0: *82 rewrite phase: 2
2016/04/13 13:52:20 [debug] 27207#0: *82 post rewrite phase: 3
2016/04/13 13:52:20 [debug] 27207#0: *82 generic phase: 4
2016/04/13 13:52:20 [debug] 27207#0: *82 http script var: "���"
2016/04/13 13:52:20 [debug] 27207#0: shmtx lock
2016/04/13 13:52:20 [debug] 27207#0: shmtx unlock
2016/04/13 13:52:20 [debug] 27207#0: *82 limit_req[0]: -2 0.000
2016/04/13 13:52:20 [debug] 27207#0: *82 http script var: "localhost"
2016/04/13 13:52:20 [debug] 27207#0: shmtx lock
2016/04/13 13:52:20 [debug] 27207#0: shmtx unlock
2016/04/13 13:52:20 [debug] 27207#0: *82 limit_req[1]: 0 0.000
2016/04/13 13:52:20 [debug] 27207#0: shmtx lock
2016/04/13 13:52:20 [debug] 27207#0: shmtx unlock
2016/04/13 13:52:20 [debug] 27207#0: *82 generic phase: 5
2016/04/13 13:52:20 [debug] 27207#0: *82 access phase: 6
2016/04/13 13:52:20 [debug] 27207#0: *82 access phase: 7
2016/04/13 13:52:20 [debug] 27207#0: *82 post access phase: 8
2016/04/13 13:52:20 [debug] 27207#0: *82 content phase: 9
2016/04/13 13:52:20 [debug] 27207#0: *82 content phase: 10
2016/04/13 13:52:20 [debug] 27207#0: *82 content phase: 11
2016/04/13 13:52:20 [debug] 27207#0: *82 http filename: "/home/oschaaf/code/google/mps-issue-1175//src/install/mod_pagespeed_test/A.proxy_pass2.css.pagespeed.cf.boNLCPRApP.css"
2016/04/13 13:52:20 [debug] 27207#0: *82 add cleanup: 0000000001EFDF80
2016/04/13 13:52:20 [debug] 27207#0: *82 http finalize request: 404, "/mod_pagespeed_test/A.proxy_pass2.css.pagespeed.cf.boNLCPRApP.css?" a:1, c:1
2016/04/13 13:52:20 [debug] 27207#0: *82 http special response: 404, "/mod_pagespeed_test/A.proxy_pass2.css.pagespeed.cf.boNLCPRApP.css?"
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 header filter
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 output header: ":status: 404"
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 output header: "server: nginx/1.9.14"
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 output header: "date: Wed, 13 Apr 2016 11:52:20 GMT"
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 output header: "content-type: text/html"
2016/04/13 13:52:20 [debug] 27207#0: *82 http2:19 create HEADERS frame 0000000001EFE210: len:45
2016/04/13 13:52:20 [debug] 27207#0: *82 http cleanup add: 0000000001EFE318
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 frame out: 0000000001EFE210 sid:19 bl:1 len:45
2016/04/13 13:52:20 [debug] 27207#0: *82 malloc: 0000000001EEF080:16384
2016/04/13 13:52:20 [debug] 27207#0: *82 SSL buf copy: 9
2016/04/13 13:52:20 [debug] 27207#0: *82 SSL buf copy: 45
2016/04/13 13:52:20 [debug] 27207#0: *82 http2:19 HEADERS frame 0000000001EFE210 was sent
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 frame sent: 0000000001EFE210 sid:19 bl:1 len:45
2016/04/13 13:52:20 [debug] 27207#0: *82 http output filter "/mod_pagespeed_test/A.proxy_pass2.css.pagespeed.cf.boNLCPRApP.css?"
2016/04/13 13:52:20 [debug] 27207#0: *82 http copy filter: "/mod_pagespeed_test/A.proxy_pass2.css.pagespeed.cf.boNLCPRApP.css?"
2016/04/13 13:52:20 [debug] 27207#0: *82 http ssi filter "/mod_pagespeed_test/A.proxy_pass2.css.pagespeed.cf.boNLCPRApP.css?"
2016/04/13 13:52:20 [debug] 27207#0: *82 saved: 0 state: 0
2016/04/13 13:52:20 [debug] 27207#0: *82 parse: -2, looked: 0 00000000006C4EE0-00000000006C4F54
2016/04/13 13:52:20 [debug] 27207#0: *82 saved: 0
2016/04/13 13:52:20 [debug] 27207#0: *82 saved: 0 state: 0
2016/04/13 13:52:20 [debug] 27207#0: *82 parse: -2, looked: 0 00000000006C5620-00000000006C5655
2016/04/13 13:52:20 [debug] 27207#0: *82 saved: 0
2016/04/13 13:52:20 [debug] 27207#0: *82 saved: 0 state: 0
2016/04/13 13:52:20 [debug] 27207#0: *82 parse: -2, looked: 0 00000000006C5440-00000000006C55D2
2016/04/13 13:52:20 [debug] 27207#0: *82 saved: 0
2016/04/13 13:52:20 [debug] 27207#0: *82 ssi out: 0000000001EFE4E8 00000000006C4EE0
2016/04/13 13:52:20 [debug] 27207#0: *82 ssi out: 0000000001EFE548 00000000006C5620
2016/04/13 13:52:20 [debug] 27207#0: *82 ssi out: 0000000001EFE5A8 00000000006C5440
2016/04/13 13:52:20 [debug] 27207#0: *82 http postpone filter "/mod_pagespeed_test/A.proxy_pass2.css.pagespeed.cf.boNLCPRApP.css?" 0000000001EFE538
2016/04/13 13:52:20 [debug] 27207#0: *82 write new buf t:0 f:0 0000000000000000, pos 00000000006C4EE0, size: 116 file: 0, size: 0
2016/04/13 13:52:20 [debug] 27207#0: *82 write new buf t:0 f:0 0000000000000000, pos 00000000006C5620, size: 53 file: 0, size: 0
2016/04/13 13:52:20 [debug] 27207#0: *82 write new buf t:0 f:0 0000000000000000, pos 00000000006C5440, size: 402 file: 0, size: 0
2016/04/13 13:52:20 [debug] 27207#0: *82 http write filter: l:1 f:0 s:571
2016/04/13 13:52:20 [debug] 27207#0: *82 http write filter limit 0
2016/04/13 13:52:20 [debug] 27207#0: *82 http2:19 create DATA frame 0000000001EFE210: len:571 flags:1
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 frame out: 0000000001EFE210 sid:19 bl:0 len:571
2016/04/13 13:52:20 [debug] 27207#0: *82 SSL buf copy: 9
2016/04/13 13:52:20 [debug] 27207#0: *82 SSL buf copy: 116
2016/04/13 13:52:20 [debug] 27207#0: *82 SSL buf copy: 53
2016/04/13 13:52:20 [debug] 27207#0: *82 SSL buf copy: 402
2016/04/13 13:52:20 [debug] 27207#0: *82 SSL to write: 634
2016/04/13 13:52:20 [debug] 27207#0: *82 SSL_write: 634
2016/04/13 13:52:20 [debug] 27207#0: *82 http2:19 DATA frame 0000000001EFE210 was sent
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 frame sent: 0000000001EFE210 sid:19 bl:0 len:571
2016/04/13 13:52:20 [debug] 27207#0: *82 http write filter 0000000000000000
2016/04/13 13:52:20 [debug] 27207#0: *82 http copy filter: 0 "/mod_pagespeed_test/A.proxy_pass2.css.pagespeed.cf.boNLCPRApP.css?"
2016/04/13 13:52:20 [debug] 27207#0: *82 http finalize request: 0, "/mod_pagespeed_test/A.proxy_pass2.css.pagespeed.cf.boNLCPRApP.css?" a:1, c:1
2016/04/13 13:52:20 [debug] 27207#0: *82 http request count:1 blk:0
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 close stream 19, queued 0, processing 1
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 send RST_STREAM frame sid:19, status:0
2016/04/13 13:52:20 [debug] 27207#0: *82 http close request
2016/04/13 13:52:20 [debug] 27207#0: *82 http log handler
2016/04/13 13:52:20 [debug] 27207#0: *82 posix_memalign: 0000000001EF3090:4096 @16
2016/04/13 13:52:20 [debug] 27207#0: *82 free: 0000000001F00980, unused: 0
2016/04/13 13:52:20 [debug] 27207#0: *82 free: 0000000001EFD740, unused: 216
2016/04/13 13:52:20 [debug] 27207#0: *82 free: 0000000001EF3090, unused: 3799
2016/04/13 13:52:20 [debug] 27207#0: *82 free: 0000000001E5CF60, unused: 32
2016/04/13 13:52:20 [debug] 27207#0: *82 free: 0000000001EFE750, unused: 721
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 frame complete pos:0000000001F02888 end:0000000001F02888
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 frame out: 0000000001E6DEC8 sid:0 bl:0 len:4
2016/04/13 13:52:20 [debug] 27207#0: *82 SSL buf copy: 13
2016/04/13 13:52:20 [debug] 27207#0: *82 SSL to write: 13
2016/04/13 13:52:20 [debug] 27207#0: *82 SSL_write: 13
2016/04/13 13:52:20 [debug] 27207#0: *82 http2 frame sent: 0000000001E6DEC8 sid:0 bl:0 len:4
2016/04/13 13:52:20 [debug] 27207#0: *82 free: 0000000001E6DC70, unused: 3320
2016/04/13 13:52:20 [debug] 27207#0: *82 free: 0000000001EEF080
2016/04/13 13:52:20 [debug] 27207#0: *82 reusable connection: 1
2016/04/13 13:52:20 [debug] 27207#0: *82 event timer del: 9: 1460548518022
2016/04/13 13:52:20 [debug] 27207#0: *82 event timer add: 9: 180000:1460548520768
2016/04/13 13:52:20 [debug] 27207#0: timer delta: 2746
2016/04/13 13:52:20 [debug] 27207#0: worker cycle
2016/04/13 13:52:20 [debug] 27207#0: epoll timer: 180000
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment