Skip to content

Instantly share code, notes, and snippets.

@rjp

rjp/gist:1434542

Created December 5, 2011 17:49
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save rjp/1434542 to your computer and use it in GitHub Desktop.
Save rjp/1434542 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
gistfile1.txt
2011/12/05 17:45:03 [debug] 11136#0: epoll: fd:11 ev:0001 d:0853AB1C
2011/12/05 17:45:03 [debug] 11136#0: post event 08553AF4
2011/12/05 17:45:03 [debug] 11136#0: timer delta: 7457
2011/12/05 17:45:03 [debug] 11136#0: posted event 08553AF4
2011/12/05 17:45:03 [debug] 11136#0: delete posted event 08553AF4
2011/12/05 17:45:03 [debug] 11136#0: accept on 0.0.0.0:8080, ready: 0
2011/12/05 17:45:03 [debug] 11136#0: posix_memalign: 081A5E70:256 @16
2011/12/05 17:45:03 [debug] 11136#0: *1 accept: 206.251.255.197 fd:17
2011/12/05 17:45:03 [debug] 11136#0: *1 event timer add: 17: 60000:257236343
2011/12/05 17:45:03 [debug] 11136#0: *1 epoll add event: fd:17 op:1 ev:80000001
2011/12/05 17:45:03 [debug] 11136#0: posted event 00000000
2011/12/05 17:45:03 [debug] 11136#0: posted events 00000000
2011/12/05 17:45:03 [debug] 11136#0: worker cycle
2011/12/05 17:45:03 [debug] 11136#0: accept mutex locked
2011/12/05 17:45:03 [debug] 11136#0: epoll timer: 60000
2011/12/05 17:45:03 [debug] 11136#0: epoll: fd:17 ev:0001 d:0853AD10
2011/12/05 17:45:03 [debug] 11136#0: *1 post event 08553BF8
2011/12/05 17:45:03 [debug] 11136#0: timer delta: 1
2011/12/05 17:45:03 [debug] 11136#0: posted events 08553BF8
2011/12/05 17:45:03 [debug] 11136#0: posted event 08553BF8
2011/12/05 17:45:03 [debug] 11136#0: *1 delete posted event 08553BF8
2011/12/05 17:45:03 [debug] 11136#0: *1 malloc: 0852DBB8:668
2011/12/05 17:45:03 [debug] 11136#0: *1 malloc: 0852DE58:1024
2011/12/05 17:45:03 [debug] 11136#0: *1 posix_memalign: 0852E260:4096 @16
2011/12/05 17:45:03 [debug] 11136#0: *1 http process request line
2011/12/05 17:45:03 [debug] 11136#0: *1 recv: fd:17 168 of 1024
2011/12/05 17:45:03 [debug] 11136#0: *1 http request line: "GET /url/84762 HTTP/1.1"
2011/12/05 17:45:03 [debug] 11136#0: *1 http uri: "/url/84762"
2011/12/05 17:45:03 [debug] 11136#0: *1 http args: ""
2011/12/05 17:45:03 [debug] 11136#0: *1 http exten: ""
2011/12/05 17:45:03 [debug] 11136#0: *1 http process request header line
2011/12/05 17:45:03 [debug] 11136#0: *1 http header: "User-Agent: curl/7.18.0 (i486-pc-linux-gnu) libcurl/7.18.0 OpenSSL/0.9.8g zlib/1.2.3.3 libidn/1.1"
2011/12/05 17:45:03 [debug] 11136#0: *1 http header: "Host: new.frottage.org:8080"
2011/12/05 17:45:03 [debug] 11136#0: *1 http header: "Accept: */*"
2011/12/05 17:45:03 [debug] 11136#0: *1 http header done
2011/12/05 17:45:03 [debug] 11136#0: *1 event timer del: 17: 257236343
2011/12/05 17:45:03 [debug] 11136#0: *1 rewrite phase: 0
2011/12/05 17:45:03 [debug] 11136#0: *1 test location: "/urls"
2011/12/05 17:45:03 [debug] 11136#0: *1 test location: ~ "/url/(?<num>\d+)"
2011/12/05 17:45:03 [debug] 11136#0: *1 http regex set $num to "84762"
2011/12/05 17:45:03 [debug] 11136#0: *1 using configuration "/url/(?<num>\d+)"
2011/12/05 17:45:03 [debug] 11136#0: *1 http cl:-1 max:1048576
2011/12/05 17:45:03 [debug] 11136#0: *1 rewrite phase: 2
2011/12/05 17:45:03 [debug] 11136#0: *1 post rewrite phase: 3
2011/12/05 17:45:03 [debug] 11136#0: *1 generic phase: 4
2011/12/05 17:45:03 [debug] 11136#0: *1 generic phase: 5
2011/12/05 17:45:03 [debug] 11136#0: *1 access phase: 6
2011/12/05 17:45:03 [debug] 11136#0: *1 access phase: 7
2011/12/05 17:45:03 [debug] 11136#0: *1 access phase: 8
2011/12/05 17:45:03 [debug] 11136#0: *1 post access phase: 9
2011/12/05 17:45:03 [debug] 11136#0: *1 http set discard body
2011/12/05 17:45:03 [debug] 11136#0: *1 http init upstream, client timer: 0
2011/12/05 17:45:03 [debug] 11136#0: *1 epoll add event: fd:17 op:3 ev:80000005
2011/12/05 17:45:03 [debug] 11136#0: *1 http cleanup add: 0852E9B4
2011/12/05 17:45:03 [debug] 11136#0: *1 http script copy: "SELECT * FROM urls WHERE id="
2011/12/05 17:45:03 [debug] 11136#0: *1 http script var: "84762"
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres starts connecting
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres creates connection fd 18
2011/12/05 17:45:03 [debug] 11136#0: *1 epoll add event: fd:18 op:1 ev:80000001
2011/12/05 17:45:03 [debug] 11136#0: *1 epoll add event: fd:18 op:3 ev:80000005
2011/12/05 17:45:03 [debug] 11136#0: *1 http upstream connect: -2
2011/12/05 17:45:03 [debug] 11136#0: *1 event timer add: 18: 10000:257186344
2011/12/05 17:45:03 [debug] 11136#0: *1 http finalize request: -4, "/url/84762?" a:1, c:2
2011/12/05 17:45:03 [debug] 11136#0: *1 http request count:2 blk:0
2011/12/05 17:45:03 [debug] 11136#0: posted event 00000000
2011/12/05 17:45:03 [debug] 11136#0: worker cycle
2011/12/05 17:45:03 [debug] 11136#0: accept mutex locked
2011/12/05 17:45:03 [debug] 11136#0: epoll timer: 10000
2011/12/05 17:45:03 [debug] 11136#0: epoll: fd:17 ev:0004 d:0853AD10
2011/12/05 17:45:03 [debug] 11136#0: *1 post event 08560C00
2011/12/05 17:45:03 [debug] 11136#0: epoll: fd:18 ev:0004 d:0853AD74
2011/12/05 17:45:03 [debug] 11136#0: *1 post event 08560C34
2011/12/05 17:45:03 [debug] 11136#0: timer delta: 0
2011/12/05 17:45:03 [debug] 11136#0: posted events 08560C34
2011/12/05 17:45:03 [debug] 11136#0: posted event 08560C34
2011/12/05 17:45:03 [debug] 11136#0: *1 delete posted event 08560C34
2011/12/05 17:45:03 [debug] 11136#0: *1 http upstream request: "/url/84762?"
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres process events
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres trying to connect, status 2
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres re-polling on connection made
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres re-polling returned 1
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres still busy connecting, status 1
2011/12/05 17:45:03 [debug] 11136#0: posted event 08560C00
2011/12/05 17:45:03 [debug] 11136#0: *1 delete posted event 08560C00
2011/12/05 17:45:03 [debug] 11136#0: *1 http run request: "/url/84762?"
2011/12/05 17:45:03 [debug] 11136#0: *1 http request empty handler
2011/12/05 17:45:03 [debug] 11136#0: posted event 00000000
2011/12/05 17:45:03 [debug] 11136#0: worker cycle
2011/12/05 17:45:03 [debug] 11136#0: accept mutex locked
2011/12/05 17:45:03 [debug] 11136#0: epoll timer: 10000
2011/12/05 17:45:03 [debug] 11136#0: epoll: fd:18 ev:0005 d:0853AD74
2011/12/05 17:45:03 [debug] 11136#0: *1 post event 08553C2C
2011/12/05 17:45:03 [debug] 11136#0: *1 post event 08560C34
2011/12/05 17:45:03 [debug] 11136#0: timer delta: 4
2011/12/05 17:45:03 [debug] 11136#0: posted events 08560C34
2011/12/05 17:45:03 [debug] 11136#0: posted event 08560C34
2011/12/05 17:45:03 [debug] 11136#0: *1 delete posted event 08560C34
2011/12/05 17:45:03 [debug] 11136#0: *1 http upstream request: "/url/84762?"
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres process events
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres trying to connect, status 1
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres still busy connecting, status 1
2011/12/05 17:45:03 [debug] 11136#0: posted event 08553C2C
2011/12/05 17:45:03 [debug] 11136#0: *1 delete posted event 08553C2C
2011/12/05 17:45:03 [debug] 11136#0: *1 http upstream request: "/url/84762?"
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres process events
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres trying to connect, status 1
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres still busy connecting, status 1
2011/12/05 17:45:03 [debug] 11136#0: posted event 00000000
2011/12/05 17:45:03 [debug] 11136#0: worker cycle
2011/12/05 17:45:03 [debug] 11136#0: accept mutex locked
2011/12/05 17:45:03 [debug] 11136#0: epoll timer: 9996
2011/12/05 17:45:03 [debug] 11136#0: epoll: fd:18 ev:0005 d:0853AD74
2011/12/05 17:45:03 [debug] 11136#0: *1 post event 08553C2C
2011/12/05 17:45:03 [debug] 11136#0: *1 post event 08560C34
2011/12/05 17:45:03 [debug] 11136#0: timer delta: 2
2011/12/05 17:45:03 [debug] 11136#0: posted events 08560C34
2011/12/05 17:45:03 [debug] 11136#0: posted event 08560C34
2011/12/05 17:45:03 [debug] 11136#0: *1 delete posted event 08560C34
2011/12/05 17:45:03 [debug] 11136#0: *1 http upstream request: "/url/84762?"
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres process events
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres trying to connect, status 3
2011/12/05 17:45:03 [debug] 11136#0: *1 event timer del: 18: 257186344
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres connected successfully
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres sending query "SELECT * FROM urls WHERE id=84762"
2011/12/05 17:45:03 [debug] 11136#0: *1 event timer add: 18: 30000:257206350
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres sent query successfully
2011/12/05 17:45:03 [debug] 11136#0: posted event 08553C2C
2011/12/05 17:45:03 [debug] 11136#0: *1 delete posted event 08553C2C
2011/12/05 17:45:03 [debug] 11136#0: *1 http upstream request: "/url/84762?"
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres process events
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres getting result
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres still busy receiving result
2011/12/05 17:45:03 [debug] 11136#0: posted event 00000000
2011/12/05 17:45:03 [debug] 11136#0: worker cycle
2011/12/05 17:45:03 [debug] 11136#0: accept mutex locked
2011/12/05 17:45:03 [debug] 11136#0: epoll timer: 30000
2011/12/05 17:45:03 [debug] 11136#0: epoll: fd:18 ev:0005 d:0853AD74
2011/12/05 17:45:03 [debug] 11136#0: *1 post event 08553C2C
2011/12/05 17:45:03 [debug] 11136#0: *1 post event 08560C34
2011/12/05 17:45:03 [debug] 11136#0: timer delta: 2
2011/12/05 17:45:03 [debug] 11136#0: posted events 08560C34
2011/12/05 17:45:03 [debug] 11136#0: posted event 08560C34
2011/12/05 17:45:03 [debug] 11136#0: *1 delete posted event 08560C34
2011/12/05 17:45:03 [debug] 11136#0: *1 http upstream request: "/url/84762?"
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres process events
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres getting result
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres completed receiving the result
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres received 15 cols and 1 rows
2011/12/05 17:45:03 [debug] 11136#0: *1 event timer del: 18: 257206350
2011/12/05 17:45:03 [debug] 11136#0: *1 finalize http upstream request: 0
2011/12/05 17:45:03 [debug] 11136#0: *1 HTTP/1.1 200 OK
Server: ngx_openresty/1.0.10.23
Date: Mon, 05 Dec 2011 17:45:03 GMT
Content-Type: application/x-resty-dbd-stream
Transfer-Encoding: chunked
Connection: keep-alive
2011/12/05 17:45:03 [debug] 11136#0: *1 write new buf t:1 f:0 0852ED84, pos 0852ED84, size: 187 file: 0, size: 0
2011/12/05 17:45:03 [debug] 11136#0: *1 http write filter: l:0 f:0 s:187
2011/12/05 17:45:03 [debug] 11136#0: *1 http output filter "/url/84762?"
2011/12/05 17:45:03 [debug] 11136#0: *1 http copy filter: "/url/84762?"
2011/12/05 17:45:03 [debug] 11136#0: *1 http postpone filter "/url/84762?" 0852EE80
2011/12/05 17:45:03 [debug] 11136#0: *1 http chunk: 30
2011/12/05 17:45:03 [debug] 11136#0: *1 http chunk: 176
2011/12/05 17:45:03 [debug] 11136#0: *1 http chunk: 293
2011/12/05 17:45:03 [debug] 11136#0: *1 write old buf t:1 f:0 0852ED84, pos 0852ED84, size: 187 file: 0, size: 0
2011/12/05 17:45:03 [debug] 11136#0: *1 write new buf t:1 f:0 0852EEEC, pos 0852EEEC, size: 5 file: 0, size: 0
2011/12/05 17:45:03 [debug] 11136#0: *1 write new buf t:1 f:0 0852EAD0, pos 0852EAD0, size: 30 file: 0, size: 0
2011/12/05 17:45:03 [debug] 11136#0: *1 write new buf t:1 f:0 0852EB2C, pos 0852EB2C, size: 176 file: 0, size: 0
2011/12/05 17:45:03 [debug] 11136#0: *1 write new buf t:1 f:0 0852EC18, pos 0852EC18, size: 293 file: 0, size: 0
2011/12/05 17:45:03 [debug] 11136#0: *1 write new buf t:0 f:0 00000000, pos 0816CBFC, size: 2 file: 0, size: 0
2011/12/05 17:45:03 [debug] 11136#0: *1 http write filter: l:0 f:0 s:693
2011/12/05 17:45:03 [debug] 11136#0: *1 http copy filter: 0 "/url/84762?"
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres: free keepalive peer
2011/12/05 17:45:03 [debug] 11136#0: *1 postgres: free keepalive peer: saving connection 0853AD74
2011/12/05 17:45:03 [debug] 11136#0: *1 http output filter "/url/84762?"
2011/12/05 17:45:03 [debug] 11136#0: *1 http copy filter: "/url/84762?"
2011/12/05 17:45:03 [debug] 11136#0: *1 http postpone filter "/url/84762?" 0852EF98
2011/12/05 17:45:03 [debug] 11136#0: *1 http chunk: 0
2011/12/05 17:45:03 [debug] 11136#0: *1 write old buf t:1 f:0 0852ED84, pos 0852ED84, size: 187 file: 0, size: 0
2011/12/05 17:45:03 [debug] 11136#0: *1 write old buf t:1 f:0 0852EEEC, pos 0852EEEC, size: 5 file: 0, size: 0
2011/12/05 17:45:03 [debug] 11136#0: *1 write old buf t:1 f:0 0852EAD0, pos 0852EAD0, size: 30 file: 0, size: 0
2011/12/05 17:45:03 [debug] 11136#0: *1 write old buf t:1 f:0 0852EB2C, pos 0852EB2C, size: 176 file: 0, size: 0
2011/12/05 17:45:03 [debug] 11136#0: *1 write old buf t:1 f:0 0852EC18, pos 0852EC18, size: 293 file: 0, size: 0
2011/12/05 17:45:03 [debug] 11136#0: *1 write old buf t:0 f:0 00000000, pos 0816CBFC, size: 2 file: 0, size: 0
2011/12/05 17:45:03 [debug] 11136#0: *1 write new buf t:0 f:0 00000000, pos 0816CBF6, size: 5 file: 0, size: 0
2011/12/05 17:45:03 [debug] 11136#0: *1 http write filter: l:1 f:0 s:698
2011/12/05 17:45:03 [debug] 11136#0: *1 http write filter limit 0
2011/12/05 17:45:03 [debug] 11136#0: *1 writev: 698
2011/12/05 17:45:03 [debug] 11136#0: *1 http write filter 00000000
2011/12/05 17:45:03 [debug] 11136#0: *1 http copy filter: 0 "/url/84762?"
2011/12/05 17:45:03 [debug] 11136#0: *1 http finalize request: 0, "/url/84762?" a:1, c:1
2011/12/05 17:45:03 [debug] 11136#0: *1 set http keepalive handler
2011/12/05 17:45:03 [debug] 11136#0: *1 http close request
2011/12/05 17:45:03 [debug] 11136#0: *1 http log handler
2011/12/05 17:45:03 [debug] 11136#0: *1 free: 0852E260, unused: 444
2011/12/05 17:45:03 [debug] 11136#0: *1 event timer add: 17: 75000:257251352
2011/12/05 17:45:03 [debug] 11136#0: *1 free: 0852DBB8
2011/12/05 17:45:03 [debug] 11136#0: *1 free: 0852DE58
2011/12/05 17:45:03 [debug] 11136#0: *1 hc free: 00000000 0
2011/12/05 17:45:03 [debug] 11136#0: *1 hc busy: 00000000 0
2011/12/05 17:45:03 [debug] 11136#0: *1 tcp_nodelay
2011/12/05 17:45:03 [debug] 11136#0: *1 reusable connection: 1
2011/12/05 17:45:03 [debug] 11136#0: *1 post event 08553BF8
2011/12/05 17:45:03 [debug] 11136#0: posted event 08553BF8
2011/12/05 17:45:03 [debug] 11136#0: *1 delete posted event 08553BF8
2011/12/05 17:45:03 [debug] 11136#0: *1 http keepalive handler
2011/12/05 17:45:03 [debug] 11136#0: *1 malloc: 0852F878:1024
2011/12/05 17:45:03 [debug] 11136#0: *1 recv: fd:17 -1 of 1024
2011/12/05 17:45:03 [debug] 11136#0: *1 recv() not ready (11: Resource temporarily unavailable)
2011/12/05 17:45:03 [debug] 11136#0: posted event 08553C2C
2011/12/05 17:45:03 [debug] 11136#0: delete posted event 08553C2C
2011/12/05 17:45:03 [debug] 11136#0: posted event 00000000
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment