Skip to content

Instantly share code, notes, and snippets.

@kadashu
Created September 28, 2012 10:18
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 kadashu/3799021 to your computer and use it in GitHub Desktop.
Save kadashu/3799021 to your computer and use it in GitHub Desktop.
nginx debug log of 404
2012/09/28 16:29:49 [debug] 26416#0: *1 event timer del: 13: 1348821049654
2012/09/28 16:29:49 [debug] 26416#0: *1 generic phase: 0
2012/09/28 16:29:49 [debug] 26416#0: *1 rewrite phase: 1
2012/09/28 16:29:49 [debug] 26416#0: *1 test location: "/"
2012/09/28 16:29:49 [debug] 26416#0: *1 test location: "files/"
2012/09/28 16:29:49 [debug] 26416#0: *1 using configuration "/"
2012/09/28 16:29:49 [debug] 26416#0: *1 http cl:-1 max:1048576
2012/09/28 16:29:49 [debug] 26416#0: *1 rewrite phase: 3
2012/09/28 16:29:49 [debug] 26416#0: *1 http script var
2012/09/28 16:29:49 [debug] 26416#0: *1 http script var: "Android/1.0"
2012/09/28 16:29:49 [debug] 26416#0: *1 http script regex: "Android"
2012/09/28 16:29:49 [notice] 26416#0: *1 "Android" matches "Android/1.0", client: 106.187.97.22, server: ireedr.com, request: "POST /login HTTP/1.1", host: "ireedr.com"
2012/09/28 16:29:49 [debug] 26416#0: *1 http script if
2012/09/28 16:29:49 [debug] 26416#0: *1 post rewrite phase: 4
2012/09/28 16:29:49 [debug] 26416#0: *1 generic phase: 5
2012/09/28 16:29:49 [debug] 26416#0: *1 generic phase: 6
2012/09/28 16:29:49 [debug] 26416#0: *1 generic phase: 7
2012/09/28 16:29:49 [debug] 26416#0: *1 access phase: 8
2012/09/28 16:29:49 [debug] 26416#0: *1 access phase: 9
2012/09/28 16:29:49 [debug] 26416#0: *1 access phase: 10
2012/09/28 16:29:49 [debug] 26416#0: *1 post access phase: 11
2012/09/28 16:29:49 [debug] 26416#0: *1 try files phase: 12
2012/09/28 16:29:49 [debug] 26416#0: *1 posix_memalign: 0000000001E798F0:4096 @16
2012/09/28 16:29:49 [debug] 26416#0: *1 http init upstream, client timer: 0
2012/09/28 16:29:49 [debug] 26416#0: *1 epoll add event: fd:13 op:3 ev:80000005
2012/09/28 16:29:49 [debug] 26416#0: *1 http script copy: "Host: "
2012/09/28 16:29:49 [debug] 26416#0: *1 http script var: "ireedr.com"
2012/09/28 16:29:49 [debug] 26416#0: *1 http script copy: "
"
2012/09/28 16:29:49 [debug] 26416#0: *1 http script copy: ""
2012/09/28 16:29:49 [debug] 26416#0: *1 http script copy: ""
2012/09/28 16:29:49 [debug] 26416#0: *1 http script copy: "X-Real-IP: "
2012/09/28 16:29:49 [debug] 26416#0: *1 http script var: "106.187.97.22"
2012/09/28 16:29:49 [debug] 26416#0: *1 http script copy: "
"
2012/09/28 16:29:49 [debug] 26416#0: *1 http script copy: "Connection: close
"
2012/09/28 16:29:49 [debug] 26416#0: *1 http proxy header: "Accept-Encoding: identity, deflate, compress, gzip"
2012/09/28 16:29:49 [debug] 26416#0: *1 http proxy header: "Accept: */*"
2012/09/28 16:29:49 [debug] 26416#0: *1 http proxy header: "User-Agent: Android/1.0"
2012/09/28 16:29:49 [debug] 26416#0: *1 http proxy header:
"POST //login HTTP/1.0
Host: ireedr.com
X-Real-IP: 106.187.97.22
Connection: close
Accept-Encoding: identity, deflate, compress, gzip
Accept: */*
User-Agent: Android/1.0
"
2012/09/28 16:29:49 [debug] 26416#0: *1 http cleanup add: 0000000001E84688
2012/09/28 16:29:49 [debug] 26416#0: *1 get rr peer, try: 1
2012/09/28 16:29:49 [debug] 26416#0: *1 socket 14
2012/09/28 16:29:49 [debug] 26416#0: *1 epoll add connection: fd:14 ev:80000005
2012/09/28 16:29:49 [debug] 26416#0: *1 connect to 127.0.0.1:8785, fd:14 #2
2012/09/28 16:29:49 [debug] 26416#0: *1 http upstream connect: -2
2012/09/28 16:29:49 [debug] 26416#0: *1 posix_memalign: 0000000001E846B0:128 @16
2012/09/28 16:29:49 [debug] 26416#0: *1 event timer add: 14: 60000:1348821049654
2012/09/28 16:29:49 [debug] 26416#0: *1 http finalize request: -4, "/login?" a:1, c:2
2012/09/28 16:29:49 [debug] 26416#0: *1 http request count:2 blk:0
2012/09/28 16:29:49 [debug] 26416#0: *1 post event 0000000001EDF7B0
2012/09/28 16:29:49 [debug] 26416#0: *1 post event 0000000001EDF818
2012/09/28 16:29:49 [debug] 26416#0: *1 delete posted event 0000000001EDF818
2012/09/28 16:29:49 [debug] 26416#0: *1 http upstream request: "/login?"
2012/09/28 16:29:49 [debug] 26416#0: *1 http upstream send request handler
2012/09/28 16:29:49 [debug] 26416#0: *1 http upstream send request
2012/09/28 16:29:49 [debug] 26416#0: *1 chain writer buf fl:1 s:178
2012/09/28 16:29:49 [debug] 26416#0: *1 chain writer in: 0000000001E79B30
2012/09/28 16:29:49 [debug] 26416#0: *1 writev: 178
2012/09/28 16:29:49 [debug] 26416#0: *1 chain writer out: 0000000000000000
2012/09/28 16:29:49 [debug] 26416#0: *1 event timer del: 14: 1348821049654
2012/09/28 16:29:49 [debug] 26416#0: *1 event timer add: 14: 60000:1348821049655
2012/09/28 16:29:49 [debug] 26416#0: *1 delete posted event 0000000001EDF7B0
2012/09/28 16:29:49 [debug] 26416#0: *1 http run request: "/login?"
2012/09/28 16:29:49 [debug] 26416#0: *1 http upstream check client, write event:1, "/login"
2012/09/28 16:29:49 [debug] 26416#0: *1 http upstream recv(): -1 (11: Resource temporarily unavailable)
2012/09/28 16:29:49 [debug] 26416#0: *1 post event 0000000001ECC008
2012/09/28 16:29:49 [debug] 26416#0: *1 post event 0000000001EDF818
2012/09/28 16:29:49 [debug] 26416#0: *1 delete posted event 0000000001EDF818
2012/09/28 16:29:49 [debug] 26416#0: *1 http upstream request: "/login?"
2012/09/28 16:29:49 [debug] 26416#0: *1 http upstream dummy handler
2012/09/28 16:29:49 [debug] 26416#0: *1 delete posted event 0000000001ECC008
2012/09/28 16:29:49 [debug] 26416#0: *1 http upstream request: "/login?"
2012/09/28 16:29:49 [debug] 26416#0: *1 http upstream process header
2012/09/28 16:29:49 [debug] 26416#0: *1 malloc: 0000000001E7A900:4096
2012/09/28 16:29:49 [debug] 26416#0: *1 recv: fd:14 1483 of 4096
2012/09/28 16:29:49 [debug] 26416#0: *1 http proxy status 404 "404 Not Found"
2012/09/28 16:29:49 [debug] 26416#0: *1 http proxy header: "Date: Fri, 28 Sep 2012 08:29:49 GMT"
2012/09/28 16:29:49 [debug] 26416#0: *1 http proxy header: "Cache-Control: must-revalidate,no-cache,no-store"
2012/09/28 16:29:49 [debug] 26416#0: *1 http proxy header: "Content-Type: text/html;charset=ISO-8859-1"
2012/09/28 16:29:49 [debug] 26416#0: *1 http proxy header: "Content-Length: 1273"
2012/09/28 16:29:49 [debug] 26416#0: *1 http proxy header: "Server: Jetty(7.x.y-SNAPSHOT)"
2012/09/28 16:29:49 [debug] 26416#0: *1 http proxy header done
2012/09/28 16:29:49 [debug] 26416#0: *1 xslt filter header
2012/09/28 16:29:49 [debug] 26416#0: *1 HTTP/1.1 404 Not Found
Server: nginx/1.2.1
Date: Fri, 28 Sep 2012 08:29:49 GMT
Content-Type: text/html;charset=ISO-8859-1
Transfer-Encoding: chunked
Connection: keep-alive
Cache-Control: must-revalidate,no-cache,no-store
Content-Encoding: gzip
2012/09/28 16:29:49 [debug] 26416#0: *1 write new buf t:1 f:0 0000000001E79F60, pos 0000000001E79F60, size: 254 file: 0, size: 0
2012/09/28 16:29:49 [debug] 26416#0: *1 http write filter: l:0 f:0 s:254
2012/09/28 16:29:49 [debug] 26416#0: *1 http cacheable: 0
2012/09/28 16:29:49 [debug] 26416#0: *1 http proxy filter init s:404 h:0 c:0 l:1273
2012/09/28 16:29:49 [debug] 26416#0: *1 http upstream process upstream
2012/09/28 16:29:49 [debug] 26416#0: *1 pipe read upstream: 1
2012/09/28 16:29:49 [debug] 26416#0: *1 pipe preread: 1273
2012/09/28 16:29:49 [debug] 26416#0: *1 readv: 1:2613
2012/09/28 16:29:49 [debug] 26416#0: *1 pipe recv chain: 0
2012/09/28 16:29:49 [debug] 26416#0: *1 pipe buf free s:0 t:1 f:0 0000000001E7A900, pos 0000000001E7A9D2, size: 1273 file: 0, size: 0
2012/09/28 16:29:49 [debug] 26416#0: *1 pipe length: 1273
2012/09/28 16:29:49 [debug] 26416#0: *1 input buf #0
2012/09/28 16:29:49 [debug] 26416#0: *1 pipe write downstream: 1
2012/09/28 16:29:49 [debug] 26416#0: *1 pipe write downstream flush in
2012/09/28 16:29:49 [debug] 26416#0: *1 http output filter "/login?"
2012/09/28 16:29:49 [debug] 26416#0: *1 http copy filter: "/login?"
2012/09/28 16:29:49 [debug] 26416#0: *1 image filter
2012/09/28 16:29:49 [debug] 26416#0: *1 xslt filter body
2012/09/28 16:29:49 [debug] 26416#0: *1 http postpone filter "/login?" 0000000001E7A1C8
2012/09/28 16:29:49 [debug] 26416#0: *1 http gzip filter
2012/09/28 16:29:49 [debug] 26416#0: *1 malloc: 0000000001EF2EF0:24576
2012/09/28 16:29:49 [debug] 26416#0: *1 gzip alloc: n:1 s:5936 a:8192 p:0000000001EF2EF0
2012/09/28 16:29:49 [debug] 26416#0: *1 gzip alloc: n:2048 s:2 a:4096 p:0000000001EF4EF0
2012/09/28 16:29:49 [debug] 26416#0: *1 gzip alloc: n:2048 s:2 a:4096 p:0000000001EF5EF0
2012/09/28 16:29:49 [debug] 26416#0: *1 gzip alloc: n:2048 s:2 a:4096 p:0000000001EF6EF0
2012/09/28 16:29:49 [debug] 26416#0: *1 gzip alloc: n:1024 s:4 a:4096 p:0000000001EF7EF0
2012/09/28 16:29:49 [debug] 26416#0: *1 gzip in: 0000000001E7A168
2012/09/28 16:29:49 [debug] 26416#0: *1 gzip in_buf:0000000001E7A178 ni:0000000001E7A9D2 ai:1273
2012/09/28 16:29:49 [debug] 26416#0: *1 malloc: 0000000001E7B910:4096
2012/09/28 16:29:49 [debug] 26416#0: *1 deflate in: ni:0000000001E7A9D2 no:0000000001E7B910 ai:1273 ao:4096 fl:0 redo:0
2012/09/28 16:29:49 [debug] 26416#0: *1 deflate out: ni:0000000001E7AECB no:0000000001E7B910 ai:0 ao:4096 rc:0
2012/09/28 16:29:49 [debug] 26416#0: *1 gzip in_buf:0000000001E7A178 pos:0000000001E7A9D2
2012/09/28 16:29:49 [debug] 26416#0: *1 gzip in: 0000000000000000
2012/09/28 16:29:49 [debug] 26416#0: *1 http copy filter: 0 "/login?"
2012/09/28 16:29:49 [debug] 26416#0: *1 pipe write downstream done
2012/09/28 16:29:49 [debug] 26416#0: *1 event timer: 14, old: 1348821049655, new: 1348821049657
2012/09/28 16:29:49 [debug] 26416#0: *1 http upstream exit: 0000000000000000
2012/09/28 16:29:49 [debug] 26416#0: *1 finalize http upstream request: 0
2012/09/28 16:29:49 [debug] 26416#0: *1 finalize http proxy request
2012/09/28 16:29:49 [debug] 26416#0: *1 free rr peer 1 0
2012/09/28 16:29:49 [debug] 26416#0: *1 close http upstream connection: 14
2012/09/28 16:29:49 [debug] 26416#0: *1 free: 0000000001E846B0, unused: 48
2012/09/28 16:29:49 [debug] 26416#0: *1 event timer del: 14: 1348821049655
2012/09/28 16:29:49 [debug] 26416#0: *1 reusable connection: 0
2012/09/28 16:29:49 [debug] 26416#0: *1 http upstream temp fd: -1
2012/09/28 16:29:49 [debug] 26416#0: *1 http output filter "/login?"
2012/09/28 16:29:49 [debug] 26416#0: *1 http copy filter: "/login?"
2012/09/28 16:29:49 [debug] 26416#0: *1 image filter
2012/09/28 16:29:49 [debug] 26416#0: *1 xslt filter body
2012/09/28 16:29:49 [debug] 26416#0: *1 http postpone filter "/login?" 00007FFF0E1305F0
2012/09/28 16:29:49 [debug] 26416#0: *1 http gzip filter
2012/09/28 16:29:49 [debug] 26416#0: *1 gzip in: 0000000001E7A300
2012/09/28 16:29:49 [debug] 26416#0: *1 gzip in_buf:0000000001E7A2B0 ni:0000000000000000 ai:0
2012/09/28 16:29:49 [debug] 26416#0: *1 deflate in: ni:0000000000000000 no:0000000001E7B910 ai:0 ao:4096 fl:4 redo:0
2012/09/28 16:29:49 [debug] 26416#0: *1 deflate out: ni:0000000000000000 no:0000000001E7BA02 ai:0 ao:3854 rc:1
2012/09/28 16:29:49 [debug] 26416#0: *1 gzip in_buf:0000000001E7A2B0 pos:0000000000000000
2012/09/28 16:29:49 [debug] 26416#0: *1 free: 0000000001EF2EF0
2012/09/28 16:29:49 [debug] 26416#0: *1 http chunk: 10
2012/09/28 16:29:49 [debug] 26416#0: *1 http chunk: 250
2012/09/28 16:29:49 [debug] 26416#0: *1 write old buf t:1 f:0 0000000001E79F60, pos 0000000001E79F60, size: 254 file: 0, size: 0
2012/09/28 16:29:49 [debug] 26416#0: *1 write new buf t:1 f:0 0000000001E7A400, pos 0000000001E7A400, size: 5 file: 0, size: 0
2012/09/28 16:29:49 [debug] 26416#0: *1 write new buf t:0 f:0 0000000000000000, pos 00000000006C24C8, size: 10 file: 0, size: 0
2012/09/28 16:29:49 [debug] 26416#0: *1 write new buf t:1 f:0 0000000001E7B910, pos 0000000001E7B910, size: 250 file: 0, size: 0
2012/09/28 16:29:49 [debug] 26416#0: *1 write new buf t:0 f:0 0000000000000000, pos 000000000049D6B8, size: 7 file: 0, size: 0
2012/09/28 16:29:49 [debug] 26416#0: *1 http write filter: l:1 f:1 s:526
2012/09/28 16:29:49 [debug] 26416#0: *1 http write filter limit 0
2012/09/28 16:29:49 [debug] 26416#0: *1 writev: 526
2012/09/28 16:29:49 [debug] 26416#0: *1 http write filter 0000000000000000
2012/09/28 16:29:49 [debug] 26416#0: *1 http copy filter: 0 "/login?"
2012/09/28 16:29:49 [debug] 26416#0: *1 http finalize request: 0, "/login?" a:1, c:1
2012/09/28 16:29:49 [debug] 26416#0: *1 set http keepalive handler
2012/09/28 16:29:49 [debug] 26416#0: *1 http close request
2012/09/28 16:29:49 [debug] 26416#0: *1 http log handler
2012/09/28 16:29:49 [debug] 26416#0: *1 free: 0000000001E7B910
2012/09/28 16:29:49 [debug] 26416#0: *1 free: 0000000000000000
2012/09/28 16:29:49 [debug] 26416#0: *1 free: 0000000001E7A900
2012/09/28 16:29:49 [debug] 26416#0: *1 free: 0000000001E836A0, unused: 0
2012/09/28 16:29:49 [debug] 26416#0: *1 free: 0000000001E798F0, unused: 967
2012/09/28 16:29:49 [debug] 26416#0: *1 event timer add: 13: 65000:1348821054657
2012/09/28 16:29:49 [debug] 26416#0: *1 free: 0000000001E82D70
2012/09/28 16:29:49 [debug] 26416#0: *1 free: 0000000001E83290
2012/09/28 16:29:49 [debug] 26416#0: *1 hc free: 0000000000000000 0
2012/09/28 16:29:49 [debug] 26416#0: *1 hc busy: 0000000000000000 0
2012/09/28 16:29:49 [debug] 26416#0: *1 tcp_nodelay
2012/09/28 16:29:49 [debug] 26416#0: *1 reusable connection: 1
2012/09/28 16:29:49 [debug] 26416#0: *1 post event 0000000001ECBFA0
2012/09/28 16:29:49 [debug] 26416#0: *1 delete posted event 0000000001ECBFA0
2012/09/28 16:29:49 [debug] 26416#0: *1 http keepalive handler
2012/09/28 16:29:49 [debug] 26416#0: *1 malloc: 0000000001E82D70:1024
2012/09/28 16:29:49 [debug] 26416#0: *1 recv: fd:13 -1 of 1024
2012/09/28 16:29:49 [debug] 26416#0: *1 recv() not ready (11: Resource temporarily unavailable)
2012/09/28 16:29:49 [debug] 26416#0: *1 post event 0000000001ECBFA0
2012/09/28 16:29:49 [debug] 26416#0: *1 post event 0000000001EDF7B0
2012/09/28 16:29:49 [debug] 26416#0: *1 delete posted event 0000000001EDF7B0
2012/09/28 16:29:49 [debug] 26416#0: *1 http empty handler
2012/09/28 16:29:49 [debug] 26416#0: *1 delete posted event 0000000001ECBFA0
2012/09/28 16:29:49 [debug] 26416#0: *1 http keepalive handler
2012/09/28 16:29:49 [debug] 26416#0: *1 recv: fd:13 0 of 1024
2012/09/28 16:29:49 [info] 26416#0: *1 client 106.187.97.22 closed keepalive connection
2012/09/28 16:29:49 [debug] 26416#0: *1 close http connection: 13
2012/09/28 16:29:49 [debug] 26416#0: *1 event timer del: 13: 1348821054657
2012/09/28 16:29:49 [debug] 26416#0: *1 reusable connection: 0
2012/09/28 16:29:49 [debug] 26416#0: *1 free: 0000000001E82D70
2012/09/28 16:29:49 [debug] 26416#0: *1 free: 0000000000000000
2012/09/28 16:29:49 [debug] 26416#0: *1 free: 0000000001E74E40, unused: 8
2012/09/28 16:29:49 [debug] 26416#0: *1 free: 0000000001EA2310, unused: 128
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment