nginx debug output

nginx_debug.log

Server: nginx/1.10.1
Date: Fri, 25 Jan 2019 18:48:45 GMT
Content-Type: text/plain
Content-Length: 104
Connection: keep-alive

2019/01/25 18:48:45 [debug] 6#0: *520 write new buf t:1 f:0 0000557F2633CD70, pos 0000557F2633CD70, size: 154 file: 0, size: 0
2019/01/25 18:48:45 [debug] 6#0: *520 http write filter: l:0 f:0 s:154
2019/01/25 18:48:45 [debug] 6#0: *520 http output filter "/_pub/ma?"
2019/01/25 18:48:45 [debug] 6#0: *520 http copy filter: "/_pub/ma?"
2019/01/25 18:48:45 [debug] 6#0: *520 http postpone filter "/_pub/ma?" 0000557F2633CD10
2019/01/25 18:48:45 [debug] 6#0: *520 write old buf t:1 f:0 0000557F2633CD70, pos 0000557F2633CD70, size: 154 file: 0, size: 0
2019/01/25 18:48:45 [debug] 6#0: *520 write new buf t:0 f:0 0000557F2513CB20, pos 0000557F2513CB20, size: 104 file: 0, size: 0
2019/01/25 18:48:45 [debug] 6#0: *520 http write filter: l:1 f:1 s:258
2019/01/25 18:48:45 [debug] 6#0: *520 http write filter limit 0
2019/01/25 18:48:45 [debug] 6#0: *520 writev: 258 of 258
2019/01/25 18:48:45 [debug] 6#0: *520 http write filter 0000000000000000
2019/01/25 18:48:45 [debug] 6#0: *520 http copy filter: 0 "/_pub/ma?"
2019/01/25 18:48:45 [debug] 6#0: *520 http finalize request: 0, "/_pub/ma?" a:1, c:1
2019/01/25 18:48:45 [debug] 6#0: *520 set http keepalive handler
2019/01/25 18:48:45 [debug] 6#0: *520 http close request
2019/01/25 18:48:45 [debug] 6#0: *520 http log handler
100.96.0.9 - - [25/Jan/2019:18:48:45 +0000] "POST /_pub/ma HTTP/1.1" 201 104 "-" "python-requests/2.21.0"
2019/01/25 18:48:45 [debug] 6#0: *520 run cleanup: 0000557F2634E080
2019/01/25 18:48:45 [debug] 6#0: *520 file cleanup: fd:18
2019/01/25 18:48:45 [debug] 6#0: *520 free: 0000557F2633D870
2019/01/25 18:48:45 [debug] 6#0: *520 free: 0000557F2634D100, unused: 0
2019/01/25 18:48:45 [debug] 6#0: *520 free: 0000557F2633C650, unused: 1828
2019/01/25 18:48:45 [debug] 6#0: *520 free: 0000557F26354BD0
2019/01/25 18:48:45 [debug] 6#0: *520 hc free: 0000000000000000 0
2019/01/25 18:48:45 [debug] 6#0: *520 hc busy: 0000000000000000 0
2019/01/25 18:48:45 [debug] 6#0: *520 tcp_nodelay
2019/01/25 18:48:45 [debug] 6#0: *520 reusable connection: 1
2019/01/25 18:48:45 [debug] 6#0: *520 event timer add: 17: 65000:1548442190615
2019/01/25 18:48:45 [debug] 6#0: *520 post event 0000557F2630AD20
2019/01/25 18:48:45 [debug] 6#0: *520 delete posted event 0000557F2630AD20
2019/01/25 18:48:45 [debug] 6#0: *520 http keepalive handler
2019/01/25 18:48:45 [debug] 6#0: *520 malloc: 0000557F26354BD0:1024
2019/01/25 18:48:45 [debug] 6#0: *520 recv: fd:17 -1 of 1024
2019/01/25 18:48:45 [debug] 6#0: *520 recv() not ready (11: Resource temporarily unavailable)
2019/01/25 18:48:45 [debug] 6#0: *520 free: 0000557F26354BD0
2019/01/25 18:48:45 [debug] 6#0: *54 http output filter "/_sub/ma?"
2019/01/25 18:48:45 [debug] 6#0: *54 http copy filter: "/_sub/ma?"
2019/01/25 18:48:45 [debug] 6#0: *54 http postpone filter "/_sub/ma?" 0000557F26352560
2019/01/25 18:48:45 [debug] 6#0: *54 write new buf t:0 f:0 0000557F24E0300A, pos 0000557F24E0300A, size: 4 file: 0, size: 0
2019/01/25 18:48:45 [debug] 6#0: *54 write new buf t:0 f:0 0000557F26351B38, pos 0000557F26351B38, size: 12 file: 0, size: 0
2019/01/25 18:48:45 [debug] 6#0: *54 write new buf t:0 f:0 0000557F24E039BD, pos 0000557F24E039BD, size: 1 file: 0, size: 0
2019/01/25 18:48:45 [debug] 6#0: *54 write new buf t:0 f:0 0000557F24E02FE1, pos 0000557F24E02FE1, size: 6 file: 0, size: 0
2019/01/25 18:48:45 [debug] 6#0: *54 write new buf t:0 f:0 00007F08570730D8, pos 00007F08570730D8, size: 36344 file: 0, size: 0
2019/01/25 18:48:45 [debug] 6#0: *54 write new buf t:0 f:0 0000557F24E039BC, pos 0000557F24E039BC, size: 2 file: 0, size: 0
2019/01/25 18:48:45 [debug] 6#0: *54 http write filter: l:0 f:1 s:36369
2019/01/25 18:48:45 [debug] 6#0: *54 http write filter limit 0
2019/01/25 18:48:45 [debug] 6#0: *54 writev: 36369 of 36369
2019/01/25 18:48:45 [debug] 6#0: *54 http write filter 0000000000000000
2019/01/25 18:48:45 [debug] 6#0: *54 http copy filter: 0 "/_sub/ma?"
2019/01/25 18:48:45 [debug] 6#0: *53 http output filter "/_sub/ma?"
2019/01/25 18:48:45 [debug] 6#0: *53 http copy filter: "/_sub/ma?"
2019/01/25 18:48:45 [debug] 6#0: *53 http postpone filter "/_sub/ma?" 0000557F262DA4C0
2019/01/25 18:48:45 [debug] 6#0: *53 write new buf t:0 f:0 0000557F24E0300A, pos 0000557F24E0300A, size: 4 file: 0, size: 0
2019/01/25 18:48:45 [debug] 6#0: *53 write new buf t:0 f:0 0000557F262D9AA8, pos 0000557F262D9AA8, size: 12 file: 0, size: 0
2019/01/25 18:48:45 [debug] 6#0: *53 write new buf t:0 f:0 0000557F24E039BD, pos 0000557F24E039BD, size: 1 file: 0, size: 0
2019/01/25 18:48:45 [debug] 6#0: *53 write new buf t:0 f:0 0000557F24E02FE1, pos 0000557F24E02FE1, size: 6 file: 0, size: 0
2019/01/25 18:48:45 [debug] 6#0: *53 write new buf t:0 f:0 00007F08570730D8, pos 00007F08570730D8, size: 36344 file: 0, size: 0
2019/01/25 18:48:45 [debug] 6#0: *53 write new buf t:0 f:0 0000557F24E039BC, pos 0000557F24E039BC, size: 2 file: 0, size: 0
2019/01/25 18:48:45 [debug] 6#0: *53 http write filter: l:0 f:1 s:36369
2019/01/25 18:48:45 [debug] 6#0: *53 http write filter limit 0
2019/01/25 18:48:45 [debug] 6#0: *53 writev: 36369 of 36369
2019/01/25 18:48:45 [debug] 6#0: *53 http write filter 0000000000000000
2019/01/25 18:48:45 [debug] 6#0: *53 http copy filter: 0 "/_sub/ma?"
2019/01/25 18:48:45 [debug] 6#0: *520 http keepalive handler
2019/01/25 18:48:45 [debug] 6#0: *520 malloc: 0000557F26354BD0:1024
2019/01/25 18:48:45 [debug] 6#0: *520 recv: fd:17 0 of 1024
2019/01/25 18:48:45 [info] 6#0: *520 client 100.96.0.9 closed keepalive connection
2019/01/25 18:48:45 [debug] 6#0: *520 close http connection: 17
2019/01/25 18:48:45 [debug] 6#0: *520 event timer del: 17: 1548442190615
2019/01/25 18:48:45 [debug] 6#0: *520 reusable connection: 0
2019/01/25 18:48:45 [debug] 6#0: *520 free: 0000557F26354BD0
2019/01/25 18:48:45 [debug] 6#0: *520 free: 0000557F2634FF50, unused: 128
2019/01/25 18:48:48 [debug] 6#0: accept on 0.0.0.0:80, ready: 0
2019/01/25 18:48:48 [debug] 6#0: posix_memalign: 0000557F2634FF50:512 @16
2019/01/25 18:48:48 [debug] 6#0: *521 accept: 100.96.0.9:44540 fd:17
2019/01/25 18:48:48 [debug] 6#0: *521 event timer add: 17: 60000:1548442188713
2019/01/25 18:48:48 [debug] 6#0: *521 reusable connection: 1
2019/01/25 18:48:48 [debug] 6#0: *521 epoll add event: fd:17 op:1 ev:80002001
2019/01/25 18:48:48 [debug] 6#0: *521 http wait request handler
2019/01/25 18:48:48 [debug] 6#0: *521 malloc: 0000557F26354BD0:1024
2019/01/25 18:48:48 [debug] 6#0: *521 recv: fd:17 1024 of 1024
2019/01/25 18:48:48 [debug] 6#0: *521 reusable connection: 0
2019/01/25 18:48:48 [debug] 6#0: *521 posix_memalign: 0000557F2633C650:4096 @16
2019/01/25 18:48:48 [debug] 6#0: *521 http process request line
2019/01/25 18:48:48 [debug] 6#0: *521 http request line: "POST /_pub/ma HTTP/1.1"
2019/01/25 18:48:48 [debug] 6#0: *521 http uri: "/_pub/ma"
2019/01/25 18:48:48 [debug] 6#0: *521 http args: ""
2019/01/25 18:48:48 [debug] 6#0: *521 http exten: ""
2019/01/25 18:48:48 [debug] 6#0: *521 posix_memalign: 0000557F2634D100:4096 @16
2019/01/25 18:48:48 [debug] 6#0: *521 http process request header line
2019/01/25 18:48:48 [debug] 6#0: *521 http header: "Host: nchan-service"
2019/01/25 18:48:48 [debug] 6#0: *521 http header: "User-Agent: python-requests/2.21.0"
2019/01/25 18:48:48 [debug] 6#0: *521 http header: "Accept-Encoding: gzip, deflate"
2019/01/25 18:48:48 [debug] 6#0: *521 http header: "Accept: */*"
2019/01/25 18:48:48 [debug] 6#0: *521 http header: "Connection: keep-alive"
2019/01/25 18:48:48 [debug] 6#0: *521 http header: "Content-Length: 36351"
2019/01/25 18:48:48 [debug] 6#0: *521 http header: "Content-Type: application/json"
2019/01/25 18:48:48 [debug] 6#0: *521 http header done
2019/01/25 18:48:48 [debug] 6#0: *521 event timer del: 17: 1548442188713
2019/01/25 18:48:48 [debug] 6#0: *521 generic phase: 0
2019/01/25 18:48:48 [debug] 6#0: *521 rewrite phase: 1
2019/01/25 18:48:48 [debug] 6#0: *521 test location: "/"
2019/01/25 18:48:48 [debug] 6#0: *521 test location: "nchanx"
2019/01/25 18:48:48 [debug] 6#0: *521 test location: "allx"
2019/01/25 18:48:48 [debug] 6#0: *521 test location: ~ "/_(pub|sub)/(\w+)$"
2019/01/25 18:48:48 [debug] 6#0: *521 using configuration "/_(pub|sub)/(\w+)$"
2019/01/25 18:48:48 [debug] 6#0: *521 http cl:36351 max:10485760
2019/01/25 18:48:48 [debug] 6#0: *521 rewrite phase: 3
2019/01/25 18:48:48 [debug] 6#0: *521 post rewrite phase: 4
2019/01/25 18:48:48 [debug] 6#0: *521 generic phase: 5
2019/01/25 18:48:48 [debug] 6#0: *521 generic phase: 6
2019/01/25 18:48:48 [debug] 6#0: *521 generic phase: 7
2019/01/25 18:48:48 [debug] 6#0: *521 access phase: 8
2019/01/25 18:48:48 [debug] 6#0: *521 access phase: 9
2019/01/25 18:48:48 [debug] 6#0: *521 access phase: 10
2019/01/25 18:48:48 [debug] 6#0: *521 post access phase: 11
2019/01/25 18:48:48 [debug] 6#0: *521 http script capture: "ma"
2019/01/25 18:48:48 [debug] 6#0: *521 http client request body preread 817
2019/01/25 18:48:48 [debug] 6#0: *521 http request body content length filter
2019/01/25 18:48:48 [debug] 6#0: *521 http body new buf t:1 f:0 0000557F26354C9F, pos 0000557F26354C9F, size: 817 file: 0, size: 0
2019/01/25 18:48:48 [debug] 6#0: *521 malloc: 0000557F2633D870:36351
2019/01/25 18:48:48 [debug] 6#0: *521 http read client request body
2019/01/25 18:48:48 [debug] 6#0: *521 recv: fd:17 35534 of 35534
2019/01/25 18:48:48 [debug] 6#0: *521 http client request body recv 35534
2019/01/25 18:48:48 [debug] 6#0: *521 http body old buf t:1 f:0 0000557F26354C9F, pos 0000557F26354C9F, size: 817 file: 0, size: 0
2019/01/25 18:48:48 [debug] 6#0: *521 http body new buf t:1 f:0 0000557F2633D870, pos 0000557F2633D870, size: 35534 file: 0, size: 0
2019/01/25 18:48:48 [debug] 6#0: *521 http write client request body, bufs 0000557F2633D4C8
2019/01/25 18:48:48 [debug] 6#0: *521 add cleanup: 0000557F2633D5D0
2019/01/25 18:48:48 [debug] 6#0: *521 hashed path: /var/cache/nginx/client_temp/0000000203
2019/01/25 18:48:48 [debug] 6#0: *521 temp fd:18
2019/01/25 18:48:48 [debug] 6#0: *521 writev: 18, 36351, 0
2019/01/25 18:48:48 [debug] 6#0: *521 http client request body rest 0
2019/01/25 18:48:48 [debug] 6#0: *521 http script capture: "ma"
2019/01/25 18:48:48 [debug] 6#0: *521 http cleanup add: 0000557F2634D758
2019/01/25 18:48:48 [debug] 6#0: *521 http finalize request: -4, "/_pub/ma?" a:1, c:2
2019/01/25 18:48:48 [debug] 6#0: *521 http request count:2 blk:0
2019/01/25 18:48:48 [debug] 6#0: *521 HTTP/1.1 201 Created
Server: nginx/1.10.1
Date: Fri, 25 Jan 2019 18:48:48 GMT
Content-Type: text/plain
Content-Length: 104
Connection: keep-alive

2019/01/25 18:48:48 [debug] 6#0: *521 write new buf t:1 f:0 0000557F2634D820, pos 0000557F2634D820, size: 154 file: 0, size: 0
2019/01/25 18:48:48 [debug] 6#0: *521 http write filter: l:0 f:0 s:154
2019/01/25 18:48:48 [debug] 6#0: *521 http output filter "/_pub/ma?"
2019/01/25 18:48:48 [debug] 6#0: *521 http copy filter: "/_pub/ma?"
2019/01/25 18:48:48 [debug] 6#0: *521 http postpone filter "/_pub/ma?" 0000557F2634D7C0
2019/01/25 18:48:48 [debug] 6#0: *521 write old buf t:1 f:0 0000557F2634D820, pos 0000557F2634D820, size: 154 file: 0, size: 0
2019/01/25 18:48:48 [debug] 6#0: *521 write new buf t:0 f:0 0000557F2513CB20, pos 0000557F2513CB20, size: 104 file: 0, size: 0
2019/01/25 18:48:48 [debug] 6#0: *521 http write filter: l:1 f:1 s:258
2019/01/25 18:48:48 [debug] 6#0: *521 http write filter limit 0
2019/01/25 18:48:48 [debug] 6#0: *521 writev: 258 of 258
2019/01/25 18:48:48 [debug] 6#0: *521 http write filter 0000000000000000
2019/01/25 18:48:48 [debug] 6#0: *521 http copy filter: 0 "/_pub/ma?"
2019/01/25 18:48:48 [debug] 6#0: *521 http finalize request: 0, "/_pub/ma?" a:1, c:1
2019/01/25 18:48:48 [debug] 6#0: *521 set http keepalive handler
2019/01/25 18:48:48 [debug] 6#0: *521 http close request
2019/01/25 18:48:48 [debug] 6#0: *521 http log handler
100.96.0.9 - - [25/Jan/2019:18:48:48 +0000] "POST /_pub/ma HTTP/1.1" 201 104 "-" "python-requests/2.21.0"
2019/01/25 18:48:48 [debug] 6#0: *521 run cleanup: 0000557F2633D5D0
2019/01/25 18:48:48 [debug] 6#0: *521 file cleanup: fd:18
2019/01/25 18:48:48 [debug] 6#0: *521 free: 0000557F2633D870
2019/01/25 18:48:48 [debug] 6#0: *521 free: 0000557F2633C650, unused: 0
2019/01/25 18:48:48 [debug] 6#0: *521 free: 0000557F2634D100, unused: 1828
2019/01/25 18:48:48 [debug] 6#0: *521 free: 0000557F26354BD0
2019/01/25 18:48:48 [debug] 6#0: *521 hc free: 0000000000000000 0
2019/01/25 18:48:48 [debug] 6#0: *521 hc busy: 0000000000000000 0
2019/01/25 18:48:48 [debug] 6#0: *521 tcp_nodelay
2019/01/25 18:48:48 [debug] 6#0: *521 reusable connection: 1
2019/01/25 18:48:48 [debug] 6#0: *521 event timer add: 17: 65000:1548442193717
2019/01/25 18:48:48 [debug] 6#0: *521 post event 0000557F2630AD20
2019/01/25 18:48:48 [debug] 6#0: *521 delete posted event 0000557F2630AD20
2019/01/25 18:48:48 [debug] 6#0: *521 http keepalive handler
2019/01/25 18:48:48 [debug] 6#0: *521 malloc: 0000557F26354BD0:1024
2019/01/25 18:48:48 [debug] 6#0: *521 recv: fd:17 -1 of 1024
2019/01/25 18:48:48 [debug] 6#0: *521 recv() not ready (11: Resource temporarily unavailable)
2019/01/25 18:48:48 [debug] 6#0: *521 free: 0000557F26354BD0
2019/01/25 18:48:48 [debug] 6#0: *54 http output filter "/_sub/ma?"
2019/01/25 18:48:48 [debug] 6#0: *54 http copy filter: "/_sub/ma?"
2019/01/25 18:48:48 [debug] 6#0: *54 http postpone filter "/_sub/ma?" 0000557F26351540
2019/01/25 18:48:48 [debug] 6#0: *54 write new buf t:0 f:0 0000557F24E0300A, pos 0000557F24E0300A, size: 4 file: 0, size: 0
2019/01/25 18:48:48 [debug] 6#0: *54 write new buf t:0 f:0 0000557F26351B38, pos 0000557F26351B38, size: 12 file: 0, size: 0
2019/01/25 18:48:48 [debug] 6#0: *54 write new buf t:0 f:0 0000557F24E039BD, pos 0000557F24E039BD, size: 1 file: 0, size: 0
2019/01/25 18:48:48 [debug] 6#0: *54 write new buf t:0 f:0 0000557F24E02FE1, pos 0000557F24E02FE1, size: 6 file: 0, size: 0
2019/01/25 18:48:48 [debug] 6#0: *54 write new buf t:0 f:0 00007F08570D30D8, pos 00007F08570D30D8, size: 36351 file: 0, size: 0
2019/01/25 18:48:48 [debug] 6#0: *54 write new buf t:0 f:0 0000557F24E039BC, pos 0000557F24E039BC, size: 2 file: 0, size: 0
2019/01/25 18:48:48 [debug] 6#0: *54 http write filter: l:0 f:1 s:36376
2019/01/25 18:48:48 [debug] 6#0: *54 http write filter limit 0
2019/01/25 18:48:48 [debug] 6#0: *54 writev: 36376 of 36376
2019/01/25 18:48:48 [debug] 6#0: *54 http write filter 0000000000000000
2019/01/25 18:48:48 [debug] 6#0: *54 http copy filter: 0 "/_sub/ma?"
2019/01/25 18:48:48 [debug] 6#0: *53 http output filter "/_sub/ma?"
2019/01/25 18:48:48 [debug] 6#0: *53 http copy filter: "/_sub/ma?"
2019/01/25 18:48:48 [debug] 6#0: *53 http postpone filter "/_sub/ma?" 0000557F262DD140
2019/01/25 18:48:48 [debug] 6#0: *53 write new buf t:0 f:0 0000557F24E0300A, pos 0000557F24E0300A, size: 4 file: 0, size: 0
2019/01/25 18:48:48 [debug] 6#0: *53 write new buf t:0 f:0 0000557F262D9AA8, pos 0000557F262D9AA8, size: 12 file: 0, size: 0
2019/01/25 18:48:48 [debug] 6#0: *53 write new buf t:0 f:0 0000557F24E039BD, pos 0000557F24E039BD, size: 1 file: 0, size: 0
2019/01/25 18:48:48 [debug] 6#0: *53 write new buf t:0 f:0 0000557F24E02FE1, pos 0000557F24E02FE1, size: 6 file: 0, size: 0
2019/01/25 18:48:48 [debug] 6#0: *53 write new buf t:0 f:0 00007F08570D30D8, pos 00007F08570D30D8, size: 36351 file: 0, size: 0
2019/01/25 18:48:48 [debug] 6#0: *53 write new buf t:0 f:0 0000557F24E039BC, pos 0000557F24E039BC, size: 2 file: 0, size: 0
2019/01/25 18:48:48 [debug] 6#0: *53 http write filter: l:0 f:1 s:36376
2019/01/25 18:48:48 [debug] 6#0: *53 http write filter limit 0
2019/01/25 18:48:48 [debug] 6#0: *53 writev: 36376 of 36376
2019/01/25 18:48:48 [debug] 6#0: *53 http write filter 0000000000000000
2019/01/25 18:48:48 [debug] 6#0: *53 http copy filter: 0 "/_sub/ma?"
2019/01/25 18:48:48 [debug] 6#0: *521 http keepalive handler
2019/01/25 18:48:48 [debug] 6#0: *521 malloc: 0000557F26354BD0:1024
2019/01/25 18:48:48 [debug] 6#0: *521 recv: fd:17 0 of 1024
2019/01/25 18:48:48 [info] 6#0: *521 client 100.96.0.9 closed keepalive connection
2019/01/25 18:48:48 [debug] 6#0: *521 close http connection: 17
2019/01/25 18:48:48 [debug] 6#0: *521 event timer del: 17: 1548442193717
2019/01/25 18:48:48 [debug] 6#0: *521 reusable connection: 0
2019/01/25 18:48:48 [debug] 6#0: *521 free: 0000557F26354BD0
2019/01/25 18:48:48 [debug] 6#0: *521 free: 0000557F2634FF50, unused: 128
2019/01/25 18:48:50 [debug] 6#0: accept on 0.0.0.0:80, ready: 0
2019/01/25 18:48:50 [debug] 6#0: posix_memalign: 0000557F2634FF50:512 @16
2019/01/25 18:48:50 [debug] 6#0: *522 accept: 100.100.0.0:50914 fd:17
2019/01/25 18:48:50 [debug] 6#0: *522 event timer add: 17: 60000:1548442190337
2019/01/25 18:48:50 [debug] 6#0: *522 reusable connection: 1
2019/01/25 18:48:50 [debug] 6#0: *522 epoll add event: fd:17 op:1 ev:80002001
2019/01/25 18:48:50 [debug] 6#0: *522 http wait request handler
2019/01/25 18:48:50 [debug] 6#0: *522 malloc: 0000557F26354BD0:1024
2019/01/25 18:48:50 [debug] 6#0: *522 recv: fd:17 132 of 1024
2019/01/25 18:48:50 [debug] 6#0: *522 reusable connection: 0
2019/01/25 18:48:50 [debug] 6#0: *522 posix_memalign: 0000557F2634D100:4096 @16
2019/01/25 18:48:50 [debug] 6#0: *522 http process request line
2019/01/25 18:48:50 [debug] 6#0: *522 http request line: "GET / HTTP/1.1"
2019/01/25 18:48:50 [debug] 6#0: *522 http uri: "/"
2019/01/25 18:48:50 [debug] 6#0: *522 http args: ""
2019/01/25 18:48:50 [debug] 6#0: *522 http exten: ""
2019/01/25 18:48:50 [debug] 6#0: *522 posix_memalign: 0000557F2633C650:4096 @16
2019/01/25 18:48:50 [debug] 6#0: *522 http process request header line
2019/01/25 18:48:50 [debug] 6#0: *522 http header: "Host: 10.32.56.77:30348"
2019/01/25 18:48:50 [debug] 6#0: *522 http header: "Connection: close"
2019/01/25 18:48:50 [debug] 6#0: *522 http header: "User-Agent: ELB-HealthChecker/2.0"
2019/01/25 18:48:50 [debug] 6#0: *522 http header: "Accept-Encoding: gzip, compressed"
2019/01/25 18:48:50 [debug] 6#0: *522 http header done
2019/01/25 18:48:50 [debug] 6#0: *522 event timer del: 17: 1548442190337
2019/01/25 18:48:50 [debug] 6#0: *522 generic phase: 0
2019/01/25 18:48:50 [debug] 6#0: *522 rewrite phase: 1
2019/01/25 18:48:50 [debug] 6#0: *522 test location: "/"
2019/01/25 18:48:50 [debug] 6#0: *522 test location: ~ "/_(pub|sub)/(\w+)$"
2019/01/25 18:48:50 [debug] 6#0: *522 using configuration "/"
2019/01/25 18:48:50 [debug] 6#0: *522 http cl:-1 max:10485760
2019/01/25 18:48:50 [debug] 6#0: *522 rewrite phase: 3
2019/01/25 18:48:50 [debug] 6#0: *522 http set discard body
2019/01/25 18:48:50 [debug] 6#0: *522 HTTP/1.1 200 OK
Server: nginx/1.10.1
Date: Fri, 25 Jan 2019 18:48:50 GMT
Content-Type: application/octet-stream
Content-Length: 2
Connection: close
Content-Type: text/plain

2019/01/25 18:48:50 [debug] 6#0: *522 write new buf t:1 f:0 0000557F2634DF40, pos 0000557F2634DF40, size: 182 file: 0, size: 0
2019/01/25 18:48:50 [debug] 6#0: *522 http write filter: l:0 f:0 s:182
2019/01/25 18:48:50 [debug] 6#0: *522 http output filter "/?"
2019/01/25 18:48:50 [debug] 6#0: *522 http copy filter: "/?"
2019/01/25 18:48:50 [debug] 6#0: *522 http postpone filter "/?" 00007FFD8425F540
2019/01/25 18:48:50 [debug] 6#0: *522 write old buf t:1 f:0 0000557F2634DF40, pos 0000557F2634DF40, size: 182 file: 0, size: 0
2019/01/25 18:48:50 [debug] 6#0: *522 write new buf t:0 f:0 0000000000000000, pos 0000557F262F85F3, size: 2 file: 0, size: 0
2019/01/25 18:48:50 [debug] 6#0: *522 http write filter: l:1 f:0 s:184
2019/01/25 18:48:50 [debug] 6#0: *522 http write filter limit 0
2019/01/25 18:48:50 [debug] 6#0: *522 writev: 184 of 184
2019/01/25 18:48:50 [debug] 6#0: *522 http write filter 0000000000000000
2019/01/25 18:48:50 [debug] 6#0: *522 http copy filter: 0 "/?"
2019/01/25 18:48:50 [debug] 6#0: *522 http finalize request: 0, "/?" a:1, c:1
2019/01/25 18:48:50 [debug] 6#0: *522 event timer add: 17: 5000:1548442135337
2019/01/25 18:48:50 [debug] 6#0: *522 http lingering close handler
2019/01/25 18:48:50 [debug] 6#0: *522 recv: fd:17 -1 of 4096
2019/01/25 18:48:50 [debug] 6#0: *522 recv() not ready (11: Resource temporarily unavailable)
2019/01/25 18:48:50 [debug] 6#0: *522 lingering read: -2
2019/01/25 18:48:50 [debug] 6#0: *522 event timer: 17, old: 1548442135337, new: 1548442135337
2019/01/25 18:48:50 [debug] 6#0: accept on 0.0.0.0:80, ready: 0
2019/01/25 18:48:50 [debug] 6#0: posix_memalign: 0000557F2634FD40:512 @16
2019/01/25 18:48:50 [debug] 6#0: *523 accept: 100.116.0.0:13764 fd:18
2019/01/25 18:48:50 [debug] 6#0: *523 event timer add: 18: 60000:1548442190337
2019/01/25 18:48:50 [debug] 6#0: *523 reusable connection: 1
2019/01/25 18:48:50 [debug] 6#0: *523 epoll add event: fd:18 op:1 ev:80002001
2019/01/25 18:48:50 [debug] 6#0: accept on 0.0.0.0:80, ready: 0
2019/01/25 18:48:50 [debug] 6#0: posix_memalign: 0000557F2633D660:512 @16
2019/01/25 18:48:50 [debug] 6#0: *524 accept: 100.96.0.1:9386 fd:19
2019/01/25 18:48:50 [debug] 6#0: *524 event timer add: 19: 60000:1548442190338
2019/01/25 18:48:50 [debug] 6#0: *524 reusable connection: 1
2019/01/25 18:48:50 [debug] 6#0: *524 epoll add event: fd:19 op:1 ev:80002001
2019/01/25 18:48:50 [debug] 6#0: *523 http wait request handler
2019/01/25 18:48:50 [debug] 6#0: *523 malloc: 0000557F263527A0:1024
2019/01/25 18:48:50 [debug] 6#0: *523 recv: fd:18 133 of 1024
2019/01/25 18:48:50 [debug] 6#0: *523 reusable connection: 0
2019/01/25 18:48:50 [debug] 6#0: *523 posix_memalign: 0000557F2633D870:4096 @16
2019/01/25 18:48:50 [debug] 6#0: *523 http process request line
2019/01/25 18:48:50 [debug] 6#0: *523 http request line: "GET / HTTP/1.1"
2019/01/25 18:48:50 [debug] 6#0: *523 http uri: "/"
2019/01/25 18:48:50 [debug] 6#0: *523 http args: ""
2019/01/25 18:48:50 [debug] 6#0: *523 http exten: ""
2019/01/25 18:48:50 [debug] 6#0: *523 posix_memalign: 0000557F2633E880:4096 @16
2019/01/25 18:48:50 [debug] 6#0: *523 http process request header line
2019/01/25 18:48:50 [debug] 6#0: *523 http header: "Host: 10.32.36.133:30348"
2019/01/25 18:48:50 [debug] 6#0: *523 http header: "Connection: close"
2019/01/25 18:48:50 [debug] 6#0: *523 http header: "User-Agent: ELB-HealthChecker/2.0"
2019/01/25 18:48:50 [debug] 6#0: *523 http header: "Accept-Encoding: gzip, compressed"
2019/01/25 18:48:50 [debug] 6#0: *523 http header done
2019/01/25 18:48:50 [debug] 6#0: *523 event timer del: 18: 1548442190337
2019/01/25 18:48:50 [debug] 6#0: *523 generic phase: 0
2019/01/25 18:48:50 [debug] 6#0: *523 rewrite phase: 1
2019/01/25 18:48:50 [debug] 6#0: *523 test location: "/"
2019/01/25 18:48:50 [debug] 6#0: *523 test location: ~ "/_(pub|sub)/(\w+)$"
2019/01/25 18:48:50 [debug] 6#0: *523 using configuration "/"
2019/01/25 18:48:50 [debug] 6#0: *523 http cl:-1 max:10485760
2019/01/25 18:48:50 [debug] 6#0: *523 rewrite phase: 3
2019/01/25 18:48:50 [debug] 6#0: *523 http set discard body
2019/01/25 18:48:50 [debug] 6#0: *523 HTTP/1.1 200 OK
Server: nginx/1.10.1
Date: Fri, 25 Jan 2019 18:48:50 GMT
Content-Type: application/octet-stream
Content-Length: 2
Connection: close
Content-Type: text/plain

2019/01/25 18:48:50 [debug] 6#0: *523 write new buf t:1 f:0 0000557F2633E6B0, pos 0000557F2633E6B0, size: 182 file: 0, size: 0
2019/01/25 18:48:50 [debug] 6#0: *523 http write filter: l:0 f:0 s:182
2019/01/25 18:48:50 [debug] 6#0: *523 http output filter "/?"
2019/01/25 18:48:50 [debug] 6#0: *523 http copy filter: "/?"
2019/01/25 18:48:50 [debug] 6#0: *523 http postpone filter "/?" 00007FFD8425F540
2019/01/25 18:48:50 [debug] 6#0: *523 write old buf t:1 f:0 0000557F2633E6B0, pos 0000557F2633E6B0, size: 182 file: 0, size: 0
2019/01/25 18:48:50 [debug] 6#0: *523 write new buf t:0 f:0 0000000000000000, pos 0000557F262F85F3, size: 2 file: 0, size: 0
2019/01/25 18:48:50 [debug] 6#0: *523 http write filter: l:1 f:0 s:184
2019/01/25 18:48:50 [debug] 6#0: *523 http write filter limit 0
2019/01/25 18:48:50 [debug] 6#0: *523 writev: 184 of 184
2019/01/25 18:48:50 [debug] 6#0: *523 http write filter 0000000000000000
2019/01/25 18:48:50 [debug] 6#0: *523 http copy filter: 0 "/?"
2019/01/25 18:48:50 [debug] 6#0: *523 http finalize request: 0, "/?" a:1, c:1
2019/01/25 18:48:50 [debug] 6#0: *523 event timer add: 18: 5000:1548442135338
100.116.0.0 - - [25/Jan/2019:18:48:50 +0000] "GET / HTTP/1.1" 200 2 "-" "ELB-HealthChecker/2.0"
100.100.0.0 - - [25/Jan/2019:18:48:50 +0000] "GET / HTTP/1.1" 200 2 "-" "ELB-HealthChecker/2.0"
100.96.0.1 - - [25/Jan/2019:18:48:50 +0000] "GET / HTTP/1.1" 200 2 "-" "ELB-HealthChecker/2.0"
2019/01/25 18:48:50 [debug] 6#0: *523 http lingering close handler
2019/01/25 18:48:50 [debug] 6#0: *523 recv: fd:18 0 of 4096
2019/01/25 18:48:50 [debug] 6#0: *523 lingering read: 0
2019/01/25 18:48:50 [debug] 6#0: *523 http request count:1 blk:0
2019/01/25 18:48:50 [debug] 6#0: *523 http close request
2019/01/25 18:48:50 [debug] 6#0: *523 http log handler
2019/01/25 18:48:50 [debug] 6#0: *523 free: 0000557F2633D870, unused: 96
2019/01/25 18:48:50 [debug] 6#0: *523 free: 0000557F2633E880, unused: 2972
2019/01/25 18:48:50 [debug] 6#0: *523 close http connection: 18
2019/01/25 18:48:50 [debug] 6#0: *523 event timer del: 18: 1548442135338
2019/01/25 18:48:50 [debug] 6#0: *523 reusable connection: 0
2019/01/25 18:48:50 [debug] 6#0: *523 free: 0000557F263527A0
2019/01/25 18:48:50 [debug] 6#0: *523 free: 0000557F2634FD40, unused: 128
2019/01/25 18:48:50 [debug] 6#0: *524 http wait request handler
2019/01/25 18:48:50 [debug] 6#0: *524 malloc: 0000557F263527A0:1024
2019/01/25 18:48:50 [debug] 6#0: *524 recv: fd:19 133 of 1024
2019/01/25 18:48:50 [debug] 6#0: *524 reusable connection: 0
2019/01/25 18:48:50 [debug] 6#0: *524 posix_memalign: 0000557F2633D870:4096 @16
2019/01/25 18:48:50 [debug] 6#0: *524 http process request line
2019/01/25 18:48:50 [debug] 6#0: *524 http request line: "GET / HTTP/1.1"
2019/01/25 18:48:50 [debug] 6#0: *524 http uri: "/"
2019/01/25 18:48:50 [debug] 6#0: *524 http args: ""
2019/01/25 18:48:50 [debug] 6#0: *524 http exten: ""
2019/01/25 18:48:50 [debug] 6#0: *524 posix_memalign: 0000557F2633E880:4096 @16
2019/01/25 18:48:50 [debug] 6#0: *524 http process request header line
2019/01/25 18:48:50 [debug] 6#0: *524 http header: "Host: 10.32.93.140:30348"
2019/01/25 18:48:50 [debug] 6#0: *524 http header: "Connection: close"
2019/01/25 18:48:50 [debug] 6#0: *524 http header: "User-Agent: ELB-HealthChecker/2.0"
2019/01/25 18:48:50 [debug] 6#0: *524 http header: "Accept-Encoding: gzip, compressed"
2019/01/25 18:48:50 [debug] 6#0: *524 http header done
2019/01/25 18:48:50 [debug] 6#0: *524 event timer del: 19: 1548442190338
2019/01/25 18:48:50 [debug] 6#0: *524 generic phase: 0
2019/01/25 18:48:50 [debug] 6#0: *524 rewrite phase: 1
2019/01/25 18:48:50 [debug] 6#0: *524 test location: "/"
2019/01/25 18:48:50 [debug] 6#0: *524 test location: ~ "/_(pub|sub)/(\w+)$"
2019/01/25 18:48:50 [debug] 6#0: *524 using configuration "/"
2019/01/25 18:48:50 [debug] 6#0: *524 http cl:-1 max:10485760
2019/01/25 18:48:50 [debug] 6#0: *524 rewrite phase: 3
2019/01/25 18:48:50 [debug] 6#0: *524 http set discard body
2019/01/25 18:48:50 [debug] 6#0: *524 HTTP/1.1 200 OK
Server: nginx/1.10.1
Date: Fri, 25 Jan 2019 18:48:50 GMT
Content-Type: application/octet-stream
Content-Length: 2
Connection: close
Content-Type: text/plain

2019/01/25 18:48:50 [debug] 6#0: *524 write new buf t:1 f:0 0000557F2633E6B0, pos 0000557F2633E6B0, size: 182 file: 0, size: 0
2019/01/25 18:48:50 [debug] 6#0: *524 http write filter: l:0 f:0 s:182
2019/01/25 18:48:50 [debug] 6#0: *524 http output filter "/?"
2019/01/25 18:48:50 [debug] 6#0: *524 http copy filter: "/?"
2019/01/25 18:48:50 [debug] 6#0: *524 http postpone filter "/?" 00007FFD8425F540
2019/01/25 18:48:50 [debug] 6#0: *524 write old buf t:1 f:0 0000557F2633E6B0, pos 0000557F2633E6B0, size: 182 file: 0, size: 0
2019/01/25 18:48:50 [debug] 6#0: *524 write new buf t:0 f:0 0000000000000000, pos 0000557F262F85F3, size: 2 file: 0, size: 0
2019/01/25 18:48:50 [debug] 6#0: *524 http write filter: l:1 f:0 s:184
2019/01/25 18:48:50 [debug] 6#0: *524 http write filter limit 0
2019/01/25 18:48:50 [debug] 6#0: *524 writev: 184 of 184
2019/01/25 18:48:50 [debug] 6#0: *524 http write filter 0000000000000000
2019/01/25 18:48:50 [debug] 6#0: *524 http copy filter: 0 "/?"
2019/01/25 18:48:50 [debug] 6#0: *524 http finalize request: 0, "/?" a:1, c:1
2019/01/25 18:48:50 [debug] 6#0: *524 event timer add: 19: 5000:1548442135339
2019/01/25 18:48:50 [debug] 6#0: *524 http lingering close handler
2019/01/25 18:48:50 [debug] 6#0: *524 recv: fd:19 -1 of 4096
2019/01/25 18:48:50 [debug] 6#0: *524 recv() not ready (11: Resource temporarily unavailable)
2019/01/25 18:48:50 [debug] 6#0: *524 lingering read: -2
2019/01/25 18:48:50 [debug] 6#0: *524 event timer: 19, old: 1548442135339, new: 1548442135339
2019/01/25 18:48:50 [debug] 6#0: *522 http lingering close handler
2019/01/25 18:48:50 [debug] 6#0: *522 recv: fd:17 0 of 4096
2019/01/25 18:48:50 [debug] 6#0: *522 lingering read: 0
2019/01/25 18:48:50 [debug] 6#0: *522 http request count:1 blk:0
2019/01/25 18:48:50 [debug] 6#0: *522 http close request
2019/01/25 18:48:50 [debug] 6#0: *522 http log handler
2019/01/25 18:48:50 [debug] 6#0: *522 free: 0000557F2634D100, unused: 96
2019/01/25 18:48:50 [debug] 6#0: *522 free: 0000557F2633C650, unused: 2972
2019/01/25 18:48:50 [debug] 6#0: *522 close http connection: 17
2019/01/25 18:48:50 [debug] 6#0: *522 event timer del: 17: 1548442135337
2019/01/25 18:48:50 [debug] 6#0: *522 reusable connection: 0
2019/01/25 18:48:50 [debug] 6#0: *522 free: 0000557F26354BD0
2019/01/25 18:48:50 [debug] 6#0: *522 free: 0000557F2634FF50, unused: 128
2019/01/25 18:48:50 [debug] 6#0: *524 http lingering close handler
2019/01/25 18:48:50 [debug] 6#0: *524 recv: fd:19 0 of 4096
2019/01/25 18:48:50 [debug] 6#0: *524 lingering read: 0
2019/01/25 18:48:50 [debug] 6#0: *524 http request count:1 blk:0
2019/01/25 18:48:50 [debug] 6#0: *524 http close request
2019/01/25 18:48:50 [debug] 6#0: *524 http log handler
2019/01/25 18:48:50 [debug] 6#0: *524 free: 0000557F2633D870, unused: 96
2019/01/25 18:48:50 [debug] 6#0: *524 free: 0000557F2633E880, unused: 2973
2019/01/25 18:48:50 [debug] 6#0: *524 close http connection: 19
2019/01/25 18:48:50 [debug] 6#0: *524 event timer del: 19: 1548442135339
2019/01/25 18:48:50 [debug] 6#0: *524 reusable connection: 0
2019/01/25 18:48:50 [debug] 6#0: *524 free: 0000557F263527A0
2019/01/25 18:48:50 [debug] 6#0: *524 free: 0000557F2633D660, unused: 128
2019/01/25 18:48:53 [debug] 6#0: accept on 0.0.0.0:80, ready: 0
2019/01/25 18:48:53 [debug] 6#0: posix_memalign: 0000557F2633D660:512 @16
2019/01/25 18:48:53 [debug] 6#0: *525 accept: 100.96.0.9:44560 fd:17
2019/01/25 18:48:53 [debug] 6#0: *525 event timer add: 17: 60000:1548442193418
2019/01/25 18:48:53 [debug] 6#0: *525 reusable connection: 1
2019/01/25 18:48:53 [debug] 6#0: *525 epoll add event: fd:17 op:1 ev:80002001
2019/01/25 18:48:53 [debug] 6#0: *525 http wait request handler
2019/01/25 18:48:53 [debug] 6#0: *525 malloc: 0000557F263527A0:1024
2019/01/25 18:48:53 [debug] 6#0: *525 recv: fd:17 207 of 1024
2019/01/25 18:48:53 [debug] 6#0: *525 reusable connection: 0
2019/01/25 18:48:53 [debug] 6#0: *525 posix_memalign: 0000557F2634D100:4096 @16
2019/01/25 18:48:53 [debug] 6#0: *525 http process request line
2019/01/25 18:48:53 [debug] 6#0: *525 http request line: "POST /_pub/ma HTTP/1.1"
2019/01/25 18:48:53 [debug] 6#0: *525 http uri: "/_pub/ma"
2019/01/25 18:48:53 [debug] 6#0: *525 http args: ""
2019/01/25 18:48:53 [debug] 6#0: *525 http exten: ""
2019/01/25 18:48:53 [debug] 6#0: *525 posix_memalign: 0000557F2633C650:4096 @16
2019/01/25 18:48:53 [debug] 6#0: *525 http process request header line
2019/01/25 18:48:53 [debug] 6#0: *525 http header: "Host: nchan-service"
2019/01/25 18:48:53 [debug] 6#0: *525 http header: "User-Agent: python-requests/2.21.0"
2019/01/25 18:48:53 [debug] 6#0: *525 http header: "Accept-Encoding: gzip, deflate"
2019/01/25 18:48:53 [debug] 6#0: *525 http header: "Accept: */*"
2019/01/25 18:48:53 [debug] 6#0: *525 http header: "Connection: keep-alive"
2019/01/25 18:48:53 [debug] 6#0: *525 http header: "Content-Length: 36346"
2019/01/25 18:48:53 [debug] 6#0: *525 http header: "Content-Type: application/json"
2019/01/25 18:48:53 [debug] 6#0: *525 http header done
2019/01/25 18:48:53 [debug] 6#0: *525 event timer del: 17: 1548442193418
2019/01/25 18:48:53 [debug] 6#0: *525 generic phase: 0
2019/01/25 18:48:53 [debug] 6#0: *525 rewrite phase: 1
2019/01/25 18:48:53 [debug] 6#0: *525 test location: "/"
2019/01/25 18:48:53 [debug] 6#0: *525 test location: "nchanx"
2019/01/25 18:48:53 [debug] 6#0: *525 test location: "allx"
2019/01/25 18:48:53 [debug] 6#0: *525 test location: ~ "/_(pub|sub)/(\w+)$"
2019/01/25 18:48:53 [debug] 6#0: *525 using configuration "/_(pub|sub)/(\w+)$"
2019/01/25 18:48:53 [debug] 6#0: *525 http cl:36346 max:10485760
2019/01/25 18:48:53 [debug] 6#0: *525 rewrite phase: 3
2019/01/25 18:48:53 [debug] 6#0: *525 post rewrite phase: 4
2019/01/25 18:48:53 [debug] 6#0: *525 generic phase: 5
2019/01/25 18:48:53 [debug] 6#0: *525 generic phase: 6
2019/01/25 18:48:53 [debug] 6#0: *525 generic phase: 7
2019/01/25 18:48:53 [debug] 6#0: *525 access phase: 8
2019/01/25 18:48:53 [debug] 6#0: *525 access phase: 9
2019/01/25 18:48:53 [debug] 6#0: *525 access phase: 10
2019/01/25 18:48:53 [debug] 6#0: *525 post access phase: 11
2019/01/25 18:48:53 [debug] 6#0: *525 http script capture: "ma"
2019/01/25 18:48:53 [debug] 6#0: *525 http request body content length filter
2019/01/25 18:48:53 [debug] 6#0: *525 malloc: 0000557F2633D870:36346
2019/01/25 18:48:53 [debug] 6#0: *525 http read client request body
2019/01/25 18:48:53 [debug] 6#0: *525 recv: fd:17 8860 of 36346
2019/01/25 18:48:53 [debug] 6#0: *525 http client request body recv 8860
2019/01/25 18:48:53 [debug] 6#0: *525 http client request body rest 36346
2019/01/25 18:48:53 [debug] 6#0: *525 recv: fd:17 -1 of 27486
2019/01/25 18:48:53 [debug] 6#0: *525 recv() not ready (11: Resource temporarily unavailable)
2019/01/25 18:48:53 [debug] 6#0: *525 http client request body recv -2
2019/01/25 18:48:53 [debug] 6#0: *525 http client request body rest 36346
2019/01/25 18:48:53 [debug] 6#0: *525 event timer add: 17: 60000:1548442193424
2019/01/25 18:48:53 [debug] 6#0: *525 http finalize request: -4, "/_pub/ma?" a:1, c:2
2019/01/25 18:48:53 [debug] 6#0: *525 http request count:2 blk:0
2019/01/25 18:48:53 [debug] 6#0: *525 http run request: "/_pub/ma?"
2019/01/25 18:48:53 [debug] 6#0: *525 http read client request body
2019/01/25 18:48:53 [debug] 6#0: *525 recv: fd:17 8860 of 27486
2019/01/25 18:48:53 [debug] 6#0: *525 http client request body recv 8860
2019/01/25 18:48:53 [debug] 6#0: *525 http client request body rest 36346
2019/01/25 18:48:53 [debug] 6#0: *525 recv: fd:17 -1 of 18626
2019/01/25 18:48:53 [debug] 6#0: *525 recv() not ready (11: Resource temporarily unavailable)
2019/01/25 18:48:53 [debug] 6#0: *525 http client request body recv -2
2019/01/25 18:48:53 [debug] 6#0: *525 http client request body rest 36346
2019/01/25 18:48:53 [debug] 6#0: *525 event timer: 17, old: 1548442193424, new: 1548442193425
2019/01/25 18:48:53 [debug] 6#0: *525 http run request: "/_pub/ma?"
2019/01/25 18:48:53 [debug] 6#0: *525 http read client request body
2019/01/25 18:48:53 [debug] 6#0: *525 recv: fd:17 18626 of 18626
2019/01/25 18:48:53 [debug] 6#0: *525 http client request body recv 18626
2019/01/25 18:48:53 [debug] 6#0: *525 http body new buf t:1 f:0 0000557F2633D870, pos 0000557F2633D870, size: 36346 file: 0, size: 0
2019/01/25 18:48:53 [debug] 6#0: *525 http write client request body, bufs 0000557F2634DFD8
2019/01/25 18:48:53 [debug] 6#0: *525 add cleanup: 0000557F2634E0E8
2019/01/25 18:48:53 [debug] 6#0: *525 hashed path: /var/cache/nginx/client_temp/0000000204
2019/01/25 18:48:53 [debug] 6#0: *525 temp fd:18
2019/01/25 18:48:53 [debug] 6#0: *525 write: 18, 0000557F2633D870, 36346, 0
2019/01/25 18:48:53 [debug] 6#0: *525 http client request body rest 0
2019/01/25 18:48:53 [debug] 6#0: *525 event timer del: 17: 1548442193424
2019/01/25 18:48:53 [debug] 6#0: *525 http script capture: "ma"
2019/01/25 18:48:53 [debug] 6#0: *525 http cleanup add: 0000557F2633CC38
2019/01/25 18:48:53 [debug] 6#0: *525 HTTP/1.1 201 Created
Server: nginx/1.10.1
Date: Fri, 25 Jan 2019 18:48:53 GMT
Content-Type: text/plain
Content-Length: 104
Connection: keep-alive

2019/01/25 18:48:53 [debug] 6#0: *525 write new buf t:1 f:0 0000557F2633CD00, pos 0000557F2633CD00, size: 154 file: 0, size: 0
2019/01/25 18:48:53 [debug] 6#0: *525 http write filter: l:0 f:0 s:154
2019/01/25 18:48:53 [debug] 6#0: *525 http output filter "/_pub/ma?"
2019/01/25 18:48:53 [debug] 6#0: *525 http copy filter: "/_pub/ma?"
2019/01/25 18:48:53 [debug] 6#0: *525 http postpone filter "/_pub/ma?" 0000557F2633CCA0
2019/01/25 18:48:53 [debug] 6#0: *525 write old buf t:1 f:0 0000557F2633CD00, pos 0000557F2633CD00, size: 154 file: 0, size: 0
2019/01/25 18:48:53 [debug] 6#0: *525 write new buf t:0 f:0 0000557F2513CB20, pos 0000557F2513CB20, size: 104 file: 0, size: 0
2019/01/25 18:48:53 [debug] 6#0: *525 http write filter: l:1 f:1 s:258
2019/01/25 18:48:53 [debug] 6#0: *525 http write filter limit 0
2019/01/25 18:48:53 [debug] 6#0: *525 writev: 258 of 258
2019/01/25 18:48:53 [debug] 6#0: *525 http write filter 0000000000000000
2019/01/25 18:48:53 [debug] 6#0: *525 http copy filter: 0 "/_pub/ma?"
2019/01/25 18:48:53 [debug] 6#0: *525 http finalize request: 0, "/_pub/ma?" a:1, c:1
2019/01/25 18:48:53 [debug] 6#0: *525 set http keepalive handler
2019/01/25 18:48:53 [debug] 6#0: *525 http close request
2019/01/25 18:48:53 [debug] 6#0: *525 http log handler
2019/01/25 18:48:53 [debug] 6#0: *525 run cleanup: 0000557F2634E0E8
2019/01/25 18:48:53 [debug] 6#0: *525 file cleanup: fd:18
2019/01/25 18:48:53 [debug] 6#0: *525 free: 0000557F2633D870
2019/01/25 18:48:53 [debug] 6#0: *525 free: 0000557F2634D100, unused: 0
2019/01/25 18:48:53 [debug] 6#0: *525 free: 0000557F2633C650, unused: 1924
2019/01/25 18:48:53 [debug] 6#0: *525 free: 0000557F263527A0
2019/01/25 18:48:53 [debug] 6#0: *525 hc free: 0000000000000000 0
2019/01/25 18:48:53 [debug] 6#0: *525 hc busy: 0000000000000000 0
2019/01/25 18:48:53 [debug] 6#0: *525 tcp_nodelay
2019/01/25 18:48:53 [debug] 6#0: *525 reusable connection: 1
2019/01/25 18:48:53 [debug] 6#0: *525 event timer add: 17: 65000:1548442198426
2019/01/25 18:48:53 [debug] 6#0: *525 post event 0000557F2630ADE0
2019/01/25 18:48:53 [debug] 6#0: *525 delete posted event 0000557F2630ADE0
2019/01/25 18:48:53 [debug] 6#0: *525 http keepalive handler
2019/01/25 18:48:53 [debug] 6#0: *525 malloc: 0000557F263527A0:1024
2019/01/25 18:48:53 [debug] 6#0: *525 recv: fd:17 -1 of 1024
2019/01/25 18:48:53 [debug] 6#0: *525 recv() not ready (11: Resource temporarily unavailable)
2019/01/25 18:48:53 [debug] 6#0: *525 free: 0000557F263527A0
100.96.0.9 - - [25/Jan/2019:18:48:53 +0000] "POST /_pub/ma HTTP/1.1" 201 104 "-" "python-requests/2.21.0"