71X0cQMkW4qiS5NCuaDnkv changeset

Changeset376562313462 (b)
ParentNone (a)
ab
0+2010/03/09 20:17:42 [debug] 751#0: post event 000000000078ED30
0+2010/03/09 20:17:42 [debug] 751#0: delete posted event 000000000078ED30
0+2010/03/09 20:17:42 [debug] 751#0: accept on 0.0.0.0:80, ready: 0
0+2010/03/09 20:17:42 [debug] 751#0: posix_memalign: 00000000006C34E0:256 @16
0+2010/03/09 20:17:42 [debug] 751#0: *97 accept: 62.147.138.134 fd:8
0+2010/03/09 20:17:42 [debug] 751#0: *97 event timer add: 8: 600000:1268162862662
0+2010/03/09 20:17:42 [debug] 751#0: *97 epoll add event: fd:8 op:1 ev:80000001
0+2010/03/09 20:17:42 [debug] 751#0: *97 post event 000000000078EE00
0+2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 000000000078EE00
0+2010/03/09 20:17:42 [debug] 751#0: *97 malloc: 00000000006D08F0:1256
0+2010/03/09 20:17:42 [debug] 751#0: *97 posix_memalign: 00000000006C37C0:256 @16
0+2010/03/09 20:17:42 [debug] 751#0: *97 malloc: 000000000078A910:1024
0+2010/03/09 20:17:42 [debug] 751#0: *97 posix_memalign: 00000000006D0DE0:4096 @16
0+2010/03/09 20:17:42 [debug] 751#0: *97 http process request line
0+2010/03/09 20:17:42 [debug] 751#0: *97 recv: fd:8 123 of 1024
0+2010/03/09 20:17:42 [debug] 751#0: *97 http request line: "GET /test/ HTTP/1.1"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http uri: "/test/"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http args: ""
0+2010/03/09 20:17:42 [debug] 751#0: *97 http exten: ""
0+2010/03/09 20:17:42 [debug] 751#0: *97 http process request header line
0+2010/03/09 20:17:42 [debug] 751#0: *97 http header: "TE: deflate,gzip;q=0.3"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http header: "Connection: TE, close"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http header: "Host: i20.etigris.com"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http header: "User-Agent: lwp-request/2.07"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http header done
0+2010/03/09 20:17:42 [debug] 751#0: *97 event timer del: 8: 1268162862662
0+2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 0
0+2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 1
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script var
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script var: "GET"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script regex: "POST"
0+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"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script if
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script if: false
0+2010/03/09 20:17:42 [debug] 751#0: *97 test location: "/"
0+2010/03/09 20:17:42 [debug] 751#0: *97 test location: "region"
0+2010/03/09 20:17:42 [debug] 751#0: *97 test location: "ssi"
0+2010/03/09 20:17:42 [debug] 751#0: *97 test location: "ville"
0+2010/03/09 20:17:42 [debug] 751#0: *97 using configuration "/"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http cl:-1 max:1048576
0+2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 3
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script complex value
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script var: "/test/"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script set $memcached_key
0+2010/03/09 20:17:42 [debug] 751#0: *97 post rewrite phase: 4
0+2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 5
0+2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 6
0+2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 7
0+2010/03/09 20:17:42 [debug] 751#0: *97 access phase: 8
0+2010/03/09 20:17:42 [debug] 751#0: *97 access phase: 9
0+2010/03/09 20:17:42 [debug] 751#0: *97 post access phase: 10
0+2010/03/09 20:17:42 [debug] 751#0: *97 http set discard body
0+2010/03/09 20:17:42 [debug] 751#0: *97 http init upstream, client timer: 0
0+2010/03/09 20:17:42 [debug] 751#0: *97 epoll add event: fd:8 op:3 ev:80000005
0+2010/03/09 20:17:42 [debug] 751#0: *97 http memcached request: "/test/"
0+2010/03/09 20:17:42 [debug] 751#0: *97 posix_memalign: 00000000006C7AE0:4096 @16
0+2010/03/09 20:17:42 [debug] 751#0: *97 http cleanup add: 00000000006D1DC8
0+2010/03/09 20:17:42 [debug] 751#0: *97 get rr peer, try: 1
0+2010/03/09 20:17:42 [debug] 751#0: *97 socket 9
0+2010/03/09 20:17:42 [debug] 751#0: *97 epoll add connection: fd:9 ev:80000005
0+2010/03/09 20:17:42 [debug] 751#0: *97 connect to 127.0.0.1:11211, fd:9 #98
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream connect: -2
0+2010/03/09 20:17:42 [debug] 751#0: *97 event timer add: 9: 1000000:1268163262730
0+2010/03/09 20:17:42 [debug] 751#0: *97 http finalize request: -4, "/test/?" a:1, c:2
0+2010/03/09 20:17:42 [debug] 751#0: *97 http request count:2 blk:0
0+2010/03/09 20:17:42 [debug] 751#0: *97 post event 00000000007A8E10
0+2010/03/09 20:17:42 [debug] 751#0: *97 post event 00000000007A8E78
0+2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 00000000007A8E78
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream request: "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream send request handler
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream send request
0+2010/03/09 20:17:42 [debug] 751#0: *97 chain writer buf fl:0 s:12
0+2010/03/09 20:17:42 [debug] 751#0: *97 chain writer in: 00000000006C7B58
0+2010/03/09 20:17:42 [debug] 751#0: *97 writev: 12
0+2010/03/09 20:17:42 [debug] 751#0: *97 chain writer out: 0000000000000000
0+2010/03/09 20:17:42 [debug] 751#0: *97 event timer del: 9: 1268163262730
0+2010/03/09 20:17:42 [debug] 751#0: *97 event timer add: 9: 1000000:1268163262730
0+2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 00000000007A8E10
0+2010/03/09 20:17:42 [debug] 751#0: *97 http run request: "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream check client, write event:1, "/test/"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream recv(): -1 (11: Resource temporarily unavailable)
0+2010/03/09 20:17:42 [debug] 751#0: *97 post event 000000000078EE68
0+2010/03/09 20:17:42 [debug] 751#0: *97 post event 00000000007A8E78
0+2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 00000000007A8E78
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream request: "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream dummy handler
0+2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 000000000078EE68
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream request: "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream process header
0+2010/03/09 20:17:42 [debug] 751#0: *97 malloc: 00000000006C8AF0:4096
0+2010/03/09 20:17:42 [debug] 751#0: *97 recv: fd:9 5 of 4096
0+2010/03/09 20:17:42 [debug] 751#0: *97 memcached: "END"
0+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"
0+2010/03/09 20:17:42 [debug] 751#0: *97 finalize http upstream request: 404
0+2010/03/09 20:17:42 [debug] 751#0: *97 finalize http memcached request
0+2010/03/09 20:17:42 [debug] 751#0: *97 free rr peer 1 0
0+2010/03/09 20:17:42 [debug] 751#0: *97 close http upstream connection: 9
0+2010/03/09 20:17:42 [debug] 751#0: *97 event timer del: 9: 1268163262730
0+2010/03/09 20:17:42 [debug] 751#0: *97 http finalize request: 404, "/test/?" a:1, c:1
0+2010/03/09 20:17:42 [debug] 751#0: *97 http special response: 404, "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 test location: "@python"
0+2010/03/09 20:17:42 [debug] 751#0: *97 using location: @python "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 3
0+2010/03/09 20:17:42 [debug] 751#0: *97 post rewrite phase: 4
0+2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 5
0+2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 6
0+2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 7
0+2010/03/09 20:17:42 [debug] 751#0: *97 access phase: 8
0+2010/03/09 20:17:42 [debug] 751#0: *97 access phase: 9
0+2010/03/09 20:17:42 [debug] 751#0: *97 post access phase: 10
0+2010/03/09 20:17:42 [debug] 751#0: *97 http init upstream, client timer: 0
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script copy: "Host: "
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script var: "i20.etigris.com"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script copy: "
0+"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script copy: "Connection: close
0+"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "TE: deflate,gzip;q=0.3"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "User-Agent: lwp-request/2.07"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header:
0+"GET /test/ HTTP/1.0
0+Host: i20.etigris.com
0+Connection: close
0+TE: deflate,gzip;q=0.3
0+User-Agent: lwp-request/2.07
0+
0+"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http cleanup add: 00000000006C8328
0+2010/03/09 20:17:42 [debug] 751#0: *97 get rr peer, try: 1
0+2010/03/09 20:17:42 [debug] 751#0: *97 socket 9
0+2010/03/09 20:17:42 [debug] 751#0: *97 epoll add connection: fd:9 ev:80000005
0+2010/03/09 20:17:42 [debug] 751#0: *97 connect to 0.0.0.0:8080, fd:9 #99
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream connect: -2
0+2010/03/09 20:17:42 [debug] 751#0: *97 event timer add: 9: 60000:1268162322730
0+2010/03/09 20:17:42 [debug] 751#0: *97 http finalize request: -4, "/test/?" a:1, c:3
0+2010/03/09 20:17:42 [debug] 751#0: *97 http request count:3 blk:0
0+2010/03/09 20:17:42 [debug] 751#0: *97 http finalize request: -4, "/test/?" a:1, c:2
0+2010/03/09 20:17:42 [debug] 751#0: *97 http request count:2 blk:0
0+2010/03/09 20:17:42 [debug] 751#0: *97 post event 00000000007A8E78
0+2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 00000000007A8E78
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream request: "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream send request handler
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream send request
0+2010/03/09 20:17:42 [debug] 751#0: *97 chain writer buf fl:1 s:119
0+2010/03/09 20:17:42 [debug] 751#0: *97 chain writer in: 00000000006C8440
0+2010/03/09 20:17:42 [debug] 751#0: *97 writev: 119
0+2010/03/09 20:17:42 [debug] 751#0: *97 chain writer out: 0000000000000000
0+2010/03/09 20:17:42 [debug] 751#0: *97 event timer del: 9: 1268162322730
0+2010/03/09 20:17:42 [debug] 751#0: *97 event timer add: 9: 60000:1268162322731
0+2010/03/09 20:17:42 [debug] 751#0: *97 post event 000000000078EE68
0+2010/03/09 20:17:42 [debug] 751#0: *97 post event 00000000007A8E78
0+2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 00000000007A8E78
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream request: "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream dummy handler
0+2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 000000000078EE68
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream request: "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream process header
0+2010/03/09 20:17:42 [debug] 751#0: *97 malloc: 00000000006C9B00:4096
0+2010/03/09 20:17:42 [debug] 751#0: *97 recv: fd:9 221 of 4096
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy status 200 "200 OK"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Server: gunicorn/0.6.2"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Date: Tue, 09 Mar 2010 19:17:42 GMT"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Status: 200 OK"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Connection: close"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Content-Length: 46"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Content-Type: text/html; charset=UTF-8"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header done
0+2010/03/09 20:17:42 [debug] 751#0: *97 xslt filter header
0+2010/03/09 20:17:42 [debug] 751#0: *97 HTTP/1.1 200 OK
0+Server: nginx/0.8.34
0+Date: Tue, 09 Mar 2010 19:17:42 GMT
0+Content-Type: text/html; charset=UTF-8
0+Transfer-Encoding: chunked
0+Connection: close
0+Status: 200 OK
0+
0+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
0+2010/03/09 20:17:42 [debug] 751#0: *97 http write filter: l:0 f:0 s:181
0+2010/03/09 20:17:42 [debug] 751#0: *97 http cacheable: 0
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream process upstream
0+2010/03/09 20:17:42 [debug] 751#0: *97 pipe read upstream: 1
0+2010/03/09 20:17:42 [debug] 751#0: *97 pipe preread: 46
0+2010/03/09 20:17:42 [debug] 751#0: *97 readv: 1:3875
0+2010/03/09 20:17:42 [debug] 751#0: *97 pipe recv chain: 0
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
0+2010/03/09 20:17:42 [debug] 751#0: *97 posix_memalign: 00000000006CAB10:4096 @16
0+2010/03/09 20:17:42 [debug] 751#0: *97 input buf #0
0+2010/03/09 20:17:42 [debug] 751#0: *97 pipe write downstream: 1
0+2010/03/09 20:17:42 [debug] 751#0: *97 pipe write downstream flush in
0+2010/03/09 20:17:42 [debug] 751#0: *97 http output filter "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 xslt filter body
0+2010/03/09 20:17:42 [debug] 751#0: *97 http ssi filter "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 saved: 0 state: 0
0+2010/03/09 20:17:42 [debug] 751#0: *97 parse: 0, looked: 0 00000000006C9BAF-00000000006C9BB5
0+2010/03/09 20:17:42 [debug] 751#0: *97 saved: 0
0+2010/03/09 20:17:42 [debug] 751#0: *97 ssi flush
0+2010/03/09 20:17:42 [debug] 751#0: *97 ssi out: 00000000006CAD08 00000000006C9BAF
0+2010/03/09 20:17:42 [debug] 751#0: *97 http postpone filter "/test/?" 00000000006CAD58
0+2010/03/09 20:17:42 [debug] 751#0: *97 http chunk: 6
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
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
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
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
0+2010/03/09 20:17:42 [debug] 751#0: *97 http write filter: l:0 f:0 s:192
0+2010/03/09 20:17:42 [debug] 751#0: *97 ssi include: "/ssi/"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http subrequest "/ssi/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 saved: 0 state: 0
0+2010/03/09 20:17:42 [debug] 751#0: *97 parse: -2, looked: 0 00000000006C9BD6-00000000006C9BDD
0+2010/03/09 20:17:42 [debug] 751#0: *97 saved: 0
0+2010/03/09 20:17:42 [debug] 751#0: *97 ssi out: 00000000006CB868 00000000006C9BD6
0+2010/03/09 20:17:42 [debug] 751#0: *97 http postpone filter "/test/?" 00000000006CB8B8
0+2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: 0 "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 pipe write downstream done
0+2010/03/09 20:17:42 [debug] 751#0: *97 event timer: 9, old: 1268162322731, new: 1268162322772
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream exit: 0000000000000000
0+2010/03/09 20:17:42 [debug] 751#0: *97 finalize http upstream request: 0
0+2010/03/09 20:17:42 [debug] 751#0: *97 finalize http proxy request
0+2010/03/09 20:17:42 [debug] 751#0: *97 free rr peer 1 0
0+2010/03/09 20:17:42 [debug] 751#0: *97 close http upstream connection: 9
0+2010/03/09 20:17:42 [debug] 751#0: *97 event timer del: 9: 1268162322731
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream temp fd: -1
0+2010/03/09 20:17:42 [debug] 751#0: *97 http output filter "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 xslt filter body
0+2010/03/09 20:17:42 [debug] 751#0: *97 http ssi filter "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 ssi out: 00000000006CB950 0000000000000000
0+2010/03/09 20:17:42 [debug] 751#0: *97 http postpone filter "/test/?" 00000000006CB9A0
0+2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: 0 "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http finalize request: 0, "/test/?" a:0, c:2
0+2010/03/09 20:17:42 [debug] 751#0: *97 event timer add: 8: 600000:1268162862772
0+2010/03/09 20:17:42 [debug] 751#0: *97 http posted request: "/ssi/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 1
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script var
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script var: "GET"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script regex: "POST"
0+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"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script if
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script if: false
0+2010/03/09 20:17:42 [debug] 751#0: *97 test location: "/"
0+2010/03/09 20:17:42 [debug] 751#0: *97 test location: "region"
0+2010/03/09 20:17:42 [debug] 751#0: *97 test location: "ssi"
0+2010/03/09 20:17:42 [debug] 751#0: *97 using configuration "/ssi"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http cl:-1 max:1048576
0+2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 3
0+2010/03/09 20:17:42 [debug] 751#0: *97 post rewrite phase: 4
0+2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 5
0+2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 6
0+2010/03/09 20:17:42 [debug] 751#0: *97 generic phase: 7
0+2010/03/09 20:17:42 [debug] 751#0: *97 posix_memalign: 00000000007C2D50:4096 @16
0+2010/03/09 20:17:42 [debug] 751#0: *97 http init upstream, client timer: 0
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script copy: "Host: "
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script var: "i20.etigris.com"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script copy: "
0+"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http script copy: "Connection: close
0+"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "TE: deflate,gzip;q=0.3"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "User-Agent: lwp-request/2.07"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header:
0+"GET /ssi/ HTTP/1.0
0+Host: i20.etigris.com
0+Connection: close
0+TE: deflate,gzip;q=0.3
0+User-Agent: lwp-request/2.07
0+
0+"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http cleanup add: 00000000007C3310
0+2010/03/09 20:17:42 [debug] 751#0: *97 get rr peer, try: 1
0+2010/03/09 20:17:42 [debug] 751#0: *97 socket 9
0+2010/03/09 20:17:42 [debug] 751#0: *97 epoll add connection: fd:9 ev:80000005
0+2010/03/09 20:17:42 [debug] 751#0: *97 connect to 0.0.0.0:8080, fd:9 #100
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream connect: -2
0+2010/03/09 20:17:42 [debug] 751#0: *97 event timer add: 9: 60000:1268162322772
0+2010/03/09 20:17:42 [debug] 751#0: *97 http finalize request: -4, "/ssi/?" a:1, c:3
0+2010/03/09 20:17:42 [debug] 751#0: *97 http request count:3 blk:0
0+2010/03/09 20:17:42 [debug] 751#0: *97 post event 00000000007A8E78
0+2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 00000000007A8E78
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream request: "/ssi/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream send request handler
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream send request
0+2010/03/09 20:17:42 [debug] 751#0: *97 chain writer buf fl:1 s:118
0+2010/03/09 20:17:42 [debug] 751#0: *97 chain writer in: 00000000007C3348
0+2010/03/09 20:17:42 [debug] 751#0: *97 writev: 118
0+2010/03/09 20:17:42 [debug] 751#0: *97 chain writer out: 0000000000000000
0+2010/03/09 20:17:42 [debug] 751#0: *97 event timer del: 9: 1268162322772
0+2010/03/09 20:17:42 [debug] 751#0: *97 event timer add: 9: 60000:1268162322773
0+2010/03/09 20:17:42 [debug] 751#0: *97 post event 000000000078EE68
0+2010/03/09 20:17:42 [debug] 751#0: *97 post event 00000000007A8E78
0+2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 00000000007A8E78
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream request: "/ssi/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream dummy handler
0+2010/03/09 20:17:42 [debug] 751#0: *97 delete posted event 000000000078EE68
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream request: "/ssi/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream process header
0+2010/03/09 20:17:42 [debug] 751#0: *97 malloc: 00000000007C3D60:4096
0+2010/03/09 20:17:42 [debug] 751#0: *97 recv: fd:9 177 of 4096
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy status 200 "200 OK"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Server: gunicorn/0.6.2"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Date: Tue, 09 Mar 2010 19:17:42 GMT"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Status: 200 OK"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Connection: close"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Content-Length: 3"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header: "Content-Type: text/html; charset=UTF-8"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http proxy header done
0+2010/03/09 20:17:42 [debug] 751#0: *97 xslt filter header
0+2010/03/09 20:17:42 [debug] 751#0: *97 charset: "UTF-8" > "UTF-8"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http cacheable: 0
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream process upstream
0+2010/03/09 20:17:42 [debug] 751#0: *97 pipe read upstream: 1
0+2010/03/09 20:17:42 [debug] 751#0: *97 pipe preread: 3
0+2010/03/09 20:17:42 [debug] 751#0: *97 readv: 1:3919
0+2010/03/09 20:17:42 [debug] 751#0: *97 pipe recv chain: 0
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
0+2010/03/09 20:17:42 [debug] 751#0: *97 input buf #0
0+2010/03/09 20:17:42 [debug] 751#0: *97 pipe write downstream: 1
0+2010/03/09 20:17:42 [debug] 751#0: *97 pipe write downstream flush in
0+2010/03/09 20:17:42 [debug] 751#0: *97 http output filter "/ssi/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: "/ssi/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 xslt filter body
0+2010/03/09 20:17:42 [debug] 751#0: *97 http postpone filter "/ssi/?" 00000000007C3750
0+2010/03/09 20:17:42 [debug] 751#0: *97 http chunk: 3
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
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
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
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
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
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
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
0+2010/03/09 20:17:42 [debug] 751#0: *97 http write filter: l:0 f:0 s:200
0+2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: 0 "/ssi/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 pipe write downstream done
0+2010/03/09 20:17:42 [debug] 751#0: *97 event timer: 9, old: 1268162322773, new: 1268162322773
0+2010/03/09 20:17:42 [debug] 751#0: *97 event timer del: 8: 1268162862772
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream exit: 0000000000000000
0+2010/03/09 20:17:42 [debug] 751#0: *97 finalize http upstream request: 0
0+2010/03/09 20:17:42 [debug] 751#0: *97 finalize http proxy request
0+2010/03/09 20:17:42 [debug] 751#0: *97 free rr peer 1 0
0+2010/03/09 20:17:42 [debug] 751#0: *97 close http upstream connection: 9
0+2010/03/09 20:17:42 [debug] 751#0: *97 event timer del: 9: 1268162322773
0+2010/03/09 20:17:42 [debug] 751#0: *97 http upstream temp fd: -1
0+2010/03/09 20:17:42 [debug] 751#0: *97 http output filter "/ssi/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: "/ssi/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 xslt filter body
0+2010/03/09 20:17:42 [debug] 751#0: *97 http postpone filter "/ssi/?" 00007FFF2491F020
0+2010/03/09 20:17:42 [debug] 751#0: *97 http chunk: 0
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
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
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
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
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
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
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
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
0+2010/03/09 20:17:42 [debug] 751#0: *97 http write filter: l:0 f:0 s:200
0+2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: 0 "/ssi/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http finalize request: 0, "/ssi/?" a:1, c:2
0+2010/03/09 20:17:42 [debug] 751#0: *97 http wake parent request: "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http posted request: "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http writer handler: "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http output filter "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 xslt filter body
0+2010/03/09 20:17:42 [debug] 751#0: *97 http ssi filter "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http postpone filter "/test/?" 0000000000000000
0+2010/03/09 20:17:42 [debug] 751#0: *97 http postpone filter output "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http chunk: 7
0+2010/03/09 20:17:42 [debug] 751#0: *97 http chunk: 0
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
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
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
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
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
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
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
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
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
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
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
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
0+2010/03/09 20:17:42 [debug] 751#0: *97 http write filter: l:1 f:0 s:217
0+2010/03/09 20:17:42 [debug] 751#0: *97 http write filter limit 0
0+2010/03/09 20:17:42 [debug] 751#0: *97 writev: 217
0+2010/03/09 20:17:42 [debug] 751#0: *97 http write filter 0000000000000000
0+2010/03/09 20:17:42 [debug] 751#0: *97 http copy filter: 0 "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http writer output filter: 0, "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http writer done: "/test/?"
0+2010/03/09 20:17:42 [debug] 751#0: *97 http finalize request: 0, "/test/?" a:1, c:1
0+2010/03/09 20:17:42 [debug] 751#0: *97 http request count:1 blk:0
0+2010/03/09 20:17:42 [debug] 751#0: *97 http close request
0+2010/03/09 20:17:42 [debug] 751#0: *97 http log handler
0+2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000007C3D60
0+2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000006C9B00
0+2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000006C8AF0
0+2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000006D0DE0, unused: 0
0+2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000006C7AE0, unused: 2
0+2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000006CAB10, unused: 2
0+2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000007C2D50, unused: 642
0+2010/03/09 20:17:42 [debug] 751#0: *97 close http connection: 8
0+2010/03/09 20:17:42 [debug] 751#0: *97 free: 000000000078A910
0+2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000006D08F0
0+2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000006C34E0, unused: 8
0+2010/03/09 20:17:42 [debug] 751#0: *97 free: 00000000006C37C0, unused: 128
...
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92
93
94
95
96
97
98
99
100
101
102
103
104
105
106
107
108
109
110
111
112
113
114
115
116
117
118
119
120
121
122
123
124
125
126
127
128
129
130
131
132
133
134
135
136
137
138
139
140
141
142
143
144
145
146
147
148
149
150
151
152
153
154
155
156
157
158
159
160
161
162
163
164
165
166
167
168
169
170
171
172
173
174
175
176
177
178
179
180
181
182
183
184
185
186
187
188
189
190
191
192
193
194
195
196
197
198
199
200
201
202
203
204
205
206
207
208
209
210
211
212
213
214
215
216
217
218
219
220
221
222
223
224
225
226
227
228
229
230
231
232
233
234
235
236
237
238
239
240
241
242
243
244
245
246
247
248
249
250
251
252
253
254
255
256
257
258
259
260
261
262
263
264
265
266
267
268
269
270
271
272
273
274
275
276
277
278
279
280
281
282
283
284
285
286
287
288
289
290
291
292
293
294
295
296
297
298
299
300
301
302
303
304
305
306
307
308
309
310
311
312
313
314
315
316
317
318
319
320
321
322
323
324
325
326
327
328
329
330
331
332
333
334
335
336
337
338
339
340
341
342
343
344
345
346
347
348
349
350
351
352
353
354
355
356
357
358
359
360
361
362
363
364
365
366
367
368
369
370
371
372
373
374
375
376
377
378
379
380
381
382
383
384
385
386
387
388
389
390
391
392
393
394
395
396
397
398
399
400
401
402
403
404
405
406
--- 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