Skip to content

Instantly share code, notes, and snippets.

@CaptainChemist

CaptainChemist/gist:ab920a953ead13d7244a657e1521ab71

Created January 9, 2018 19:10
Show Gist options
  • Save CaptainChemist/ab920a953ead13d7244a657e1521ab71 to your computer and use it in GitHub Desktop.
Save CaptainChemist/ab920a953ead13d7244a657e1521ab71 to your computer and use it in GitHub Desktop.
Download ZIP
Successful POST request
Raw
gistfile1.txt
2018/01/09 17:54:08 [debug] 10524#10524: epoll add event: fd:8 op:1 ev:00002001
2018/01/09 17:54:26 [debug] 10524#10524: post event 000055D3DFDF6A40
2018/01/09 17:54:26 [debug] 10524#10524: delete posted event 000055D3DFDF6A40
2018/01/09 17:54:26 [debug] 10524#10524: accept on 0.0.0.0:80, ready: 0
2018/01/09 17:54:26 [debug] 10524#10524: posix_memalign: 000055D3DFDE31D0:512 @16
2018/01/09 17:54:26 [debug] 10524#10524: *1 accept: 172.31.000.000:4079 fd:9
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer add: 9: 180000:1515520646206
2018/01/09 17:54:26 [debug] 10524#10524: *1 reusable connection: 1
2018/01/09 17:54:26 [debug] 10524#10524: *1 epoll add event: fd:9 op:1 ev:80002001
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http wait request handler
2018/01/09 17:54:26 [debug] 10524#10524: *1 malloc: 000055D3DFDE33E0:1024
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 1024 of 1024
2018/01/09 17:54:26 [debug] 10524#10524: *1 reusable connection: 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 posix_memalign: 000055D3DFDE0F30:4096 @16
2018/01/09 17:54:26 [debug] 10524#10524: *1 http process request line
2018/01/09 17:54:26 [debug] 10524#10524: *1 http request line: "POST /mps/updateFunction?mode=async HTTP/1.1"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http uri: "/mps/updateFunction"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http args: "mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http exten: ""
2018/01/09 17:54:26 [debug] 10524#10524: *1 http process request header line
2018/01/09 17:54:26 [debug] 10524#10524: *1 http header: "X-Forwarded-For: 54.145.000.000"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http header: "X-Forwarded-Proto: http"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http header: "X-Forwarded-Port: 80"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http header: "Host: my-load-balancer.us-east-1.elb.amazonaws.com"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http header: "X-Amzn-Trace-Id: Root=1-5a5501d2-7fd1b59646cb91a36c8c9ada"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http header: "Content-Length: 131927"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http header: "Accept: application/json, text/plain, */*"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http header: "Content-Type: application/json;charset=utf-8"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http header: "User-Agent: axios/0.16.2"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http header done
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer del: 9: 1515520646206
2018/01/09 17:54:26 [debug] 10524#10524: *1 generic phase: 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 rewrite phase: 1
2018/01/09 17:54:26 [debug] 10524#10524: *1 test location: "/"
2018/01/09 17:54:26 [debug] 10524#10524: *1 test location: "admin"
2018/01/09 17:54:26 [debug] 10524#10524: *1 using configuration "/"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http cl:131927 max:5242880000
2018/01/09 17:54:26 [debug] 10524#10524: *1 rewrite phase: 3
2018/01/09 17:54:26 [debug] 10524#10524: *1 post rewrite phase: 4
2018/01/09 17:54:26 [debug] 10524#10524: *1 generic phase: 5
2018/01/09 17:54:26 [debug] 10524#10524: *1 generic phase: 6
2018/01/09 17:54:26 [debug] 10524#10524: *1 generic phase: 7
2018/01/09 17:54:26 [debug] 10524#10524: *1 access phase: 8
2018/01/09 17:54:26 [debug] 10524#10524: *1 access phase: 9
2018/01/09 17:54:26 [debug] 10524#10524: *1 access phase: 10
2018/01/09 17:54:26 [debug] 10524#10524: *1 post access phase: 11
2018/01/09 17:54:26 [debug] 10524#10524: *1 posix_memalign: 000055D3DFDD3F80:4096 @16
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body preread 613
2018/01/09 17:54:26 [debug] 10524#10524: *1 http request body content length filter
2018/01/09 17:54:26 [debug] 10524#10524: *1 http body new buf t:1 f:0 000055D3DFDE357B, pos 000055D3DFDE357B, size: 613 file: 0, size: 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 malloc: 000055D3DFE1AA60:131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 13576 of 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 13576
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 117738
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer add: 9: 180000:1515520646206
2018/01/09 17:54:26 [debug] 10524#10524: *1 http finalize request: -4, "/mps/updateFunction?mode=async" a:1, c:2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http request count:2 blk:0
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 2928 of 117738
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 2928
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 114810
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646206
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 5760 of 114810
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 5760
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 109050
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646206
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 2432 of 109050
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 2432
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 106618
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646206
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 17840 of 106618
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 17840
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 88778
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646207
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 2896 of 88778
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 2896
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 85882
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646208
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 3840 of 85882
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 3840
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 8192 of 82042
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 8192
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 73850
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646208
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 15480 of 73850
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 15480
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 58370
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646208
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 904 of 58370
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 904
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 57466
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646209
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 7784 of 57466
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 7784
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 49682
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646209
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 408 of 49682
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 408
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 49274
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646209
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 3936 of 49274
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 3936
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 45338
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646209
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 4256 of 45338
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 4256
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 41082
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646209
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 7328 of 41082
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 7328
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 33754
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646210
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 4344 of 33754
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 4344
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 29410
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646210
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 2896 of 29410
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 2896
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 26514
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646210
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 1816 of 26514
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 1816
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 24698
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646211
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 8320 of 24698
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 8320
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 16378
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646211
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 2896 of 16378
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 2896
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 13482
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646211
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 5168 of 13482
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 5168
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 8314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646211
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 8192 of 8314
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 8192
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 122
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 9, old: 1515520646206, new: 1515520646211
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http read client request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 122 of 122
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body recv 122
2018/01/09 17:54:26 [debug] 10524#10524: *1 http body old buf t:1 f:0 000055D3DFDE357B, pos 000055D3DFDE357B, size: 613 file: 0, size: 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 http body new buf t:1 f:0 000055D3DFE1AA60, pos 000055D3DFE1AA60, size: 131314 file: 0, size: 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 http client request body rest 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer del: 9: 1515520646206
2018/01/09 17:54:26 [debug] 10524#10524: *1 http init upstream, client timer: 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 epoll add event: fd:9 op:3 ev:80002005
2018/01/09 17:54:26 [debug] 10524#10524: *1 http script copy: "Host: "
2018/01/09 17:54:26 [debug] 10524#10524: *1 http script var: "processingServer"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http script copy: "
"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http script copy: "Connection: close
"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http script copy: "Content-Length: "
2018/01/09 17:54:26 [debug] 10524#10524: *1 http script var: "131927"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http script copy: "
"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http script copy: ""
2018/01/09 17:54:26 [debug] 10524#10524: *1 http script copy: ""
2018/01/09 17:54:26 [debug] 10524#10524: *1 http proxy header: "X-Forwarded-For: 54.145.000.000"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http proxy header: "X-Forwarded-Proto: http"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http proxy header: "X-Forwarded-Port: 80"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http proxy header: "X-Amzn-Trace-Id: Root=1-5a5501d2-7fd1b59646cb91a36c8c9ada"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http proxy header: "Accept: application/json, text/plain, */*"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http proxy header: "Content-Type: application/json;charset=utf-8"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http proxy header: "User-Agent: axios/0.16.2"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http proxy header:
"POST /mps/updateFunction?mode=async HTTP/1.1
Host: processingServer
Connection: close
Content-Length: 131927
X-Forwarded-For: 54.145.000.000
X-Forwarded-Proto: http
X-Forwarded-Port: 80
X-Amzn-Trace-Id: Root=1-5a5501d2-7fd1b59646cb91a36c8c9ada
Accept: application/json, text/plain, */*
Content-Type: application/json;charset=utf-8
User-Agent: axios/0.16.2
"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http cleanup add: 000055D3DFDD4A48
2018/01/09 17:54:26 [debug] 10524#10524: *1 init keepalive peer
2018/01/09 17:54:26 [debug] 10524#10524: *1 get keepalive peer
2018/01/09 17:54:26 [debug] 10524#10524: *1 get rr peer, try: 1
2018/01/09 17:54:26 [debug] 10524#10524: *1 stream socket 10
2018/01/09 17:54:26 [debug] 10524#10524: *1 epoll add connection: fd:10 ev:80002005
2018/01/09 17:54:26 [debug] 10524#10524: *1 connect to 127.0.0.1:9928, fd:10 #2
2018/01/09 17:54:26 [debug] 10524#10524: *1 http upstream connect: -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 posix_memalign: 000055D3DFDC2630:128 @16
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer add: 10: 60000:1515520526211
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFE08B10
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFE08B70
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFE08B10
2018/01/09 17:54:26 [debug] 10524#10524: *1 http run request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http upstream check client, write event:1, "/mps/updateFunction"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http upstream recv(): -1 (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFE08B70
2018/01/09 17:54:26 [debug] 10524#10524: *1 http upstream request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http upstream send request handler
2018/01/09 17:54:26 [debug] 10524#10524: *1 http upstream send request
2018/01/09 17:54:26 [debug] 10524#10524: *1 http upstream send request body
2018/01/09 17:54:26 [debug] 10524#10524: *1 chain writer buf fl:0 s:391
2018/01/09 17:54:26 [debug] 10524#10524: *1 chain writer buf fl:0 s:613
2018/01/09 17:54:26 [debug] 10524#10524: *1 chain writer buf fl:1 s:131314
2018/01/09 17:54:26 [debug] 10524#10524: *1 chain writer in: 000055D3DFDD4AE8
2018/01/09 17:54:26 [debug] 10524#10524: *1 writev: 132318 of 132318
2018/01/09 17:54:26 [debug] 10524#10524: *1 chain writer out: 0000000000000000
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer del: 10: 1515520526211
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer add: 10: 300000:1515520766211
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B60
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFE08B70
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B60
2018/01/09 17:54:26 [debug] 10524#10524: *1 http upstream request: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http upstream process header
2018/01/09 17:54:26 [debug] 10524#10524: *1 malloc: 000055D3DFDC6BE0:4096
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:10 491 of 4096
2018/01/09 17:54:26 [debug] 10524#10524: *1 http proxy status 201 "201 Created"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http proxy header: "Location: /~7685a61a-4eea-4ebc-b18a-003f32c9520e/requests/20f6840c-ac3c-464e-a963-a31e4e59c11e"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http proxy header: "X-MPS-Start-Time: 00000002dff60ce1"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http proxy header: "Access-Control-Expose-Headers: X-MPS-Start-Time"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http proxy header: "Content-Length: 246"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http proxy header: "Connection: Close"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http proxy header done
2018/01/09 17:54:26 [debug] 10524#10524: *1 xslt filter header
2018/01/09 17:54:26 [debug] 10524#10524: *1 posix_memalign: 000055D3DFDC7BF0:4096 @16
2018/01/09 17:54:26 [debug] 10524#10524: *1 HTTP/1.1 201 Created
Server: nginx/1.10.3 (Ubuntu)
Date: Tue, 09 Jan 2018 17:54:26 GMT
Content-Length: 246
Connection: keep-alive
Location: /~7685a61a-4eea-4ebc-b18a-003f32c9520e/requests/20f6840c-ac3c-464e-a963-a31e4e59c11e
X-MPS-Start-Time: 00000002dff60ce1
Access-Control-Expose-Headers: X-MPS-Start-Time
X-external-IP: 54.89.000.000
2018/01/09 17:54:26 [debug] 10524#10524: *1 write new buf t:1 f:0 000055D3DFDC7C10, pos 000055D3DFDC7C10, size: 348 file: 0, size: 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 http write filter: l:0 f:0 s:348
2018/01/09 17:54:26 [debug] 10524#10524: *1 http cacheable: 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 http proxy filter init s:201 h:0 c:0 l:246
2018/01/09 17:54:26 [debug] 10524#10524: *1 http upstream process upstream
2018/01/09 17:54:26 [debug] 10524#10524: *1 pipe read upstream: 1
2018/01/09 17:54:26 [debug] 10524#10524: *1 pipe preread: 246
2018/01/09 17:54:26 [debug] 10524#10524: *1 readv: 1, last:3605
2018/01/09 17:54:26 [debug] 10524#10524: *1 readv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 pipe recv chain: -2
2018/01/09 17:54:26 [debug] 10524#10524: *1 pipe buf free s:0 t:1 f:0 000055D3DFDC6BE0, pos 000055D3DFDC6CD5, size: 246 file: 0, size: 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 pipe length: 246
2018/01/09 17:54:26 [debug] 10524#10524: *1 input buf #0
2018/01/09 17:54:26 [debug] 10524#10524: *1 pipe write downstream: 1
2018/01/09 17:54:26 [debug] 10524#10524: *1 pipe write downstream flush in
2018/01/09 17:54:26 [debug] 10524#10524: *1 http output filter "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http copy filter: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 image filter
2018/01/09 17:54:26 [debug] 10524#10524: *1 xslt filter body
2018/01/09 17:54:26 [debug] 10524#10524: *1 http postpone filter "/mps/updateFunction?mode=async" 000055D3DFDD4AB8
2018/01/09 17:54:26 [debug] 10524#10524: *1 write old buf t:1 f:0 000055D3DFDC7C10, pos 000055D3DFDC7C10, size: 348 file: 0, size: 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 write new buf t:1 f:0 000055D3DFDC6BE0, pos 000055D3DFDC6CD5, size: 246 file: 0, size: 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 http write filter: l:0 f:0 s:594
2018/01/09 17:54:26 [debug] 10524#10524: *1 http copy filter: 0 "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 pipe write downstream done
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer: 10, old: 1515520766211, new: 1515520766212
2018/01/09 17:54:26 [debug] 10524#10524: *1 http upstream exit: 0000000000000000
2018/01/09 17:54:26 [debug] 10524#10524: *1 finalize http upstream request: 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 finalize http proxy request
2018/01/09 17:54:26 [debug] 10524#10524: *1 free keepalive peer
2018/01/09 17:54:26 [debug] 10524#10524: *1 free rr peer 1 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 close http upstream connection: 10
2018/01/09 17:54:26 [debug] 10524#10524: *1 free: 000055D3DFDC2630, unused: 48
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer del: 10: 1515520766211
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFE08B70
2018/01/09 17:54:26 [debug] 10524#10524: *1 reusable connection: 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 http upstream temp fd: -1
2018/01/09 17:54:26 [debug] 10524#10524: *1 http output filter "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http copy filter: "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 image filter
2018/01/09 17:54:26 [debug] 10524#10524: *1 xslt filter body
2018/01/09 17:54:26 [debug] 10524#10524: *1 http postpone filter "/mps/updateFunction?mode=async" 00007FFEF7EC0BB0
2018/01/09 17:54:26 [debug] 10524#10524: *1 write old buf t:1 f:0 000055D3DFDC7C10, pos 000055D3DFDC7C10, size: 348 file: 0, size: 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 write old buf t:1 f:0 000055D3DFDC6BE0, pos 000055D3DFDC6CD5, size: 246 file: 0, size: 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 http write filter: l:1 f:0 s:594
2018/01/09 17:54:26 [debug] 10524#10524: *1 http write filter limit 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 writev: 594 of 594
2018/01/09 17:54:26 [debug] 10524#10524: *1 http write filter 0000000000000000
2018/01/09 17:54:26 [debug] 10524#10524: *1 http copy filter: 0 "/mps/updateFunction?mode=async"
2018/01/09 17:54:26 [debug] 10524#10524: *1 http finalize request: 0, "/mps/updateFunction?mode=async" a:1, c:1
2018/01/09 17:54:26 [debug] 10524#10524: *1 set http keepalive handler
2018/01/09 17:54:26 [debug] 10524#10524: *1 http close request
2018/01/09 17:54:26 [debug] 10524#10524: *1 http log handler
2018/01/09 17:54:26 [debug] 10524#10524: *1 malloc: 00007F3DF5A6D010:131927
2018/01/09 17:54:26 [debug] 10524#10524: *1 malloc: 00007F3DF5A48010:148242
2018/01/09 17:54:26 [debug] 10524#10524: *1 malloc: 00007F3DF5A23010:148242
2018/01/09 17:54:26 [debug] 10524#10524: *1 free: 00007F3DF5A23010
2018/01/09 17:54:26 [debug] 10524#10524: *1 free: 00007F3DF5A48010
2018/01/09 17:54:26 [debug] 10524#10524: *1 free: 00007F3DF5A6D010
2018/01/09 17:54:26 [debug] 10524#10524: *1 free: 000055D3DFDC6BE0
2018/01/09 17:54:26 [debug] 10524#10524: *1 free: 000055D3DFE1AA60
2018/01/09 17:54:26 [debug] 10524#10524: *1 free: 000055D3DFDE0F30, unused: 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 free: 000055D3DFDD3F80, unused: 24
2018/01/09 17:54:26 [debug] 10524#10524: *1 free: 000055D3DFDC7BF0, unused: 3416
2018/01/09 17:54:26 [debug] 10524#10524: *1 free: 000055D3DFDE33E0
2018/01/09 17:54:26 [debug] 10524#10524: *1 hc free: 0000000000000000 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 hc busy: 0000000000000000 0
2018/01/09 17:54:26 [debug] 10524#10524: *1 tcp_nodelay
2018/01/09 17:54:26 [debug] 10524#10524: *1 reusable connection: 1
2018/01/09 17:54:26 [debug] 10524#10524: *1 event timer add: 9: 100000000:1515620466212
2018/01/09 17:54:26 [debug] 10524#10524: *1 post event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 delete posted event 000055D3DFDF6B00
2018/01/09 17:54:26 [debug] 10524#10524: *1 http keepalive handler
2018/01/09 17:54:26 [debug] 10524#10524: *1 malloc: 000055D3DFDE33E0:1024
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv: fd:9 -1 of 1024
2018/01/09 17:54:26 [debug] 10524#10524: *1 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:26 [debug] 10524#10524: *1 free: 000055D3DFDE33E0
2018/01/09 17:54:27 [debug] 10524#10524: post event 000055D3DFDF6A40
2018/01/09 17:54:27 [debug] 10524#10524: delete posted event 000055D3DFDF6A40
2018/01/09 17:54:27 [debug] 10524#10524: accept on 0.0.0.0:80, ready: 0
2018/01/09 17:54:27 [debug] 10524#10524: posix_memalign: 000055D3DFDE33E0:512 @16
2018/01/09 17:54:27 [debug] 10524#10524: *3 accept: 172.31.000.000:4083 fd:10
2018/01/09 17:54:27 [debug] 10524#10524: *3 event timer add: 10: 180000:1515520647378
2018/01/09 17:54:27 [debug] 10524#10524: *3 reusable connection: 1
2018/01/09 17:54:27 [debug] 10524#10524: *3 epoll add event: fd:10 op:1 ev:80002001
2018/01/09 17:54:27 [debug] 10524#10524: *3 post event 000055D3DFDF6B60
2018/01/09 17:54:27 [debug] 10524#10524: *3 delete posted event 000055D3DFDF6B60
2018/01/09 17:54:27 [debug] 10524#10524: *3 http wait request handler
2018/01/09 17:54:27 [debug] 10524#10524: *3 malloc: 000055D3DFDE35F0:1024
2018/01/09 17:54:27 [debug] 10524#10524: *3 recv: fd:10 128 of 1024
2018/01/09 17:54:27 [debug] 10524#10524: *3 reusable connection: 0
2018/01/09 17:54:27 [debug] 10524#10524: *3 posix_memalign: 000055D3DFDE0F30:4096 @16
2018/01/09 17:54:27 [debug] 10524#10524: *3 http process request line
2018/01/09 17:54:27 [debug] 10524#10524: *3 http request line: "GET / HTTP/1.1"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http uri: "/"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http args: ""
2018/01/09 17:54:27 [debug] 10524#10524: *3 http exten: ""
2018/01/09 17:54:27 [debug] 10524#10524: *3 http process request header line
2018/01/09 17:54:27 [debug] 10524#10524: *3 http header: "Host: 172.31.000.000"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http header: "Connection: close"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http header: "User-Agent: ELB-HealthChecker/2.0"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http header: "Accept-Encoding: gzip, compressed"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http header done
2018/01/09 17:54:27 [debug] 10524#10524: *3 event timer del: 10: 1515520647378
2018/01/09 17:54:27 [debug] 10524#10524: *3 generic phase: 0
2018/01/09 17:54:27 [debug] 10524#10524: *3 rewrite phase: 1
2018/01/09 17:54:27 [debug] 10524#10524: *3 test location: "/"
2018/01/09 17:54:27 [debug] 10524#10524: *3 using configuration "/"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http cl:-1 max:5242880000
2018/01/09 17:54:27 [debug] 10524#10524: *3 rewrite phase: 3
2018/01/09 17:54:27 [debug] 10524#10524: *3 post rewrite phase: 4
2018/01/09 17:54:27 [debug] 10524#10524: *3 generic phase: 5
2018/01/09 17:54:27 [debug] 10524#10524: *3 generic phase: 6
2018/01/09 17:54:27 [debug] 10524#10524: *3 generic phase: 7
2018/01/09 17:54:27 [debug] 10524#10524: *3 access phase: 8
2018/01/09 17:54:27 [debug] 10524#10524: *3 access phase: 9
2018/01/09 17:54:27 [debug] 10524#10524: *3 access phase: 10
2018/01/09 17:54:27 [debug] 10524#10524: *3 post access phase: 11
2018/01/09 17:54:27 [debug] 10524#10524: *3 posix_memalign: 000055D3DFDD3F80:4096 @16
2018/01/09 17:54:27 [debug] 10524#10524: *3 http init upstream, client timer: 0
2018/01/09 17:54:27 [debug] 10524#10524: *3 epoll add event: fd:10 op:3 ev:80002005
2018/01/09 17:54:27 [debug] 10524#10524: *3 http script copy: "Host: "
2018/01/09 17:54:27 [debug] 10524#10524: *3 http script var: "processingServer"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http script copy: "
"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http script copy: "Connection: close
"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http script copy: ""
2018/01/09 17:54:27 [debug] 10524#10524: *3 http script copy: ""
2018/01/09 17:54:27 [debug] 10524#10524: *3 http script copy: ""
2018/01/09 17:54:27 [debug] 10524#10524: *3 http script copy: ""
2018/01/09 17:54:27 [debug] 10524#10524: *3 http proxy header: "User-Agent: ELB-HealthChecker/2.0"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http proxy header: "Accept-Encoding: gzip, compressed"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http proxy header:
"GET / HTTP/1.1
Host: processingServer
Connection: close
User-Agent: ELB-HealthChecker/2.0
Accept-Encoding: gzip, compressed
"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http cleanup add: 000055D3DFDD46F8
2018/01/09 17:54:27 [debug] 10524#10524: *3 init keepalive peer
2018/01/09 17:54:27 [debug] 10524#10524: *3 get keepalive peer
2018/01/09 17:54:27 [debug] 10524#10524: *3 get rr peer, try: 1
2018/01/09 17:54:27 [debug] 10524#10524: *3 stream socket 14
2018/01/09 17:54:27 [debug] 10524#10524: *3 epoll add connection: fd:14 ev:80002005
2018/01/09 17:54:27 [debug] 10524#10524: *3 connect to 127.0.0.1:9928, fd:14 #4
2018/01/09 17:54:27 [debug] 10524#10524: *3 http upstream connect: -2
2018/01/09 17:54:27 [debug] 10524#10524: *3 posix_memalign: 000055D3DFDC2630:128 @16
2018/01/09 17:54:27 [debug] 10524#10524: *3 event timer add: 14: 60000:1515520527378
2018/01/09 17:54:27 [debug] 10524#10524: *3 http finalize request: -4, "/?" a:1, c:2
2018/01/09 17:54:27 [debug] 10524#10524: *3 http request count:2 blk:0
2018/01/09 17:54:27 [debug] 10524#10524: *3 post event 000055D3DFE08B70
2018/01/09 17:54:27 [debug] 10524#10524: *3 post event 000055D3DFE08BD0
2018/01/09 17:54:27 [debug] 10524#10524: *3 delete posted event 000055D3DFE08B70
2018/01/09 17:54:27 [debug] 10524#10524: *3 http run request: "/?"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http upstream check client, write event:1, "/"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http upstream recv(): -1 (11: Resource temporarily unavailable)
2018/01/09 17:54:27 [debug] 10524#10524: *3 delete posted event 000055D3DFE08BD0
2018/01/09 17:54:27 [debug] 10524#10524: *3 http upstream request: "/?"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http upstream send request handler
2018/01/09 17:54:27 [debug] 10524#10524: *3 http upstream send request
2018/01/09 17:54:27 [debug] 10524#10524: *3 http upstream send request body
2018/01/09 17:54:27 [debug] 10524#10524: *3 chain writer buf fl:1 s:131
2018/01/09 17:54:27 [debug] 10524#10524: *3 chain writer in: 000055D3DFDD4768
2018/01/09 17:54:27 [debug] 10524#10524: *3 writev: 131 of 131
2018/01/09 17:54:27 [debug] 10524#10524: *3 chain writer out: 0000000000000000
2018/01/09 17:54:27 [debug] 10524#10524: *3 event timer del: 14: 1515520527378
2018/01/09 17:54:27 [debug] 10524#10524: *3 event timer add: 14: 300000:1515520767378
2018/01/09 17:54:27 [debug] 10524#10524: *3 post event 000055D3DFDF6BC0
2018/01/09 17:54:27 [debug] 10524#10524: *3 post event 000055D3DFE08BD0
2018/01/09 17:54:27 [debug] 10524#10524: *3 delete posted event 000055D3DFDF6BC0
2018/01/09 17:54:27 [debug] 10524#10524: *3 http upstream request: "/?"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http upstream process header
2018/01/09 17:54:27 [debug] 10524#10524: *3 malloc: 000055D3DFDC6BE0:4096
2018/01/09 17:54:27 [debug] 10524#10524: *3 recv: fd:14 91 of 4096
2018/01/09 17:54:27 [debug] 10524#10524: *3 http proxy status 404 "404 URL not found (request id=0:11789:0)"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http proxy header: "Content-Length: 0"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http proxy header: "Connection: Close"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http proxy header done
2018/01/09 17:54:27 [debug] 10524#10524: *3 xslt filter header
2018/01/09 17:54:27 [debug] 10524#10524: *3 HTTP/1.1 404 URL not found (request id=0:11789:0)
Server: nginx/1.10.3 (Ubuntu)
Date: Tue, 09 Jan 2018 17:54:27 GMT
Content-Length: 0
Connection: close
2018/01/09 17:54:27 [debug] 10524#10524: *3 write new buf t:1 f:0 000055D3DFDD49C0, pos 000055D3DFDD49C0, size: 159 file: 0, size: 0
2018/01/09 17:54:27 [debug] 10524#10524: *3 http write filter: l:0 f:0 s:159
2018/01/09 17:54:27 [debug] 10524#10524: *3 http cacheable: 0
2018/01/09 17:54:27 [debug] 10524#10524: *3 http proxy filter init s:404 h:0 c:0 l:0
2018/01/09 17:54:27 [debug] 10524#10524: *3 http upstream process upstream
2018/01/09 17:54:27 [debug] 10524#10524: *3 pipe read upstream: 1
2018/01/09 17:54:27 [debug] 10524#10524: *3 pipe preread: 0
2018/01/09 17:54:27 [debug] 10524#10524: *3 readv: 1, last:4005
2018/01/09 17:54:27 [debug] 10524#10524: *3 readv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:27 [debug] 10524#10524: *3 pipe recv chain: -2
2018/01/09 17:54:27 [debug] 10524#10524: *3 pipe buf free s:0 t:1 f:0 000055D3DFDC6BE0, pos 000055D3DFDC6C3B, size: 0 file: 0, size: 0
2018/01/09 17:54:27 [debug] 10524#10524: *3 pipe length: 0
2018/01/09 17:54:27 [debug] 10524#10524: *3 pipe write downstream: 1
2018/01/09 17:54:27 [debug] 10524#10524: *3 pipe write downstream done
2018/01/09 17:54:27 [debug] 10524#10524: *3 event timer: 14, old: 1515520767378, new: 1515520767379
2018/01/09 17:54:27 [debug] 10524#10524: *3 http upstream exit: 0000000000000000
2018/01/09 17:54:27 [debug] 10524#10524: *3 finalize http upstream request: 0
2018/01/09 17:54:27 [debug] 10524#10524: *3 finalize http proxy request
2018/01/09 17:54:27 [debug] 10524#10524: *3 free keepalive peer
2018/01/09 17:54:27 [debug] 10524#10524: *3 free rr peer 1 0
2018/01/09 17:54:27 [debug] 10524#10524: *3 close http upstream connection: 14
2018/01/09 17:54:27 [debug] 10524#10524: *3 free: 000055D3DFDC2630, unused: 48
2018/01/09 17:54:27 [debug] 10524#10524: *3 event timer del: 14: 1515520767378
2018/01/09 17:54:27 [debug] 10524#10524: *3 delete posted event 000055D3DFE08BD0
2018/01/09 17:54:27 [debug] 10524#10524: *3 reusable connection: 0
2018/01/09 17:54:27 [debug] 10524#10524: *3 http upstream temp fd: -1
2018/01/09 17:54:27 [debug] 10524#10524: *3 http output filter "/?"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http copy filter: "/?"
2018/01/09 17:54:27 [debug] 10524#10524: *3 image filter
2018/01/09 17:54:27 [debug] 10524#10524: *3 xslt filter body
2018/01/09 17:54:27 [debug] 10524#10524: *3 http postpone filter "/?" 00007FFEF7EC0BB0
2018/01/09 17:54:27 [debug] 10524#10524: *3 write old buf t:1 f:0 000055D3DFDD49C0, pos 000055D3DFDD49C0, size: 159 file: 0, size: 0
2018/01/09 17:54:27 [debug] 10524#10524: *3 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
2018/01/09 17:54:27 [debug] 10524#10524: *3 http write filter: l:1 f:0 s:159
2018/01/09 17:54:27 [debug] 10524#10524: *3 http write filter limit 0
2018/01/09 17:54:27 [debug] 10524#10524: *3 writev: 159 of 159
2018/01/09 17:54:27 [debug] 10524#10524: *3 http write filter 0000000000000000
2018/01/09 17:54:27 [debug] 10524#10524: *3 http copy filter: 0 "/?"
2018/01/09 17:54:27 [debug] 10524#10524: *3 http finalize request: 0, "/?" a:1, c:1
2018/01/09 17:54:27 [debug] 10524#10524: *3 event timer add: 10: 5000:1515520472379
2018/01/09 17:54:27 [debug] 10524#10524: *3 http lingering close handler
2018/01/09 17:54:27 [debug] 10524#10524: *3 recv: fd:10 -1 of 4096
2018/01/09 17:54:27 [debug] 10524#10524: *3 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:27 [debug] 10524#10524: *3 lingering read: -2
2018/01/09 17:54:27 [debug] 10524#10524: *3 event timer: 10, old: 1515520472379, new: 1515520472379
2018/01/09 17:54:27 [debug] 10524#10524: *3 post event 000055D3DFE08B70
2018/01/09 17:54:27 [debug] 10524#10524: *3 delete posted event 000055D3DFE08B70
2018/01/09 17:54:27 [debug] 10524#10524: *3 http empty handler
2018/01/09 17:54:27 [debug] 10524#10524: *3 post event 000055D3DFDF6B60
2018/01/09 17:54:27 [debug] 10524#10524: *3 post event 000055D3DFE08B70
2018/01/09 17:54:27 [debug] 10524#10524: *3 delete posted event 000055D3DFDF6B60
2018/01/09 17:54:27 [debug] 10524#10524: *3 http lingering close handler
2018/01/09 17:54:27 [debug] 10524#10524: *3 recv: fd:10 0 of 4096
2018/01/09 17:54:27 [debug] 10524#10524: *3 lingering read: 0
2018/01/09 17:54:27 [debug] 10524#10524: *3 http request count:1 blk:0
2018/01/09 17:54:27 [debug] 10524#10524: *3 http close request
2018/01/09 17:54:27 [debug] 10524#10524: *3 http log handler
2018/01/09 17:54:27 [debug] 10524#10524: *3 free: 000055D3DFDC6BE0
2018/01/09 17:54:27 [debug] 10524#10524: *3 free: 000055D3DFDE0F30, unused: 4
2018/01/09 17:54:27 [debug] 10524#10524: *3 free: 000055D3DFDD3F80, unused: 800
2018/01/09 17:54:27 [debug] 10524#10524: *3 close http connection: 10
2018/01/09 17:54:27 [debug] 10524#10524: *3 event timer del: 10: 1515520472379
2018/01/09 17:54:27 [debug] 10524#10524: *3 delete posted event 000055D3DFE08B70
2018/01/09 17:54:27 [debug] 10524#10524: *3 reusable connection: 0
2018/01/09 17:54:27 [debug] 10524#10524: *3 free: 000055D3DFDE35F0
2018/01/09 17:54:27 [debug] 10524#10524: *3 free: 000055D3DFDE33E0, unused: 128
2018/01/09 17:54:28 [debug] 10524#10524: post event 000055D3DFDF6A40
2018/01/09 17:54:28 [debug] 10524#10524: delete posted event 000055D3DFDF6A40
2018/01/09 17:54:28 [debug] 10524#10524: accept on 0.0.0.0:80, ready: 0
2018/01/09 17:54:28 [debug] 10524#10524: posix_memalign: 000055D3DFDE33E0:512 @16
2018/01/09 17:54:28 [debug] 10524#10524: *5 accept: 172.31.000.000:52231 fd:10
2018/01/09 17:54:28 [debug] 10524#10524: *5 event timer add: 10: 180000:1515520648526
2018/01/09 17:54:28 [debug] 10524#10524: *5 reusable connection: 1
2018/01/09 17:54:28 [debug] 10524#10524: *5 epoll add event: fd:10 op:1 ev:80002001
2018/01/09 17:54:28 [debug] 10524#10524: *5 post event 000055D3DFDF6B60
2018/01/09 17:54:28 [debug] 10524#10524: *5 delete posted event 000055D3DFDF6B60
2018/01/09 17:54:28 [debug] 10524#10524: *5 http wait request handler
2018/01/09 17:54:28 [debug] 10524#10524: *5 malloc: 000055D3DFDE35F0:1024
2018/01/09 17:54:28 [debug] 10524#10524: *5 recv: fd:10 128 of 1024
2018/01/09 17:54:28 [debug] 10524#10524: *5 reusable connection: 0
2018/01/09 17:54:28 [debug] 10524#10524: *5 posix_memalign: 000055D3DFDE0F30:4096 @16
2018/01/09 17:54:28 [debug] 10524#10524: *5 http process request line
2018/01/09 17:54:28 [debug] 10524#10524: *5 http request line: "GET / HTTP/1.1"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http uri: "/"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http args: ""
2018/01/09 17:54:28 [debug] 10524#10524: *5 http exten: ""
2018/01/09 17:54:28 [debug] 10524#10524: *5 http process request header line
2018/01/09 17:54:28 [debug] 10524#10524: *5 http header: "Host: 172.31.000.000"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http header: "Connection: close"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http header: "User-Agent: ELB-HealthChecker/2.0"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http header: "Accept-Encoding: gzip, compressed"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http header done
2018/01/09 17:54:28 [debug] 10524#10524: *5 event timer del: 10: 1515520648526
2018/01/09 17:54:28 [debug] 10524#10524: *5 generic phase: 0
2018/01/09 17:54:28 [debug] 10524#10524: *5 rewrite phase: 1
2018/01/09 17:54:28 [debug] 10524#10524: *5 test location: "/"
2018/01/09 17:54:28 [debug] 10524#10524: *5 using configuration "/"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http cl:-1 max:5242880000
2018/01/09 17:54:28 [debug] 10524#10524: *5 rewrite phase: 3
2018/01/09 17:54:28 [debug] 10524#10524: *5 post rewrite phase: 4
2018/01/09 17:54:28 [debug] 10524#10524: *5 generic phase: 5
2018/01/09 17:54:28 [debug] 10524#10524: *5 generic phase: 6
2018/01/09 17:54:28 [debug] 10524#10524: *5 generic phase: 7
2018/01/09 17:54:28 [debug] 10524#10524: *5 access phase: 8
2018/01/09 17:54:28 [debug] 10524#10524: *5 access phase: 9
2018/01/09 17:54:28 [debug] 10524#10524: *5 access phase: 10
2018/01/09 17:54:28 [debug] 10524#10524: *5 post access phase: 11
2018/01/09 17:54:28 [debug] 10524#10524: *5 posix_memalign: 000055D3DFDD3F80:4096 @16
2018/01/09 17:54:28 [debug] 10524#10524: *5 http init upstream, client timer: 0
2018/01/09 17:54:28 [debug] 10524#10524: *5 epoll add event: fd:10 op:3 ev:80002005
2018/01/09 17:54:28 [debug] 10524#10524: *5 http script copy: "Host: "
2018/01/09 17:54:28 [debug] 10524#10524: *5 http script var: "processingServer"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http script copy: "
"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http script copy: "Connection: close
"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http script copy: ""
2018/01/09 17:54:28 [debug] 10524#10524: *5 http script copy: ""
2018/01/09 17:54:28 [debug] 10524#10524: *5 http script copy: ""
2018/01/09 17:54:28 [debug] 10524#10524: *5 http script copy: ""
2018/01/09 17:54:28 [debug] 10524#10524: *5 http proxy header: "User-Agent: ELB-HealthChecker/2.0"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http proxy header: "Accept-Encoding: gzip, compressed"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http proxy header:
"GET / HTTP/1.1
Host: processingServer
Connection: close
User-Agent: ELB-HealthChecker/2.0
Accept-Encoding: gzip, compressed
"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http cleanup add: 000055D3DFDD46F8
2018/01/09 17:54:28 [debug] 10524#10524: *5 init keepalive peer
2018/01/09 17:54:28 [debug] 10524#10524: *5 get keepalive peer
2018/01/09 17:54:28 [debug] 10524#10524: *5 get rr peer, try: 1
2018/01/09 17:54:28 [debug] 10524#10524: *5 stream socket 14
2018/01/09 17:54:28 [debug] 10524#10524: *5 epoll add connection: fd:14 ev:80002005
2018/01/09 17:54:28 [debug] 10524#10524: *5 connect to 127.0.0.1:9928, fd:14 #6
2018/01/09 17:54:28 [debug] 10524#10524: *5 http upstream connect: -2
2018/01/09 17:54:28 [debug] 10524#10524: *5 posix_memalign: 000055D3DFDC2630:128 @16
2018/01/09 17:54:28 [debug] 10524#10524: *5 event timer add: 14: 60000:1515520528527
2018/01/09 17:54:28 [debug] 10524#10524: *5 http finalize request: -4, "/?" a:1, c:2
2018/01/09 17:54:28 [debug] 10524#10524: *5 http request count:2 blk:0
2018/01/09 17:54:28 [debug] 10524#10524: *5 post event 000055D3DFE08B70
2018/01/09 17:54:28 [debug] 10524#10524: *5 post event 000055D3DFE08BD0
2018/01/09 17:54:28 [debug] 10524#10524: *5 delete posted event 000055D3DFE08B70
2018/01/09 17:54:28 [debug] 10524#10524: *5 http run request: "/?"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http upstream check client, write event:1, "/"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http upstream recv(): -1 (11: Resource temporarily unavailable)
2018/01/09 17:54:28 [debug] 10524#10524: *5 delete posted event 000055D3DFE08BD0
2018/01/09 17:54:28 [debug] 10524#10524: *5 http upstream request: "/?"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http upstream send request handler
2018/01/09 17:54:28 [debug] 10524#10524: *5 http upstream send request
2018/01/09 17:54:28 [debug] 10524#10524: *5 http upstream send request body
2018/01/09 17:54:28 [debug] 10524#10524: *5 chain writer buf fl:1 s:131
2018/01/09 17:54:28 [debug] 10524#10524: *5 chain writer in: 000055D3DFDD4768
2018/01/09 17:54:28 [debug] 10524#10524: *5 writev: 131 of 131
2018/01/09 17:54:28 [debug] 10524#10524: *5 chain writer out: 0000000000000000
2018/01/09 17:54:28 [debug] 10524#10524: *5 event timer del: 14: 1515520528527
2018/01/09 17:54:28 [debug] 10524#10524: *5 event timer add: 14: 300000:1515520768527
2018/01/09 17:54:28 [debug] 10524#10524: *5 post event 000055D3DFDF6BC0
2018/01/09 17:54:28 [debug] 10524#10524: *5 post event 000055D3DFE08BD0
2018/01/09 17:54:28 [debug] 10524#10524: *5 delete posted event 000055D3DFDF6BC0
2018/01/09 17:54:28 [debug] 10524#10524: *5 http upstream request: "/?"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http upstream process header
2018/01/09 17:54:28 [debug] 10524#10524: *5 malloc: 000055D3DFDC6BE0:4096
2018/01/09 17:54:28 [debug] 10524#10524: *5 recv: fd:14 91 of 4096
2018/01/09 17:54:28 [debug] 10524#10524: *5 http proxy status 404 "404 URL not found (request id=0:11791:0)"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http proxy header: "Content-Length: 0"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http proxy header: "Connection: Close"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http proxy header done
2018/01/09 17:54:28 [debug] 10524#10524: *5 xslt filter header
2018/01/09 17:54:28 [debug] 10524#10524: *5 HTTP/1.1 404 URL not found (request id=0:11791:0)
Server: nginx/1.10.3 (Ubuntu)
Date: Tue, 09 Jan 2018 17:54:28 GMT
Content-Length: 0
Connection: close
2018/01/09 17:54:28 [debug] 10524#10524: *5 write new buf t:1 f:0 000055D3DFDD49C0, pos 000055D3DFDD49C0, size: 159 file: 0, size: 0
2018/01/09 17:54:28 [debug] 10524#10524: *5 http write filter: l:0 f:0 s:159
2018/01/09 17:54:28 [debug] 10524#10524: *5 http cacheable: 0
2018/01/09 17:54:28 [debug] 10524#10524: *5 http proxy filter init s:404 h:0 c:0 l:0
2018/01/09 17:54:28 [debug] 10524#10524: *5 http upstream process upstream
2018/01/09 17:54:28 [debug] 10524#10524: *5 pipe read upstream: 1
2018/01/09 17:54:28 [debug] 10524#10524: *5 pipe preread: 0
2018/01/09 17:54:28 [debug] 10524#10524: *5 readv: 1, last:4005
2018/01/09 17:54:28 [debug] 10524#10524: *5 readv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:28 [debug] 10524#10524: *5 pipe recv chain: -2
2018/01/09 17:54:28 [debug] 10524#10524: *5 pipe buf free s:0 t:1 f:0 000055D3DFDC6BE0, pos 000055D3DFDC6C3B, size: 0 file: 0, size: 0
2018/01/09 17:54:28 [debug] 10524#10524: *5 pipe length: 0
2018/01/09 17:54:28 [debug] 10524#10524: *5 pipe write downstream: 1
2018/01/09 17:54:28 [debug] 10524#10524: *5 pipe write downstream done
2018/01/09 17:54:28 [debug] 10524#10524: *5 event timer: 14, old: 1515520768527, new: 1515520768527
2018/01/09 17:54:28 [debug] 10524#10524: *5 http upstream exit: 0000000000000000
2018/01/09 17:54:28 [debug] 10524#10524: *5 finalize http upstream request: 0
2018/01/09 17:54:28 [debug] 10524#10524: *5 finalize http proxy request
2018/01/09 17:54:28 [debug] 10524#10524: *5 free keepalive peer
2018/01/09 17:54:28 [debug] 10524#10524: *5 free rr peer 1 0
2018/01/09 17:54:28 [debug] 10524#10524: *5 close http upstream connection: 14
2018/01/09 17:54:28 [debug] 10524#10524: *5 free: 000055D3DFDC2630, unused: 48
2018/01/09 17:54:28 [debug] 10524#10524: *5 event timer del: 14: 1515520768527
2018/01/09 17:54:28 [debug] 10524#10524: *5 delete posted event 000055D3DFE08BD0
2018/01/09 17:54:28 [debug] 10524#10524: *5 reusable connection: 0
2018/01/09 17:54:28 [debug] 10524#10524: *5 http upstream temp fd: -1
2018/01/09 17:54:28 [debug] 10524#10524: *5 http output filter "/?"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http copy filter: "/?"
2018/01/09 17:54:28 [debug] 10524#10524: *5 image filter
2018/01/09 17:54:28 [debug] 10524#10524: *5 xslt filter body
2018/01/09 17:54:28 [debug] 10524#10524: *5 http postpone filter "/?" 00007FFEF7EC0BB0
2018/01/09 17:54:28 [debug] 10524#10524: *5 write old buf t:1 f:0 000055D3DFDD49C0, pos 000055D3DFDD49C0, size: 159 file: 0, size: 0
2018/01/09 17:54:28 [debug] 10524#10524: *5 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
2018/01/09 17:54:28 [debug] 10524#10524: *5 http write filter: l:1 f:0 s:159
2018/01/09 17:54:28 [debug] 10524#10524: *5 http write filter limit 0
2018/01/09 17:54:28 [debug] 10524#10524: *5 writev: 159 of 159
2018/01/09 17:54:28 [debug] 10524#10524: *5 http write filter 0000000000000000
2018/01/09 17:54:28 [debug] 10524#10524: *5 http copy filter: 0 "/?"
2018/01/09 17:54:28 [debug] 10524#10524: *5 http finalize request: 0, "/?" a:1, c:1
2018/01/09 17:54:28 [debug] 10524#10524: *5 event timer add: 10: 5000:1515520473527
2018/01/09 17:54:28 [debug] 10524#10524: *5 http lingering close handler
2018/01/09 17:54:28 [debug] 10524#10524: *5 recv: fd:10 -1 of 4096
2018/01/09 17:54:28 [debug] 10524#10524: *5 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:28 [debug] 10524#10524: *5 lingering read: -2
2018/01/09 17:54:28 [debug] 10524#10524: *5 event timer: 10, old: 1515520473527, new: 1515520473527
2018/01/09 17:54:28 [debug] 10524#10524: *5 post event 000055D3DFE08B70
2018/01/09 17:54:28 [debug] 10524#10524: *5 delete posted event 000055D3DFE08B70
2018/01/09 17:54:28 [debug] 10524#10524: *5 http empty handler
2018/01/09 17:54:28 [debug] 10524#10524: *5 post event 000055D3DFDF6B60
2018/01/09 17:54:28 [debug] 10524#10524: *5 post event 000055D3DFE08B70
2018/01/09 17:54:28 [debug] 10524#10524: *5 delete posted event 000055D3DFDF6B60
2018/01/09 17:54:28 [debug] 10524#10524: *5 http lingering close handler
2018/01/09 17:54:28 [debug] 10524#10524: *5 recv: fd:10 0 of 4096
2018/01/09 17:54:28 [debug] 10524#10524: *5 lingering read: 0
2018/01/09 17:54:28 [debug] 10524#10524: *5 http request count:1 blk:0
2018/01/09 17:54:28 [debug] 10524#10524: *5 http close request
2018/01/09 17:54:28 [debug] 10524#10524: *5 http log handler
2018/01/09 17:54:28 [debug] 10524#10524: *5 free: 000055D3DFDC6BE0
2018/01/09 17:54:28 [debug] 10524#10524: *5 free: 000055D3DFDE0F30, unused: 4
2018/01/09 17:54:28 [debug] 10524#10524: *5 free: 000055D3DFDD3F80, unused: 800
2018/01/09 17:54:28 [debug] 10524#10524: *5 close http connection: 10
2018/01/09 17:54:28 [debug] 10524#10524: *5 event timer del: 10: 1515520473527
2018/01/09 17:54:28 [debug] 10524#10524: *5 delete posted event 000055D3DFE08B70
2018/01/09 17:54:28 [debug] 10524#10524: *5 reusable connection: 0
2018/01/09 17:54:28 [debug] 10524#10524: *5 free: 000055D3DFDE35F0
2018/01/09 17:54:28 [debug] 10524#10524: *5 free: 000055D3DFDE33E0, unused: 128
2018/01/09 17:54:42 [debug] 10524#10524: post event 000055D3DFDF6A40
2018/01/09 17:54:42 [debug] 10524#10524: delete posted event 000055D3DFDF6A40
2018/01/09 17:54:42 [debug] 10524#10524: accept on 0.0.0.0:80, ready: 0
2018/01/09 17:54:42 [debug] 10524#10524: posix_memalign: 000055D3DFDE33E0:512 @16
2018/01/09 17:54:42 [debug] 10524#10524: *7 accept: 172.31.000.000:52235 fd:10
2018/01/09 17:54:42 [debug] 10524#10524: *7 event timer add: 10: 180000:1515520662789
2018/01/09 17:54:42 [debug] 10524#10524: *7 reusable connection: 1
2018/01/09 17:54:42 [debug] 10524#10524: *7 epoll add event: fd:10 op:1 ev:80002001
2018/01/09 17:54:42 [debug] 10524#10524: *7 post event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 delete posted event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 http wait request handler
2018/01/09 17:54:42 [debug] 10524#10524: *7 malloc: 000055D3DFDE35F0:1024
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 1024 of 1024
2018/01/09 17:54:42 [debug] 10524#10524: *7 reusable connection: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 posix_memalign: 000055D3DFDE0F30:4096 @16
2018/01/09 17:54:42 [debug] 10524#10524: *7 http process request line
2018/01/09 17:54:42 [debug] 10524#10524: *7 http request line: "POST /mps/mySecondFunction?mode=sync HTTP/1.1"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http uri: "/mps/mySecondFunction"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http args: "mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http exten: ""
2018/01/09 17:54:42 [debug] 10524#10524: *7 http process request header line
2018/01/09 17:54:42 [debug] 10524#10524: *7 http header: "X-Forwarded-For: 54.145.000.000"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http header: "X-Forwarded-Proto: http"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http header: "X-Forwarded-Port: 80"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http header: "Host: my-load-balancer.us-east-1.elb.amazonaws.com"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http header: "X-Amzn-Trace-Id: Root=1-5a5501e2-62bf8c152c81659d47eb3c6f"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http header: "Content-Length: 131900"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http header: "Accept: application/json, text/plain, */*"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http header: "Content-Type: application/json;charset=utf-8"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http header: "User-Agent: axios/0.16.2"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http header done
2018/01/09 17:54:42 [debug] 10524#10524: *7 event timer del: 10: 1515520662789
2018/01/09 17:54:42 [debug] 10524#10524: *7 generic phase: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 rewrite phase: 1
2018/01/09 17:54:42 [debug] 10524#10524: *7 test location: "/"
2018/01/09 17:54:42 [debug] 10524#10524: *7 test location: "admin"
2018/01/09 17:54:42 [debug] 10524#10524: *7 using configuration "/"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http cl:131900 max:5242880000
2018/01/09 17:54:42 [debug] 10524#10524: *7 rewrite phase: 3
2018/01/09 17:54:42 [debug] 10524#10524: *7 post rewrite phase: 4
2018/01/09 17:54:42 [debug] 10524#10524: *7 generic phase: 5
2018/01/09 17:54:42 [debug] 10524#10524: *7 generic phase: 6
2018/01/09 17:54:42 [debug] 10524#10524: *7 generic phase: 7
2018/01/09 17:54:42 [debug] 10524#10524: *7 access phase: 8
2018/01/09 17:54:42 [debug] 10524#10524: *7 access phase: 9
2018/01/09 17:54:42 [debug] 10524#10524: *7 access phase: 10
2018/01/09 17:54:42 [debug] 10524#10524: *7 post access phase: 11
2018/01/09 17:54:42 [debug] 10524#10524: *7 posix_memalign: 000055D3DFDD3F80:4096 @16
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body preread 618
2018/01/09 17:54:42 [debug] 10524#10524: *7 http request body content length filter
2018/01/09 17:54:42 [debug] 10524#10524: *7 http body new buf t:1 f:0 000055D3DFDE3786, pos 000055D3DFDE3786, size: 618 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 malloc: 000055D3DFE1AA60:131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 http read client request body
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 25823 of 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv 25823
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 -1 of 105459
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv -2
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 event timer add: 10: 180000:1515520662789
2018/01/09 17:54:42 [debug] 10524#10524: *7 http finalize request: -4, "/mps/mySecondFunction?mode=sync" a:1, c:2
2018/01/09 17:54:42 [debug] 10524#10524: *7 http request count:2 blk:0
2018/01/09 17:54:42 [debug] 10524#10524: *7 post event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 delete posted event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 http run request: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http read client request body
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 16713 of 105459
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv 16713
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 -1 of 88746
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv -2
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 event timer: 10, old: 1515520662789, new: 1515520662790
2018/01/09 17:54:42 [debug] 10524#10524: *7 post event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 delete posted event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 http run request: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http read client request body
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 6736 of 88746
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv 6736
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 -1 of 82010
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv -2
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 event timer: 10, old: 1515520662789, new: 1515520662791
2018/01/09 17:54:42 [debug] 10524#10524: *7 post event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 delete posted event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 http run request: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http read client request body
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 23672 of 82010
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv 23672
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 -1 of 58338
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv -2
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 event timer: 10, old: 1515520662789, new: 1515520662791
2018/01/09 17:54:42 [debug] 10524#10524: *7 post event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 delete posted event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 http run request: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http read client request body
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 904 of 58338
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv 904
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 -1 of 57434
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv -2
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 event timer: 10, old: 1515520662789, new: 1515520662792
2018/01/09 17:54:42 [debug] 10524#10524: *7 post event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 delete posted event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 http run request: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http read client request body
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 8192 of 57434
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv 8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 -1 of 49242
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv -2
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 event timer: 10, old: 1515520662789, new: 1515520662792
2018/01/09 17:54:42 [debug] 10524#10524: *7 post event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 delete posted event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 http run request: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http read client request body
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 22760 of 49242
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv 22760
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 -1 of 26482
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv -2
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 event timer: 10, old: 1515520662789, new: 1515520662792
2018/01/09 17:54:42 [debug] 10524#10524: *7 post event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 delete posted event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 http run request: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http read client request body
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 1816 of 26482
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv 1816
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 -1 of 24666
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv -2
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 event timer: 10, old: 1515520662789, new: 1515520662793
2018/01/09 17:54:42 [debug] 10524#10524: *7 post event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 delete posted event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 http run request: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http read client request body
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 8192 of 24666
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv 8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 -1 of 16474
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv -2
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 event timer: 10, old: 1515520662789, new: 1515520662793
2018/01/09 17:54:42 [debug] 10524#10524: *7 post event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 delete posted event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 http run request: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http read client request body
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 16474 of 16474
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body recv 16474
2018/01/09 17:54:42 [debug] 10524#10524: *7 http body old buf t:1 f:0 000055D3DFDE3786, pos 000055D3DFDE3786, size: 618 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http body new buf t:1 f:0 000055D3DFE1AA60, pos 000055D3DFE1AA60, size: 131282 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http client request body rest 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 event timer del: 10: 1515520662789
2018/01/09 17:54:42 [debug] 10524#10524: *7 http init upstream, client timer: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 epoll add event: fd:10 op:3 ev:80002005
2018/01/09 17:54:42 [debug] 10524#10524: *7 http script copy: "Host: "
2018/01/09 17:54:42 [debug] 10524#10524: *7 http script var: "processingServer"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http script copy: "
"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http script copy: "Connection: close
"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http script copy: "Content-Length: "
2018/01/09 17:54:42 [debug] 10524#10524: *7 http script var: "131900"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http script copy: "
"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http script copy: ""
2018/01/09 17:54:42 [debug] 10524#10524: *7 http script copy: ""
2018/01/09 17:54:42 [debug] 10524#10524: *7 http proxy header: "X-Forwarded-For: 54.145.000.000"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http proxy header: "X-Forwarded-Proto: http"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http proxy header: "X-Forwarded-Port: 80"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http proxy header: "X-Amzn-Trace-Id: Root=1-5a5501e2-62bf8c152c81659d47eb3c6f"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http proxy header: "Accept: application/json, text/plain, */*"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http proxy header: "Content-Type: application/json;charset=utf-8"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http proxy header: "User-Agent: axios/0.16.2"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http proxy header:
"POST /mps/mySecondFunction?mode=sync HTTP/1.1
Host: processingServer
Connection: close
Content-Length: 131900
X-Forwarded-For: 54.145.000.000
X-Forwarded-Proto: http
X-Forwarded-Port: 80
X-Amzn-Trace-Id: Root=1-5a5501e2-62bf8c152c81659d47eb3c6f
Accept: application/json, text/plain, */*
Content-Type: application/json;charset=utf-8
User-Agent: axios/0.16.2
"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http cleanup add: 000055D3DFDD4A40
2018/01/09 17:54:42 [debug] 10524#10524: *7 init keepalive peer
2018/01/09 17:54:42 [debug] 10524#10524: *7 get keepalive peer
2018/01/09 17:54:42 [debug] 10524#10524: *7 get rr peer, try: 1
2018/01/09 17:54:42 [debug] 10524#10524: *7 stream socket 14
2018/01/09 17:54:42 [debug] 10524#10524: *7 epoll add connection: fd:14 ev:80002005
2018/01/09 17:54:42 [debug] 10524#10524: *7 connect to 127.0.0.1:9928, fd:14 #8
2018/01/09 17:54:42 [debug] 10524#10524: *7 http upstream connect: -2
2018/01/09 17:54:42 [debug] 10524#10524: *7 posix_memalign: 000055D3DFDC2630:128 @16
2018/01/09 17:54:42 [debug] 10524#10524: *7 event timer add: 14: 60000:1515520542793
2018/01/09 17:54:42 [debug] 10524#10524: *7 post event 000055D3DFE08B70
2018/01/09 17:54:42 [debug] 10524#10524: *7 post event 000055D3DFE08BD0
2018/01/09 17:54:42 [debug] 10524#10524: *7 delete posted event 000055D3DFE08B70
2018/01/09 17:54:42 [debug] 10524#10524: *7 http run request: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http upstream check client, write event:1, "/mps/mySecondFunction"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http upstream recv(): -1 (11: Resource temporarily unavailable)
2018/01/09 17:54:42 [debug] 10524#10524: *7 delete posted event 000055D3DFE08BD0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http upstream request: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http upstream send request handler
2018/01/09 17:54:42 [debug] 10524#10524: *7 http upstream send request
2018/01/09 17:54:42 [debug] 10524#10524: *7 http upstream send request body
2018/01/09 17:54:42 [debug] 10524#10524: *7 chain writer buf fl:0 s:386
2018/01/09 17:54:42 [debug] 10524#10524: *7 chain writer buf fl:0 s:618
2018/01/09 17:54:42 [debug] 10524#10524: *7 chain writer buf fl:1 s:131282
2018/01/09 17:54:42 [debug] 10524#10524: *7 chain writer in: 000055D3DFDD4AE0
2018/01/09 17:54:42 [debug] 10524#10524: *7 writev: 132286 of 132286
2018/01/09 17:54:42 [debug] 10524#10524: *7 chain writer out: 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 event timer del: 14: 1515520542793
2018/01/09 17:54:42 [debug] 10524#10524: *7 event timer add: 14: 300000:1515520782793
2018/01/09 17:54:42 [debug] 10524#10524: *7 post event 000055D3DFDF6BC0
2018/01/09 17:54:42 [debug] 10524#10524: *7 post event 000055D3DFE08BD0
2018/01/09 17:54:42 [debug] 10524#10524: *7 delete posted event 000055D3DFDF6BC0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http upstream request: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http upstream process header
2018/01/09 17:54:42 [debug] 10524#10524: *7 malloc: 000055D3DFDC6BE0:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:14 4096 of 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 http proxy status 200 "200 OK"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http proxy header: "Content-Type: application/json"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http proxy header: "Content-Length: 136385"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http proxy header: "Connection: Close"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http proxy header done
2018/01/09 17:54:42 [debug] 10524#10524: *7 xslt filter header
2018/01/09 17:54:42 [debug] 10524#10524: *7 HTTP/1.1 200 OK
Server: nginx/1.10.3 (Ubuntu)
Date: Tue, 09 Jan 2018 17:54:42 GMT
Content-Type: application/json
Content-Length: 136385
Connection: keep-alive
X-external-IP: 54.89.000.000
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFDD4D60, pos 000055D3DFDD4D60, size: 197 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter: l:0 f:0 s:197
2018/01/09 17:54:42 [debug] 10524#10524: *7 http cacheable: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http proxy filter init s:200 h:0 c:0 l:136385
2018/01/09 17:54:42 [debug] 10524#10524: *7 http upstream process upstream
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe read upstream: 1
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe preread: 4002
2018/01/09 17:54:42 [debug] 10524#10524: *7 posix_memalign: 000055D3DFDC7BF0:4096 @16
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #0
2018/01/09 17:54:42 [debug] 10524#10524: *7 malloc: 000055D3DFDC8C00:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 readv: 1, last:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe recv chain: 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #1
2018/01/09 17:54:42 [debug] 10524#10524: *7 malloc: 000055D3DFDC9C10:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 readv: 1, last:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe recv chain: 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #2
2018/01/09 17:54:42 [debug] 10524#10524: *7 malloc: 000055D3DFE3AB40:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 readv: 1, last:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe recv chain: 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #3
2018/01/09 17:54:42 [debug] 10524#10524: *7 malloc: 000055D3DFE3BB50:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 readv: 1, last:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe recv chain: 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #4
2018/01/09 17:54:42 [debug] 10524#10524: *7 malloc: 000055D3DFE3CB60:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 readv: 1, last:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe recv chain: 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #5
2018/01/09 17:54:42 [debug] 10524#10524: *7 malloc: 000055D3DFE3DB70:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 readv: 1, last:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe recv chain: 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #6
2018/01/09 17:54:42 [debug] 10524#10524: *7 malloc: 000055D3DFE3EB80:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 readv: 1, last:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe recv chain: 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #7
2018/01/09 17:54:42 [debug] 10524#10524: *7 malloc: 000055D3DFE3FB90:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 readv: 1, last:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe recv chain: 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #8
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe downstream ready
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFDC6BE0, pos 000055D3DFDC6C3E, size: 4002 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFDC8C00, pos 000055D3DFDC8C00, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFDC9C10, pos 000055D3DFDC9C10, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3AB40, pos 000055D3DFE3AB40, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3BB50, pos 000055D3DFE3BB50, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3CB60, pos 000055D3DFE3CB60, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3DB70, pos 000055D3DFE3DB70, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3EB80, pos 000055D3DFE3EB80, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3FB90, pos 000055D3DFE3FB90, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe length: 99615
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write downstream: 1
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write busy: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFDC6C3E 4002
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFDC8C00 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFDC9C10 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write: out:000055D3DFDD4AB0, f:1
2018/01/09 17:54:42 [debug] 10524#10524: *7 http output filter "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 image filter
2018/01/09 17:54:42 [debug] 10524#10524: *7 xslt filter body
2018/01/09 17:54:42 [debug] 10524#10524: *7 http postpone filter "/mps/mySecondFunction?mode=sync" 000055D3DFDD4AC0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write old buf t:1 f:0 000055D3DFDD4D60, pos 000055D3DFDD4D60, size: 197 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFDC6BE0, pos 000055D3DFDC6C3E, size: 4002 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFDC8C00, pos 000055D3DFDC8C00, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter: l:0 f:1 s:8295
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter limit 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 writev: 8295 of 8295
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: 0 "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write busy: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFDC9C10 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3AB40 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3BB50 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write: out:000055D3DFDD4AF0, f:1
2018/01/09 17:54:42 [debug] 10524#10524: *7 http output filter "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 image filter
2018/01/09 17:54:42 [debug] 10524#10524: *7 xslt filter body
2018/01/09 17:54:42 [debug] 10524#10524: *7 http postpone filter "/mps/mySecondFunction?mode=sync" 000055D3DFDC8298
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFDC9C10, pos 000055D3DFDC9C10, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3AB40, pos 000055D3DFE3AB40, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter: l:0 f:1 s:8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter limit 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 writev: 8192 of 8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: 0 "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write busy: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3BB50 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3CB60 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3DB70 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write: out:000055D3DFDC7EA0, f:1
2018/01/09 17:54:42 [debug] 10524#10524: *7 http output filter "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 image filter
2018/01/09 17:54:42 [debug] 10524#10524: *7 xslt filter body
2018/01/09 17:54:42 [debug] 10524#10524: *7 http postpone filter "/mps/mySecondFunction?mode=sync" 000055D3DFDC82A8
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3BB50, pos 000055D3DFE3BB50, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3CB60, pos 000055D3DFE3CB60, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter: l:0 f:1 s:8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter limit 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 writev: 8192 of 8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: 0 "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write busy: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3DB70 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3EB80 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3FB90 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write: out:000055D3DFDC8020, f:1
2018/01/09 17:54:42 [debug] 10524#10524: *7 http output filter "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 image filter
2018/01/09 17:54:42 [debug] 10524#10524: *7 xslt filter body
2018/01/09 17:54:42 [debug] 10524#10524: *7 http postpone filter "/mps/mySecondFunction?mode=sync" 000055D3DFDC82C8
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3DB70, pos 000055D3DFE3DB70, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3EB80, pos 000055D3DFE3EB80, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter: l:0 f:1 s:8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter limit 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 writev: 8192 of 8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: 0 "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write busy: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3FB90 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write: out:000055D3DFDC81A0, f:0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http output filter "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 image filter
2018/01/09 17:54:42 [debug] 10524#10524: *7 xslt filter body
2018/01/09 17:54:42 [debug] 10524#10524: *7 http postpone filter "/mps/mySecondFunction?mode=sync" 000055D3DFDC81A0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3FB90, pos 000055D3DFE3FB90, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter: l:0 f:1 s:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter limit 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 writev: 4096 of 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: 0 "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write busy: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write: out:0000000000000000, f:0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe read upstream: 1
2018/01/09 17:54:42 [debug] 10524#10524: *7 readv: 9, last:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe recv chain: 36864
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #9
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #10
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #11
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #12
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #13
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #14
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #15
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #16
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #17
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe downstream ready
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3FB90, pos 000055D3DFE3FB90, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3DB70, pos 000055D3DFE3DB70, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3EB80, pos 000055D3DFE3EB80, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3BB50, pos 000055D3DFE3BB50, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3CB60, pos 000055D3DFE3CB60, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFDC9C10, pos 000055D3DFDC9C10, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3AB40, pos 000055D3DFE3AB40, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFDC6BE0, pos 000055D3DFDC6BE0, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFDC8C00, pos 000055D3DFDC8C00, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe length: 62751
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write downstream: 1
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write busy: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3FB90 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3DB70 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3EB80 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write: out:000055D3DFDC81A0, f:1
2018/01/09 17:54:42 [debug] 10524#10524: *7 http output filter "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 image filter
2018/01/09 17:54:42 [debug] 10524#10524: *7 xslt filter body
2018/01/09 17:54:42 [debug] 10524#10524: *7 http postpone filter "/mps/mySecondFunction?mode=sync" 000055D3DFDC8278
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3FB90, pos 000055D3DFE3FB90, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3DB70, pos 000055D3DFE3DB70, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter: l:0 f:1 s:8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter limit 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 writev: 8192 of 8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: 0 "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write busy: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3EB80 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3BB50 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3CB60 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write: out:000055D3DFDC8020, f:1
2018/01/09 17:54:42 [debug] 10524#10524: *7 http output filter "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 image filter
2018/01/09 17:54:42 [debug] 10524#10524: *7 xslt filter body
2018/01/09 17:54:42 [debug] 10524#10524: *7 http postpone filter "/mps/mySecondFunction?mode=sync" 000055D3DFDC8298
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3EB80, pos 000055D3DFE3EB80, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3BB50, pos 000055D3DFE3BB50, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter: l:0 f:1 s:8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter limit 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 writev: 8192 of 8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: 0 "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write busy: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3CB60 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFDC9C10 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3AB40 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write: out:000055D3DFDC7EA0, f:1
2018/01/09 17:54:42 [debug] 10524#10524: *7 http output filter "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 image filter
2018/01/09 17:54:42 [debug] 10524#10524: *7 xslt filter body
2018/01/09 17:54:42 [debug] 10524#10524: *7 http postpone filter "/mps/mySecondFunction?mode=sync" 000055D3DFDC82A8
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3CB60, pos 000055D3DFE3CB60, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFDC9C10, pos 000055D3DFDC9C10, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter: l:0 f:1 s:8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter limit 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 writev: 8192 of 8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: 0 "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write busy: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3AB40 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFDC6BE0 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFDC8C00 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write: out:000055D3DFDD4AF0, f:1
2018/01/09 17:54:42 [debug] 10524#10524: *7 http output filter "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 image filter
2018/01/09 17:54:42 [debug] 10524#10524: *7 xslt filter body
2018/01/09 17:54:42 [debug] 10524#10524: *7 http postpone filter "/mps/mySecondFunction?mode=sync" 000055D3DFDC82C8
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3AB40, pos 000055D3DFE3AB40, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFDC6BE0, pos 000055D3DFDC6BE0, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter: l:0 f:1 s:8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter limit 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 writev: 8192 of 8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: 0 "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write busy: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFDC8C00 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write: out:000055D3DFDD4AB0, f:0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http output filter "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 image filter
2018/01/09 17:54:42 [debug] 10524#10524: *7 xslt filter body
2018/01/09 17:54:42 [debug] 10524#10524: *7 http postpone filter "/mps/mySecondFunction?mode=sync" 000055D3DFDD4AB0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFDC8C00, pos 000055D3DFDC8C00, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter: l:0 f:1 s:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter limit 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 writev: 4096 of 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: 0 "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write busy: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write: out:0000000000000000, f:0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe read upstream: 1
2018/01/09 17:54:42 [debug] 10524#10524: *7 readv: 9, last:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe recv chain: 36864
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #18
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #19
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #20
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #21
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #22
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #23
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #24
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #25
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #26
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe downstream ready
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFDC8C00, pos 000055D3DFDC8C00, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3AB40, pos 000055D3DFE3AB40, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFDC6BE0, pos 000055D3DFDC6BE0, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3CB60, pos 000055D3DFE3CB60, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFDC9C10, pos 000055D3DFDC9C10, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3EB80, pos 000055D3DFE3EB80, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3BB50, pos 000055D3DFE3BB50, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3FB90, pos 000055D3DFE3FB90, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3DB70, pos 000055D3DFE3DB70, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe length: 25887
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write downstream: 1
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write busy: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFDC8C00 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3AB40 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFDC6BE0 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write: out:000055D3DFDD4AB0, f:1
2018/01/09 17:54:42 [debug] 10524#10524: *7 http output filter "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 image filter
2018/01/09 17:54:42 [debug] 10524#10524: *7 xslt filter body
2018/01/09 17:54:42 [debug] 10524#10524: *7 http postpone filter "/mps/mySecondFunction?mode=sync" 000055D3DFDD4AC0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFDC8C00, pos 000055D3DFDC8C00, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3AB40, pos 000055D3DFE3AB40, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter: l:0 f:1 s:8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter limit 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 writev: 8192 of 8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: 0 "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write busy: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFDC6BE0 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3CB60 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFDC9C10 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write: out:000055D3DFDD4AF0, f:1
2018/01/09 17:54:42 [debug] 10524#10524: *7 http output filter "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 image filter
2018/01/09 17:54:42 [debug] 10524#10524: *7 xslt filter body
2018/01/09 17:54:42 [debug] 10524#10524: *7 http postpone filter "/mps/mySecondFunction?mode=sync" 000055D3DFDC8298
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFDC6BE0, pos 000055D3DFDC6BE0, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3CB60, pos 000055D3DFE3CB60, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter: l:0 f:1 s:8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter limit 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 writev: 8192 of 8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: 0 "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write busy: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFDC9C10 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3EB80 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3BB50 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write: out:000055D3DFDC7EA0, f:1
2018/01/09 17:54:42 [debug] 10524#10524: *7 http output filter "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 image filter
2018/01/09 17:54:42 [debug] 10524#10524: *7 xslt filter body
2018/01/09 17:54:42 [debug] 10524#10524: *7 http postpone filter "/mps/mySecondFunction?mode=sync" 000055D3DFDC82A8
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFDC9C10, pos 000055D3DFDC9C10, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3EB80, pos 000055D3DFE3EB80, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter: l:0 f:1 s:8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter limit 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 writev: 8192 of 8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: 0 "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write busy: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3BB50 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3FB90 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3DB70 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write: out:000055D3DFDC8020, f:1
2018/01/09 17:54:42 [debug] 10524#10524: *7 http output filter "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 image filter
2018/01/09 17:54:42 [debug] 10524#10524: *7 xslt filter body
2018/01/09 17:54:42 [debug] 10524#10524: *7 http postpone filter "/mps/mySecondFunction?mode=sync" 000055D3DFDC82C8
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3BB50, pos 000055D3DFE3BB50, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3FB90, pos 000055D3DFE3FB90, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter: l:0 f:1 s:8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter limit 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 writev: 8192 of 8192
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: 0 "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write busy: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write buf ls:1 000055D3DFE3DB70 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write: out:000055D3DFDC81A0, f:0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http output filter "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 image filter
2018/01/09 17:54:42 [debug] 10524#10524: *7 xslt filter body
2018/01/09 17:54:42 [debug] 10524#10524: *7 http postpone filter "/mps/mySecondFunction?mode=sync" 000055D3DFDC81A0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3DB70, pos 000055D3DFE3DB70, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter: l:0 f:1 s:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter limit 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 writev: 4096 of 4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: 0 "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write busy: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write: out:0000000000000000, f:0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe read upstream: 1
2018/01/09 17:54:42 [debug] 10524#10524: *7 readv: 9, last:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe recv chain: 25887
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #27
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #28
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #29
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #30
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #31
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #32
2018/01/09 17:54:42 [debug] 10524#10524: *7 readv: 3, last:4096
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe recv chain: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3DB70, pos 000055D3DFE3DB70, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3BB50, pos 000055D3DFE3BB50, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3FB90, pos 000055D3DFE3FB90, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFDC9C10, pos 000055D3DFDC9C10, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFE3EB80, pos 000055D3DFE3EB80, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf in s:1 t:1 f:0 000055D3DFDC6BE0, pos 000055D3DFDC6BE0, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf free s:0 t:1 f:0 000055D3DFE3CB60, pos 000055D3DFE3CB60, size: 1311 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf free s:0 t:1 f:0 000055D3DFDC8C00, pos 000055D3DFDC8C00, size: 0 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe buf free s:0 t:1 f:0 000055D3DFE3AB40, pos 000055D3DFE3AB40, size: 0 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe length: 1311
2018/01/09 17:54:42 [debug] 10524#10524: *7 input buf #33
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFE3AB40
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write downstream: 1
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write downstream flush in
2018/01/09 17:54:42 [debug] 10524#10524: *7 http output filter "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 image filter
2018/01/09 17:54:42 [debug] 10524#10524: *7 xslt filter body
2018/01/09 17:54:42 [debug] 10524#10524: *7 http postpone filter "/mps/mySecondFunction?mode=sync" 000055D3DFDC8288
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3DB70, pos 000055D3DFE3DB70, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3BB50, pos 000055D3DFE3BB50, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3FB90, pos 000055D3DFE3FB90, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFDC9C10, pos 000055D3DFDC9C10, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3EB80, pos 000055D3DFE3EB80, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFDC6BE0, pos 000055D3DFDC6BE0, size: 4096 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:1 f:0 000055D3DFE3CB60, pos 000055D3DFE3CB60, size: 1311 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter: l:0 f:0 s:25887
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter limit 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 writev: 25887 of 25887
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: 0 "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 pipe write downstream done
2018/01/09 17:54:42 [debug] 10524#10524: *7 event timer: 14, old: 1515520782793, new: 1515520782922
2018/01/09 17:54:42 [debug] 10524#10524: *7 http upstream exit: 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 finalize http upstream request: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 finalize http proxy request
2018/01/09 17:54:42 [debug] 10524#10524: *7 free keepalive peer
2018/01/09 17:54:42 [debug] 10524#10524: *7 free rr peer 1 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 close http upstream connection: 14
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFDC2630, unused: 48
2018/01/09 17:54:42 [debug] 10524#10524: *7 event timer del: 14: 1515520782793
2018/01/09 17:54:42 [debug] 10524#10524: *7 delete posted event 000055D3DFE08BD0
2018/01/09 17:54:42 [debug] 10524#10524: *7 reusable connection: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http upstream temp fd: -1
2018/01/09 17:54:42 [debug] 10524#10524: *7 http output filter "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 image filter
2018/01/09 17:54:42 [debug] 10524#10524: *7 xslt filter body
2018/01/09 17:54:42 [debug] 10524#10524: *7 http postpone filter "/mps/mySecondFunction?mode=sync" 00007FFEF7EC0BB0
2018/01/09 17:54:42 [debug] 10524#10524: *7 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http write filter: l:1 f:0 s:0
2018/01/09 17:54:42 [debug] 10524#10524: *7 http copy filter: 0 "/mps/mySecondFunction?mode=sync"
2018/01/09 17:54:42 [debug] 10524#10524: *7 http finalize request: 0, "/mps/mySecondFunction?mode=sync" a:1, c:1
2018/01/09 17:54:42 [debug] 10524#10524: *7 set http keepalive handler
2018/01/09 17:54:42 [debug] 10524#10524: *7 http close request
2018/01/09 17:54:42 [debug] 10524#10524: *7 http log handler
2018/01/09 17:54:42 [debug] 10524#10524: *7 malloc: 000055D3DFE40BA0:131900
2018/01/09 17:54:42 [debug] 10524#10524: *7 malloc: 000055D3DFE60EF0:148191
2018/01/09 17:54:42 [debug] 10524#10524: *7 malloc: 000055D3DFE851E0:148191
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFE851E0
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFE60EF0
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFE40BA0
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFE3FB90
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFE3EB80
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFE3DB70
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFE3CB60
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFE3BB50
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 0000000000000000
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFDC9C10
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFDC8C00
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFDC6BE0
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFE1AA60
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFDE0F30, unused: 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFDD3F80, unused: 8
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFDC7BF0, unused: 2072
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFDE35F0
2018/01/09 17:54:42 [debug] 10524#10524: *7 hc free: 0000000000000000 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 hc busy: 0000000000000000 0
2018/01/09 17:54:42 [debug] 10524#10524: *7 tcp_nodelay
2018/01/09 17:54:42 [debug] 10524#10524: *7 reusable connection: 1
2018/01/09 17:54:42 [debug] 10524#10524: *7 event timer add: 10: 100000000:1515620482922
2018/01/09 17:54:42 [debug] 10524#10524: *7 post event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 delete posted event 000055D3DFDF6B60
2018/01/09 17:54:42 [debug] 10524#10524: *7 http keepalive handler
2018/01/09 17:54:42 [debug] 10524#10524: *7 malloc: 000055D3DFDE35F0:1024
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv: fd:10 -1 of 1024
2018/01/09 17:54:42 [debug] 10524#10524: *7 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:42 [debug] 10524#10524: *7 free: 000055D3DFDE35F0
2018/01/09 17:54:57 [debug] 10524#10524: post event 000055D3DFDF6A40
2018/01/09 17:54:57 [debug] 10524#10524: delete posted event 000055D3DFDF6A40
2018/01/09 17:54:57 [debug] 10524#10524: accept on 0.0.0.0:80, ready: 0
2018/01/09 17:54:57 [debug] 10524#10524: posix_memalign: 000055D3DFDE35F0:512 @16
2018/01/09 17:54:57 [debug] 10524#10524: *9 accept: 172.31.000.000:4087 fd:14
2018/01/09 17:54:57 [debug] 10524#10524: *9 event timer add: 14: 180000:1515520677410
2018/01/09 17:54:57 [debug] 10524#10524: *9 reusable connection: 1
2018/01/09 17:54:57 [debug] 10524#10524: *9 epoll add event: fd:14 op:1 ev:80002001
2018/01/09 17:54:57 [debug] 10524#10524: *9 post event 000055D3DFDF6BC0
2018/01/09 17:54:57 [debug] 10524#10524: *9 delete posted event 000055D3DFDF6BC0
2018/01/09 17:54:57 [debug] 10524#10524: *9 http wait request handler
2018/01/09 17:54:57 [debug] 10524#10524: *9 malloc: 000055D3DFDE3800:1024
2018/01/09 17:54:57 [debug] 10524#10524: *9 recv: fd:14 128 of 1024
2018/01/09 17:54:57 [debug] 10524#10524: *9 reusable connection: 0
2018/01/09 17:54:57 [debug] 10524#10524: *9 posix_memalign: 000055D3DFDE0F30:4096 @16
2018/01/09 17:54:57 [debug] 10524#10524: *9 http process request line
2018/01/09 17:54:57 [debug] 10524#10524: *9 http request line: "GET / HTTP/1.1"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http uri: "/"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http args: ""
2018/01/09 17:54:57 [debug] 10524#10524: *9 http exten: ""
2018/01/09 17:54:57 [debug] 10524#10524: *9 http process request header line
2018/01/09 17:54:57 [debug] 10524#10524: *9 http header: "Host: 172.31.000.000"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http header: "Connection: close"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http header: "User-Agent: ELB-HealthChecker/2.0"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http header: "Accept-Encoding: gzip, compressed"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http header done
2018/01/09 17:54:57 [debug] 10524#10524: *9 event timer del: 14: 1515520677410
2018/01/09 17:54:57 [debug] 10524#10524: *9 generic phase: 0
2018/01/09 17:54:57 [debug] 10524#10524: *9 rewrite phase: 1
2018/01/09 17:54:57 [debug] 10524#10524: *9 test location: "/"
2018/01/09 17:54:57 [debug] 10524#10524: *9 using configuration "/"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http cl:-1 max:5242880000
2018/01/09 17:54:57 [debug] 10524#10524: *9 rewrite phase: 3
2018/01/09 17:54:57 [debug] 10524#10524: *9 post rewrite phase: 4
2018/01/09 17:54:57 [debug] 10524#10524: *9 generic phase: 5
2018/01/09 17:54:57 [debug] 10524#10524: *9 generic phase: 6
2018/01/09 17:54:57 [debug] 10524#10524: *9 generic phase: 7
2018/01/09 17:54:57 [debug] 10524#10524: *9 access phase: 8
2018/01/09 17:54:57 [debug] 10524#10524: *9 access phase: 9
2018/01/09 17:54:57 [debug] 10524#10524: *9 access phase: 10
2018/01/09 17:54:57 [debug] 10524#10524: *9 post access phase: 11
2018/01/09 17:54:57 [debug] 10524#10524: *9 posix_memalign: 000055D3DFDD3F80:4096 @16
2018/01/09 17:54:57 [debug] 10524#10524: *9 http init upstream, client timer: 0
2018/01/09 17:54:57 [debug] 10524#10524: *9 epoll add event: fd:14 op:3 ev:80002005
2018/01/09 17:54:57 [debug] 10524#10524: *9 http script copy: "Host: "
2018/01/09 17:54:57 [debug] 10524#10524: *9 http script var: "processingServer"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http script copy: "
"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http script copy: "Connection: close
"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http script copy: ""
2018/01/09 17:54:57 [debug] 10524#10524: *9 http script copy: ""
2018/01/09 17:54:57 [debug] 10524#10524: *9 http script copy: ""
2018/01/09 17:54:57 [debug] 10524#10524: *9 http script copy: ""
2018/01/09 17:54:57 [debug] 10524#10524: *9 http proxy header: "User-Agent: ELB-HealthChecker/2.0"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http proxy header: "Accept-Encoding: gzip, compressed"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http proxy header:
"GET / HTTP/1.1
Host: processingServer
Connection: close
User-Agent: ELB-HealthChecker/2.0
Accept-Encoding: gzip, compressed
"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http cleanup add: 000055D3DFDD46F8
2018/01/09 17:54:57 [debug] 10524#10524: *9 init keepalive peer
2018/01/09 17:54:57 [debug] 10524#10524: *9 get keepalive peer
2018/01/09 17:54:57 [debug] 10524#10524: *9 get rr peer, try: 1
2018/01/09 17:54:57 [debug] 10524#10524: *9 stream socket 15
2018/01/09 17:54:57 [debug] 10524#10524: *9 epoll add connection: fd:15 ev:80002005
2018/01/09 17:54:57 [debug] 10524#10524: *9 connect to 127.0.0.1:9928, fd:15 #10
2018/01/09 17:54:57 [debug] 10524#10524: *9 http upstream connect: -2
2018/01/09 17:54:57 [debug] 10524#10524: *9 posix_memalign: 000055D3DFDC2630:128 @16
2018/01/09 17:54:57 [debug] 10524#10524: *9 event timer add: 15: 60000:1515520557410
2018/01/09 17:54:57 [debug] 10524#10524: *9 http finalize request: -4, "/?" a:1, c:2
2018/01/09 17:54:57 [debug] 10524#10524: *9 http request count:2 blk:0
2018/01/09 17:54:57 [debug] 10524#10524: *9 post event 000055D3DFE08BD0
2018/01/09 17:54:57 [debug] 10524#10524: *9 post event 000055D3DFE08C30
2018/01/09 17:54:57 [debug] 10524#10524: *9 delete posted event 000055D3DFE08BD0
2018/01/09 17:54:57 [debug] 10524#10524: *9 http run request: "/?"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http upstream check client, write event:1, "/"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http upstream recv(): -1 (11: Resource temporarily unavailable)
2018/01/09 17:54:57 [debug] 10524#10524: *9 delete posted event 000055D3DFE08C30
2018/01/09 17:54:57 [debug] 10524#10524: *9 http upstream request: "/?"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http upstream send request handler
2018/01/09 17:54:57 [debug] 10524#10524: *9 http upstream send request
2018/01/09 17:54:57 [debug] 10524#10524: *9 http upstream send request body
2018/01/09 17:54:57 [debug] 10524#10524: *9 chain writer buf fl:1 s:131
2018/01/09 17:54:57 [debug] 10524#10524: *9 chain writer in: 000055D3DFDD4768
2018/01/09 17:54:57 [debug] 10524#10524: *9 writev: 131 of 131
2018/01/09 17:54:57 [debug] 10524#10524: *9 chain writer out: 0000000000000000
2018/01/09 17:54:57 [debug] 10524#10524: *9 event timer del: 15: 1515520557410
2018/01/09 17:54:57 [debug] 10524#10524: *9 event timer add: 15: 300000:1515520797410
2018/01/09 17:54:57 [debug] 10524#10524: *9 post event 000055D3DFDF6C20
2018/01/09 17:54:57 [debug] 10524#10524: *9 post event 000055D3DFE08C30
2018/01/09 17:54:57 [debug] 10524#10524: *9 delete posted event 000055D3DFDF6C20
2018/01/09 17:54:57 [debug] 10524#10524: *9 http upstream request: "/?"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http upstream process header
2018/01/09 17:54:57 [debug] 10524#10524: *9 malloc: 000055D3DFDC6BE0:4096
2018/01/09 17:54:57 [debug] 10524#10524: *9 recv: fd:15 91 of 4096
2018/01/09 17:54:57 [debug] 10524#10524: *9 http proxy status 404 "404 URL not found (request id=0:11802:0)"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http proxy header: "Content-Length: 0"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http proxy header: "Connection: Close"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http proxy header done
2018/01/09 17:54:57 [debug] 10524#10524: *9 xslt filter header
2018/01/09 17:54:57 [debug] 10524#10524: *9 HTTP/1.1 404 URL not found (request id=0:11802:0)
Server: nginx/1.10.3 (Ubuntu)
Date: Tue, 09 Jan 2018 17:54:57 GMT
Content-Length: 0
Connection: close
2018/01/09 17:54:57 [debug] 10524#10524: *9 write new buf t:1 f:0 000055D3DFDD49C0, pos 000055D3DFDD49C0, size: 159 file: 0, size: 0
2018/01/09 17:54:57 [debug] 10524#10524: *9 http write filter: l:0 f:0 s:159
2018/01/09 17:54:57 [debug] 10524#10524: *9 http cacheable: 0
2018/01/09 17:54:57 [debug] 10524#10524: *9 http proxy filter init s:404 h:0 c:0 l:0
2018/01/09 17:54:57 [debug] 10524#10524: *9 http upstream process upstream
2018/01/09 17:54:57 [debug] 10524#10524: *9 pipe read upstream: 1
2018/01/09 17:54:57 [debug] 10524#10524: *9 pipe preread: 0
2018/01/09 17:54:57 [debug] 10524#10524: *9 readv: 1, last:4005
2018/01/09 17:54:57 [debug] 10524#10524: *9 pipe recv chain: 0
2018/01/09 17:54:57 [debug] 10524#10524: *9 pipe buf free s:0 t:1 f:0 000055D3DFDC6BE0, pos 000055D3DFDC6C3B, size: 0 file: 0, size: 0
2018/01/09 17:54:57 [debug] 10524#10524: *9 pipe length: 0
2018/01/09 17:54:57 [debug] 10524#10524: *9 pipe write downstream: 1
2018/01/09 17:54:57 [debug] 10524#10524: *9 pipe write downstream done
2018/01/09 17:54:57 [debug] 10524#10524: *9 event timer: 15, old: 1515520797410, new: 1515520797410
2018/01/09 17:54:57 [debug] 10524#10524: *9 http upstream exit: 0000000000000000
2018/01/09 17:54:57 [debug] 10524#10524: *9 finalize http upstream request: 0
2018/01/09 17:54:57 [debug] 10524#10524: *9 finalize http proxy request
2018/01/09 17:54:57 [debug] 10524#10524: *9 free keepalive peer
2018/01/09 17:54:57 [debug] 10524#10524: *9 free rr peer 1 0
2018/01/09 17:54:57 [debug] 10524#10524: *9 close http upstream connection: 15
2018/01/09 17:54:57 [debug] 10524#10524: *9 free: 000055D3DFDC2630, unused: 48
2018/01/09 17:54:57 [debug] 10524#10524: *9 event timer del: 15: 1515520797410
2018/01/09 17:54:57 [debug] 10524#10524: *9 delete posted event 000055D3DFE08C30
2018/01/09 17:54:57 [debug] 10524#10524: *9 reusable connection: 0
2018/01/09 17:54:57 [debug] 10524#10524: *9 http upstream temp fd: -1
2018/01/09 17:54:57 [debug] 10524#10524: *9 http output filter "/?"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http copy filter: "/?"
2018/01/09 17:54:57 [debug] 10524#10524: *9 image filter
2018/01/09 17:54:57 [debug] 10524#10524: *9 xslt filter body
2018/01/09 17:54:57 [debug] 10524#10524: *9 http postpone filter "/?" 00007FFEF7EC0BB0
2018/01/09 17:54:57 [debug] 10524#10524: *9 write old buf t:1 f:0 000055D3DFDD49C0, pos 000055D3DFDD49C0, size: 159 file: 0, size: 0
2018/01/09 17:54:57 [debug] 10524#10524: *9 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
2018/01/09 17:54:57 [debug] 10524#10524: *9 http write filter: l:1 f:0 s:159
2018/01/09 17:54:57 [debug] 10524#10524: *9 http write filter limit 0
2018/01/09 17:54:57 [debug] 10524#10524: *9 writev: 159 of 159
2018/01/09 17:54:57 [debug] 10524#10524: *9 http write filter 0000000000000000
2018/01/09 17:54:57 [debug] 10524#10524: *9 http copy filter: 0 "/?"
2018/01/09 17:54:57 [debug] 10524#10524: *9 http finalize request: 0, "/?" a:1, c:1
2018/01/09 17:54:57 [debug] 10524#10524: *9 event timer add: 14: 5000:1515520502410
2018/01/09 17:54:57 [debug] 10524#10524: *9 http lingering close handler
2018/01/09 17:54:57 [debug] 10524#10524: *9 recv: fd:14 -1 of 4096
2018/01/09 17:54:57 [debug] 10524#10524: *9 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:57 [debug] 10524#10524: *9 lingering read: -2
2018/01/09 17:54:57 [debug] 10524#10524: *9 event timer: 14, old: 1515520502410, new: 1515520502410
2018/01/09 17:54:57 [debug] 10524#10524: *9 post event 000055D3DFE08BD0
2018/01/09 17:54:57 [debug] 10524#10524: *9 delete posted event 000055D3DFE08BD0
2018/01/09 17:54:57 [debug] 10524#10524: *9 http empty handler
2018/01/09 17:54:57 [debug] 10524#10524: *9 post event 000055D3DFDF6BC0
2018/01/09 17:54:57 [debug] 10524#10524: *9 post event 000055D3DFE08BD0
2018/01/09 17:54:57 [debug] 10524#10524: *9 delete posted event 000055D3DFDF6BC0
2018/01/09 17:54:57 [debug] 10524#10524: *9 http lingering close handler
2018/01/09 17:54:57 [debug] 10524#10524: *9 recv: fd:14 0 of 4096
2018/01/09 17:54:57 [debug] 10524#10524: *9 lingering read: 0
2018/01/09 17:54:57 [debug] 10524#10524: *9 http request count:1 blk:0
2018/01/09 17:54:57 [debug] 10524#10524: *9 http close request
2018/01/09 17:54:57 [debug] 10524#10524: *9 http log handler
2018/01/09 17:54:57 [debug] 10524#10524: *9 free: 000055D3DFDC6BE0
2018/01/09 17:54:57 [debug] 10524#10524: *9 free: 000055D3DFDE0F30, unused: 4
2018/01/09 17:54:57 [debug] 10524#10524: *9 free: 000055D3DFDD3F80, unused: 800
2018/01/09 17:54:57 [debug] 10524#10524: *9 close http connection: 14
2018/01/09 17:54:57 [debug] 10524#10524: *9 event timer del: 14: 1515520502410
2018/01/09 17:54:57 [debug] 10524#10524: *9 delete posted event 000055D3DFE08BD0
2018/01/09 17:54:57 [debug] 10524#10524: *9 reusable connection: 0
2018/01/09 17:54:57 [debug] 10524#10524: *9 free: 000055D3DFDE3800
2018/01/09 17:54:57 [debug] 10524#10524: *9 free: 000055D3DFDE35F0, unused: 128
2018/01/09 17:54:58 [debug] 10524#10524: post event 000055D3DFDF6A40
2018/01/09 17:54:58 [debug] 10524#10524: delete posted event 000055D3DFDF6A40
2018/01/09 17:54:58 [debug] 10524#10524: accept on 0.0.0.0:80, ready: 0
2018/01/09 17:54:58 [debug] 10524#10524: posix_memalign: 000055D3DFDE35F0:512 @16
2018/01/09 17:54:58 [debug] 10524#10524: *11 accept: 172.31.000.000:52239 fd:14
2018/01/09 17:54:58 [debug] 10524#10524: *11 event timer add: 14: 180000:1515520678556
2018/01/09 17:54:58 [debug] 10524#10524: *11 reusable connection: 1
2018/01/09 17:54:58 [debug] 10524#10524: *11 epoll add event: fd:14 op:1 ev:80002001
2018/01/09 17:54:58 [debug] 10524#10524: *11 post event 000055D3DFDF6BC0
2018/01/09 17:54:58 [debug] 10524#10524: *11 delete posted event 000055D3DFDF6BC0
2018/01/09 17:54:58 [debug] 10524#10524: *11 http wait request handler
2018/01/09 17:54:58 [debug] 10524#10524: *11 malloc: 000055D3DFDE3800:1024
2018/01/09 17:54:58 [debug] 10524#10524: *11 recv: fd:14 128 of 1024
2018/01/09 17:54:58 [debug] 10524#10524: *11 reusable connection: 0
2018/01/09 17:54:58 [debug] 10524#10524: *11 posix_memalign: 000055D3DFDE0F30:4096 @16
2018/01/09 17:54:58 [debug] 10524#10524: *11 http process request line
2018/01/09 17:54:58 [debug] 10524#10524: *11 http request line: "GET / HTTP/1.1"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http uri: "/"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http args: ""
2018/01/09 17:54:58 [debug] 10524#10524: *11 http exten: ""
2018/01/09 17:54:58 [debug] 10524#10524: *11 http process request header line
2018/01/09 17:54:58 [debug] 10524#10524: *11 http header: "Host: 172.31.000.000"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http header: "Connection: close"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http header: "User-Agent: ELB-HealthChecker/2.0"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http header: "Accept-Encoding: gzip, compressed"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http header done
2018/01/09 17:54:58 [debug] 10524#10524: *11 event timer del: 14: 1515520678556
2018/01/09 17:54:58 [debug] 10524#10524: *11 generic phase: 0
2018/01/09 17:54:58 [debug] 10524#10524: *11 rewrite phase: 1
2018/01/09 17:54:58 [debug] 10524#10524: *11 test location: "/"
2018/01/09 17:54:58 [debug] 10524#10524: *11 using configuration "/"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http cl:-1 max:5242880000
2018/01/09 17:54:58 [debug] 10524#10524: *11 rewrite phase: 3
2018/01/09 17:54:58 [debug] 10524#10524: *11 post rewrite phase: 4
2018/01/09 17:54:58 [debug] 10524#10524: *11 generic phase: 5
2018/01/09 17:54:58 [debug] 10524#10524: *11 generic phase: 6
2018/01/09 17:54:58 [debug] 10524#10524: *11 generic phase: 7
2018/01/09 17:54:58 [debug] 10524#10524: *11 access phase: 8
2018/01/09 17:54:58 [debug] 10524#10524: *11 access phase: 9
2018/01/09 17:54:58 [debug] 10524#10524: *11 access phase: 10
2018/01/09 17:54:58 [debug] 10524#10524: *11 post access phase: 11
2018/01/09 17:54:58 [debug] 10524#10524: *11 posix_memalign: 000055D3DFDD3F80:4096 @16
2018/01/09 17:54:58 [debug] 10524#10524: *11 http init upstream, client timer: 0
2018/01/09 17:54:58 [debug] 10524#10524: *11 epoll add event: fd:14 op:3 ev:80002005
2018/01/09 17:54:58 [debug] 10524#10524: *11 http script copy: "Host: "
2018/01/09 17:54:58 [debug] 10524#10524: *11 http script var: "processingServer"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http script copy: "
"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http script copy: "Connection: close
"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http script copy: ""
2018/01/09 17:54:58 [debug] 10524#10524: *11 http script copy: ""
2018/01/09 17:54:58 [debug] 10524#10524: *11 http script copy: ""
2018/01/09 17:54:58 [debug] 10524#10524: *11 http script copy: ""
2018/01/09 17:54:58 [debug] 10524#10524: *11 http proxy header: "User-Agent: ELB-HealthChecker/2.0"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http proxy header: "Accept-Encoding: gzip, compressed"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http proxy header:
"GET / HTTP/1.1
Host: processingServer
Connection: close
User-Agent: ELB-HealthChecker/2.0
Accept-Encoding: gzip, compressed
"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http cleanup add: 000055D3DFDD46F8
2018/01/09 17:54:58 [debug] 10524#10524: *11 init keepalive peer
2018/01/09 17:54:58 [debug] 10524#10524: *11 get keepalive peer
2018/01/09 17:54:58 [debug] 10524#10524: *11 get rr peer, try: 1
2018/01/09 17:54:58 [debug] 10524#10524: *11 stream socket 15
2018/01/09 17:54:58 [debug] 10524#10524: *11 epoll add connection: fd:15 ev:80002005
2018/01/09 17:54:58 [debug] 10524#10524: *11 connect to 127.0.0.1:9928, fd:15 #12
2018/01/09 17:54:58 [debug] 10524#10524: *11 http upstream connect: -2
2018/01/09 17:54:58 [debug] 10524#10524: *11 posix_memalign: 000055D3DFDC2630:128 @16
2018/01/09 17:54:58 [debug] 10524#10524: *11 event timer add: 15: 60000:1515520558556
2018/01/09 17:54:58 [debug] 10524#10524: *11 http finalize request: -4, "/?" a:1, c:2
2018/01/09 17:54:58 [debug] 10524#10524: *11 http request count:2 blk:0
2018/01/09 17:54:58 [debug] 10524#10524: *11 post event 000055D3DFE08BD0
2018/01/09 17:54:58 [debug] 10524#10524: *11 post event 000055D3DFE08C30
2018/01/09 17:54:58 [debug] 10524#10524: *11 delete posted event 000055D3DFE08BD0
2018/01/09 17:54:58 [debug] 10524#10524: *11 http run request: "/?"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http upstream check client, write event:1, "/"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http upstream recv(): -1 (11: Resource temporarily unavailable)
2018/01/09 17:54:58 [debug] 10524#10524: *11 delete posted event 000055D3DFE08C30
2018/01/09 17:54:58 [debug] 10524#10524: *11 http upstream request: "/?"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http upstream send request handler
2018/01/09 17:54:58 [debug] 10524#10524: *11 http upstream send request
2018/01/09 17:54:58 [debug] 10524#10524: *11 http upstream send request body
2018/01/09 17:54:58 [debug] 10524#10524: *11 chain writer buf fl:1 s:131
2018/01/09 17:54:58 [debug] 10524#10524: *11 chain writer in: 000055D3DFDD4768
2018/01/09 17:54:58 [debug] 10524#10524: *11 writev: 131 of 131
2018/01/09 17:54:58 [debug] 10524#10524: *11 chain writer out: 0000000000000000
2018/01/09 17:54:58 [debug] 10524#10524: *11 event timer del: 15: 1515520558556
2018/01/09 17:54:58 [debug] 10524#10524: *11 event timer add: 15: 300000:1515520798556
2018/01/09 17:54:58 [debug] 10524#10524: *11 post event 000055D3DFDF6C20
2018/01/09 17:54:58 [debug] 10524#10524: *11 post event 000055D3DFE08C30
2018/01/09 17:54:58 [debug] 10524#10524: *11 delete posted event 000055D3DFDF6C20
2018/01/09 17:54:58 [debug] 10524#10524: *11 http upstream request: "/?"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http upstream process header
2018/01/09 17:54:58 [debug] 10524#10524: *11 malloc: 000055D3DFDC6BE0:4096
2018/01/09 17:54:58 [debug] 10524#10524: *11 recv: fd:15 91 of 4096
2018/01/09 17:54:58 [debug] 10524#10524: *11 http proxy status 404 "404 URL not found (request id=0:11803:0)"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http proxy header: "Content-Length: 0"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http proxy header: "Connection: Close"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http proxy header done
2018/01/09 17:54:58 [debug] 10524#10524: *11 xslt filter header
2018/01/09 17:54:58 [debug] 10524#10524: *11 HTTP/1.1 404 URL not found (request id=0:11803:0)
Server: nginx/1.10.3 (Ubuntu)
Date: Tue, 09 Jan 2018 17:54:58 GMT
Content-Length: 0
Connection: close
2018/01/09 17:54:58 [debug] 10524#10524: *11 write new buf t:1 f:0 000055D3DFDD49C0, pos 000055D3DFDD49C0, size: 159 file: 0, size: 0
2018/01/09 17:54:58 [debug] 10524#10524: *11 http write filter: l:0 f:0 s:159
2018/01/09 17:54:58 [debug] 10524#10524: *11 http cacheable: 0
2018/01/09 17:54:58 [debug] 10524#10524: *11 http proxy filter init s:404 h:0 c:0 l:0
2018/01/09 17:54:58 [debug] 10524#10524: *11 http upstream process upstream
2018/01/09 17:54:58 [debug] 10524#10524: *11 pipe read upstream: 1
2018/01/09 17:54:58 [debug] 10524#10524: *11 pipe preread: 0
2018/01/09 17:54:58 [debug] 10524#10524: *11 readv: 1, last:4005
2018/01/09 17:54:58 [debug] 10524#10524: *11 readv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:58 [debug] 10524#10524: *11 pipe recv chain: -2
2018/01/09 17:54:58 [debug] 10524#10524: *11 pipe buf free s:0 t:1 f:0 000055D3DFDC6BE0, pos 000055D3DFDC6C3B, size: 0 file: 0, size: 0
2018/01/09 17:54:58 [debug] 10524#10524: *11 pipe length: 0
2018/01/09 17:54:58 [debug] 10524#10524: *11 pipe write downstream: 1
2018/01/09 17:54:58 [debug] 10524#10524: *11 pipe write downstream done
2018/01/09 17:54:58 [debug] 10524#10524: *11 event timer: 15, old: 1515520798556, new: 1515520798557
2018/01/09 17:54:58 [debug] 10524#10524: *11 http upstream exit: 0000000000000000
2018/01/09 17:54:58 [debug] 10524#10524: *11 finalize http upstream request: 0
2018/01/09 17:54:58 [debug] 10524#10524: *11 finalize http proxy request
2018/01/09 17:54:58 [debug] 10524#10524: *11 free keepalive peer
2018/01/09 17:54:58 [debug] 10524#10524: *11 free rr peer 1 0
2018/01/09 17:54:58 [debug] 10524#10524: *11 close http upstream connection: 15
2018/01/09 17:54:58 [debug] 10524#10524: *11 free: 000055D3DFDC2630, unused: 48
2018/01/09 17:54:58 [debug] 10524#10524: *11 event timer del: 15: 1515520798556
2018/01/09 17:54:58 [debug] 10524#10524: *11 delete posted event 000055D3DFE08C30
2018/01/09 17:54:58 [debug] 10524#10524: *11 reusable connection: 0
2018/01/09 17:54:58 [debug] 10524#10524: *11 http upstream temp fd: -1
2018/01/09 17:54:58 [debug] 10524#10524: *11 http output filter "/?"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http copy filter: "/?"
2018/01/09 17:54:58 [debug] 10524#10524: *11 image filter
2018/01/09 17:54:58 [debug] 10524#10524: *11 xslt filter body
2018/01/09 17:54:58 [debug] 10524#10524: *11 http postpone filter "/?" 00007FFEF7EC0BB0
2018/01/09 17:54:58 [debug] 10524#10524: *11 write old buf t:1 f:0 000055D3DFDD49C0, pos 000055D3DFDD49C0, size: 159 file: 0, size: 0
2018/01/09 17:54:58 [debug] 10524#10524: *11 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
2018/01/09 17:54:58 [debug] 10524#10524: *11 http write filter: l:1 f:0 s:159
2018/01/09 17:54:58 [debug] 10524#10524: *11 http write filter limit 0
2018/01/09 17:54:58 [debug] 10524#10524: *11 writev: 159 of 159
2018/01/09 17:54:58 [debug] 10524#10524: *11 http write filter 0000000000000000
2018/01/09 17:54:58 [debug] 10524#10524: *11 http copy filter: 0 "/?"
2018/01/09 17:54:58 [debug] 10524#10524: *11 http finalize request: 0, "/?" a:1, c:1
2018/01/09 17:54:58 [debug] 10524#10524: *11 event timer add: 14: 5000:1515520503557
2018/01/09 17:54:58 [debug] 10524#10524: *11 http lingering close handler
2018/01/09 17:54:58 [debug] 10524#10524: *11 recv: fd:14 -1 of 4096
2018/01/09 17:54:58 [debug] 10524#10524: *11 recv() not ready (11: Resource temporarily unavailable)
2018/01/09 17:54:58 [debug] 10524#10524: *11 lingering read: -2
2018/01/09 17:54:58 [debug] 10524#10524: *11 event timer: 14, old: 1515520503557, new: 1515520503557
2018/01/09 17:54:58 [debug] 10524#10524: *11 post event 000055D3DFE08BD0
2018/01/09 17:54:58 [debug] 10524#10524: *11 delete posted event 000055D3DFE08BD0
2018/01/09 17:54:58 [debug] 10524#10524: *11 http empty handler
2018/01/09 17:54:58 [debug] 10524#10524: *11 post event 000055D3DFDF6BC0
2018/01/09 17:54:58 [debug] 10524#10524: *11 post event 000055D3DFE08BD0
2018/01/09 17:54:58 [debug] 10524#10524: *11 delete posted event 000055D3DFDF6BC0
2018/01/09 17:54:58 [debug] 10524#10524: *11 http lingering close handler
2018/01/09 17:54:58 [debug] 10524#10524: *11 recv: fd:14 0 of 4096
2018/01/09 17:54:58 [debug] 10524#10524: *11 lingering read: 0
2018/01/09 17:54:58 [debug] 10524#10524: *11 http request count:1 blk:0
2018/01/09 17:54:58 [debug] 10524#10524: *11 http close request
2018/01/09 17:54:58 [debug] 10524#10524: *11 http log handler
2018/01/09 17:54:58 [debug] 10524#10524: *11 free: 000055D3DFDC6BE0
2018/01/09 17:54:58 [debug] 10524#10524: *11 free: 000055D3DFDE0F30, unused: 4
2018/01/09 17:54:58 [debug] 10524#10524: *11 free: 000055D3DFDD3F80, unused: 800
2018/01/09 17:54:58 [debug] 10524#10524: *11 close http connection: 14
2018/01/09 17:54:58 [debug] 10524#10524: *11 event timer del: 14: 1515520503557
2018/01/09 17:54:58 [debug] 10524#10524: *11 delete posted event 000055D3DFE08BD0
2018/01/09 17:54:58 [debug] 10524#10524: *11 reusable connection: 0
2018/01/09 17:54:58 [debug] 10524#10524: *11 free: 000055D3DFDE3800
2018/01/09 17:54:58 [debug] 10524#10524: *11 free: 000055D3DFDE35F0, unused: 128
2018/01/09 17:55:16 [debug] 10524#10524: epoll del event: fd:8 op:2 ev:00000000
2018/01/09 17:55:16 [debug] 10524#10524: *1 http keepalive handler
2018/01/09 17:55:16 [debug] 10524#10524: *1 close http connection: 9
2018/01/09 17:55:16 [debug] 10524#10524: *1 event timer del: 9: 1515620466212
2018/01/09 17:55:16 [debug] 10524#10524: *1 reusable connection: 0
2018/01/09 17:55:16 [debug] 10524#10524: *1 free: 0000000000000000
2018/01/09 17:55:16 [debug] 10524#10524: *1 free: 000055D3DFDE31D0, unused: 128
2018/01/09 17:55:16 [debug] 10524#10524: *7 http keepalive handler
2018/01/09 17:55:16 [debug] 10524#10524: *7 close http connection: 10
2018/01/09 17:55:16 [debug] 10524#10524: *7 event timer del: 10: 1515620482922
2018/01/09 17:55:16 [debug] 10524#10524: *7 reusable connection: 0
2018/01/09 17:55:16 [debug] 10524#10524: *7 free: 0000000000000000
2018/01/09 17:55:16 [debug] 10524#10524: *7 free: 000055D3DFDE33E0, unused: 128
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment