Ticket #2033: error.log.errorpage.keepalive.patched

2020/08/25 18:45:04 [debug] 2412#0: bind() 0.0.0.0:8081 #6 
2020/08/25 18:45:04 [debug] 2412#0: bind() 0.0.0.0:8080 #7 
2020/08/25 18:45:04 [debug] 2412#0: bind() 0.0.0.0:80 #8 
2020/08/25 18:45:04 [notice] 2412#0: using the "epoll" event method
2020/08/25 18:45:04 [notice] 2412#0: nginx/1.16.1
2020/08/25 18:45:04 [notice] 2412#0: built by gcc 4.8.5 20150623 (Red Hat 4.8.5-39) (GCC) 
2020/08/25 18:45:04 [notice] 2412#0: OS: Linux 3.10.0-1062.18.1.el7.x86_64
2020/08/25 18:45:04 [notice] 2412#0: getrlimit(RLIMIT_NOFILE): 1024:4096
2020/08/25 18:45:04 [debug] 2412#0: write: 9, 00007FFCFD2A97B0, 5, 0
2020/08/25 18:45:04 [debug] 2412#0: add cleanup: 0000000001546FF8
2020/08/25 18:45:04 [debug] 2412#0: malloc: 00000000015248E0:8
2020/08/25 18:45:04 [debug] 2412#0: notify eventfd: 9
2020/08/25 18:45:04 [debug] 2412#0: testing the EPOLLRDHUP flag: success
2020/08/25 18:45:04 [debug] 2412#0: malloc: 0000000001529B40:6144
2020/08/25 18:45:04 [debug] 2412#0: malloc: 00007FDE84F9E010:237568
2020/08/25 18:45:04 [debug] 2412#0: malloc: 000000000154A7E0:98304
2020/08/25 18:45:04 [debug] 2412#0: malloc: 00000000015627F0:98304
2020/08/25 18:45:04 [debug] 2412#0: epoll add event: fd:6 op:1 ev:00002001
2020/08/25 18:45:04 [debug] 2412#0: epoll add event: fd:7 op:1 ev:00002001
2020/08/25 18:45:04 [debug] 2412#0: epoll add event: fd:8 op:1 ev:00002001
2020/08/25 18:45:04 [debug] 2412#0: worker cycle
2020/08/25 18:45:04 [debug] 2412#0: epoll timer: -1
2020/08/25 18:45:07 [debug] 2412#0: epoll: fd:8 ev:0001 d:00007FDE84F9E1E0
2020/08/25 18:45:07 [debug] 2412#0: accept on 0.0.0.0:80, ready: 0
2020/08/25 18:45:07 [debug] 2412#0: posix_memalign: 000000000151A6C0:512 @16
2020/08/25 18:45:07 [debug] 2412#0: *1 accept: 127.0.0.1:33626 fd:10
2020/08/25 18:45:07 [debug] 2412#0: *1 event timer add: 10: 60000:34100292
2020/08/25 18:45:07 [debug] 2412#0: *1 reusable connection: 1
2020/08/25 18:45:07 [debug] 2412#0: *1 epoll add event: fd:10 op:1 ev:80002001
2020/08/25 18:45:07 [debug] 2412#0: timer delta: 3400
2020/08/25 18:45:07 [debug] 2412#0: worker cycle
2020/08/25 18:45:07 [debug] 2412#0: epoll timer: 60000
2020/08/25 18:45:07 [debug] 2412#0: epoll: fd:10 ev:0001 d:00007FDE84F9E2C8
2020/08/25 18:45:07 [debug] 2412#0: *1 http wait request handler
2020/08/25 18:45:07 [debug] 2412#0: *1 malloc: 0000000001534FE0:1024
2020/08/25 18:45:07 [debug] 2412#0: *1 recv: eof:0, avail:1
2020/08/25 18:45:07 [debug] 2412#0: *1 recv: fd:10 75 of 1024
2020/08/25 18:45:07 [debug] 2412#0: *1 reusable connection: 0
2020/08/25 18:45:07 [debug] 2412#0: *1 posix_memalign: 000000000152B350:4096 @16
2020/08/25 18:45:07 [debug] 2412#0: *1 http process request line
2020/08/25 18:45:07 [debug] 2412#0: *1 http request line: "GET / HTTP/1.1"
2020/08/25 18:45:07 [debug] 2412#0: *1 http uri: "/"
2020/08/25 18:45:07 [debug] 2412#0: *1 http args: ""
2020/08/25 18:45:07 [debug] 2412#0: *1 http exten: ""
2020/08/25 18:45:07 [debug] 2412#0: *1 posix_memalign: 000000000151F310:4096 @16
2020/08/25 18:45:07 [debug] 2412#0: *1 http process request header line
2020/08/25 18:45:07 [debug] 2412#0: *1 http header: "User-Agent: curl/7.29.0"
2020/08/25 18:45:07 [debug] 2412#0: *1 http header: "Host: example.com"
2020/08/25 18:45:07 [debug] 2412#0: *1 http header: "Accept: */*"
2020/08/25 18:45:07 [debug] 2412#0: *1 http header done
2020/08/25 18:45:07 [debug] 2412#0: *1 event timer del: 10: 34100292
2020/08/25 18:45:07 [debug] 2412#0: *1 rewrite phase: 0
2020/08/25 18:45:07 [debug] 2412#0: *1 test location: "/"
2020/08/25 18:45:07 [debug] 2412#0: *1 using configuration "/"
2020/08/25 18:45:07 [debug] 2412#0: *1 http cl:-1 max:1048576
2020/08/25 18:45:07 [debug] 2412#0: *1 rewrite phase: 2
2020/08/25 18:45:07 [debug] 2412#0: *1 post rewrite phase: 3
2020/08/25 18:45:07 [debug] 2412#0: *1 generic phase: 4
2020/08/25 18:45:07 [debug] 2412#0: *1 generic phase: 5
2020/08/25 18:45:07 [debug] 2412#0: *1 access phase: 6
2020/08/25 18:45:07 [debug] 2412#0: *1 access phase: 7
2020/08/25 18:45:07 [debug] 2412#0: *1 post access phase: 8
2020/08/25 18:45:07 [debug] 2412#0: *1 generic phase: 9
2020/08/25 18:45:07 [debug] 2412#0: *1 generic phase: 10
2020/08/25 18:45:07 [debug] 2412#0: *1 http init upstream, client timer: 0
2020/08/25 18:45:07 [debug] 2412#0: *1 epoll add event: fd:10 op:3 ev:80002005
2020/08/25 18:45:07 [debug] 2412#0: *1 http script copy: "Host"
2020/08/25 18:45:07 [debug] 2412#0: *1 http script var: "redirect_server"
2020/08/25 18:45:07 [debug] 2412#0: *1 http script copy: ""
2020/08/25 18:45:07 [debug] 2412#0: *1 http script copy: ""
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header: "User-Agent: curl/7.29.0"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header: "Accept: */*"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header:
"GET / HTTP/1.1
Host: redirect_server
User-Agent: curl/7.29.0
Accept: */*

"
2020/08/25 18:45:07 [debug] 2412#0: *1 http cleanup add: 000000000151FB18
2020/08/25 18:45:07 [debug] 2412#0: *1 init keepalive peer
2020/08/25 18:45:07 [debug] 2412#0: *1 get keepalive peer
2020/08/25 18:45:07 [debug] 2412#0: *1 get rr peer, try: 1
2020/08/25 18:45:07 [debug] 2412#0: *1 stream socket 11
2020/08/25 18:45:07 [debug] 2412#0: *1 epoll add connection: fd:11 ev:80002005
2020/08/25 18:45:07 [debug] 2412#0: *1 connect to 127.0.0.1:8080, fd:11 #2
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream connect: -2
2020/08/25 18:45:07 [debug] 2412#0: *1 posix_memalign: 000000000151A8D0:128 @16
2020/08/25 18:45:07 [debug] 2412#0: *1 event timer add: 11: 60000:34100292
2020/08/25 18:45:07 [debug] 2412#0: *1 http finalize request: -4, "/?" a:1, c:2
2020/08/25 18:45:07 [debug] 2412#0: *1 http request count:2 blk:0
2020/08/25 18:45:07 [debug] 2412#0: timer delta: 0
2020/08/25 18:45:07 [debug] 2412#0: worker cycle
2020/08/25 18:45:07 [debug] 2412#0: epoll timer: 60000
2020/08/25 18:45:07 [debug] 2412#0: epoll: fd:10 ev:0004 d:00007FDE84F9E2C8
2020/08/25 18:45:07 [debug] 2412#0: *1 http run request: "/?"
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream check client, write event:1, "/"
2020/08/25 18:45:07 [debug] 2412#0: epoll: fd:11 ev:0004 d:00007FDE84F9E3B0
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream request: "/?"
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream send request handler
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream send request
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream send request body
2020/08/25 18:45:07 [debug] 2412#0: *1 chain writer buf fl:1 s:79
2020/08/25 18:45:07 [debug] 2412#0: *1 chain writer in: 000000000152C340
2020/08/25 18:45:07 [debug] 2412#0: *1 writev: 79 of 79
2020/08/25 18:45:07 [debug] 2412#0: *1 chain writer out: 0000000000000000
2020/08/25 18:45:07 [debug] 2412#0: *1 event timer del: 11: 34100292
2020/08/25 18:45:07 [debug] 2412#0: *1 event timer add: 11: 60000:34100292
2020/08/25 18:45:07 [debug] 2412#0: epoll: fd:7 ev:0001 d:00007FDE84F9E0F8
2020/08/25 18:45:07 [debug] 2412#0: accept on 0.0.0.0:8080, ready: 0
2020/08/25 18:45:07 [debug] 2412#0: posix_memalign: 000000000152C360:512 @16
2020/08/25 18:45:07 [debug] 2412#0: *3 accept: 127.0.0.1:40316 fd:12
2020/08/25 18:45:07 [debug] 2412#0: *3 event timer add: 12: 60000:34100292
2020/08/25 18:45:07 [debug] 2412#0: *3 reusable connection: 1
2020/08/25 18:45:07 [debug] 2412#0: *3 epoll add event: fd:12 op:1 ev:80002001
2020/08/25 18:45:07 [debug] 2412#0: timer delta: 0
2020/08/25 18:45:07 [debug] 2412#0: worker cycle
2020/08/25 18:45:07 [debug] 2412#0: epoll timer: 60000
2020/08/25 18:45:07 [debug] 2412#0: epoll: fd:12 ev:0001 d:00007FDE84F9E498
2020/08/25 18:45:07 [debug] 2412#0: *3 http wait request handler
2020/08/25 18:45:07 [debug] 2412#0: *3 malloc: 000000000152C570:1024
2020/08/25 18:45:07 [debug] 2412#0: *3 recv: eof:0, avail:1
2020/08/25 18:45:07 [debug] 2412#0: *3 recv: fd:12 79 of 1024
2020/08/25 18:45:07 [debug] 2412#0: *3 reusable connection: 0
2020/08/25 18:45:07 [debug] 2412#0: *3 posix_memalign: 0000000001520320:4096 @16
2020/08/25 18:45:07 [debug] 2412#0: *3 http process request line
2020/08/25 18:45:07 [debug] 2412#0: *3 http request line: "GET / HTTP/1.1"
2020/08/25 18:45:07 [debug] 2412#0: *3 http uri: "/"
2020/08/25 18:45:07 [debug] 2412#0: *3 http args: ""
2020/08/25 18:45:07 [debug] 2412#0: *3 http exten: ""
2020/08/25 18:45:07 [debug] 2412#0: *3 posix_memalign: 0000000001521330:4096 @16
2020/08/25 18:45:07 [debug] 2412#0: *3 http process request header line
2020/08/25 18:45:07 [debug] 2412#0: *3 http header: "Host: redirect_server"
2020/08/25 18:45:07 [debug] 2412#0: *3 http header: "User-Agent: curl/7.29.0"
2020/08/25 18:45:07 [debug] 2412#0: *3 http header: "Accept: */*"
2020/08/25 18:45:07 [debug] 2412#0: *3 http header done
2020/08/25 18:45:07 [debug] 2412#0: *3 event timer del: 12: 34100292
2020/08/25 18:45:07 [debug] 2412#0: *3 rewrite phase: 0
2020/08/25 18:45:07 [debug] 2412#0: *3 test location: "/"
2020/08/25 18:45:07 [debug] 2412#0: *3 using configuration "/"
2020/08/25 18:45:07 [debug] 2412#0: *3 http cl:-1 max:1048576
2020/08/25 18:45:07 [debug] 2412#0: *3 rewrite phase: 2
2020/08/25 18:45:07 [debug] 2412#0: *3 http set discard body
2020/08/25 18:45:07 [debug] 2412#0: *3 http script copy: "http://origin.com:8081"
2020/08/25 18:45:07 [debug] 2412#0: *3 http script var: "/"
2020/08/25 18:45:07 [debug] 2412#0: *3 http finalize request: 302, "/?" a:1, c:1
2020/08/25 18:45:07 [debug] 2412#0: *3 http special response: 302, "/?"
2020/08/25 18:45:07 [debug] 2412#0: *3 http set discard body
2020/08/25 18:45:07 [debug] 2412#0: *3 HTTP/1.1 302 Moved Temporarily
Server: nginx/1.16.1
Date: Tue, 25 Aug 2020 21:45:07 GMT
Content-Type: text/html
Content-Length: 145
Connection: keep-alive
Location: http://origin.com:8081/

2020/08/25 18:45:07 [debug] 2412#0: *3 write new buf t:1 f:0 0000000001521140, pos 0000000001521140, size: 198 file: 0, size: 0
2020/08/25 18:45:07 [debug] 2412#0: *3 http write filter: l:0 f:0 s:198
2020/08/25 18:45:07 [debug] 2412#0: *3 http output filter "/?"
2020/08/25 18:45:07 [debug] 2412#0: *3 http copy filter: "/?"
2020/08/25 18:45:07 [debug] 2412#0: *3 http postpone filter "/?" 00000000015212C8
2020/08/25 18:45:07 [debug] 2412#0: *3 write old buf t:1 f:0 0000000001521140, pos 0000000001521140, size: 198 file: 0, size: 0
2020/08/25 18:45:07 [debug] 2412#0: *3 write new buf t:0 f:0 0000000000000000, pos 00000000006B0BA0, size: 92 file: 0, size: 0
2020/08/25 18:45:07 [debug] 2412#0: *3 write new buf t:0 f:0 0000000000000000, pos 00000000006B0F00, size: 53 file: 0, size: 0
2020/08/25 18:45:07 [debug] 2412#0: *3 http write filter: l:1 f:0 s:343
2020/08/25 18:45:07 [debug] 2412#0: *3 http write filter limit 0
2020/08/25 18:45:07 [debug] 2412#0: *3 writev: 343 of 343
2020/08/25 18:45:07 [debug] 2412#0: *3 http write filter 0000000000000000
2020/08/25 18:45:07 [debug] 2412#0: *3 http copy filter: 0 "/?"
2020/08/25 18:45:07 [debug] 2412#0: *3 http finalize request: 0, "/?" a:1, c:1
2020/08/25 18:45:07 [debug] 2412#0: *3 set http keepalive handler
2020/08/25 18:45:07 [debug] 2412#0: *3 http close request
2020/08/25 18:45:07 [debug] 2412#0: *3 http log handler
2020/08/25 18:45:07 [debug] 2412#0: *3 free: 0000000001520320, unused: 24
2020/08/25 18:45:07 [debug] 2412#0: *3 free: 0000000001521330, unused: 2876
2020/08/25 18:45:07 [debug] 2412#0: *3 free: 000000000152C570
2020/08/25 18:45:07 [debug] 2412#0: *3 hc free: 0000000000000000
2020/08/25 18:45:07 [debug] 2412#0: *3 hc busy: 0000000000000000 0
2020/08/25 18:45:07 [debug] 2412#0: *3 tcp_nodelay
2020/08/25 18:45:07 [debug] 2412#0: *3 reusable connection: 1
2020/08/25 18:45:07 [debug] 2412#0: *3 event timer add: 12: 65000:34105292
2020/08/25 18:45:07 [debug] 2412#0: timer delta: 0
2020/08/25 18:45:07 [debug] 2412#0: worker cycle
2020/08/25 18:45:07 [debug] 2412#0: epoll timer: 60000
2020/08/25 18:45:07 [debug] 2412#0: epoll: fd:11 ev:0005 d:00007FDE84F9E3B0
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream request: "/?"
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream process header
2020/08/25 18:45:07 [debug] 2412#0: *1 malloc: 0000000001520320:4096
2020/08/25 18:45:07 [debug] 2412#0: *1 recv: eof:0, avail:1
2020/08/25 18:45:07 [debug] 2412#0: *1 recv: fd:11 343 of 4096
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy status 302 "302 Moved Temporarily"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header: "Server: nginx/1.16.1"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header: "Date: Tue, 25 Aug 2020 21:45:07 GMT"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header: "Content-Type: text/html"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header: "Content-Length: 145"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header: "Connection: keep-alive"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header: "Location: http://origin.com:8081/"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header done
2020/08/25 18:45:07 [debug] 2412#0: *1 finalize http upstream request: 302
2020/08/25 18:45:07 [debug] 2412#0: *1 finalize http proxy request
2020/08/25 18:45:07 [debug] 2412#0: *1 free keepalive peer
2020/08/25 18:45:07 [debug] 2412#0: *1 free keepalive peer: saving connection 00007FDE84F9E3B0
2020/08/25 18:45:07 [debug] 2412#0: *1 event timer: 11, old: 34100292, new: 34100293
2020/08/25 18:45:07 [debug] 2412#0: *1 free rr peer 1 0
2020/08/25 18:45:07 [debug] 2412#0: *1 http finalize request: 302, "/?" a:1, c:1
2020/08/25 18:45:07 [debug] 2412#0: *1 http special response: 302, "/?"
2020/08/25 18:45:07 [debug] 2412#0: *1 test location: "@handle_redirect"
2020/08/25 18:45:07 [debug] 2412#0: *1 using location: @handle_redirect "/?"
2020/08/25 18:45:07 [debug] 2412#0: *1 rewrite phase: 2
2020/08/25 18:45:07 [debug] 2412#0: *1 http script complex value
2020/08/25 18:45:07 [debug] 2412#0: *1 http script var: "http://origin.com:8081/"
2020/08/25 18:45:07 [debug] 2412#0: *1 http script set $redirect_location
2020/08/25 18:45:07 [debug] 2412#0: *1 post rewrite phase: 3
2020/08/25 18:45:07 [debug] 2412#0: *1 generic phase: 4
2020/08/25 18:45:07 [debug] 2412#0: *1 generic phase: 5
2020/08/25 18:45:07 [debug] 2412#0: *1 access phase: 6
2020/08/25 18:45:07 [debug] 2412#0: *1 access phase: 7
2020/08/25 18:45:07 [debug] 2412#0: *1 post access phase: 8
2020/08/25 18:45:07 [debug] 2412#0: *1 generic phase: 9
2020/08/25 18:45:07 [debug] 2412#0: *1 generic phase: 10
2020/08/25 18:45:07 [debug] 2412#0: *1 posix_memalign: 0000000001521330:4096 @16
2020/08/25 18:45:07 [debug] 2412#0: *1 http script var: "http://origin.com:8081/"
2020/08/25 18:45:07 [debug] 2412#0: *1 http init upstream, client timer: 0
2020/08/25 18:45:07 [debug] 2412#0: *1 http script copy: "Host"
2020/08/25 18:45:07 [debug] 2412#0: *1 http script var: "origin.com:8081"
2020/08/25 18:45:07 [debug] 2412#0: *1 http script copy: ""
2020/08/25 18:45:07 [debug] 2412#0: *1 http script copy: ""
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header: "User-Agent: curl/7.29.0"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header: "Accept: */*"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header:
"GET / HTTP/1.1
Host: origin.com:8081
User-Agent: curl/7.29.0
Accept: */*

"
2020/08/25 18:45:07 [debug] 2412#0: *1 http cleanup add: 00000000015202D0
2020/08/25 18:45:07 [debug] 2412#0: malloc: 000000000151A960:224
2020/08/25 18:45:07 [debug] 2412#0: resolve: "origin.com"
2020/08/25 18:45:07 [debug] 2412#0: malloc: 000000000152C570:184
2020/08/25 18:45:07 [debug] 2412#0: malloc: 000000000152C630:10
2020/08/25 18:45:07 [debug] 2412#0: malloc: 000000000152C650:28
2020/08/25 18:45:07 [debug] 2412#0: resolve: "origin.com" A 65094
2020/08/25 18:45:07 [debug] 2412#0: UDP socket 13
2020/08/25 18:45:07 [debug] 2412#0: connect to 127.0.0.1:53, fd:13 #4
2020/08/25 18:45:07 [debug] 2412#0: epoll add event: fd:13 op:1 ev:80002001
2020/08/25 18:45:07 [debug] 2412#0: send: fd:13 28 of 28
2020/08/25 18:45:07 [debug] 2412#0: malloc: 000000000152C680:96
2020/08/25 18:45:07 [debug] 2412#0: event timer add: -1: 30000:34070293
2020/08/25 18:45:07 [debug] 2412#0: event timer add: -1: 5000:34045293
2020/08/25 18:45:07 [debug] 2412#0: *1 http finalize request: -4, "/?" a:1, c:3
2020/08/25 18:45:07 [debug] 2412#0: *1 http request count:3 blk:0
2020/08/25 18:45:07 [debug] 2412#0: *1 http finalize request: -4, "/?" a:1, c:2
2020/08/25 18:45:07 [debug] 2412#0: *1 http request count:2 blk:0
2020/08/25 18:45:07 [debug] 2412#0: keepalive dummy handler
2020/08/25 18:45:07 [debug] 2412#0: timer delta: 1
2020/08/25 18:45:07 [debug] 2412#0: worker cycle
2020/08/25 18:45:07 [debug] 2412#0: epoll timer: 5000
2020/08/25 18:45:07 [debug] 2412#0: epoll: fd:13 ev:0001 d:00007FDE84F9E580
2020/08/25 18:45:07 [debug] 2412#0: recv: fd:13 44 of 4096
2020/08/25 18:45:07 [debug] 2412#0: resolver DNS response 65094 fl:8580 1/1/0/0
2020/08/25 18:45:07 [debug] 2412#0: resolver DNS response qt:1 cl:1
2020/08/25 18:45:07 [debug] 2412#0: malloc: 000000000152C6F0:10
2020/08/25 18:45:07 [debug] 2412#0: resolver qs:origin.com
2020/08/25 18:45:07 [debug] 2412#0: resolver naddrs:1 cname:0000000000000000 ttl:3600
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream resolve: "/?"
2020/08/25 18:45:07 [debug] 2412#0: *1 name was resolved to 127.0.0.1
2020/08/25 18:45:07 [debug] 2412#0: resolve name done: 0
2020/08/25 18:45:07 [debug] 2412#0: event timer del: -1: 34070293
2020/08/25 18:45:07 [debug] 2412#0: resolver expire
2020/08/25 18:45:07 [debug] 2412#0: event timer del: -1: 34045293
2020/08/25 18:45:07 [debug] 2412#0: *1 get rr peer, try: 1
2020/08/25 18:45:07 [debug] 2412#0: *1 stream socket 14
2020/08/25 18:45:07 [debug] 2412#0: *1 epoll add connection: fd:14 ev:80002005
2020/08/25 18:45:07 [debug] 2412#0: *1 connect to 127.0.0.1:8081, fd:14 #5
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream connect: -2
2020/08/25 18:45:07 [debug] 2412#0: *1 posix_memalign: 000000000151A960:128 @16
2020/08/25 18:45:07 [debug] 2412#0: *1 event timer add: 14: 60000:34100294
2020/08/25 18:45:07 [debug] 2412#0: recv: fd:13 -1 of 4096
2020/08/25 18:45:07 [debug] 2412#0: recv() not ready (11: Resource temporarily unavailable)
2020/08/25 18:45:07 [debug] 2412#0: timer delta: 1
2020/08/25 18:45:07 [debug] 2412#0: worker cycle
2020/08/25 18:45:07 [debug] 2412#0: epoll timer: 59998
2020/08/25 18:45:07 [debug] 2412#0: epoll: fd:14 ev:0004 d:00007FDE84F9E668
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream request: "/?"
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream send request handler
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream send request
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream send request body
2020/08/25 18:45:07 [debug] 2412#0: *1 chain writer buf fl:1 s:79
2020/08/25 18:45:07 [debug] 2412#0: *1 chain writer in: 0000000001521A10
2020/08/25 18:45:07 [debug] 2412#0: *1 writev: 79 of 79
2020/08/25 18:45:07 [debug] 2412#0: *1 chain writer out: 0000000000000000
2020/08/25 18:45:07 [debug] 2412#0: *1 event timer del: 14: 34100294
2020/08/25 18:45:07 [debug] 2412#0: *1 event timer add: 14: 60000:34100294
2020/08/25 18:45:07 [debug] 2412#0: epoll: fd:6 ev:0001 d:00007FDE84F9E010
2020/08/25 18:45:07 [debug] 2412#0: accept on 0.0.0.0:8081, ready: 0
2020/08/25 18:45:07 [debug] 2412#0: posix_memalign: 000000000152C710:512 @16
2020/08/25 18:45:07 [debug] 2412#0: *6 accept: 127.0.0.1:40906 fd:15
2020/08/25 18:45:07 [debug] 2412#0: *6 event timer add: 15: 60000:34100294
2020/08/25 18:45:07 [debug] 2412#0: *6 reusable connection: 1
2020/08/25 18:45:07 [debug] 2412#0: *6 epoll add event: fd:15 op:1 ev:80002001
2020/08/25 18:45:07 [debug] 2412#0: timer delta: 0
2020/08/25 18:45:07 [debug] 2412#0: worker cycle
2020/08/25 18:45:07 [debug] 2412#0: epoll timer: 59998
2020/08/25 18:45:07 [debug] 2412#0: epoll: fd:15 ev:0001 d:00007FDE84F9E750
2020/08/25 18:45:07 [debug] 2412#0: *6 http wait request handler
2020/08/25 18:45:07 [debug] 2412#0: *6 malloc: 000000000152C920:1024
2020/08/25 18:45:07 [debug] 2412#0: *6 recv: eof:0, avail:1
2020/08/25 18:45:07 [debug] 2412#0: *6 recv: fd:15 79 of 1024
2020/08/25 18:45:07 [debug] 2412#0: *6 reusable connection: 0
2020/08/25 18:45:07 [debug] 2412#0: *6 posix_memalign: 0000000001522340:4096 @16
2020/08/25 18:45:07 [debug] 2412#0: *6 http process request line
2020/08/25 18:45:07 [debug] 2412#0: *6 http request line: "GET / HTTP/1.1"
2020/08/25 18:45:07 [debug] 2412#0: *6 http uri: "/"
2020/08/25 18:45:07 [debug] 2412#0: *6 http args: ""
2020/08/25 18:45:07 [debug] 2412#0: *6 http exten: ""
2020/08/25 18:45:07 [debug] 2412#0: *6 posix_memalign: 000000000157A800:4096 @16
2020/08/25 18:45:07 [debug] 2412#0: *6 http process request header line
2020/08/25 18:45:07 [debug] 2412#0: *6 http header: "Host: origin.com:8081"
2020/08/25 18:45:07 [debug] 2412#0: *6 http header: "User-Agent: curl/7.29.0"
2020/08/25 18:45:07 [debug] 2412#0: *6 http header: "Accept: */*"
2020/08/25 18:45:07 [debug] 2412#0: *6 http header done
2020/08/25 18:45:07 [debug] 2412#0: *6 event timer del: 15: 34100294
2020/08/25 18:45:07 [debug] 2412#0: *6 rewrite phase: 0
2020/08/25 18:45:07 [debug] 2412#0: *6 using configuration ""
2020/08/25 18:45:07 [debug] 2412#0: *6 http cl:-1 max:1048576
2020/08/25 18:45:07 [debug] 2412#0: *6 rewrite phase: 2
2020/08/25 18:45:07 [debug] 2412#0: *6 post rewrite phase: 3
2020/08/25 18:45:07 [debug] 2412#0: *6 generic phase: 4
2020/08/25 18:45:07 [debug] 2412#0: *6 generic phase: 5
2020/08/25 18:45:07 [debug] 2412#0: *6 access phase: 6
2020/08/25 18:45:07 [debug] 2412#0: *6 access phase: 7
2020/08/25 18:45:07 [debug] 2412#0: *6 post access phase: 8
2020/08/25 18:45:07 [debug] 2412#0: *6 generic phase: 9
2020/08/25 18:45:07 [debug] 2412#0: *6 generic phase: 10
2020/08/25 18:45:07 [debug] 2412#0: *6 content phase: 11
2020/08/25 18:45:07 [debug] 2412#0: *6 open index "/usr/local/nginx/html/index.html"
2020/08/25 18:45:07 [debug] 2412#0: *6 internal redirect: "/index.html?"
2020/08/25 18:45:07 [debug] 2412#0: *6 rewrite phase: 0
2020/08/25 18:45:07 [debug] 2412#0: *6 using configuration ""
2020/08/25 18:45:07 [debug] 2412#0: *6 http cl:-1 max:1048576
2020/08/25 18:45:07 [debug] 2412#0: *6 rewrite phase: 2
2020/08/25 18:45:07 [debug] 2412#0: *6 post rewrite phase: 3
2020/08/25 18:45:07 [debug] 2412#0: *6 generic phase: 4
2020/08/25 18:45:07 [debug] 2412#0: *6 generic phase: 5
2020/08/25 18:45:07 [debug] 2412#0: *6 access phase: 6
2020/08/25 18:45:07 [debug] 2412#0: *6 access phase: 7
2020/08/25 18:45:07 [debug] 2412#0: *6 post access phase: 8
2020/08/25 18:45:07 [debug] 2412#0: *6 generic phase: 9
2020/08/25 18:45:07 [debug] 2412#0: *6 generic phase: 10
2020/08/25 18:45:07 [debug] 2412#0: *6 content phase: 11
2020/08/25 18:45:07 [debug] 2412#0: *6 content phase: 12
2020/08/25 18:45:07 [debug] 2412#0: *6 content phase: 13
2020/08/25 18:45:07 [debug] 2412#0: *6 http filename: "/usr/local/nginx/html/index.html"
2020/08/25 18:45:07 [debug] 2412#0: *6 add cleanup: 0000000001523040
2020/08/25 18:45:07 [debug] 2412#0: *6 http static fd: 16
2020/08/25 18:45:07 [debug] 2412#0: *6 http set discard body
2020/08/25 18:45:07 [debug] 2412#0: *6 HTTP/1.1 200 OK
Server: nginx/1.16.1
Date: Tue, 25 Aug 2020 21:45:07 GMT
Content-Type: text/html
Content-Length: 3698
Last-Modified: Tue, 25 Aug 2020 21:33:55 GMT
Connection: keep-alive
ETag: "5f4583c3-e72"
Accept-Ranges: bytes

2020/08/25 18:45:07 [debug] 2412#0: *6 write new buf t:1 f:0 0000000001523208, pos 0000000001523208, size: 239 file: 0, size: 0
2020/08/25 18:45:07 [debug] 2412#0: *6 http write filter: l:0 f:0 s:239
2020/08/25 18:45:07 [debug] 2412#0: *6 http output filter "/index.html?"
2020/08/25 18:45:07 [debug] 2412#0: *6 http copy filter: "/index.html?"
2020/08/25 18:45:07 [debug] 2412#0: *6 http postpone filter "/index.html?" 00007FFCFD2A93E0
2020/08/25 18:45:07 [debug] 2412#0: *6 write old buf t:1 f:0 0000000001523208, pos 0000000001523208, size: 239 file: 0, size: 0
2020/08/25 18:45:07 [debug] 2412#0: *6 write new buf t:0 f:1 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 3698
2020/08/25 18:45:07 [debug] 2412#0: *6 http write filter: l:1 f:0 s:3937
2020/08/25 18:45:07 [debug] 2412#0: *6 http write filter limit 0
2020/08/25 18:45:07 [debug] 2412#0: *6 writev: 239 of 239
2020/08/25 18:45:07 [debug] 2412#0: *6 sendfile: @0 3698
2020/08/25 18:45:07 [debug] 2412#0: *6 sendfile: 3698 of 3698 @0
2020/08/25 18:45:07 [debug] 2412#0: *6 http write filter 0000000000000000
2020/08/25 18:45:07 [debug] 2412#0: *6 http copy filter: 0 "/index.html?"
2020/08/25 18:45:07 [debug] 2412#0: *6 http finalize request: 0, "/index.html?" a:1, c:2
2020/08/25 18:45:07 [debug] 2412#0: *6 http request count:2 blk:0
2020/08/25 18:45:07 [debug] 2412#0: *6 http finalize request: -4, "/index.html?" a:1, c:1
2020/08/25 18:45:07 [debug] 2412#0: *6 set http keepalive handler
2020/08/25 18:45:07 [debug] 2412#0: *6 http close request
2020/08/25 18:45:07 [debug] 2412#0: *6 http log handler
2020/08/25 18:45:07 [debug] 2412#0: *6 run cleanup: 0000000001523040
2020/08/25 18:45:07 [debug] 2412#0: *6 file cleanup: fd:16
2020/08/25 18:45:07 [debug] 2412#0: *6 free: 0000000001522340, unused: 24
2020/08/25 18:45:07 [debug] 2412#0: *6 free: 000000000157A800, unused: 2876
2020/08/25 18:45:07 [debug] 2412#0: *6 free: 000000000152C920
2020/08/25 18:45:07 [debug] 2412#0: *6 hc free: 0000000000000000
2020/08/25 18:45:07 [debug] 2412#0: *6 hc busy: 0000000000000000 0
2020/08/25 18:45:07 [debug] 2412#0: *6 tcp_nodelay
2020/08/25 18:45:07 [debug] 2412#0: *6 reusable connection: 1
2020/08/25 18:45:07 [debug] 2412#0: *6 event timer add: 15: 65000:34105295
2020/08/25 18:45:07 [debug] 2412#0: timer delta: 1
2020/08/25 18:45:07 [debug] 2412#0: worker cycle
2020/08/25 18:45:07 [debug] 2412#0: epoll timer: 59997
2020/08/25 18:45:07 [debug] 2412#0: epoll: fd:14 ev:0005 d:00007FDE84F9E668
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream request: "/?"
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream process header
2020/08/25 18:45:07 [debug] 2412#0: *1 malloc: 0000000001522340:4096
2020/08/25 18:45:07 [debug] 2412#0: *1 recv: eof:0, avail:1
2020/08/25 18:45:07 [debug] 2412#0: *1 recv: fd:14 3937 of 4096
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy status 200 "200 OK"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header: "Server: nginx/1.16.1"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header: "Date: Tue, 25 Aug 2020 21:45:07 GMT"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header: "Content-Type: text/html"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header: "Content-Length: 3698"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header: "Last-Modified: Tue, 25 Aug 2020 21:33:55 GMT"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header: "Connection: keep-alive"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header: "ETag: "5f4583c3-e72""
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header: "Accept-Ranges: bytes"
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy header done
2020/08/25 18:45:07 [debug] 2412#0: *1 HTTP/1.1 200 OK
Server: nginx/1.16.1
Date: Tue, 25 Aug 2020 21:45:07 GMT
Content-Type: text/html
Content-Length: 3698
Connection: keep-alive
Last-Modified: Tue, 25 Aug 2020 21:33:55 GMT
ETag: "5f4583c3-e72"
Accept-Ranges: bytes

2020/08/25 18:45:07 [debug] 2412#0: *1 write new buf t:1 f:0 0000000001521D80, pos 0000000001521D80, size: 239 file: 0, size: 0
2020/08/25 18:45:07 [debug] 2412#0: *1 http write filter: l:0 f:0 s:239
2020/08/25 18:45:07 [debug] 2412#0: *1 http cacheable: 0
2020/08/25 18:45:07 [debug] 2412#0: *1 http proxy filter init s:200 h:0 c:0 l:3698
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream process upstream
2020/08/25 18:45:07 [debug] 2412#0: *1 pipe read upstream: 0
2020/08/25 18:45:07 [debug] 2412#0: *1 pipe preread: 3698
2020/08/25 18:45:07 [debug] 2412#0: *1 pipe buf free s:0 t:1 f:0 0000000001522340, pos 000000000152242F, size: 3698 file: 0, size: 0
2020/08/25 18:45:07 [debug] 2412#0: *1 pipe length: 3698
2020/08/25 18:45:07 [debug] 2412#0: *1 input buf #0
2020/08/25 18:45:07 [debug] 2412#0: *1 pipe write downstream: 1
2020/08/25 18:45:07 [debug] 2412#0: *1 pipe write downstream flush in
2020/08/25 18:45:07 [debug] 2412#0: *1 http output filter "/?"
2020/08/25 18:45:07 [debug] 2412#0: *1 http copy filter: "/?"
2020/08/25 18:45:07 [debug] 2412#0: *1 http postpone filter "/?" 0000000001521F88
2020/08/25 18:45:07 [debug] 2412#0: *1 write old buf t:1 f:0 0000000001521D80, pos 0000000001521D80, size: 239 file: 0, size: 0
2020/08/25 18:45:07 [debug] 2412#0: *1 write new buf t:1 f:0 0000000001522340, pos 000000000152242F, size: 3698 file: 0, size: 0
2020/08/25 18:45:07 [debug] 2412#0: *1 http write filter: l:0 f:0 s:3937
2020/08/25 18:45:07 [debug] 2412#0: *1 http write filter limit 0
2020/08/25 18:45:07 [debug] 2412#0: *1 writev: 3937 of 3937
2020/08/25 18:45:07 [debug] 2412#0: *1 http write filter 0000000000000000
2020/08/25 18:45:07 [debug] 2412#0: *1 http copy filter: 0 "/?"
2020/08/25 18:45:07 [debug] 2412#0: *1 pipe write downstream done
2020/08/25 18:45:07 [debug] 2412#0: *1 event timer: 14, old: 34100294, new: 34100295
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream exit: 0000000000000000
2020/08/25 18:45:07 [debug] 2412#0: *1 finalize http upstream request: 0
2020/08/25 18:45:07 [debug] 2412#0: *1 finalize http proxy request
2020/08/25 18:45:07 [debug] 2412#0: *1 free rr peer 1 0
2020/08/25 18:45:07 [debug] 2412#0: *1 close http upstream connection: 14
2020/08/25 18:45:07 [debug] 2412#0: *1 free: 000000000151A960, unused: 48
2020/08/25 18:45:07 [debug] 2412#0: *1 event timer del: 14: 34100294
2020/08/25 18:45:07 [debug] 2412#0: *1 reusable connection: 0
2020/08/25 18:45:07 [debug] 2412#0: *1 http upstream temp fd: -1
2020/08/25 18:45:07 [debug] 2412#0: *1 http output filter "/?"
2020/08/25 18:45:07 [debug] 2412#0: *1 http copy filter: "/?"
2020/08/25 18:45:07 [debug] 2412#0: *1 http postpone filter "/?" 00007FFCFD2A9660
2020/08/25 18:45:07 [debug] 2412#0: *1 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
2020/08/25 18:45:07 [debug] 2412#0: *1 http write filter: l:1 f:0 s:0
2020/08/25 18:45:07 [debug] 2412#0: *1 http copy filter: 0 "/?"
2020/08/25 18:45:07 [debug] 2412#0: *1 http finalize request: 0, "/?" a:1, c:1
2020/08/25 18:45:07 [debug] 2412#0: *1 set http keepalive handler
2020/08/25 18:45:07 [debug] 2412#0: *1 http close request
2020/08/25 18:45:07 [debug] 2412#0: *1 http log handler
2020/08/25 18:45:07 [debug] 2412#0: *1 free: 0000000001522340
2020/08/25 18:45:07 [debug] 2412#0: *1 free: 0000000001520320
2020/08/25 18:45:07 [debug] 2412#0: *1 free: 000000000152B350, unused: 0
2020/08/25 18:45:07 [debug] 2412#0: *1 free: 000000000151F310, unused: 0
2020/08/25 18:45:07 [debug] 2412#0: *1 free: 0000000001521330, unused: 532
2020/08/25 18:45:07 [debug] 2412#0: *1 free: 0000000001534FE0
2020/08/25 18:45:07 [debug] 2412#0: *1 hc free: 0000000000000000
2020/08/25 18:45:07 [debug] 2412#0: *1 hc busy: 0000000000000000 0
2020/08/25 18:45:07 [debug] 2412#0: *1 tcp_nodelay
2020/08/25 18:45:07 [debug] 2412#0: *1 reusable connection: 1
2020/08/25 18:45:07 [debug] 2412#0: *1 event timer add: 10: 65000:34105295
2020/08/25 18:45:07 [debug] 2412#0: timer delta: 0
2020/08/25 18:45:07 [debug] 2412#0: worker cycle
2020/08/25 18:45:07 [debug] 2412#0: epoll timer: 59997
2020/08/25 18:45:07 [debug] 2412#0: epoll: fd:15 ev:2001 d:00007FDE84F9E750
2020/08/25 18:45:07 [debug] 2412#0: *6 http keepalive handler
2020/08/25 18:45:07 [debug] 2412#0: *6 malloc: 000000000152C920:1024
2020/08/25 18:45:07 [debug] 2412#0: *6 recv: eof:1, avail:1
2020/08/25 18:45:07 [debug] 2412#0: *6 recv: fd:15 0 of 1024
2020/08/25 18:45:07 [info] 2412#0: *6 client 127.0.0.1 closed keepalive connection
2020/08/25 18:45:07 [debug] 2412#0: *6 close http connection: 15
2020/08/25 18:45:07 [debug] 2412#0: *6 event timer del: 15: 34105295
2020/08/25 18:45:07 [debug] 2412#0: *6 reusable connection: 0
2020/08/25 18:45:07 [debug] 2412#0: *6 free: 000000000152C920
2020/08/25 18:45:07 [debug] 2412#0: *6 free: 000000000152C710, unused: 152
2020/08/25 18:45:07 [debug] 2412#0: timer delta: 0
2020/08/25 18:45:07 [debug] 2412#0: worker cycle
2020/08/25 18:45:07 [debug] 2412#0: epoll timer: 59997
2020/08/25 18:45:07 [debug] 2412#0: epoll: fd:10 ev:2005 d:00007FDE84F9E2C8
2020/08/25 18:45:07 [debug] 2412#0: *1 http keepalive handler
2020/08/25 18:45:07 [debug] 2412#0: *1 malloc: 000000000152C650:1024
2020/08/25 18:45:07 [debug] 2412#0: *1 recv: eof:1, avail:1
2020/08/25 18:45:07 [debug] 2412#0: *1 recv: fd:10 0 of 1024
2020/08/25 18:45:07 [info] 2412#0: *1 client 127.0.0.1 closed keepalive connection
2020/08/25 18:45:07 [debug] 2412#0: *1 close http connection: 10
2020/08/25 18:45:07 [debug] 2412#0: *1 event timer del: 10: 34105295
2020/08/25 18:45:07 [debug] 2412#0: *1 reusable connection: 0
2020/08/25 18:45:07 [debug] 2412#0: *1 free: 000000000152C650
2020/08/25 18:45:07 [debug] 2412#0: *1 free: 000000000151A6C0, unused: 152
2020/08/25 18:45:07 [debug] 2412#0: timer delta: 1
2020/08/25 18:45:07 [debug] 2412#0: worker cycle
2020/08/25 18:45:07 [debug] 2412#0: epoll timer: 59996
2020/08/25 18:45:20 [notice] 2412#0: signal 2 (SIGINT) received, exiting
2020/08/25 18:45:20 [info] 2412#0: epoll_wait() failed (4: Interrupted system call)
2020/08/25 18:45:20 [debug] 2412#0: timer delta: 12993
2020/08/25 18:45:20 [debug] 2412#0: flush files
2020/08/25 18:45:20 [notice] 2412#0: exit
2020/08/25 18:45:20 [debug] 2412#0: epoll del event: fd:6 op:2 ev:00000000
2020/08/25 18:45:20 [debug] 2412#0: close listening 0.0.0.0:8081 #6 
2020/08/25 18:45:20 [debug] 2412#0: epoll del event: fd:7 op:2 ev:00000000
2020/08/25 18:45:20 [debug] 2412#0: close listening 0.0.0.0:8080 #7 
2020/08/25 18:45:20 [debug] 2412#0: epoll del event: fd:8 op:2 ev:00000000
2020/08/25 18:45:20 [debug] 2412#0: close listening 0.0.0.0:80 #8 
2020/08/25 18:45:20 [debug] 2412#0: run cleanup: 0000000001546FF8
2020/08/25 18:45:20 [debug] 2412#0: run cleanup: 0000000001533EE0
2020/08/25 18:45:20 [debug] 2412#0: cleanup resolver
2020/08/25 18:45:20 [debug] 2412#0: reusable connection: 0
2020/08/25 18:45:20 [debug] 2412#0: free: 0000000001549650
2020/08/25 18:45:20 [debug] 2412#0: free: 00000000015444B0
2020/08/25 18:45:20 [debug] 2412#0: free: 0000000001537470
2020/08/25 18:45:20 [debug] 2412#0: free: 0000000001535FF0
2020/08/25 18:45:20 [debug] 2412#0: free: 000000000152FFC0
2020/08/25 18:45:20 [debug] 2412#0: free: 000000000152EF00
2020/08/25 18:45:20 [debug] 2412#0: free: 000000000152DE40
2020/08/25 18:45:20 [debug] 2412#0: free: 000000000152CD80
2020/08/25 18:45:20 [debug] 2412#0: free: 000000000151B300, unused: 0
2020/08/25 18:45:20 [debug] 2412#0: free: 0000000001525B30, unused: 0
2020/08/25 18:45:20 [debug] 2412#0: free: 0000000001530FD0, unused: 0
2020/08/25 18:45:20 [debug] 2412#0: free: 0000000001538480, unused: 0
2020/08/25 18:45:20 [debug] 2412#0: free: 000000000153C490, unused: 0
2020/08/25 18:45:20 [debug] 2412#0: free: 00000000015404A0, unused: 0
2020/08/25 18:45:20 [debug] 2412#0: free: 0000000001545640, unused: 9776