gistfile1.txt

2016/09/22 13:23:02 [debug] 25295#0: epoll: fd:6 ev:0001 d:00007FADB94FE220
2016/09/22 13:23:02 [debug] 25295#0: accept on 0.0.0.0:80, ready: 0
2016/09/22 13:23:02 [debug] 25295#0: posix_memalign: 00007FADB94F7500:512 @16
2016/09/22 13:23:02 [debug] 25295#0: *1 accept: 127.0.0.1:45967 fd:3
2016/09/22 13:23:02 [debug] 25295#0: *1 event timer add: 3: 60000:1474575842609
2016/09/22 13:23:02 [debug] 25295#0: *1 reusable connection: 1
2016/09/22 13:23:02 [debug] 25295#0: *1 epoll add event: fd:3 op:1 ev:80002001
2016/09/22 13:23:02 [debug] 25295#0: timer delta: 1078
2016/09/22 13:23:02 [debug] 25295#0: worker cycle
2016/09/22 13:23:02 [debug] 25295#0: epoll timer: 60000
2016/09/22 13:23:02 [debug] 25295#0: epoll: fd:3 ev:0001 d:00007FADB94FE3F0
2016/09/22 13:23:02 [debug] 25295#0: *1 http wait request handler
2016/09/22 13:23:02 [debug] 25295#0: *1 malloc: 00007FADB94C2490:1024
2016/09/22 13:23:02 [debug] 25295#0: *1 recv: eof:0, avail:1
2016/09/22 13:23:02 [debug] 25295#0: *1 recv: fd:3 76 of 1024
2016/09/22 13:23:02 [debug] 25295#0: *1 reusable connection: 0
2016/09/22 13:23:02 [debug] 25295#0: *1 posix_memalign: 00007FADB94D4560:4096 @16
2016/09/22 13:23:02 [debug] 25295#0: *1 http process request line
2016/09/22 13:23:02 [debug] 25295#0: *1 http request line: "GET /foo HTTP/1.1"
2016/09/22 13:23:02 [debug] 25295#0: *1 http uri: "/foo"
2016/09/22 13:23:02 [debug] 25295#0: *1 http args: ""
2016/09/22 13:23:02 [debug] 25295#0: *1 http exten: ""
2016/09/22 13:23:02 [debug] 25295#0: *1 http process request header line
2016/09/22 13:23:02 [debug] 25295#0: *1 http header: "User-Agent: curl/7.35.0"
2016/09/22 13:23:02 [debug] 25295#0: *1 http header: "Host: localhost"
2016/09/22 13:23:02 [debug] 25295#0: *1 http header: "Accept: */*"
2016/09/22 13:23:02 [debug] 25295#0: *1 http header done
2016/09/22 13:23:02 [debug] 25295#0: *1 event timer del: 3: 1474575842609
2016/09/22 13:23:02 [debug] 25295#0: *1 rewrite phase: 0
2016/09/22 13:23:02 [debug] 25295#0: *1 rewrite phase: 1
2016/09/22 13:23:02 [debug] 25295#0: *1 test location: "/"
2016/09/22 13:23:02 [debug] 25295#0: *1 test location: "bar"
2016/09/22 13:23:02 [debug] 25295#0: *1 test location: "foo"
2016/09/22 13:23:02 [debug] 25295#0: *1 using configuration "/foo"
2016/09/22 13:23:02 [debug] 25295#0: *1 http cl:-1 max:1048576
2016/09/22 13:23:02 [debug] 25295#0: *1 rewrite phase: 3
2016/09/22 13:23:02 [debug] 25295#0: *1 rewrite phase: 4
2016/09/22 13:23:02 [debug] 25295#0: *1 post rewrite phase: 5
2016/09/22 13:23:02 [debug] 25295#0: *1 generic phase: 6
2016/09/22 13:23:02 [debug] 25295#0: *1 generic phase: 7
2016/09/22 13:23:02 [debug] 25295#0: *1 access phase: 8
2016/09/22 13:23:02 [debug] 25295#0: *1 lua access handler, uri:"/foo" c:1
2016/09/22 13:23:02 [debug] 25295#0: *1 access phase: 8
2016/09/22 13:23:02 [debug] 25295#0: *1 access phase: 9
2016/09/22 13:23:02 [debug] 25295#0: *1 access phase: 10
2016/09/22 13:23:02 [debug] 25295#0: *1 lua access handler, uri:"/foo" c:1
2016/09/22 13:23:02 [debug] 25295#0: *1 post access phase: 11
2016/09/22 13:23:02 [debug] 25295#0: *1 lua content handler, uri:"/foo" c:1
2016/09/22 13:23:02 [debug] 25295#0: *1 posix_memalign: 00007FADB94C7020:4096 @16
2016/09/22 13:23:02 [debug] 25295#0: *1 lua reset ctx
2016/09/22 13:23:02 [debug] 25295#0: *1 lua creating new thread
2016/09/22 13:23:02 [debug] 25295#0: *1 http cleanup add: 00007FADB94D54E8
2016/09/22 13:23:02 [debug] 25295#0: *1 lua run thread, top:0 c:1
2016/09/22 13:23:02 [debug] 25295#0: *1 lua location capture, uri:"/foo" c:1
2016/09/22 13:23:02 [debug] 25295#0: *1 lua http subrequest "/bar?"
2016/09/22 13:23:02 [debug] 25295#0: *1 posix_memalign: 00007FADB94C8030:4096 @16
2016/09/22 13:23:02 [debug] 25295#0: *1 lua resume returned 1
2016/09/22 13:23:02 [debug] 25295#0: *1 lua thread yielded
2016/09/22 13:23:02 [debug] 25295#0: *1 http finalize request: -4, "/foo?" a:1, c:3
2016/09/22 13:23:02 [debug] 25295#0: *1 http request count:3 blk:0
2016/09/22 13:23:02 [debug] 25295#0: *1 http posted request: "/bar?"
2016/09/22 13:23:02 [debug] 25295#0: *1 rewrite phase: 0
2016/09/22 13:23:02 [debug] 25295#0: *1 rewrite phase: 1
2016/09/22 13:23:02 [debug] 25295#0: *1 test location: "/"
2016/09/22 13:23:02 [debug] 25295#0: *1 test location: "bar"
2016/09/22 13:23:02 [debug] 25295#0: *1 using configuration "/bar"
2016/09/22 13:23:02 [debug] 25295#0: *1 http cl:-1 max:1048576
2016/09/22 13:23:02 [debug] 25295#0: *1 rewrite phase: 3
2016/09/22 13:23:02 [debug] 25295#0: *1 rewrite phase: 4
2016/09/22 13:23:02 [debug] 25295#0: *1 post rewrite phase: 5
2016/09/22 13:23:02 [debug] 25295#0: *1 generic phase: 6
2016/09/22 13:23:02 [debug] 25295#0: *1 generic phase: 7
2016/09/22 13:23:02 [debug] 25295#0: *1 lua content handler, uri:"/bar" c:2
2016/09/22 13:23:02 [debug] 25295#0: *1 lua reset ctx
2016/09/22 13:23:02 [debug] 25295#0: *1 lua creating new thread
2016/09/22 13:23:02 [debug] 25295#0: *1 http cleanup add: 00007FADB94C7EF0
2016/09/22 13:23:02 [debug] 25295#0: *1 lua run thread, top:0 c:2
2016/09/22 13:23:02 [debug] 25295#0: *1 lua resume returned 0
2016/09/22 13:23:02 [debug] 25295#0: *1 lua light thread ended normally
2016/09/22 13:23:02 [debug] 25295#0: *1 lua deleting light thread
2016/09/22 13:23:02 [debug] 25295#0: *1 lua header filter for user lua code, uri "/bar"
2016/09/22 13:23:02 [debug] 25295#0: *1 lua capture header filter, uri "/bar"
2016/09/22 13:23:02 [debug] 25295#0: *1 lua capturing response body
2016/09/22 13:23:02 [debug] 25295#0: *1 lua sending last buf of the response body
2016/09/22 13:23:02 [debug] 25295#0: *1 http output filter "/bar?"
2016/09/22 13:23:02 [debug] 25295#0: *1 http copy filter: "/bar?"
2016/09/22 13:23:02 [debug] 25295#0: *1 lua body filter for user lua code, uri "/bar"
2016/09/22 13:23:02 [debug] 25295#0: *1 lua capture body filter, uri "/bar"
2016/09/22 13:23:02 [debug] 25295#0: *1 lua capture body filter capturing response body, uri "/bar"
2016/09/22 13:23:02 [debug] 25295#0: *1 http copy filter: 0 "/bar?"
2016/09/22 13:23:02 [debug] 25295#0: *1 http finalize request: 0, "/bar?" a:0, c:2
2016/09/22 13:23:02 [debug] 25295#0: *1 lua run post subrequest handler, rc:0 c:2
2016/09/22 13:23:02 [debug] 25295#0: *1 lua restoring write event handler
2016/09/22 13:23:02 [debug] 25295#0: *1 http wake parent request: "/foo?"
2016/09/22 13:23:02 [debug] 25295#0: *1 http posted request: "/foo?"
2016/09/22 13:23:02 [debug] 25295#0: *1 lua run subrequests done, resuming lua thread
2016/09/22 13:23:02 [debug] 25295#0: *1 lua handle subrequest responses
2016/09/22 13:23:02 [debug] 25295#0: *1 lua run thread, top:0 c:1
2016/09/22 13:23:02 [debug] 25295#0: *1 lua redirect to "/somewhere-else" with code 301
2016/09/22 13:23:02 [debug] 25295#0: *1 lua resume returned 1
2016/09/22 13:23:02 [debug] 25295#0: *1 lua thread yielded
2016/09/22 13:23:02 [debug] 25295#0: *1 lua thread aborting request with status 301
2016/09/22 13:23:02 [debug] 25295#0: *1 lua request cleanup: forcible=0
2016/09/22 13:23:02 [debug] 25295#0: *1 lua run thread returned 301
2016/09/22 13:23:02 [debug] 25295#0: *1 http finalize request: 301, "/foo?" a:1, c:1
2016/09/22 13:23:02 [debug] 25295#0: *1 http special response: 301, "/foo?"
2016/09/22 13:23:02 [debug] 25295#0: *1 http set discard body
2016/09/22 13:23:02 [debug] 25295#0: *1 lua header filter for user lua code, uri "/foo"
2016/09/22 13:23:02 [debug] 25295#0: *1 lua capture header filter, uri "/foo"
2016/09/22 13:23:02 [debug] 25295#0: *1 HTTP/1.1 301 Moved Permanently
Server: openresty/1.11.2.1
Date: Thu, 22 Sep 2016 20:23:02 GMT
Content-Type: text/html
Content-Length: 191
Connection: keep-alive
Set-Cookie: this is a cookie
Location: /somewhere-else

2016/09/22 13:23:02 [debug] 25295#0: *1 write new buf t:1 f:0 00007FADB94C84B0, pos 00007FADB94C84B0, size: 226 file: 0, size: 0
2016/09/22 13:23:02 [debug] 25295#0: *1 http write filter: l:0 f:0 s:226
2016/09/22 13:23:02 [debug] 25295#0: *1 http output filter "/foo?"
2016/09/22 13:23:02 [debug] 25295#0: *1 http copy filter: "/foo?"
2016/09/22 13:23:02 [debug] 25295#0: *1 lua body filter for user lua code, uri "/foo"
2016/09/22 13:23:02 [debug] 25295#0: *1 lua capture body filter, uri "/foo"
2016/09/22 13:23:02 [debug] 25295#0: *1 http postpone filter "/foo?" 00007FADB94C86C0
2016/09/22 13:23:02 [debug] 25295#0: *1 write old buf t:1 f:0 00007FADB94C84B0, pos 00007FADB94C84B0, size: 226 file: 0, size: 0
2016/09/22 13:23:02 [debug] 25295#0: *1 write new buf t:0 f:0 0000000000000000, pos 0000000000A0D180, size: 132 file: 0, size: 0
2016/09/22 13:23:02 [debug] 25295#0: *1 write new buf t:0 f:0 0000000000000000, pos 0000000000A0D460, size: 59 file: 0, size: 0
2016/09/22 13:23:02 [debug] 25295#0: *1 http write filter: l:1 f:0 s:417
2016/09/22 13:23:02 [debug] 25295#0: *1 http write filter limit 0
2016/09/22 13:23:02 [debug] 25295#0: *1 writev: 417 of 417
2016/09/22 13:23:02 [debug] 25295#0: *1 http write filter 0000000000000000
2016/09/22 13:23:02 [debug] 25295#0: *1 http copy filter: 0 "/foo?"
2016/09/22 13:23:02 [debug] 25295#0: *1 http finalize request: 0, "/foo?" a:1, c:1
2016/09/22 13:23:02 [debug] 25295#0: *1 set http keepalive handler
2016/09/22 13:23:02 [debug] 25295#0: *1 http close request
2016/09/22 13:23:02 [debug] 25295#0: *1 lua request cleanup: forcible=0
2016/09/22 13:23:02 [debug] 25295#0: *1 lua log handler, uri:"/foo" c:1
2016/09/22 13:23:02 [debug] 25295#0: *1 http log handler
2016/09/22 13:23:02 [debug] 25295#0: *1 free: 00007FADB94D4560, unused: 2
2016/09/22 13:23:02 [debug] 25295#0: *1 free: 00007FADB94C7020, unused: 8
2016/09/22 13:23:02 [debug] 25295#0: *1 free: 00007FADB94C8030, unused: 2229
2016/09/22 13:23:02 [debug] 25295#0: *1 free: 00007FADB94C2490
2016/09/22 13:23:02 [debug] 25295#0: *1 hc free: 0000000000000000 0
2016/09/22 13:23:02 [debug] 25295#0: *1 hc busy: 0000000000000000 0
2016/09/22 13:23:02 [debug] 25295#0: *1 tcp_nodelay
2016/09/22 13:23:02 [debug] 25295#0: *1 reusable connection: 1
2016/09/22 13:23:02 [debug] 25295#0: *1 event timer add: 3: 65000:1474575847609
2016/09/22 13:23:02 [debug] 25295#0: timer delta: 0
2016/09/22 13:23:02 [debug] 25295#0: worker cycle
2016/09/22 13:23:02 [debug] 25295#0: epoll timer: 65000
2016/09/22 13:23:02 [debug] 25295#0: epoll: fd:3 ev:2001 d:00007FADB94FE3F0
2016/09/22 13:23:02 [debug] 25295#0: *1 http keepalive handler
2016/09/22 13:23:02 [debug] 25295#0: *1 malloc: 00007FADB94C2490:1024
2016/09/22 13:23:02 [debug] 25295#0: *1 recv: eof:1, avail:1
2016/09/22 13:23:02 [debug] 25295#0: *1 recv: fd:3 0 of 1024
2016/09/22 13:23:02 [info] 25295#0: *1 client 127.0.0.1 closed keepalive connection
2016/09/22 13:23:02 [debug] 25295#0: *1 close http connection: 3
2016/09/22 13:23:02 [debug] 25295#0: *1 event timer del: 3: 1474575847609
2016/09/22 13:23:02 [debug] 25295#0: *1 reusable connection: 0
2016/09/22 13:23:02 [debug] 25295#0: *1 free: 00007FADB94C2490
2016/09/22 13:23:02 [debug] 25295#0: *1 free: 00007FADB94F7500, unused: 128
2016/09/22 13:23:02 [debug] 25295#0: timer delta: 2
2016/09/22 13:23:02 [debug] 25295#0: worker cycle
2016/09/22 13:23:02 [debug] 25295#0: epoll timer: -1