2005/12/05 20:29:14 [debug] 74468#0: kevent set event: 6: ft:-1 fl:0005
2005/12/05 20:29:31 [debug] 74468#0: accept on 0.0.0.0:80, ready: 1
2005/12/05 20:29:31 [debug] 74468#0: malloc: 080BB200:256
2005/12/05 20:29:31 [debug] 74468#0: accept: fd:7 c:1
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer add: 7: 60000:4227432911
2005/12/05 20:29:31 [debug] 74468#0: *1 kevent set event: 7: ft:-1 fl:0025
2005/12/05 20:29:31 [debug] 74468#0: *1 malloc: 080B6000:600
2005/12/05 20:29:31 [debug] 74468#0: *1 malloc: 080B6400:1024
2005/12/05 20:29:31 [debug] 74468#0: *1 malloc: 080C4000:4096
2005/12/05 20:29:31 [debug] 74468#0: *1 http process request line
2005/12/05 20:29:31 [debug] 74468#0: *1 recv: eof:0, avail:216, err:0
2005/12/05 20:29:31 [debug] 74468#0: *1 recv: fd:7 216 of 1024
2005/12/05 20:29:31 [debug] 74468#0: *1 http request line: "GET /test2.html HTTP/1.1"
2005/12/05 20:29:31 [debug] 74468#0: *1 http uri: "/test2.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 http args: ""
2005/12/05 20:29:31 [debug] 74468#0: *1 http exten: "html"
2005/12/05 20:29:31 [debug] 74468#0: *1 http process request header line
2005/12/05 20:29:31 [debug] 74468#0: *1 http header: "Accept: */*"
2005/12/05 20:29:31 [debug] 74468#0: *1 http header: "Accept-Language: ru"
2005/12/05 20:29:31 [debug] 74468#0: *1 http header: "User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322; .NET CLR 2.0.50727)"
2005/12/05 20:29:31 [debug] 74468#0: *1 http header: "Host: booksn.dore.ru"
2005/12/05 20:29:31 [debug] 74468#0: *1 http header: "Connection: Keep-Alive"
2005/12/05 20:29:31 [debug] 74468#0: *1 http header done
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer del: 7: 4227432911
2005/12/05 20:29:31 [debug] 74468#0: *1 http phase handler
2005/12/05 20:29:31 [debug] 74468#0: *1 find location for "/test2.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 find location: "/"
2005/12/05 20:29:31 [debug] 74468#0: *1 using configuration "/"
2005/12/05 20:29:31 [debug] 74468#0: *1 http cl:-1 max:1048576
2005/12/05 20:29:31 [debug] 74468#0: *1 http init upstream, client timer: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 kevent set event: 7: ft:-2 fl:0025
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Accept: */*"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Accept-Language: ru"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322; .NET CLR 2.0.50727)"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header:
"GET /test2.html HTTP/1.0
Host: localhost:40080
Connection: close
Accept: */*
Accept-Language: ru
User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322; .NET CLR 2.0.50727)

"
2005/12/05 20:29:31 [debug] 74468#0: *1 http cleanup add: 080C4754
2005/12/05 20:29:31 [debug] 74468#0: *1 socket 10
2005/12/05 20:29:31 [debug] 74468#0: *1 connect to 127.0.0.1:40080, fd:10 #2
2005/12/05 20:29:31 [debug] 74468#0: *1 kevent set event: 10: ft:-1 fl:0025
2005/12/05 20:29:31 [debug] 74468#0: *1 connected
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream connect: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream send request
2005/12/05 20:29:31 [debug] 74468#0: *1 chain writer buf size: 212
2005/12/05 20:29:31 [debug] 74468#0: *1 chain writer in: 080C4764
2005/12/05 20:29:31 [debug] 74468#0: *1 writev: 212 of 212
2005/12/05 20:29:31 [debug] 74468#0: *1 chain writer out: 00000000
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer add: 10: 60000:4227432912
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream check client, write event:1, "/test2.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream process header
2005/12/05 20:29:31 [debug] 74468#0: *1 malloc: 08100000:4096
2005/12/05 20:29:31 [debug] 74468#0: *1 recv: eof:0, avail:591, err:0
2005/12/05 20:29:31 [debug] 74468#0: *1 recv: fd:10 591 of 4096
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy status 0 "200 OK"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Date: Mon, 05 Dec 2005 17:29:31 GMT"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Server: Apache/1.3.33 (Unix) mod_deflate/1.0.21 mod_accel/1.0.34"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Last-Modified: Sat, 03 Dec 2005 14:15:35 GMT"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "ETag: "10808d-11d-51c5efc0""
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Accept-Ranges: bytes"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Content-Length: 285"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Content-Type: text/html; charset=windows-1251"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Connection: close"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header done
2005/12/05 20:29:31 [debug] 74468#0: *1 HTTP/1.1 200 OK
Server: nginx/0.3.13
Date: Mon, 05 Dec 2005 17:29:31 GMT
Content-Type: text/html; charset=windows-1251
Transfer-Encoding: chunked
Connection: keep-alive
Last-Modified: Sat, 03 Dec 2005 14:15:35 GMT
ETag: "10808d-11d-51c5efc0"
Accept-Ranges: bytes


2005/12/05 20:29:31 [debug] 74468#0: *1 write new buf t:1 f:0 080C4A38, pos 080C4A38, size: 274 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http write filter: l:0 f:0 s:274
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream process upstream
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe read upstream: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe preread: 285
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe buf free s:0 t:1 f:0 08100000, pos 08100132, size: 285 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream: 1
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write busy: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write: out:00000000, f:0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe read upstream: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe buf free s:0 t:1 f:0 08100000, pos 08100132, size: 285 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer: 10, old: 4227432912, new: 4227432942
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream process upstream
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe read upstream: 1
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe buf free s:0 t:1 f:0 08100000, pos 08100132, size: 285 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 input buf #0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream: 1
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream flush in
2005/12/05 20:29:31 [debug] 74468#0: *1 http output filter "/test2.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 copy filter: "/test2.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http ssi filter "/test2.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0 state: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 parse: 0, looked: 0 08100132-0810021A
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http postpone filter "/test2.html?" 080C4DEC
2005/12/05 20:29:31 [debug] 74468#0: *1 http postpone filter out "/test2.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http chunk: 232
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 080C4A38, pos 080C4A38, size: 274 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write new buf t:1 f:0 00000000, pos 080C4E30, size: 4 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write new buf t:1 f:0 08100000, pos 08100132, size: 232 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write new buf t:0 f:0 00000000, pos 0808FCBA, size: 2 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http write filter: l:0 f:0 s:512
2005/12/05 20:29:31 [debug] 74468#0: *1 ssi include: "/footer.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 malloc: 08101000:4096
2005/12/05 20:29:31 [debug] 74468#0: *1 http subrequest "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http phase handler
2005/12/05 20:29:31 [debug] 74468#0: *1 find location for "/footer.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 find location: "/"
2005/12/05 20:29:31 [debug] 74468#0: *1 using configuration "/"
2005/12/05 20:29:31 [debug] 74468#0: *1 http cl:-1 max:1048576
2005/12/05 20:29:31 [debug] 74468#0: *1 http init upstream, client timer: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Accept: */*"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Accept-Language: ru"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322; .NET CLR 2.0.50727)"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header:
"GET /test2.html HTTP/1.0
Host: localhost:40080
Connection: close
Accept: */*
Accept-Language: ru
User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322; .NET CLR 2.0.50727)

"
2005/12/05 20:29:31 [debug] 74468#0: *1 http cleanup add: 080C4FF0
2005/12/05 20:29:31 [debug] 74468#0: *1 socket 11
2005/12/05 20:29:31 [debug] 74468#0: *1 connect to 127.0.0.1:40080, fd:11 #3
2005/12/05 20:29:31 [debug] 74468#0: *1 kevent set event: 11: ft:-1 fl:0025
2005/12/05 20:29:31 [debug] 74468#0: *1 connected
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream connect: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream send request
2005/12/05 20:29:31 [debug] 74468#0: *1 chain writer buf size: 212
2005/12/05 20:29:31 [debug] 74468#0: *1 chain writer in: 08101684
2005/12/05 20:29:31 [debug] 74468#0: *1 writev: 212 of 212
2005/12/05 20:29:31 [debug] 74468#0: *1 chain writer out: 00000000
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer add: 11: 60000:4227432944
2005/12/05 20:29:31 [debug] 74468#0: *1 http subrequest done "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0 state: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 parse: -2, looked: 0 0810023F-0810024F
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http postpone filter "/test2.html?" 081016C0
2005/12/05 20:29:31 [debug] 74468#0: *1 copy filter: -2 "/test2.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream done
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer: 10, old: 4227432912, new: 4227432944
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream exit: 00000000
2005/12/05 20:29:31 [debug] 74468#0: *1 finalize http upstream request: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 finalize http proxy request
2005/12/05 20:29:31 [debug] 74468#0: *1 close http upstream connection: 10
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer del: 10: 4227432912
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream temp fd: -1
2005/12/05 20:29:31 [debug] 74468#0: *1 http output filter "/test2.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 copy filter: "/test2.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http ssi filter "/test2.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 http postpone filter "/test2.html?" 0810174C
2005/12/05 20:29:31 [debug] 74468#0: *1 copy filter: -2 "/test2.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http finalize request: -2, "/test2.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer add: 7: 60000:4227432944
2005/12/05 20:29:31 [debug] 74468#0: *1 http finalize non-active request: "/test2.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream process header
2005/12/05 20:29:31 [debug] 74468#0: *1 malloc: 08102000:4096
2005/12/05 20:29:31 [debug] 74468#0: *1 recv: eof:0, avail:591, err:0
2005/12/05 20:29:31 [debug] 74468#0: *1 recv: fd:11 591 of 4096
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy status 0 "200 OK"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Date: Mon, 05 Dec 2005 17:29:31 GMT"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Server: Apache/1.3.33 (Unix) mod_deflate/1.0.21 mod_accel/1.0.34"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Last-Modified: Sat, 03 Dec 2005 14:15:35 GMT"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "ETag: "10808d-11d-51c5efc0""
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Accept-Ranges: bytes"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Content-Length: 285"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Content-Type: text/html; charset=windows-1251"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Connection: close"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header done
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream process upstream
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe read upstream: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe preread: 285
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe buf free s:0 t:1 f:0 08102000, pos 08102132, size: 285 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream: 1
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write busy: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write: out:00000000, f:0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe read upstream: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe buf free s:0 t:1 f:0 08102000, pos 08102132, size: 285 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer: 11, old: 4227432944, new: 4227432961
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream process upstream
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe read upstream: 1
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe buf free s:0 t:1 f:0 08102000, pos 08102132, size: 285 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 input buf #0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream: 1
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream flush in
2005/12/05 20:29:31 [debug] 74468#0: *1 http output filter "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 copy filter: "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http ssi filter "/footer.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0 state: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 parse: 0, looked: 0 08102132-0810221A
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http postpone filter "/footer.html?" 08101C8C
2005/12/05 20:29:31 [debug] 74468#0: *1 http postpone filter out "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http chunk: 232
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 080C4A38, pos 080C4A38, size: 274 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 00000000, pos 080C4E30, size: 4 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 08100000, pos 08100132, size: 232 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:0 f:0 00000000, pos 0808FCBA, size: 2 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write new buf t:1 f:0 00000000, pos 08101CD0, size: 4 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write new buf t:1 f:0 08102000, pos 08102132, size: 232 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write new buf t:0 f:0 00000000, pos 0808FCBA, size: 2 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http write filter: l:0 f:0 s:750
2005/12/05 20:29:31 [debug] 74468#0: *1 ssi include: "/footer.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 malloc: 08103000:4096
2005/12/05 20:29:31 [debug] 74468#0: *1 http subrequest "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http phase handler
2005/12/05 20:29:31 [debug] 74468#0: *1 find location for "/footer.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 find location: "/"
2005/12/05 20:29:31 [debug] 74468#0: *1 using configuration "/"
2005/12/05 20:29:31 [debug] 74468#0: *1 http cl:-1 max:1048576
2005/12/05 20:29:31 [debug] 74468#0: *1 http init upstream, client timer: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Accept: */*"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Accept-Language: ru"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322; .NET CLR 2.0.50727)"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header:
"GET /test2.html HTTP/1.0
Host: localhost:40080
Connection: close
Accept: */*
Accept-Language: ru
User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322; .NET CLR 2.0.50727)

"
2005/12/05 20:29:31 [debug] 74468#0: *1 http cleanup add: 08103518
2005/12/05 20:29:31 [debug] 74468#0: *1 socket 10
2005/12/05 20:29:31 [debug] 74468#0: *1 connect to 127.0.0.1:40080, fd:10 #4
2005/12/05 20:29:31 [debug] 74468#0: *1 kevent set event: 10: ft:-1 fl:0025
2005/12/05 20:29:31 [debug] 74468#0: *1 connected
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream connect: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream send request
2005/12/05 20:29:31 [debug] 74468#0: *1 chain writer buf size: 212
2005/12/05 20:29:31 [debug] 74468#0: *1 chain writer in: 08103524
2005/12/05 20:29:31 [debug] 74468#0: *1 writev: 212 of 212
2005/12/05 20:29:31 [debug] 74468#0: *1 chain writer out: 00000000
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer add: 10: 60000:4227432967
2005/12/05 20:29:31 [debug] 74468#0: *1 http subrequest done "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0 state: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 parse: -2, looked: 0 0810223F-0810224F
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http postpone filter "/footer.html?" 08103560
2005/12/05 20:29:31 [debug] 74468#0: *1 copy filter: -2 "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream done
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer: 11, old: 4227432944, new: 4227432967
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream exit: 00000000
2005/12/05 20:29:31 [debug] 74468#0: *1 finalize http upstream request: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 finalize http proxy request
2005/12/05 20:29:31 [debug] 74468#0: *1 close http upstream connection: 11
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer del: 11: 4227432944
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream temp fd: -1
2005/12/05 20:29:31 [debug] 74468#0: *1 http finalize request: 0, "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer: 7, old: 4227432944, new: 4227432967
2005/12/05 20:29:31 [debug] 74468#0: *1 http finalize non-active request: "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream process header
2005/12/05 20:29:31 [debug] 74468#0: *1 malloc: 08104000:4096
2005/12/05 20:29:31 [debug] 74468#0: *1 recv: eof:0, avail:591, err:0
2005/12/05 20:29:31 [debug] 74468#0: *1 recv: fd:10 591 of 4096
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy status 0 "200 OK"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Date: Mon, 05 Dec 2005 17:29:31 GMT"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Server: Apache/1.3.33 (Unix) mod_deflate/1.0.21 mod_accel/1.0.34"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Last-Modified: Sat, 03 Dec 2005 14:15:35 GMT"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "ETag: "10808d-11d-51c5efc0""
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Accept-Ranges: bytes"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Content-Length: 285"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Content-Type: text/html; charset=windows-1251"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Connection: close"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header done
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream process upstream
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe read upstream: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe preread: 285
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe buf free s:0 t:1 f:0 08104000, pos 08104132, size: 285 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream: 1
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write busy: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write: out:00000000, f:0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe read upstream: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe buf free s:0 t:1 f:0 08104000, pos 08104132, size: 285 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer: 10, old: 4227432967, new: 4227432981
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream process upstream
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe read upstream: 1
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe buf free s:0 t:1 f:0 08104000, pos 08104132, size: 285 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 input buf #0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream: 1
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream flush in
2005/12/05 20:29:31 [debug] 74468#0: *1 http output filter "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 copy filter: "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http ssi filter "/footer.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0 state: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 parse: 0, looked: 0 08104132-0810421A
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http postpone filter "/footer.html?" 08103AAC
2005/12/05 20:29:31 [debug] 74468#0: *1 http postpone filter out "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http chunk: 232
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 080C4A38, pos 080C4A38, size: 274 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 00000000, pos 080C4E30, size: 4 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 08100000, pos 08100132, size: 232 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:0 f:0 00000000, pos 0808FCBA, size: 2 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 00000000, pos 08101CD0, size: 4 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 08102000, pos 08102132, size: 232 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:0 f:0 00000000, pos 0808FCBA, size: 2 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write new buf t:1 f:0 00000000, pos 08103AF0, size: 4 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write new buf t:1 f:0 08104000, pos 08104132, size: 232 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write new buf t:0 f:0 00000000, pos 0808FCBA, size: 2 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http write filter: l:0 f:0 s:988
2005/12/05 20:29:31 [debug] 74468#0: *1 ssi include: "/footer.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 http subrequest "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http phase handler
2005/12/05 20:29:31 [debug] 74468#0: *1 find location for "/footer.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 find location: "/"
2005/12/05 20:29:31 [debug] 74468#0: *1 using configuration "/"
2005/12/05 20:29:31 [debug] 74468#0: *1 http cl:-1 max:1048576
2005/12/05 20:29:31 [debug] 74468#0: *1 malloc: 08105000:4096
2005/12/05 20:29:31 [debug] 74468#0: *1 http init upstream, client timer: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Accept: */*"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Accept-Language: ru"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322; .NET CLR 2.0.50727)"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header:
"GET /test2.html HTTP/1.0
Host: localhost:40080
Connection: close
Accept: */*
Accept-Language: ru
User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322; .NET CLR 2.0.50727)

"
2005/12/05 20:29:31 [debug] 74468#0: *1 http cleanup add: 08103FF0
2005/12/05 20:29:31 [debug] 74468#0: *1 socket 11
2005/12/05 20:29:31 [debug] 74468#0: *1 connect to 127.0.0.1:40080, fd:11 #5
2005/12/05 20:29:31 [debug] 74468#0: *1 kevent set event: 11: ft:-1 fl:0025
2005/12/05 20:29:31 [debug] 74468#0: *1 connected
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream connect: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream send request
2005/12/05 20:29:31 [debug] 74468#0: *1 chain writer buf size: 212
2005/12/05 20:29:31 [debug] 74468#0: *1 chain writer in: 08105344
2005/12/05 20:29:31 [debug] 74468#0: *1 writev: 212 of 212
2005/12/05 20:29:31 [debug] 74468#0: *1 chain writer out: 00000000
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer add: 11: 60000:4227432984
2005/12/05 20:29:31 [debug] 74468#0: *1 http subrequest done "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0 state: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 parse: -2, looked: 0 0810423F-0810424F
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http postpone filter "/footer.html?" 08105380
2005/12/05 20:29:31 [debug] 74468#0: *1 copy filter: -2 "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream done
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer: 10, old: 4227432967, new: 4227432984
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream exit: 00000000
2005/12/05 20:29:31 [debug] 74468#0: *1 finalize http upstream request: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 finalize http proxy request
2005/12/05 20:29:31 [debug] 74468#0: *1 close http upstream connection: 10
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer del: 10: 4227432967
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream temp fd: -1
2005/12/05 20:29:31 [debug] 74468#0: *1 http finalize request: 0, "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer: 7, old: 4227432944, new: 4227432984
2005/12/05 20:29:31 [debug] 74468#0: *1 http finalize non-active request: "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream process header
2005/12/05 20:29:31 [debug] 74468#0: *1 malloc: 08106000:4096
2005/12/05 20:29:31 [debug] 74468#0: *1 recv: eof:1, avail:591, err:0
2005/12/05 20:29:31 [debug] 74468#0: *1 recv: fd:11 591 of 4096
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy status 0 "200 OK"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Date: Mon, 05 Dec 2005 17:29:31 GMT"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Server: Apache/1.3.33 (Unix) mod_deflate/1.0.21 mod_accel/1.0.34"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Last-Modified: Sat, 03 Dec 2005 14:15:35 GMT"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "ETag: "10808d-11d-51c5efc0""
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Accept-Ranges: bytes"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Content-Length: 285"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Content-Type: text/html; charset=windows-1251"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Connection: close"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header done
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream process upstream
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe read upstream: 1
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe preread: 285
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe buf free s:0 t:1 f:0 08106000, pos 08106132, size: 285 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 input buf #0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream: 1
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream flush in
2005/12/05 20:29:31 [debug] 74468#0: *1 http output filter "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 copy filter: "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http ssi filter "/footer.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0 state: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 parse: 0, looked: 0 08106132-0810621A
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http postpone filter "/footer.html?" 081058CC
2005/12/05 20:29:31 [debug] 74468#0: *1 http postpone filter out "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http chunk: 232
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 080C4A38, pos 080C4A38, size: 274 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 00000000, pos 080C4E30, size: 4 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 08100000, pos 08100132, size: 232 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:0 f:0 00000000, pos 0808FCBA, size: 2 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 00000000, pos 08101CD0, size: 4 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 08102000, pos 08102132, size: 232 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:0 f:0 00000000, pos 0808FCBA, size: 2 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 00000000, pos 08103AF0, size: 4 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 08104000, pos 08104132, size: 232 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:0 f:0 00000000, pos 0808FCBA, size: 2 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write new buf t:1 f:0 00000000, pos 08105910, size: 4 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write new buf t:1 f:0 08106000, pos 08106132, size: 232 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write new buf t:0 f:0 00000000, pos 0808FCBA, size: 2 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http write filter: l:0 f:0 s:1226
2005/12/05 20:29:31 [debug] 74468#0: *1 ssi include: "/footer.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 http subrequest "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http phase handler
2005/12/05 20:29:31 [debug] 74468#0: *1 find location for "/footer.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 find location: "/"
2005/12/05 20:29:31 [debug] 74468#0: *1 using configuration "/"
2005/12/05 20:29:31 [debug] 74468#0: *1 http cl:-1 max:1048576
2005/12/05 20:29:31 [debug] 74468#0: *1 http init upstream, client timer: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 malloc: 08107000:4096
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Accept: */*"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Accept-Language: ru"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322; .NET CLR 2.0.50727)"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header:
"GET /test2.html HTTP/1.0
Host: localhost:40080
Connection: close
Accept: */*
Accept-Language: ru
User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322; .NET CLR 2.0.50727)

"
2005/12/05 20:29:31 [debug] 74468#0: *1 http cleanup add: 08107158
2005/12/05 20:29:31 [debug] 74468#0: *1 socket 10
2005/12/05 20:29:31 [debug] 74468#0: *1 connect to 127.0.0.1:40080, fd:10 #6
2005/12/05 20:29:31 [debug] 74468#0: *1 kevent set event: 10: ft:-1 fl:0025
2005/12/05 20:29:31 [debug] 74468#0: *1 connected
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream connect: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream send request
2005/12/05 20:29:31 [debug] 74468#0: *1 chain writer buf size: 212
2005/12/05 20:29:31 [debug] 74468#0: *1 chain writer in: 08107164
2005/12/05 20:29:31 [debug] 74468#0: *1 writev: 212 of 212
2005/12/05 20:29:31 [debug] 74468#0: *1 chain writer out: 00000000
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer add: 10: 60000:4227433001
2005/12/05 20:29:31 [debug] 74468#0: *1 http subrequest done "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0 state: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 parse: -2, looked: 0 0810623F-0810624F
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http postpone filter "/footer.html?" 081071A0
2005/12/05 20:29:31 [debug] 74468#0: *1 copy filter: -2 "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream done
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer: 11, old: 4227432984, new: 4227433001
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream exit: 00000000
2005/12/05 20:29:31 [debug] 74468#0: *1 finalize http upstream request: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 finalize http proxy request
2005/12/05 20:29:31 [debug] 74468#0: *1 close http upstream connection: 11
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer del: 11: 4227432984
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream temp fd: -1
2005/12/05 20:29:31 [debug] 74468#0: *1 http finalize request: 0, "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer: 7, old: 4227432944, new: 4227433001
2005/12/05 20:29:31 [debug] 74468#0: *1 http finalize non-active request: "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream process header
2005/12/05 20:29:31 [debug] 74468#0: *1 malloc: 08108000:4096
2005/12/05 20:29:31 [debug] 74468#0: *1 recv: eof:0, avail:591, err:0
2005/12/05 20:29:31 [debug] 74468#0: *1 recv: fd:10 591 of 4096
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy status 0 "200 OK"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Date: Mon, 05 Dec 2005 17:29:31 GMT"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Server: Apache/1.3.33 (Unix) mod_deflate/1.0.21 mod_accel/1.0.34"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Last-Modified: Sat, 03 Dec 2005 14:15:35 GMT"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "ETag: "10808d-11d-51c5efc0""
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Accept-Ranges: bytes"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Content-Length: 285"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Content-Type: text/html; charset=windows-1251"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Connection: close"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header done
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream process upstream
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe read upstream: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe preread: 285
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe buf free s:0 t:1 f:0 08108000, pos 08108132, size: 285 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream: 1
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write busy: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write: out:00000000, f:0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe read upstream: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe buf free s:0 t:1 f:0 08108000, pos 08108132, size: 285 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer: 10, old: 4227433001, new: 4227433025
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream process upstream
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe read upstream: 1
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe buf free s:0 t:1 f:0 08108000, pos 08108132, size: 285 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 input buf #0
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream: 1
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream flush in
2005/12/05 20:29:31 [debug] 74468#0: *1 http output filter "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 copy filter: "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http ssi filter "/footer.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0 state: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 parse: 0, looked: 0 08108132-0810821A
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http postpone filter "/footer.html?" 081076EC
2005/12/05 20:29:31 [debug] 74468#0: *1 http postpone filter out "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http chunk: 232
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 080C4A38, pos 080C4A38, size: 274 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 00000000, pos 080C4E30, size: 4 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 08100000, pos 08100132, size: 232 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:0 f:0 00000000, pos 0808FCBA, size: 2 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 00000000, pos 08101CD0, size: 4 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 08102000, pos 08102132, size: 232 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:0 f:0 00000000, pos 0808FCBA, size: 2 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 00000000, pos 08103AF0, size: 4 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 08104000, pos 08104132, size: 232 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:0 f:0 00000000, pos 0808FCBA, size: 2 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 00000000, pos 08105910, size: 4 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:1 f:0 08106000, pos 08106132, size: 232 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write old buf t:0 f:0 00000000, pos 0808FCBA, size: 2 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write new buf t:1 f:0 00000000, pos 08107730, size: 4 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write new buf t:1 f:0 08108000, pos 08108132, size: 232 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 write new buf t:0 f:0 00000000, pos 0808FCBA, size: 2 file: 0, size: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http write filter: l:0 f:0 s:1464
2005/12/05 20:29:31 [debug] 74468#0: *1 malloc: 080BB300:256
2005/12/05 20:29:31 [debug] 74468#0: *1 writev: 1464 of 1464
2005/12/05 20:29:31 [debug] 74468#0: *1 http write filter 00000000
2005/12/05 20:29:31 [debug] 74468#0: *1 ssi include: "/footer.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 http subrequest "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http phase handler
2005/12/05 20:29:31 [debug] 74468#0: *1 find location for "/footer.html"
2005/12/05 20:29:31 [debug] 74468#0: *1 find location: "/"
2005/12/05 20:29:31 [debug] 74468#0: *1 using configuration "/"
2005/12/05 20:29:31 [debug] 74468#0: *1 http cl:-1 max:1048576
2005/12/05 20:29:31 [debug] 74468#0: *1 http init upstream, client timer: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Accept: */*"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "Accept-Language: ru"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header: "User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322; .NET CLR 2.0.50727)"
2005/12/05 20:29:31 [debug] 74468#0: *1 http proxy header:
"GET /test2.html HTTP/1.0
Host: localhost:40080
Connection: close
Accept: */*
Accept-Language: ru
User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322; .NET CLR 2.0.50727)

"
2005/12/05 20:29:31 [debug] 74468#0: *1 http cleanup add: 08107F4C
2005/12/05 20:29:31 [debug] 74468#0: *1 socket 11
2005/12/05 20:29:31 [debug] 74468#0: *1 connect to 127.0.0.1:40080, fd:11 #7
2005/12/05 20:29:31 [debug] 74468#0: *1 kevent set event: 11: ft:-1 fl:0025
2005/12/05 20:29:31 [debug] 74468#0: *1 connected
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream connect: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream send request
2005/12/05 20:29:31 [debug] 74468#0: *1 chain writer buf size: 212
2005/12/05 20:29:31 [debug] 74468#0: *1 chain writer in: 08107780
2005/12/05 20:29:31 [debug] 74468#0: *1 writev: 212 of 212
2005/12/05 20:29:31 [debug] 74468#0: *1 chain writer out: 00000000
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer add: 11: 60000:4227433027
2005/12/05 20:29:31 [debug] 74468#0: *1 http subrequest done "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0 state: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 parse: -2, looked: 0 0810823F-0810824F
2005/12/05 20:29:31 [debug] 74468#0: *1 saved: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 http postpone filter "/footer.html?" 081076EC
2005/12/05 20:29:31 [debug] 74468#0: *1 copy filter: -2 "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 pipe write downstream done
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer: 10, old: 4227433001, new: 4227433027
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream exit: 00000000
2005/12/05 20:29:31 [debug] 74468#0: *1 finalize http upstream request: 0
2005/12/05 20:29:31 [debug] 74468#0: *1 finalize http proxy request
2005/12/05 20:29:31 [debug] 74468#0: *1 close http upstream connection: 10
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer del: 10: 4227433001
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream temp fd: -1
2005/12/05 20:29:31 [debug] 74468#0: *1 http finalize request: 0, "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 event timer: 7, old: 4227432944, new: 4227433027
2005/12/05 20:29:31 [debug] 74468#0: *1 http finalize non-active request: "/footer.html?"
2005/12/05 20:29:31 [debug] 74468#0: *1 http upstream check client, write event:1, "/footer.html"
2005/12/05 20:29:32 [debug] 74468#0: *1 http upstream process header
2005/12/05 20:29:32 [debug] 74468#0: *1 malloc: 08109000:4096
2005/12/05 20:29:32 [debug] 74468#0: *1 malloc: 0810A000:4096
2005/12/05 20:29:32 [debug] 74468#0: *1 recv: eof:0, avail:591, err:0
2005/12/05 20:29:32 [debug] 74468#0: *1 recv: fd:11 591 of 4096
2005/12/05 20:29:32 [debug] 74468#0: *1 http proxy status 0 "200 OK"
2005/12/05 20:29:32 [debug] 74468#0: *1 http proxy header: "Date: Mon, 05 Dec 2005 17:29:31 GMT"
2005/12/05 20:29:32 [debug] 74468#0: *1 http proxy header: "Server: Apache/1.3.33 (Unix) mod_deflate/1.0.21 mod_accel/1.0.34"
2005/12/05 20:29:32 [debug] 74468#0: *1 http proxy header: "Last-Modified: Sat, 03 Dec 2005 14:15:35 GMT"
2005/12/05 20:29:32 [debug] 74468#0: *1 http proxy header: "ETag: "10808d-11d-51c5efc0""
2005/12/05 20:29:32 [debug] 74468#0: *1 http proxy header: "Accept-Ranges: bytes"
2005/12/05 20:29:32 [debug] 74468#0: *1 http proxy header: "Content-Length: 285"
2005/12/05 20:29:32 [debug] 74468#0: *1 http proxy header: "Content-Type: text/html; charset=windows-1251"
2005/12/05 20:29:32 [debug] 74468#0: *1 http proxy header: "Connection: close"
2005/12/05 20:29:32 [debug] 74468#0: *1 http proxy header done
2005/12/05 20:29:32 [debug] 74468#0: *1 http upstream process upstream
2005/12/05 20:29:32 [debug] 74468#0: *1 pipe read upstream: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 pipe preread: 285
2005/12/05 20:29:32 [debug] 74468#0: *1 pipe buf free s:0 t:1 f:0 08109000, pos 08109132, size: 285 file: 0, size: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 pipe write downstream: 1
2005/12/05 20:29:32 [debug] 74468#0: *1 pipe write busy: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 pipe write: out:00000000, f:0
2005/12/05 20:29:32 [debug] 74468#0: *1 pipe read upstream: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 pipe buf free s:0 t:1 f:0 08109000, pos 08109132, size: 285 file: 0, size: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 event timer del: 11: 4227433027
2005/12/05 20:29:32 [debug] 74468#0: *1 event timer add: 11: 60000:4227433887
2005/12/05 20:29:32 [debug] 74468#0: *1 http upstream process upstream
2005/12/05 20:29:32 [debug] 74468#0: *1 pipe read upstream: 1
2005/12/05 20:29:32 [debug] 74468#0: *1 pipe buf free s:0 t:1 f:0 08109000, pos 08109132, size: 285 file: 0, size: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 input buf #0
2005/12/05 20:29:32 [debug] 74468#0: *1 pipe write downstream: 1
2005/12/05 20:29:32 [debug] 74468#0: *1 pipe write downstream flush in
2005/12/05 20:29:32 [debug] 74468#0: *1 http output filter "/footer.html?"
2005/12/05 20:29:32 [debug] 74468#0: *1 copy filter: "/footer.html?"
2005/12/05 20:29:32 [debug] 74468#0: *1 http ssi filter "/footer.html"
2005/12/05 20:29:32 [debug] 74468#0: *1 saved: 0 state: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 parse: 0, looked: 0 08109132-0810921A
2005/12/05 20:29:32 [debug] 74468#0: *1 saved: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 http postpone filter "/footer.html?" 08103B48
2005/12/05 20:29:32 [debug] 74468#0: *1 http postpone filter out "/footer.html?"
2005/12/05 20:29:32 [debug] 74468#0: *1 http chunk: 232
2005/12/05 20:29:32 [debug] 74468#0: *1 write new buf t:1 f:0 00000000, pos 0810A4DC, size: 4 file: 0, size: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 write new buf t:1 f:0 08109000, pos 08109132, size: 232 file: 0, size: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 write new buf t:0 f:0 00000000, pos 0808FCBA, size: 2 file: 0, size: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 http write filter: l:0 f:0 s:238
2005/12/05 20:29:32 [debug] 74468#0: *1 ssi include: "/footer.html"
2005/12/05 20:29:32 [debug] 74468#0: *1 http subrequest "/footer.html?"
2005/12/05 20:29:32 [debug] 74468#0: *1 http phase handler
2005/12/05 20:29:32 [debug] 74468#0: *1 find location for "/footer.html"
2005/12/05 20:29:32 [debug] 74468#0: *1 find location: "/"
2005/12/05 20:29:32 [debug] 74468#0: *1 using configuration "/"
2005/12/05 20:29:32 [debug] 74468#0: *1 http cl:-1 max:1048576
2005/12/05 20:29:32 [debug] 74468#0: *1 http init upstream, client timer: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 http proxy header: "Accept: */*"
2005/12/05 20:29:32 [debug] 74468#0: *1 http proxy header: "Accept-Language: ru"
2005/12/05 20:29:32 [debug] 74468#0: *1 http proxy header: "User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322; .NET CLR 2.0.50727)"
2005/12/05 20:29:32 [debug] 74468#0: *1 http proxy header:
"GET /test2.html HTTP/1.0
Host: localhost:40080
Connection: close
Accept: */*
Accept-Language: ru
User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322; .NET CLR 2.0.50727)

"
2005/12/05 20:29:32 [debug] 74468#0: *1 http cleanup add: 0810ACD8
2005/12/05 20:29:32 [debug] 74468#0: *1 socket 10
2005/12/05 20:29:32 [debug] 74468#0: *1 connect to 127.0.0.1:40080, fd:10 #8
2005/12/05 20:29:32 [debug] 74468#0: *1 kevent set event: 10: ft:-1 fl:0025
2005/12/05 20:29:32 [debug] 74468#0: *1 connected
2005/12/05 20:29:32 [debug] 74468#0: *1 http upstream connect: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 http upstream send request
2005/12/05 20:29:32 [debug] 74468#0: *1 chain writer buf size: 212
2005/12/05 20:29:32 [debug] 74468#0: *1 chain writer in: 080C4E88
2005/12/05 20:29:32 [debug] 74468#0: *1 writev: 212 of 212
2005/12/05 20:29:32 [debug] 74468#0: *1 chain writer out: 00000000
2005/12/05 20:29:32 [debug] 74468#0: *1 event timer add: 10: 60000:4227433890
2005/12/05 20:29:32 [debug] 74468#0: *1 http subrequest done "/footer.html?"
2005/12/05 20:29:32 [debug] 74468#0: *1 saved: 0 state: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 parse: -2, looked: 0 0810923F-0810924F
2005/12/05 20:29:32 [debug] 74468#0: *1 saved: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 http postpone filter "/footer.html?" 080C4E80
2005/12/05 20:29:32 [debug] 74468#0: *1 copy filter: -2 "/footer.html?"
2005/12/05 20:29:32 [debug] 74468#0: *1 pipe write downstream done
2005/12/05 20:29:32 [debug] 74468#0: *1 event timer: 11, old: 4227433887, new: 4227433890
2005/12/05 20:29:32 [debug] 74468#0: *1 http upstream exit: 00000000
2005/12/05 20:29:32 [debug] 74468#0: *1 finalize http upstream request: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 finalize http proxy request
2005/12/05 20:29:32 [debug] 74468#0: *1 close http upstream connection: 11
2005/12/05 20:29:32 [debug] 74468#0: *1 event timer del: 11: 4227433887
2005/12/05 20:29:32 [debug] 74468#0: *1 http upstream temp fd: -1
2005/12/05 20:29:32 [debug] 74468#0: *1 http finalize request: 0, "/footer.html?"
2005/12/05 20:29:32 [debug] 74468#0: *1 event timer del: 7: 4227432944
2005/12/05 20:29:32 [debug] 74468#0: *1 event timer add: 7: 60000:4227433890
2005/12/05 20:29:32 [debug] 74468#0: *1 http finalize non-active request: "/footer.html?"
2005/12/05 20:29:32 [debug] 74468#0: *1 http upstream check client, write event:1, "/footer.html"
2005/12/05 20:29:32 [info] 74468#0: *1 kevent() reported that client closed prematurely connection, so upstream connection is closed too (54: Connection reset by peer) while sending to client, client: 80.250.162.142, server: dore.ru, URL: "/test2.html", host: "booksn.dore.ru"
2005/12/05 20:29:32 [debug] 74468#0: *1 finalize http upstream request: 499
2005/12/05 20:29:32 [debug] 74468#0: *1 finalize http proxy request
2005/12/05 20:29:32 [debug] 74468#0: *1 close http upstream connection: 10
2005/12/05 20:29:32 [debug] 74468#0: *1 event timer del: 10: 4227433890
2005/12/05 20:29:32 [debug] 74468#0: *1 http finalize request: 499, "/footer.html?"
2005/12/05 20:29:32 [debug] 74468#0: *1 http close request
2005/12/05 20:29:32 [debug] 74468#0: *1 http log handler
2005/12/05 20:29:32 [debug] 74468#0: *1 free: 08100000
2005/12/05 20:29:32 [debug] 74468#0: *1 free: 08102000
2005/12/05 20:29:32 [debug] 74468#0: *1 free: 08104000
2005/12/05 20:29:32 [debug] 74468#0: *1 free: 08106000
2005/12/05 20:29:32 [debug] 74468#0: *1 free: 08108000
2005/12/05 20:29:32 [debug] 74468#0: *1 free: 08109000
2005/12/05 20:29:32 [debug] 74468#0: *1 free: 080C4000, unused: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 free: 08101000, unused: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 free: 08103000, unused: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 free: 08105000, unused: 0
2005/12/05 20:29:32 [debug] 74468#0: *1 free: 08107000, unused: 3
2005/12/05 20:29:32 [debug] 74468#0: *1 free: 0810A000, unused: 527
2005/12/05 20:29:32 [debug] 74468#0: *1 close http connection: 7
2005/12/05 20:29:32 [debug] 74468#0: *1 event timer del: 7: 4227433890
2005/12/05 20:29:32 [debug] 74468#0: *1 free: 080B6000
2005/12/05 20:29:32 [debug] 74468#0: *1 free: 080B6400
2005/12/05 20:29:32 [debug] 74468#0: *1 free: 080BB200, unused: 68
2005/12/05 20:29:32 [debug] 74468#0: *1 free: 080BB300, unused: 96

