--- Revision None +++ Revision 376562313462 @@ -0,0 +1,403 @@ +2010/03/09 20:17:42 [debug] 751#0: post event 000000000078ED30 +2010/03/09 20:17:42 [debug] 751#0: delete posted event 000000000078ED30 +2010/03/09 20:17:42 [debug] 751#0: accept on 0.0.0.0:80, ready: 0 +2010/03/09 20:17:42 [debug] 751#0: posix_memalign: 00000000006C34E0:256 @16 +2010/03/09 20:17:42 [debug] 751#0: *97 accept: 62.147.138.134 fd:8 +2010/03/09 20:17:42 [debug] 751#0: *97 event timer add: 8: 600000:1268162862662 +2010/03/09 20:17:42 [debug] 751#0: *97 epoll add event: fd:8 op:1 ev:80000001 +2010/03/09 20:17:42 [debug] 751#0: *97 post event 000000000078EE00 +2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 000000000078EE00 +2010/03/09 20:17:42 [debug] 751#0: *97 malloc: 00000000006D08F0:1256 +2010/03/09 20:17:42 [debug] 751#0: *97 posix_memalign: 00000000006C37C0:256 @16 +2010/03/09 20:17:42 [debug] 751#0: *97 malloc: 000000000078A910:1024 +2010/03/09 20:17:42 [debug] 751#0: *97 posix_memalign: 00000000006D0DE0:4096 @16 +2010/03/09 20:17:42 [debug] 751#0: *97 http process request line +2010/03/09 20:17:42 [debug] 751#0: *97 recv: fd:8 123 of 1024 +2010/03/09 20:17:42 [debug] 751#0: *97 http request line: "GET /test/ HTTP/1.1" +2010/03/09 20:17:42 [debug] 751#0: *97 http uri: "/test/" +2010/03/09 20:17:42 [debug] 751#0: *97 http args: "" +2010/03/09 20:17:42 [debug] 751#0: *97 http exten: "" +2010/03/09 20:17:42 [debug] 751#0: *97 http process request header line +2010/03/09 20:17:42 [debug] 751#0: *97 http header: "TE: deflate,gzip;q=0.3" +2010/03/09 20:17:42 [debug] 751#0: *97 http header: "Connection: TE, close" +2010/03/09 20:17:42 [debug] 751#0: *97 http header: "Host: i20.etigris.com" +2010/03/09 20:17:42 [debug] 751#0: *97 http header: "User-Agent: lwp-request/2.07" +2010/03/09 20:17:42 [debug] 751#0: *97 http header done +2010/03/09 20:17:42 [debug] 751#0: *97 event timer del: 8: 1268162862662 +2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 1 +2010/03/09 20:17:42 [debug] 751#0: *97 http script var +2010/03/09 20:17:42 [debug] 751#0: *97 http script var: "GET" +2010/03/09 20:17:42 [debug] 751#0: *97 http script regex: "POST" +2010/03/09 20:17:42 [notice] 751#0: *97 "POST" does not match "GET", client: 62.147.138.134, server: localhost, request: "GET /test/ HTTP/1.1", host: "i20.etigris.com" +2010/03/09 20:17:42 [debug] 751#0: *97 http script if +2010/03/09 20:17:42 [debug] 751#0: *97 http script if: false +2010/03/09 20:17:42 [debug] 751#0: *97 test location: "/" +2010/03/09 20:17:42 [debug] 751#0: *97 test location: "region" +2010/03/09 20:17:42 [debug] 751#0: *97 test location: "ssi" +2010/03/09 20:17:42 [debug] 751#0: *97 test location: "ville" +2010/03/09 20:17:42 [debug] 751#0: *97 using configuration "/" +2010/03/09 20:17:42 [debug] 751#0: *97 http cl:-1 max:1048576 +2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 3 +2010/03/09 20:17:42 [debug] 751#0: *97 http script complex value +2010/03/09 20:17:42 [debug] 751#0: *97 http script var: "/test/" +2010/03/09 20:17:42 [debug] 751#0: *97 http script set $memcached_key +2010/03/09 20:17:42 [debug] 751#0: *97 post rewrite phase: 4 +2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 5 +2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 6 +2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 7 +2010/03/09 20:17:42 [debug] 751#0: *97 access phase: 8 +2010/03/09 20:17:42 [debug] 751#0: *97 access phase: 9 +2010/03/09 20:17:42 [debug] 751#0: *97 post access phase: 10 +2010/03/09 20:17:42 [debug] 751#0: *97 http set discard body +2010/03/09 20:17:42 [debug] 751#0: *97 http init upstream, client timer: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 epoll add event: fd:8 op:3 ev:80000005 +2010/03/09 20:17:42 [debug] 751#0: *97 http memcached request: "/test/" +2010/03/09 20:17:42 [debug] 751#0: *97 posix_memalign: 00000000006C7AE0:4096 @16 +2010/03/09 20:17:42 [debug] 751#0: *97 http cleanup add: 00000000006D1DC8 +2010/03/09 20:17:42 [debug] 751#0: *97 get rr peer, try: 1 +2010/03/09 20:17:42 [debug] 751#0: *97 socket 9 +2010/03/09 20:17:42 [debug] 751#0: *97 epoll add connection: fd:9 ev:80000005 +2010/03/09 20:17:42 [debug] 751#0: *97 connect to 127.0.0.1:11211, fd:9 #98 +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream connect: -2 +2010/03/09 20:17:42 [debug] 751#0: *97 event timer add: 9: 1000000:1268163262730 +2010/03/09 20:17:42 [debug] 751#0: *97 http finalize request: -4, "/test/?" a:1, c:2 +2010/03/09 20:17:42 [debug] 751#0: *97 http request count:2 blk:0 +2010/03/09 20:17:42 [debug] 751#0: *97 post event 00000000007A8E10 +2010/03/09 20:17:42 [debug] 751#0: *97 post event 00000000007A8E78 +2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 00000000007A8E78 +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream request: "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream send request handler +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream send request +2010/03/09 20:17:42 [debug] 751#0: *97 chain writer buf fl:0 s:12 +2010/03/09 20:17:42 [debug] 751#0: *97 chain writer in: 00000000006C7B58 +2010/03/09 20:17:42 [debug] 751#0: *97 writev: 12 +2010/03/09 20:17:42 [debug] 751#0: *97 chain writer out: 0000000000000000 +2010/03/09 20:17:42 [debug] 751#0: *97 event timer del: 9: 1268163262730 +2010/03/09 20:17:42 [debug] 751#0: *97 event timer add: 9: 1000000:1268163262730 +2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 00000000007A8E10 +2010/03/09 20:17:42 [debug] 751#0: *97 http run request: "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream check client, write event:1, "/test/" +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream recv(): -1 (11: Resource temporarily unavailable) +2010/03/09 20:17:42 [debug] 751#0: *97 post event 000000000078EE68 +2010/03/09 20:17:42 [debug] 751#0: *97 post event 00000000007A8E78 +2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 00000000007A8E78 +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream request: "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream dummy handler +2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 000000000078EE68 +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream request: "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream process header +2010/03/09 20:17:42 [debug] 751#0: *97 malloc: 00000000006C8AF0:4096 +2010/03/09 20:17:42 [debug] 751#0: *97 recv: fd:9 5 of 4096 +2010/03/09 20:17:42 [debug] 751#0: *97 memcached: "END" +2010/03/09 20:17:42 [info] 751#0: *97 key: "/test/" was not found by memcached while reading response header from upstream, client: 62.147.138.134, server: localhost, request: "GET /test/ HTTP/1.1", upstream: "memcached://127.0.0.1:11211", host: "i20.etigris.com" +2010/03/09 20:17:42 [debug] 751#0: *97 finalize http upstream request: 404 +2010/03/09 20:17:42 [debug] 751#0: *97 finalize http memcached request +2010/03/09 20:17:42 [debug] 751#0: *97 free rr peer 1 0 +2010/03/09 20:17:42 [debug] 751#0: *97 close http upstream connection: 9 +2010/03/09 20:17:42 [debug] 751#0: *97 event timer del: 9: 1268163262730 +2010/03/09 20:17:42 [debug] 751#0: *97 http finalize request: 404, "/test/?" a:1, c:1 +2010/03/09 20:17:42 [debug] 751#0: *97 http special response: 404, "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 test location: "@python" +2010/03/09 20:17:42 [debug] 751#0: *97 using location: @python "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 3 +2010/03/09 20:17:42 [debug] 751#0: *97 post rewrite phase: 4 +2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 5 +2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 6 +2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 7 +2010/03/09 20:17:42 [debug] 751#0: *97 access phase: 8 +2010/03/09 20:17:42 [debug] 751#0: *97 access phase: 9 +2010/03/09 20:17:42 [debug] 751#0: *97 post access phase: 10 +2010/03/09 20:17:42 [debug] 751#0: *97 http init upstream, client timer: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 http script copy: "Host: " +2010/03/09 20:17:42 [debug] 751#0: *97 http script var: "i20.etigris.com" +2010/03/09 20:17:42 [debug] 751#0: *97 http script copy: " +" +2010/03/09 20:17:42 [debug] 751#0: *97 http script copy: "Connection: close +" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "TE: deflate,gzip;q=0.3" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "User-Agent: lwp-request/2.07" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: +"GET /test/ HTTP/1.0 +Host: i20.etigris.com +Connection: close +TE: deflate,gzip;q=0.3 +User-Agent: lwp-request/2.07 + +" +2010/03/09 20:17:42 [debug] 751#0: *97 http cleanup add: 00000000006C8328 +2010/03/09 20:17:42 [debug] 751#0: *97 get rr peer, try: 1 +2010/03/09 20:17:42 [debug] 751#0: *97 socket 9 +2010/03/09 20:17:42 [debug] 751#0: *97 epoll add connection: fd:9 ev:80000005 +2010/03/09 20:17:42 [debug] 751#0: *97 connect to 0.0.0.0:8080, fd:9 #99 +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream connect: -2 +2010/03/09 20:17:42 [debug] 751#0: *97 event timer add: 9: 60000:1268162322730 +2010/03/09 20:17:42 [debug] 751#0: *97 http finalize request: -4, "/test/?" a:1, c:3 +2010/03/09 20:17:42 [debug] 751#0: *97 http request count:3 blk:0 +2010/03/09 20:17:42 [debug] 751#0: *97 http finalize request: -4, "/test/?" a:1, c:2 +2010/03/09 20:17:42 [debug] 751#0: *97 http request count:2 blk:0 +2010/03/09 20:17:42 [debug] 751#0: *97 post event 00000000007A8E78 +2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 00000000007A8E78 +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream request: "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream send request handler +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream send request +2010/03/09 20:17:42 [debug] 751#0: *97 chain writer buf fl:1 s:119 +2010/03/09 20:17:42 [debug] 751#0: *97 chain writer in: 00000000006C8440 +2010/03/09 20:17:42 [debug] 751#0: *97 writev: 119 +2010/03/09 20:17:42 [debug] 751#0: *97 chain writer out: 0000000000000000 +2010/03/09 20:17:42 [debug] 751#0: *97 event timer del: 9: 1268162322730 +2010/03/09 20:17:42 [debug] 751#0: *97 event timer add: 9: 60000:1268162322731 +2010/03/09 20:17:42 [debug] 751#0: *97 post event 000000000078EE68 +2010/03/09 20:17:42 [debug] 751#0: *97 post event 00000000007A8E78 +2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 00000000007A8E78 +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream request: "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream dummy handler +2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 000000000078EE68 +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream request: "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream process header +2010/03/09 20:17:42 [debug] 751#0: *97 malloc: 00000000006C9B00:4096 +2010/03/09 20:17:42 [debug] 751#0: *97 recv: fd:9 221 of 4096 +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy status 200 "200 OK" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Server: gunicorn/0.6.2" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Date: Tue, 09 Mar 2010 19:17:42 GMT" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Status: 200 OK" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Connection: close" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Content-Length: 46" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Content-Type: text/html; charset=UTF-8" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header done +2010/03/09 20:17:42 [debug] 751#0: *97 xslt filter header +2010/03/09 20:17:42 [debug] 751#0: *97 HTTP/1.1 200 OK +Server: nginx/0.8.34 +Date: Tue, 09 Mar 2010 19:17:42 GMT +Content-Type: text/html; charset=UTF-8 +Transfer-Encoding: chunked +Connection: close +Status: 200 OK + +2010/03/09 20:17:42 [debug] 751#0: *97 write new buf t:1 f:0 00000000006C88C8, pos 00000000006C88C8, size: 181 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 http write filter: l:0 f:0 s:181 +2010/03/09 20:17:42 [debug] 751#0: *97 http cacheable: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream process upstream +2010/03/09 20:17:42 [debug] 751#0: *97 pipe read upstream: 1 +2010/03/09 20:17:42 [debug] 751#0: *97 pipe preread: 46 +2010/03/09 20:17:42 [debug] 751#0: *97 readv: 1:3875 +2010/03/09 20:17:42 [debug] 751#0: *97 pipe recv chain: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 pipe buf free s:0 t:1 f:0 00000000006C9B00, pos 00000000006C9BAF, size: 46 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 posix_memalign: 00000000006CAB10:4096 @16 +2010/03/09 20:17:42 [debug] 751#0: *97 input buf #0 +2010/03/09 20:17:42 [debug] 751#0: *97 pipe write downstream: 1 +2010/03/09 20:17:42 [debug] 751#0: *97 pipe write downstream flush in +2010/03/09 20:17:42 [debug] 751#0: *97 http output filter "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 xslt filter body +2010/03/09 20:17:42 [debug] 751#0: *97 http ssi filter "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 saved: 0 state: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 parse: 0, looked: 0 00000000006C9BAF-00000000006C9BB5 +2010/03/09 20:17:42 [debug] 751#0: *97 saved: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 ssi flush +2010/03/09 20:17:42 [debug] 751#0: *97 ssi out: 00000000006CAD08 00000000006C9BAF +2010/03/09 20:17:42 [debug] 751#0: *97 http postpone filter "/test/?" 00000000006CAD58 +2010/03/09 20:17:42 [debug] 751#0: *97 http chunk: 6 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:1 f:0 00000000006C88C8, pos 00000000006C88C8, size: 181 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write new buf t:1 f:0 0000000000000000, pos 00000000006CADC8, size: 3 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write new buf t:1 f:0 00000000006C9B00, pos 00000000006C9BAF, size: 6 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write new buf t:0 f:0 0000000000000000, pos 000000000047F56D, size: 2 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 http write filter: l:0 f:0 s:192 +2010/03/09 20:17:42 [debug] 751#0: *97 ssi include: "/ssi/" +2010/03/09 20:17:42 [debug] 751#0: *97 http subrequest "/ssi/?" +2010/03/09 20:17:42 [debug] 751#0: *97 saved: 0 state: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 parse: -2, looked: 0 00000000006C9BD6-00000000006C9BDD +2010/03/09 20:17:42 [debug] 751#0: *97 saved: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 ssi out: 00000000006CB868 00000000006C9BD6 +2010/03/09 20:17:42 [debug] 751#0: *97 http postpone filter "/test/?" 00000000006CB8B8 +2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: 0 "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 pipe write downstream done +2010/03/09 20:17:42 [debug] 751#0: *97 event timer: 9, old: 1268162322731, new: 1268162322772 +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream exit: 0000000000000000 +2010/03/09 20:17:42 [debug] 751#0: *97 finalize http upstream request: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 finalize http proxy request +2010/03/09 20:17:42 [debug] 751#0: *97 free rr peer 1 0 +2010/03/09 20:17:42 [debug] 751#0: *97 close http upstream connection: 9 +2010/03/09 20:17:42 [debug] 751#0: *97 event timer del: 9: 1268162322731 +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream temp fd: -1 +2010/03/09 20:17:42 [debug] 751#0: *97 http output filter "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 xslt filter body +2010/03/09 20:17:42 [debug] 751#0: *97 http ssi filter "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 ssi out: 00000000006CB950 0000000000000000 +2010/03/09 20:17:42 [debug] 751#0: *97 http postpone filter "/test/?" 00000000006CB9A0 +2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: 0 "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http finalize request: 0, "/test/?" a:0, c:2 +2010/03/09 20:17:42 [debug] 751#0: *97 event timer add: 8: 600000:1268162862772 +2010/03/09 20:17:42 [debug] 751#0: *97 http posted request: "/ssi/?" +2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 1 +2010/03/09 20:17:42 [debug] 751#0: *97 http script var +2010/03/09 20:17:42 [debug] 751#0: *97 http script var: "GET" +2010/03/09 20:17:42 [debug] 751#0: *97 http script regex: "POST" +2010/03/09 20:17:42 [notice] 751#0: *97 "POST" does not match "GET", client: 62.147.138.134, server: localhost, request: "GET /test/ HTTP/1.1", subrequest: "/ssi/", host: "i20.etigris.com" +2010/03/09 20:17:42 [debug] 751#0: *97 http script if +2010/03/09 20:17:42 [debug] 751#0: *97 http script if: false +2010/03/09 20:17:42 [debug] 751#0: *97 test location: "/" +2010/03/09 20:17:42 [debug] 751#0: *97 test location: "region" +2010/03/09 20:17:42 [debug] 751#0: *97 test location: "ssi" +2010/03/09 20:17:42 [debug] 751#0: *97 using configuration "/ssi" +2010/03/09 20:17:42 [debug] 751#0: *97 http cl:-1 max:1048576 +2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 3 +2010/03/09 20:17:42 [debug] 751#0: *97 post rewrite phase: 4 +2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 5 +2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 6 +2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 7 +2010/03/09 20:17:42 [debug] 751#0: *97 posix_memalign: 00000000007C2D50:4096 @16 +2010/03/09 20:17:42 [debug] 751#0: *97 http init upstream, client timer: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 http script copy: "Host: " +2010/03/09 20:17:42 [debug] 751#0: *97 http script var: "i20.etigris.com" +2010/03/09 20:17:42 [debug] 751#0: *97 http script copy: " +" +2010/03/09 20:17:42 [debug] 751#0: *97 http script copy: "Connection: close +" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "TE: deflate,gzip;q=0.3" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "User-Agent: lwp-request/2.07" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: +"GET /ssi/ HTTP/1.0 +Host: i20.etigris.com +Connection: close +TE: deflate,gzip;q=0.3 +User-Agent: lwp-request/2.07 + +" +2010/03/09 20:17:42 [debug] 751#0: *97 http cleanup add: 00000000007C3310 +2010/03/09 20:17:42 [debug] 751#0: *97 get rr peer, try: 1 +2010/03/09 20:17:42 [debug] 751#0: *97 socket 9 +2010/03/09 20:17:42 [debug] 751#0: *97 epoll add connection: fd:9 ev:80000005 +2010/03/09 20:17:42 [debug] 751#0: *97 connect to 0.0.0.0:8080, fd:9 #100 +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream connect: -2 +2010/03/09 20:17:42 [debug] 751#0: *97 event timer add: 9: 60000:1268162322772 +2010/03/09 20:17:42 [debug] 751#0: *97 http finalize request: -4, "/ssi/?" a:1, c:3 +2010/03/09 20:17:42 [debug] 751#0: *97 http request count:3 blk:0 +2010/03/09 20:17:42 [debug] 751#0: *97 post event 00000000007A8E78 +2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 00000000007A8E78 +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream request: "/ssi/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream send request handler +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream send request +2010/03/09 20:17:42 [debug] 751#0: *97 chain writer buf fl:1 s:118 +2010/03/09 20:17:42 [debug] 751#0: *97 chain writer in: 00000000007C3348 +2010/03/09 20:17:42 [debug] 751#0: *97 writev: 118 +2010/03/09 20:17:42 [debug] 751#0: *97 chain writer out: 0000000000000000 +2010/03/09 20:17:42 [debug] 751#0: *97 event timer del: 9: 1268162322772 +2010/03/09 20:17:42 [debug] 751#0: *97 event timer add: 9: 60000:1268162322773 +2010/03/09 20:17:42 [debug] 751#0: *97 post event 000000000078EE68 +2010/03/09 20:17:42 [debug] 751#0: *97 post event 00000000007A8E78 +2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 00000000007A8E78 +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream request: "/ssi/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream dummy handler +2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 000000000078EE68 +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream request: "/ssi/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream process header +2010/03/09 20:17:42 [debug] 751#0: *97 malloc: 00000000007C3D60:4096 +2010/03/09 20:17:42 [debug] 751#0: *97 recv: fd:9 177 of 4096 +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy status 200 "200 OK" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Server: gunicorn/0.6.2" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Date: Tue, 09 Mar 2010 19:17:42 GMT" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Status: 200 OK" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Connection: close" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Content-Length: 3" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Content-Type: text/html; charset=UTF-8" +2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header done +2010/03/09 20:17:42 [debug] 751#0: *97 xslt filter header +2010/03/09 20:17:42 [debug] 751#0: *97 charset: "UTF-8" > "UTF-8" +2010/03/09 20:17:42 [debug] 751#0: *97 http cacheable: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream process upstream +2010/03/09 20:17:42 [debug] 751#0: *97 pipe read upstream: 1 +2010/03/09 20:17:42 [debug] 751#0: *97 pipe preread: 3 +2010/03/09 20:17:42 [debug] 751#0: *97 readv: 1:3919 +2010/03/09 20:17:42 [debug] 751#0: *97 pipe recv chain: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 pipe buf free s:0 t:1 f:0 00000000007C3D60, pos 00000000007C3E0E, size: 3 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 input buf #0 +2010/03/09 20:17:42 [debug] 751#0: *97 pipe write downstream: 1 +2010/03/09 20:17:42 [debug] 751#0: *97 pipe write downstream flush in +2010/03/09 20:17:42 [debug] 751#0: *97 http output filter "/ssi/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: "/ssi/?" +2010/03/09 20:17:42 [debug] 751#0: *97 xslt filter body +2010/03/09 20:17:42 [debug] 751#0: *97 http postpone filter "/ssi/?" 00000000007C3750 +2010/03/09 20:17:42 [debug] 751#0: *97 http chunk: 3 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:1 f:0 00000000006C88C8, pos 00000000006C88C8, size: 181 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:1 f:0 0000000000000000, pos 00000000006CADC8, size: 3 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:1 f:0 00000000006C9B00, pos 00000000006C9BAF, size: 6 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:0 f:0 0000000000000000, pos 000000000047F56D, size: 2 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write new buf t:1 f:0 0000000000000000, pos 00000000007C3828, size: 3 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write new buf t:1 f:0 00000000007C3D60, pos 00000000007C3E0E, size: 3 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write new buf t:0 f:0 0000000000000000, pos 000000000047F56D, size: 2 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 http write filter: l:0 f:0 s:200 +2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: 0 "/ssi/?" +2010/03/09 20:17:42 [debug] 751#0: *97 pipe write downstream done +2010/03/09 20:17:42 [debug] 751#0: *97 event timer: 9, old: 1268162322773, new: 1268162322773 +2010/03/09 20:17:42 [debug] 751#0: *97 event timer del: 8: 1268162862772 +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream exit: 0000000000000000 +2010/03/09 20:17:42 [debug] 751#0: *97 finalize http upstream request: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 finalize http proxy request +2010/03/09 20:17:42 [debug] 751#0: *97 free rr peer 1 0 +2010/03/09 20:17:42 [debug] 751#0: *97 close http upstream connection: 9 +2010/03/09 20:17:42 [debug] 751#0: *97 event timer del: 9: 1268162322773 +2010/03/09 20:17:42 [debug] 751#0: *97 http upstream temp fd: -1 +2010/03/09 20:17:42 [debug] 751#0: *97 http output filter "/ssi/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: "/ssi/?" +2010/03/09 20:17:42 [debug] 751#0: *97 xslt filter body +2010/03/09 20:17:42 [debug] 751#0: *97 http postpone filter "/ssi/?" 00007FFF2491F020 +2010/03/09 20:17:42 [debug] 751#0: *97 http chunk: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:1 f:0 00000000006C88C8, pos 00000000006C88C8, size: 181 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:1 f:0 0000000000000000, pos 00000000006CADC8, size: 3 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:1 f:0 00000000006C9B00, pos 00000000006C9BAF, size: 6 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:0 f:0 0000000000000000, pos 000000000047F56D, size: 2 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:1 f:0 0000000000000000, pos 00000000007C3828, size: 3 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:1 f:0 00000000007C3D60, pos 00000000007C3E0E, size: 3 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:0 f:0 0000000000000000, pos 000000000047F56D, size: 2 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 http write filter: l:0 f:0 s:200 +2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: 0 "/ssi/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http finalize request: 0, "/ssi/?" a:1, c:2 +2010/03/09 20:17:42 [debug] 751#0: *97 http wake parent request: "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http posted request: "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http writer handler: "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http output filter "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 xslt filter body +2010/03/09 20:17:42 [debug] 751#0: *97 http ssi filter "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http postpone filter "/test/?" 0000000000000000 +2010/03/09 20:17:42 [debug] 751#0: *97 http postpone filter output "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http chunk: 7 +2010/03/09 20:17:42 [debug] 751#0: *97 http chunk: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:1 f:0 00000000006C88C8, pos 00000000006C88C8, size: 181 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:1 f:0 0000000000000000, pos 00000000006CADC8, size: 3 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:1 f:0 00000000006C9B00, pos 00000000006C9BAF, size: 6 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:0 f:0 0000000000000000, pos 000000000047F56D, size: 2 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:1 f:0 0000000000000000, pos 00000000007C3828, size: 3 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:1 f:0 00000000007C3D60, pos 00000000007C3E0E, size: 3 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:0 f:0 0000000000000000, pos 000000000047F56D, size: 2 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write old buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write new buf t:1 f:0 0000000000000000, pos 00000000007C39B0, size: 3 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write new buf t:1 f:0 00000000006C9B00, pos 00000000006C9BD6, size: 7 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 write new buf t:0 f:0 0000000000000000, pos 000000000047F568, size: 7 file: 0, size: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 http write filter: l:1 f:0 s:217 +2010/03/09 20:17:42 [debug] 751#0: *97 http write filter limit 0 +2010/03/09 20:17:42 [debug] 751#0: *97 writev: 217 +2010/03/09 20:17:42 [debug] 751#0: *97 http write filter 0000000000000000 +2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: 0 "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http writer output filter: 0, "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http writer done: "/test/?" +2010/03/09 20:17:42 [debug] 751#0: *97 http finalize request: 0, "/test/?" a:1, c:1 +2010/03/09 20:17:42 [debug] 751#0: *97 http request count:1 blk:0 +2010/03/09 20:17:42 [debug] 751#0: *97 http close request +2010/03/09 20:17:42 [debug] 751#0: *97 http log handler +2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000007C3D60 +2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000006C9B00 +2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000006C8AF0 +2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000006D0DE0, unused: 0 +2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000006C7AE0, unused: 2 +2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000006CAB10, unused: 2 +2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000007C2D50, unused: 642 +2010/03/09 20:17:42 [debug] 751#0: *97 close http connection: 8 +2010/03/09 20:17:42 [debug] 751#0: *97 free: 000000000078A910 +2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000006D08F0 +2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000006C34E0, unused: 8 +2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000006C37C0, unused: 128