| 1 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 accept: X.X.X.X:51644 fd:117
|
|---|
| 2 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 event timer add: 117: 60000:1484362078365
|
|---|
| 3 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 reusable connection: 1
|
|---|
| 4 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 epoll add event: fd:117 op:1 ev:80002001
|
|---|
| 5 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 post event 00007F2ECE033C10
|
|---|
| 6 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 delete posted event 00007F2ECE033C10
|
|---|
| 7 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 http check ssl handshake
|
|---|
| 8 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 http recv(): 1
|
|---|
| 9 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 https ssl handshake: 0x16
|
|---|
| 10 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 SSL server name: "A.B.C"
|
|---|
| 11 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 SSL_do_handshake: -1
|
|---|
| 12 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 SSL_get_error: 2
|
|---|
| 13 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 reusable connection: 0
|
|---|
| 14 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 SSL handshake handler: 0
|
|---|
| 15 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 SSL_do_handshake: -1
|
|---|
| 16 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 SSL_get_error: 2
|
|---|
| 17 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 post event 00007F2ECE033C10
|
|---|
| 18 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 delete posted event 00007F2ECE033C10
|
|---|
| 19 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 SSL handshake handler: 0
|
|---|
| 20 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 SSL_do_handshake: 1
|
|---|
| 21 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 SSL: TLSv1, cipher: “cipher1”
|
|---|
| 22 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 reusable connection: 1
|
|---|
| 23 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 http wait request handler
|
|---|
| 24 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 malloc: 0000000004EC3890:1024
|
|---|
| 25 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 SSL_read: -1
|
|---|
| 26 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 SSL_get_error: 2
|
|---|
| 27 | 2017/01/14 02:46:58 [debug] 14576#0: *358792 free: 0000000004EC3890
|
|---|
| 28 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http wait request handler
|
|---|
| 29 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 malloc: 00000000050FFC60:1024
|
|---|
| 30 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 SSL_read: 455
|
|---|
| 31 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 SSL_read: 0
|
|---|
| 32 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 SSL_get_error: 6
|
|---|
| 33 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 peer shutdown SSL cleanly
|
|---|
| 34 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 reusable connection: 0
|
|---|
| 35 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 posix_memalign: 00000000050F7CE0:4096 @16
|
|---|
| 36 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http process request line
|
|---|
| 37 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http request line: "POST /X/Y/Z?k1=v1&k2=v2 HTTP/1.1"
|
|---|
| 38 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http uri: “X/Y/Z”
|
|---|
| 39 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http args: "k1=v1&k2=v2"
|
|---|
| 40 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http exten: ""
|
|---|
| 41 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 posix_memalign: 0000000003F42E70:4096 @16
|
|---|
| 42 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http process request header line
|
|---|
| 43 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http header: "Content-Type: application/octet-stream"
|
|---|
| 44 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http header: "content-encoding: gzip"
|
|---|
| 45 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http header: "Host: A.B.C"
|
|---|
| 46 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http header: "Content-Length: 45"
|
|---|
| 47 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http header: "Expect: 100-continue"
|
|---|
| 48 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http header: "Connection: Keep-Alive"
|
|---|
| 49 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http header done
|
|---|
| 50 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 event timer del: 117: 1484362078365
|
|---|
| 51 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 generic phase: 0
|
|---|
| 52 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 rewrite phase: 1
|
|---|
| 53 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http cleanup add: 00000000050F8C98
|
|---|
| 54 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http script value (post filter): "01010101010101010101010101010101"
|
|---|
| 55 | …
|
|---|
| 56 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 lua reset ctx
|
|---|
| 57 | …
|
|---|
| 58 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 test location: "/"
|
|---|
| 59 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 test location: “a/b”
|
|---|
| 60 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 test location: “c/d/“
|
|---|
| 61 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 test location: “f”
|
|---|
| 62 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 test location: “g”
|
|---|
| 63 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 using configuration "/"
|
|---|
| 64 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http cl:45 max:1048576
|
|---|
| 65 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 rewrite phase: 3
|
|---|
| 66 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http script var
|
|---|
| 67 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http script regex: "^Abc$"
|
|---|
| 68 | 2017/01/14 02:46:59 [notice] 14576#0: *358792 "^Abc$" does not match "", client: X.X.X.X, server: A.B.C, request: "POST /X/Y/Z?k1=v1&k2=v2 HTTP/1.1", host: "A.B.C"
|
|---|
| 69 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http script if
|
|---|
| 70 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http script if: false
|
|---|
| 71 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 rewrite phase: 4
|
|---|
| 72 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 headers more rewrite handler, uri “/X/Y/Z”
|
|---|
| 73 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 post rewrite phase: 5
|
|---|
| 74 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 generic phase: 6
|
|---|
| 75 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 generic phase: 7
|
|---|
| 76 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 generic phase: 8
|
|---|
| 77 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 access phase: 9
|
|---|
| 78 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 access phase: 10
|
|---|
| 79 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 post access phase: 11
|
|---|
| 80 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 send 100 Continue
|
|---|
| 81 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 SSL to write: 25
|
|---|
| 82 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 SSL_write: -1
|
|---|
| 83 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 SSL_get_error: 5
|
|---|
| 84 | 2017/01/14 02:46:59 [info] 14576#0: *358792 SSL_write() failed (SSL:) (32: Broken pipe), client: X.X.X.X, server: A.B.C, request: "POST /X/Y/Z?k1=v1&k2=v2 HTTP/1.1", host: "A.B.C"
|
|---|
| 85 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http finalize request: 500, "/X/Y/Z?k1=v1&k2=v2" a:1, c:1
|
|---|
| 86 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http special response: 500, "/X/Y/Z?k1=v1&k2=v2"
|
|---|
| 87 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 minutes: "/static/error/500.html?"
|
|---|
| 88 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 rewrite phase: 1
|
|---|
| 89 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 posix_memalign: 00000000036573A0:4096 @16
|
|---|
| 90 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http cleanup add: 0000000003F43DC0
|
|---|
| 91 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http script value (post filter): "01010101010101010101010101010101"
|
|---|
| 92 | …
|
|---|
| 93 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 test location: "/"
|
|---|
| 94 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 test location: “a/b”
|
|---|
| 95 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 test location: “c/d/“
|
|---|
| 96 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 test location: "static"
|
|---|
| 97 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 test location: “/f”
|
|---|
| 98 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 using configuration "/static/error"
|
|---|
| 99 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http cl:45 max:1048576
|
|---|
| 100 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 rewrite phase: 3
|
|---|
| 101 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 rewrite phase: 4
|
|---|
| 102 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 headers more rewrite handler, uri "/static/error/500.html"
|
|---|
| 103 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 post rewrite phase: 5
|
|---|
| 104 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 generic phase: 6
|
|---|
| 105 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 generic phase: 7
|
|---|
| 106 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 generic phase: 8
|
|---|
| 107 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 access phase: 9
|
|---|
| 108 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 access phase: 10
|
|---|
| 109 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 post access phase: 11
|
|---|
| 110 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 content phase: 12
|
|---|
| 111 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 content phase: 13
|
|---|
| 112 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 content phase: 14
|
|---|
| 113 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 content phase: 15
|
|---|
| 114 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http filename: "/static/error/500.html"
|
|---|
| 115 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 add cleanup: 0000000003657640
|
|---|
| 116 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http static fd: 136
|
|---|
| 117 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http set discard body
|
|---|
| 118 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http read discarded body
|
|---|
| 119 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 ngx_http_streaming_header_filter()
|
|---|
| 120 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 ip tos: 0x80
|
|---|
| 121 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 headers more header filter, uri "/static/error/500.html"
|
|---|
| 122 | …
|
|---|
| 123 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 lua header filter for user lua code, uri "/static/error/500.html"
|
|---|
| 124 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 lua capture header filter, uri "/static/error/500.html"
|
|---|
| 125 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 HTTP/1.1 500 Internal Server Error
|
|---|
| 126 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 write new buf t:1 f:0 0000000003657930, pos 0000000003657930, size: 300 file: 0, size: 0
|
|---|
| 127 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http write filter: l:0 f:0 s:300
|
|---|
| 128 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http output filter "/static/error/500.html?"
|
|---|
| 129 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http copy filter: "/static/error/500.html?"
|
|---|
| 130 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 malloc: 0000000004E14180:14343
|
|---|
| 131 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 read: 136, 0000000004E14180, 14343, 0
|
|---|
| 132 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 ngx_http_streaming_body_filter()
|
|---|
| 133 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 lua capture body filter, uri "/static/error/500.html"
|
|---|
| 134 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http postpone filter "/static/error/500.html?" 0000000003657B58
|
|---|
| 135 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 write old buf t:1 f:0 0000000003657930, pos 0000000003657930, size: 300 file: 0, size: 0
|
|---|
| 136 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 write new buf t:1 f:0 0000000004E14180, pos 0000000004E14180, size: 14343 file: 0, size: 0
|
|---|
| 137 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http write filter: l:1 f:0 s:14643
|
|---|
| 138 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http write filter limit 0
|
|---|
| 139 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 posix_memalign: 0000000003FD5F10:512 @16
|
|---|
| 140 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 malloc: 0000000003D15350:16384
|
|---|
| 141 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 SSL buf copy: 300
|
|---|
| 142 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 SSL buf copy: 14343
|
|---|
| 143 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 SSL to write: 14643
|
|---|
| 144 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 SSL_write: -1
|
|---|
| 145 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 SSL_get_error: 1
|
|---|
| 146 | 2017/01/14 02:46:59 [crit] 14576#0: *358792 SSL_write() failed (SSL: error:1409F07F:SSL routines:ssl3_write_pending:bad write retry) while sending response to client, client: X.X.X.X, server: A.B.C, request: "POST /X/Y/Z?k1=v1&k2=v2 HTTP/1.1", host: "A.B.C"
|
|---|
| 147 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http write filter FFFFFFFFFFFFFFFF
|
|---|
| 148 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http copy filter: -1 "/static/error/500.html?"
|
|---|
| 149 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http finalize request: -1, "/static/error/500.html?" a:1, c:2
|
|---|
| 150 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http terminate request count:2
|
|---|
| 151 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 lua request cleanup: forcible=0
|
|---|
| 152 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 lua request cleanup: forcible=0
|
|---|
| 153 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http terminate cleanup count:2 blk:0
|
|---|
| 154 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http finalize request: -4, "/static/error/500.html?" a:1, c:2
|
|---|
| 155 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http request count:2 blk:0
|
|---|
| 156 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http posted request: "/static/error/500.html?"
|
|---|
| 157 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http terminate handler count:1
|
|---|
| 158 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http request count:1 blk:0
|
|---|
| 159 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http close request
|
|---|
| 160 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 lua log handler, uri:"/static/error/500.html" c:0
|
|---|
| 161 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 http log handler
|
|---|
| 162 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 run cleanup: 0000000003657640
|
|---|
| 163 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 file cleanup: fd:136
|
|---|
| 164 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 free: 0000000004E14180
|
|---|
| 165 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 free: 00000000050F7CE0, unused: 0
|
|---|
| 166 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 free: 0000000003F42E70, unused: 0
|
|---|
| 167 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 free: 00000000036573A0, unused: 1448
|
|---|
| 168 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 close http connection: 117
|
|---|
| 169 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 SSL_shutdown: 1
|
|---|
| 170 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 reusable connection: 0
|
|---|
| 171 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 free: 0000000003D15350
|
|---|
| 172 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 free: 00000000050FFC60
|
|---|
| 173 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 free: 0000000003F390C0, unused: 8
|
|---|
| 174 | 2017/01/14 02:46:59 [debug] 14576#0: *358792 free: 0000000003FD5F10, unused: 384
|
|---|
