fastcgi causing 502 errors? [was: alternating 404 and 200]

[Nuno Magalhães]

I'm running nginx/0.8.10 and PHP 5.2.10 on Debian Sid with a
2.6.30-1-amd64 kernel. This is my desktop machine, mainly for testing
and screwing around with configurations (although screwing up also
comes to mind). I've recently upgraded my system through Debian's
apt-get dist-upgrade, which reset my fastcgi_params.

Nginx spawns two worker processes, php-fastcgi spawns 4, nginx is the
only http server i'm running, i have no proxies or anything.

If i have an URL like http://localhost:8080/site01/file.php?var=param
and hit the reload button constantly, i'll get a bunch of normal
replies (200), followed by a bunch of 502s, then 200, then 502, ...
The interval is about the same, maybe a second or so.

If i use http://localhost:8080/simplefile.html for a simple lorem
ipsum html file i sometimes get the 502 in the first few reloads, but
after that it's just 200.

While doing this, my CPU load doesn't go above ~70% and i've yet to
fully use the first have of its 4GiB RAM. I've stripped my nginx.conf
to the bare minimums, commenting out most stuff (maybe too much), and
i can't get around to it. The access log reports 200s and 502s (and i
have a 502 page that gets sent), the error log shows this:

2009/09/03 19:45:16 [error] 7402#0: *318 recv() failed (104:
Connection reset by peer) while reading response header from upstream,
client: 127.0.0.1, server: localhost, request: "GET
/site01/file.php?var=value HTTP/1.1", upstream:
"fastcgi://127.0.0.1:9000", host: "localhost:8080", referrer:
"http://localhost:8080/site01/file.php?var=value"

The same error over and over. This issue is *similar* to this[1] one,
but the latter got corrected in 0.8.8. I got two logs, one for a 200
and another for a 502, attached. the error starts around line 143. My
nginx.conf and server{}s are in the other post [2].

I don't know how to fix it, any suggestions are most welcome.

TIA,
Nuno Magalhães

[1] http://forum.nginx.org/read.php?2,4704,4722
[2] http://forum.nginx.org/read.php?2,5260

--
()  ascii-rubanda kampajno - kontraŭ html-a retpoŝto
/\  ascii ribbon campaign - against html e-mail
-------------- next part --------------
2009/09/03 20:14:34 [debug] 23696#0: *13 post event 00000000015549C8
2009/09/03 20:14:34 [debug] 23696#0: *13 post event 000000000156E9D8
2009/09/03 20:14:34 [debug] 23696#0: *13 delete posted event 000000000156E9D8
2009/09/03 20:14:34 [debug] 23696#0: *13 http empty handler
2009/09/03 20:14:34 [debug] 23696#0: *13 delete posted event 00000000015549C8
2009/09/03 20:14:34 [debug] 23696#0: *13 http keepalive handler
2009/09/03 20:14:34 [debug] 23696#0: *13 recv: fd:12 447 of 1024
2009/09/03 20:14:34 [debug] 23696#0: *13 malloc: 000000000152DFD0:1248
2009/09/03 20:14:34 [debug] 23696#0: *13 malloc: 000000000152E4C0:4096
2009/09/03 20:14:34 [debug] 23696#0: *13 http process request line
2009/09/03 20:14:34 [debug] 23696#0: *13 http request line: "GET / HTTP/1.1"
2009/09/03 20:14:34 [debug] 23696#0: *13 http uri: "/"
2009/09/03 20:14:34 [debug] 23696#0: *13 http args: ""
2009/09/03 20:14:34 [debug] 23696#0: *13 http exten: ""
2009/09/03 20:14:34 [debug] 23696#0: *13 http process request header line
2009/09/03 20:14:34 [debug] 23696#0: *13 http header: "Host: localhost:8081"
2009/09/03 20:14:34 [debug] 23696#0: *13 http header: "User-Agent: Mozilla/5.0 (X11; U; Linux x86_64; en-US; rv:1.9.0.11) Gecko/2009061317 Iceweasel/3.0.12 (Debian-3.0.12-1)"
2009/09/03 20:14:34 [debug] 23696#0: *13 http header: "Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8"
2009/09/03 20:14:34 [debug] 23696#0: *13 http header: "Accept-Language: en,pt;q=0.7,eo;q=0.3"
2009/09/03 20:14:34 [debug] 23696#0: *13 http header: "Accept-Encoding: gzip,deflate"
2009/09/03 20:14:34 [debug] 23696#0: *13 http header: "Accept-Charset: UTF-8,*"
2009/09/03 20:14:34 [debug] 23696#0: *13 http header: "Keep-Alive: 300"
2009/09/03 20:14:34 [debug] 23696#0: *13 http header: "Connection: keep-alive"
2009/09/03 20:14:34 [debug] 23696#0: *13 http header: "Cookie: PHPSESSID=ea9ce76311e730647d393ae721cc74f7"
2009/09/03 20:14:34 [debug] 23696#0: *13 http header: "Cache-Control: max-age=0"
2009/09/03 20:14:34 [debug] 23696#0: *13 http header done
2009/09/03 20:14:34 [debug] 23696#0: *13 event timer del: 12: 1252005347298
2009/09/03 20:14:34 [debug] 23696#0: *13 generic phase: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 test location: "/"
2009/09/03 20:14:34 [debug] 23696#0: *13 using configuration "/"
2009/09/03 20:14:34 [debug] 23696#0: *13 http cl:-1 max:1048576
2009/09/03 20:14:34 [debug] 23696#0: *13 generic phase: 2
2009/09/03 20:14:34 [debug] 23696#0: *13 post rewrite phase: 3
2009/09/03 20:14:34 [debug] 23696#0: *13 generic phase: 4
2009/09/03 20:14:34 [debug] 23696#0: *13 generic phase: 5
2009/09/03 20:14:34 [debug] 23696#0: *13 access phase: 6
2009/09/03 20:14:34 [debug] 23696#0: *13 access phase: 7
2009/09/03 20:14:34 [debug] 23696#0: *13 post access phase: 8
2009/09/03 20:14:34 [debug] 23696#0: *13 try files phase: 9
2009/09/03 20:14:34 [debug] 23696#0: *13 http script var: "/"
2009/09/03 20:14:34 [debug] 23696#0: *13 try to use file: "/" "/var/www/nginx-default/reagentes/"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script var: "/"
2009/09/03 20:14:34 [debug] 23696#0: *13 try to use file: "/" "/var/www/nginx-default/reagentes/"
2009/09/03 20:14:34 [debug] 23696#0: *13 try file uri: "/"
2009/09/03 20:14:34 [debug] 23696#0: *13 content phase: 10
2009/09/03 20:14:34 [debug] 23696#0: *13 open index "/var/www/nginx-default/reagentes/index.html"
2009/09/03 20:14:34 [debug] 23696#0: *13 internal redirect: "/index.html?"
2009/09/03 20:14:34 [debug] 23696#0: *13 generic phase: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 test location: "/"
2009/09/03 20:14:34 [debug] 23696#0: *13 test location: "50x.html"
2009/09/03 20:14:34 [debug] 23696#0: *13 test location: "favicon.ico"
2009/09/03 20:14:34 [debug] 23696#0: *13 test location: ~ "\.(php|html)$"
2009/09/03 20:14:34 [debug] 23696#0: *13 using configuration "\.(php|html)$"
2009/09/03 20:14:34 [debug] 23696#0: *13 http cl:-1 max:1048576
2009/09/03 20:14:34 [debug] 23696#0: *13 generic phase: 2
2009/09/03 20:14:34 [debug] 23696#0: *13 post rewrite phase: 3
2009/09/03 20:14:34 [debug] 23696#0: *13 generic phase: 4
2009/09/03 20:14:34 [debug] 23696#0: *13 generic phase: 5
2009/09/03 20:14:34 [debug] 23696#0: *13 access phase: 6
2009/09/03 20:14:34 [debug] 23696#0: *13 access phase: 7
2009/09/03 20:14:34 [debug] 23696#0: *13 post access phase: 8
2009/09/03 20:14:34 [debug] 23696#0: *13 try files phase: 9
2009/09/03 20:14:34 [debug] 23696#0: *13 malloc: 0000000001524BE0:4096
2009/09/03 20:14:34 [debug] 23696#0: *13 http init upstream, client timer: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "SCRIPT_FILENAME"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "/var/www/nginx-default/reagentes"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script var: "/index.html"
2009/09/03 20:14:34 [debug] 23696#0: *13 fastcgi param: "SCRIPT_FILENAME: /var/www/nginx-default/reagentes/index.html"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "DOCUMENT_ROOT/var/www/nginx-default/reagentes"
2009/09/03 20:14:34 [debug] 23696#0: *13 fastcgi param: "DOCUMENT_ROOT: /var/www/nginx-default/reagentes"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "QUERY_STRING"
2009/09/03 20:14:34 [debug] 23696#0: *13 fastcgi param: "QUERY_STRING: "
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "REQUEST_METHOD"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script var: "GET"
2009/09/03 20:14:34 [debug] 23696#0: *13 fastcgi param: "REQUEST_METHOD: GET"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "CONTENT_TYPE"
2009/09/03 20:14:34 [debug] 23696#0: *13 fastcgi param: "CONTENT_TYPE: "
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "CONTENT_LENGTH"
2009/09/03 20:14:34 [debug] 23696#0: *13 fastcgi param: "CONTENT_LENGTH: "
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "SCRIPT_NAME"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script var: "/index.html"
2009/09/03 20:14:34 [debug] 23696#0: *13 fastcgi param: "SCRIPT_NAME: /index.html"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "REQUEST_URI"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script var: "/"
2009/09/03 20:14:34 [debug] 23696#0: *13 fastcgi param: "REQUEST_URI: /"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "DOCUMENT_URI"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script var: "/index.html"
2009/09/03 20:14:34 [debug] 23696#0: *13 fastcgi param: "DOCUMENT_URI: /index.html"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "SERVER_PROTOCOL"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script var: "HTTP/1.1"
2009/09/03 20:14:34 [debug] 23696#0: *13 fastcgi param: "SERVER_PROTOCOL: HTTP/1.1"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "GATEWAY_INTERFACECGI/1.1"
2009/09/03 20:14:34 [debug] 23696#0: *13 fastcgi param: "GATEWAY_INTERFACE: CGI/1.1"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "SERVER_SOFTWARE"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "nginx/"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script var: "0.8.10"
2009/09/03 20:14:34 [debug] 23696#0: *13 fastcgi param: "SERVER_SOFTWARE: nginx/0.8.10"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "REMOTE_ADDR"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script var: "127.0.0.1"
2009/09/03 20:14:34 [debug] 23696#0: *13 fastcgi param: "REMOTE_ADDR: 127.0.0.1"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "REMOTE_PORT"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script var: "43298"
2009/09/03 20:14:34 [debug] 23696#0: *13 fastcgi param: "REMOTE_PORT: 43298"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "SERVER_ADDR"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script var: "127.0.0.1"
2009/09/03 20:14:34 [debug] 23696#0: *13 fastcgi param: "SERVER_ADDR: 127.0.0.1"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "SERVER_PORT"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script var: "8081"
2009/09/03 20:14:34 [debug] 23696#0: *13 fastcgi param: "SERVER_PORT: 8081"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "SERVER_NAME"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script var: "localhost"
2009/09/03 20:14:34 [debug] 23696#0: *13 fastcgi param: "SERVER_NAME: localhost"
2009/09/03 20:14:34 [debug] 23696#0: *13 http script copy: "REDIRECT_STATUS200"
2009/09/03 20:14:34 [debug] 23696#0: *13 fastcgi param: "REDIRECT_STATUS: 200"
2009/09/03 20:14:34 [debug] 23696#0: *13 http cleanup add: 000000000152F4A8
2009/09/03 20:14:34 [debug] 23696#0: *13 get rr peer, try: 1
2009/09/03 20:14:34 [debug] 23696#0: *13 socket 13
2009/09/03 20:14:34 [debug] 23696#0: *13 epoll add connection: fd:13 ev:80000005
2009/09/03 20:14:34 [debug] 23696#0: *13 connect to 127.0.0.1:8888, fd:13 #15
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream connect: -2
2009/09/03 20:14:34 [debug] 23696#0: *13 event timer add: 13: 60000:1252005334963
2009/09/03 20:14:34 [debug] 23696#0: *13 post event 000000000156EA40
2009/09/03 20:14:34 [debug] 23696#0: *13 delete posted event 000000000156EA40
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream request: "/index.html?"
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream send request handler
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream send request
2009/09/03 20:14:34 [debug] 23696#0: *13 chain writer buf fl:0 s:936
2009/09/03 20:14:34 [debug] 23696#0: *13 chain writer in: 0000000001525128
2009/09/03 20:14:34 [debug] 23696#0: *13 writev: 936
2009/09/03 20:14:34 [debug] 23696#0: *13 chain writer out: 0000000000000000
2009/09/03 20:14:34 [debug] 23696#0: *13 event timer del: 13: 1252005334963
2009/09/03 20:14:34 [debug] 23696#0: *13 event timer add: 13: 60000:1252005334965
2009/09/03 20:14:34 [debug] 23696#0: *13 post event 0000000001554A30
2009/09/03 20:14:34 [debug] 23696#0: *13 post event 000000000156EA40
2009/09/03 20:14:34 [debug] 23696#0: *13 delete posted event 000000000156EA40
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream request: "/index.html?"
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream dummy handler
2009/09/03 20:14:34 [debug] 23696#0: *13 delete posted event 0000000001554A30
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream request: "/index.html?"
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream process header
2009/09/03 20:14:34 [debug] 23696#0: *13 malloc: 0000000001525BF0:4096
2009/09/03 20:14:34 [debug] 23696#0: *13 recv: fd:13 1976 of 4096
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi record byte: 01
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi record byte: 06
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi record byte: 00
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi record byte: 01
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi record byte: 07
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi record byte: A9
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi record byte: 07
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi record byte: 00
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi record length: 1961
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi parser: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi header: "Expires: Thu, 19 Nov 1981 08:52:00 GMT"
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi parser: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi header: "Cache-Control: no-store, no-cache, must-revalidate, post-check=0, pre-check=0"
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi parser: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi header: "Pragma: no-cache"
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi parser: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi header: "Vary: Accept"
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi parser: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi header: "Content-Language: pt-PT"
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi parser: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi header: "Content-Type: application/xhtml+xml; charset=utf-8"
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi parser: 1
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi header done
2009/09/03 20:14:34 [debug] 23696#0: *13 mod_zip: entering header filter
2009/09/03 20:14:34 [debug] 23696#0: *13 xslt filter header
2009/09/03 20:14:34 [debug] 23696#0: *13 HTTP/1.1 200 OK
Server: nginx
Date: Thu, 03 Sep 2009 19:14:34 GMT
Content-Type: application/xhtml+xml; charset=utf-8
Transfer-Encoding: chunked
Connection: keep-alive
Vary: Accept-Encoding
Expires: Thu, 19 Nov 1981 08:52:00 GMT
Cache-Control: no-store, no-cache, must-revalidate, post-check=0, pre-check=0
Pragma: no-cache
Vary: Accept
Content-Language: pt-PT
Content-Encoding: gzip

2009/09/03 20:14:34 [debug] 23696#0: *13 write new buf t:1 f:0 0000000001525580, pos 0000000001525580, size: 398 file: 0, size: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 http write filter: l:0 f:0 s:398
2009/09/03 20:14:34 [debug] 23696#0: *13 http cacheable: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream process upstream
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe read upstream: 1
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe preread: 1738
2009/09/03 20:14:34 [debug] 23696#0: *13 readv: 1:2120
2009/09/03 20:14:34 [debug] 23696#0: *13 readv() not ready (11: Resource temporarily unavailable)
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe recv chain: -2
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe buf free s:0 t:1 f:0 0000000001525BF0, pos 0000000001525CDE, size: 1738 file: 0, size: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe write downstream: 1
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe write busy: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe write: out:0000000000000000, f:0
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe read upstream: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe buf free s:0 t:1 f:0 0000000001525BF0, pos 0000000001525CDE, size: 1738 file: 0, size: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 event timer: 13, old: 1252005334965, new: 1252005334988
2009/09/03 20:14:34 [debug] 23696#0: *13 post event 0000000001554A30
2009/09/03 20:14:34 [debug] 23696#0: *13 post event 000000000156EA40
2009/09/03 20:14:34 [debug] 23696#0: *13 delete posted event 000000000156EA40
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream request: "/index.html?"
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream dummy handler
2009/09/03 20:14:34 [debug] 23696#0: *13 delete posted event 0000000001554A30
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream request: "/index.html?"
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream process upstream
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe read upstream: 1
2009/09/03 20:14:34 [debug] 23696#0: *13 readv: 1:2120
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe recv chain: 16
2009/09/03 20:14:34 [debug] 23696#0: *13 readv: 1:2104
2009/09/03 20:14:34 [debug] 23696#0: *13 readv() not ready (11: Resource temporarily unavailable)
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe recv chain: -2
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe buf free s:0 t:1 f:0 0000000001525BF0, pos 0000000001525CDE, size: 1754 file: 0, size: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe write downstream: 1
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe write busy: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe write: out:0000000000000000, f:0
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe read upstream: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe buf free s:0 t:1 f:0 0000000001525BF0, pos 0000000001525CDE, size: 1754 file: 0, size: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 event timer: 13, old: 1252005334965, new: 1252005334989
2009/09/03 20:14:34 [debug] 23696#0: *13 post event 0000000001554A30
2009/09/03 20:14:34 [debug] 23696#0: *13 post event 000000000156EA40
2009/09/03 20:14:34 [debug] 23696#0: *13 delete posted event 000000000156EA40
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream request: "/index.html?"
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream dummy handler
2009/09/03 20:14:34 [debug] 23696#0: *13 delete posted event 0000000001554A30
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream request: "/index.html?"
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream process upstream
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe read upstream: 1
2009/09/03 20:14:34 [debug] 23696#0: *13 readv: 1:2104
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe recv chain: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe buf free s:0 t:1 f:0 0000000001525BF0, pos 0000000001525CDE, size: 1754 file: 0, size: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 input buf #0 0000000001525CDE
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi record byte: 01
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi record byte: 03
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi record byte: 00
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi record byte: 01
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi record byte: 00
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi record byte: 08
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi record byte: 00
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi record byte: 00
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi record length: 8
2009/09/03 20:14:34 [debug] 23696#0: *13 http fastcgi sent end request
2009/09/03 20:14:34 [debug] 23696#0: *13 input buf 0000000001525CDE 1731
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe write downstream: 1
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe write downstream flush in
2009/09/03 20:14:34 [debug] 23696#0: *13 http output filter "/index.html?"
2009/09/03 20:14:34 [debug] 23696#0: *13 copy filter: "/index.html?"
2009/09/03 20:14:34 [debug] 23696#0: *13 mod_zip: entering body filter
2009/09/03 20:14:34 [debug] 23696#0: *13 xslt filter body
2009/09/03 20:14:34 [debug] 23696#0: *13 http postpone filter "/index.html?" 0000000001525878
2009/09/03 20:14:34 [debug] 23696#0: *13 http gzip filter
2009/09/03 20:14:34 [debug] 23696#0: *13 malloc: 00000000015888B0:270336
2009/09/03 20:14:34 [debug] 23696#0: *13 gzip alloc: n:1 s:5928 a:8192 p:00000000015888B0
2009/09/03 20:14:34 [debug] 23696#0: *13 gzip alloc: n:32768 s:2 a:65536 p:000000000158A8B0
2009/09/03 20:14:34 [debug] 23696#0: *13 gzip alloc: n:32768 s:2 a:65536 p:000000000159A8B0
2009/09/03 20:14:34 [debug] 23696#0: *13 gzip alloc: n:32768 s:2 a:65536 p:00000000015AA8B0
2009/09/03 20:14:34 [debug] 23696#0: *13 gzip alloc: n:16384 s:4 a:65536 p:00000000015BA8B0
2009/09/03 20:14:34 [debug] 23696#0: *13 gzip in: 00000000015258F8
2009/09/03 20:14:34 [debug] 23696#0: *13 gzip in_buf:0000000001525828 ni:0000000001525CDE ai:1731
2009/09/03 20:14:34 [debug] 23696#0: *13 malloc: 0000000001526C00:4096
2009/09/03 20:14:34 [debug] 23696#0: *13 deflate in: ni:0000000001525CDE no:0000000001526C00 ai:1731 ao:4096 fl:0 redo:0
2009/09/03 20:14:34 [debug] 23696#0: *13 deflate out: ni:00000000015263A1 no:0000000001526C00 ai:0 ao:4096 rc:0
2009/09/03 20:14:34 [debug] 23696#0: *13 gzip in_buf:0000000001525828 pos:0000000001525CDE
2009/09/03 20:14:34 [debug] 23696#0: *13 gzip in: 0000000000000000
2009/09/03 20:14:34 [debug] 23696#0: *13 copy filter: 0 "/index.html?"
2009/09/03 20:14:34 [debug] 23696#0: *13 pipe write downstream done
2009/09/03 20:14:34 [debug] 23696#0: *13 event timer: 13, old: 1252005334965, new: 1252005334989
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream exit: 0000000000000000
2009/09/03 20:14:34 [debug] 23696#0: *13 finalize http upstream request: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 finalize http fastcgi request
2009/09/03 20:14:34 [debug] 23696#0: *13 free rr peer 1 0
2009/09/03 20:14:34 [debug] 23696#0: *13 close http upstream connection: 13
2009/09/03 20:14:34 [debug] 23696#0: *13 event timer del: 13: 1252005334965
2009/09/03 20:14:34 [debug] 23696#0: *13 http upstream temp fd: -1
2009/09/03 20:14:34 [debug] 23696#0: *13 http output filter "/index.html?"
2009/09/03 20:14:34 [debug] 23696#0: *13 copy filter: "/index.html?"
2009/09/03 20:14:34 [debug] 23696#0: *13 mod_zip: entering body filter
2009/09/03 20:14:34 [debug] 23696#0: *13 xslt filter body
2009/09/03 20:14:34 [debug] 23696#0: *13 http postpone filter "/index.html?" 00007FFF661C16F0
2009/09/03 20:14:34 [debug] 23696#0: *13 http gzip filter
2009/09/03 20:14:34 [debug] 23696#0: *13 gzip in: 00000000015259B8
2009/09/03 20:14:34 [debug] 23696#0: *13 gzip in_buf:0000000001525968 ni:0000000000000000 ai:0
2009/09/03 20:14:34 [debug] 23696#0: *13 deflate in: ni:0000000000000000 no:0000000001526C00 ai:0 ao:4096 fl:4 redo:0
2009/09/03 20:14:34 [debug] 23696#0: *13 deflate out: ni:0000000000000000 no:0000000001526EE4 ai:0 ao:3356 rc:1
2009/09/03 20:14:34 [debug] 23696#0: *13 gzip in_buf:0000000001525968 pos:0000000000000000
2009/09/03 20:14:34 [debug] 23696#0: *13 free: 00000000015888B0
2009/09/03 20:14:34 [debug] 23696#0: *13 http chunk: 10
2009/09/03 20:14:34 [debug] 23696#0: *13 http chunk: 748
2009/09/03 20:14:34 [debug] 23696#0: *13 write old buf t:1 f:0 0000000001525580, pos 0000000001525580, size: 398 file: 0, size: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 write new buf t:1 f:0 0000000000000000, pos 0000000001525AA8, size: 5 file: 0, size: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 write new buf t:0 f:0 0000000000000000, pos 0000000000685BA8, size: 10 file: 0, size: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 write new buf t:1 f:0 0000000001526C00, pos 0000000001526C00, size: 748 file: 0, size: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 write new buf t:0 f:0 0000000000000000, pos 000000000046EED8, size: 7 file: 0, size: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 http write filter: l:1 f:1 s:1168
2009/09/03 20:14:34 [debug] 23696#0: *13 http write filter limit 0
2009/09/03 20:14:34 [debug] 23696#0: *13 writev: 1168
2009/09/03 20:14:34 [debug] 23696#0: *13 http write filter 0000000000000000
2009/09/03 20:14:34 [debug] 23696#0: *13 copy filter: 0 "/index.html?"
2009/09/03 20:14:34 [debug] 23696#0: *13 http finalize request: 0, "/index.html?" 1
2009/09/03 20:14:34 [debug] 23696#0: *13 set http keepalive handler
2009/09/03 20:14:34 [debug] 23696#0: *13 http close request
2009/09/03 20:14:34 [debug] 23696#0: *13 http log handler
2009/09/03 20:14:34 [debug] 23696#0: *13 malloc: 0000000001527C10:4096
2009/09/03 20:14:34 [debug] 23696#0: *13 free: 0000000001526C00
2009/09/03 20:14:34 [debug] 23696#0: *13 free: 0000000000000000
2009/09/03 20:14:34 [debug] 23696#0: *13 free: 0000000001525BF0
2009/09/03 20:14:34 [debug] 23696#0: *13 free: 000000000152E4C0, unused: 0
2009/09/03 20:14:34 [debug] 23696#0: *13 free: 0000000001524BE0, unused: 130
2009/09/03 20:14:34 [debug] 23696#0: *13 free: 0000000001527C10, unused: 3818
2009/09/03 20:14:34 [debug] 23696#0: *13 event timer add: 12: 75000:1252005349989
2009/09/03 20:14:34 [debug] 23696#0: *13 free: 000000000152DFD0
2009/09/03 20:14:34 [debug] 23696#0: *13 free: 0000000001550470
2009/09/03 20:14:34 [debug] 23696#0: *13 hc free: 0000000000000000 0
2009/09/03 20:14:34 [debug] 23696#0: *13 hc busy: 0000000000000000 0
2009/09/03 20:14:34 [debug] 23696#0: *13 tcp_nodelay
2009/09/03 20:14:34 [debug] 23696#0: *13 post event 00000000015549C8
2009/09/03 20:14:34 [debug] 23696#0: *13 delete posted event 00000000015549C8
2009/09/03 20:14:34 [debug] 23696#0: *13 http keepalive handler
2009/09/03 20:14:34 [debug] 23696#0: *13 malloc: 0000000001550470:1024
2009/09/03 20:14:34 [debug] 23696#0: *13 recv: fd:12 -1 of 1024
2009/09/03 20:14:34 [debug] 23696#0: *13 recv() not ready (11: Resource temporarily unavailable)
2009/09/03 20:14:35 [debug] 23696#0: *13 post event 00000000015549C8
2009/09/03 20:14:35 [debug] 23696#0: *13 post event 000000000156E9D8
2009/09/03 20:14:35 [debug] 23696#0: *13 delete posted event 000000000156E9D8
2009/09/03 20:14:35 [debug] 23696#0: *13 http empty handler
2009/09/03 20:14:35 [debug] 23696#0: *13 delete posted event 00000000015549C8
2009/09/03 20:14:35 [debug] 23696#0: *13 http keepalive handler
2009/09/03 20:14:35 [debug] 23696#0: *13 recv: fd:12 494 of 1024
2009/09/03 20:14:35 [debug] 23696#0: *13 malloc: 000000000152DFD0:1248
2009/09/03 20:14:35 [debug] 23696#0: *13 malloc: 000000000152E4C0:4096
2009/09/03 20:14:35 [debug] 23696#0: *13 http process request line
2009/09/03 20:14:35 [debug] 23696#0: *13 http request line: "GET /stilo.css HTTP/1.1"
2009/09/03 20:14:35 [debug] 23696#0: *13 http uri: "/stilo.css"
2009/09/03 20:14:35 [debug] 23696#0: *13 http args: ""
2009/09/03 20:14:35 [debug] 23696#0: *13 http exten: "css"
2009/09/03 20:14:35 [debug] 23696#0: *13 http process request header line
2009/09/03 20:14:35 [debug] 23696#0: *13 http header: "Host: localhost:8081"
2009/09/03 20:14:35 [debug] 23696#0: *13 http header: "User-Agent: Mozilla/5.0 (X11; U; Linux x86_64; en-US; rv:1.9.0.11) Gecko/2009061317 Iceweasel/3.0.12 (Debian-3.0.12-1)"
2009/09/03 20:14:35 [debug] 23696#0: *13 http header: "Accept: text/css,*/*;q=0.1"
2009/09/03 20:14:35 [debug] 23696#0: *13 http header: "Accept-Language: en,pt;q=0.7,eo;q=0.3"
2009/09/03 20:14:35 [debug] 23696#0: *13 http header: "Accept-Encoding: gzip,deflate"
2009/09/03 20:14:35 [debug] 23696#0: *13 http header: "Accept-Charset: UTF-8,*"
2009/09/03 20:14:35 [debug] 23696#0: *13 http header: "Keep-Alive: 300"
2009/09/03 20:14:35 [debug] 23696#0: *13 http header: "Connection: keep-alive"
2009/09/03 20:14:35 [debug] 23696#0: *13 http header: "Referer: http://localhost:8081/"
2009/09/03 20:14:35 [debug] 23696#0: *13 http header: "Cookie: PHPSESSID=ea9ce76311e730647d393ae721cc74f7"
2009/09/03 20:14:35 [debug] 23696#0: *13 http header: "If-Modified-Since: Thu, 03 Sep 2009 14:22:10 GMT"
2009/09/03 20:14:35 [debug] 23696#0: *13 http header: "Cache-Control: max-age=0"
2009/09/03 20:14:35 [debug] 23696#0: *13 http header done
2009/09/03 20:14:35 [debug] 23696#0: *13 event timer del: 12: 1252005349989
2009/09/03 20:14:35 [debug] 23696#0: *13 generic phase: 0
2009/09/03 20:14:35 [debug] 23696#0: *13 test location: "/"
2009/09/03 20:14:35 [debug] 23696#0: *13 test location: "50x.html"
2009/09/03 20:14:35 [debug] 23696#0: *13 test location: "favicon.ico"
2009/09/03 20:14:35 [debug] 23696#0: *13 test location: ~ "\.(php|html)$"
2009/09/03 20:14:35 [debug] 23696#0: *13 using configuration "/"
2009/09/03 20:14:35 [debug] 23696#0: *13 http cl:-1 max:1048576
2009/09/03 20:14:35 [debug] 23696#0: *13 generic phase: 2
2009/09/03 20:14:35 [debug] 23696#0: *13 post rewrite phase: 3
2009/09/03 20:14:35 [debug] 23696#0: *13 generic phase: 4
2009/09/03 20:14:35 [debug] 23696#0: *13 generic phase: 5
2009/09/03 20:14:35 [debug] 23696#0: *13 access phase: 6
2009/09/03 20:14:35 [debug] 23696#0: *13 access phase: 7
2009/09/03 20:14:35 [debug] 23696#0: *13 post access phase: 8
2009/09/03 20:14:35 [debug] 23696#0: *13 try files phase: 9
2009/09/03 20:14:35 [debug] 23696#0: *13 http script var: "/stilo.css"
2009/09/03 20:14:35 [debug] 23696#0: *13 try to use file: "/stilo.css" "/var/www/nginx-default/reagentes/stilo.css"
2009/09/03 20:14:35 [debug] 23696#0: *13 try file uri: "/stilo.css"
2009/09/03 20:14:35 [debug] 23696#0: *13 content phase: 10
2009/09/03 20:14:35 [debug] 23696#0: *13 content phase: 11
2009/09/03 20:14:35 [debug] 23696#0: *13 content phase: 12
2009/09/03 20:14:35 [debug] 23696#0: *13 http filename: "/var/www/nginx-default/reagentes/stilo.css.gz"
2009/09/03 20:14:35 [debug] 23696#0: *13 add cleanup: 000000000152F098
2009/09/03 20:14:35 [debug] 23696#0: *13 content phase: 13
2009/09/03 20:14:35 [debug] 23696#0: *13 http filename: "/var/www/nginx-default/reagentes/stilo.css"
2009/09/03 20:14:35 [debug] 23696#0: *13 add cleanup: 000000000152F0F8
2009/09/03 20:14:35 [debug] 23696#0: *13 http static fd: 13
2009/09/03 20:14:35 [debug] 23696#0: *13 http set discard body
2009/09/03 20:14:35 [debug] 23696#0: *13 http ims:1251987730 lm:1251987730
2009/09/03 20:14:35 [debug] 23696#0: *13 mod_zip: entering header filter
2009/09/03 20:14:35 [debug] 23696#0: *13 xslt filter header
2009/09/03 20:14:35 [debug] 23696#0: *13 HTTP/1.1 304 Not Modified
Server: nginx
Date: Thu, 03 Sep 2009 19:14:35 GMT
Last-Modified: Thu, 03 Sep 2009 14:22:10 GMT
Connection: keep-alive
Vary: Accept-Encoding

2009/09/03 20:14:35 [debug] 23696#0: *13 write new buf t:1 f:0 000000000152F2A0, pos 000000000152F2A0, size: 174 file: 0, size: 0
2009/09/03 20:14:35 [debug] 23696#0: *13 http write filter: l:1 f:0 s:174
2009/09/03 20:14:35 [debug] 23696#0: *13 http write filter limit 0
2009/09/03 20:14:35 [debug] 23696#0: *13 writev: 174
2009/09/03 20:14:35 [debug] 23696#0: *13 http write filter 0000000000000000
2009/09/03 20:14:35 [debug] 23696#0: *13 http finalize request: 0, "/stilo.css?" 1
2009/09/03 20:14:35 [debug] 23696#0: *13 set http keepalive handler
2009/09/03 20:14:35 [debug] 23696#0: *13 http close request
2009/09/03 20:14:35 [debug] 23696#0: *13 http log handler
2009/09/03 20:14:35 [debug] 23696#0: *13 run cleanup: 000000000152F0F8
2009/09/03 20:14:35 [debug] 23696#0: *13 file cleanup: fd:13
2009/09/03 20:14:35 [debug] 23696#0: *13 free: 000000000152E4C0, unused: 100
2009/09/03 20:14:35 [debug] 23696#0: *13 event timer add: 12: 75000:1252005350047
2009/09/03 20:14:35 [debug] 23696#0: *13 free: 000000000152DFD0
2009/09/03 20:14:35 [debug] 23696#0: *13 free: 0000000001550470
2009/09/03 20:14:35 [debug] 23696#0: *13 hc free: 0000000000000000 0
2009/09/03 20:14:35 [debug] 23696#0: *13 hc busy: 0000000000000000 0
2009/09/03 20:14:35 [debug] 23696#0: *13 post event 00000000015549C8
2009/09/03 20:14:35 [debug] 23696#0: *13 delete posted event 00000000015549C8
2009/09/03 20:14:35 [debug] 23696#0: *13 http keepalive handler
2009/09/03 20:14:35 [debug] 23696#0: *13 malloc: 0000000001550470:1024
2009/09/03 20:14:35 [debug] 23696#0: *13 recv: fd:12 -1 of 1024
2009/09/03 20:14:35 [debug] 23696#0: *13 recv() not ready (11: Resource temporarily unavailable)
-------------- next part --------------
2009/09/03 20:12:57 [debug] 23696#0: post event 0000000001554890
2009/09/03 20:12:57 [debug] 23696#0: delete posted event 0000000001554890
2009/09/03 20:12:57 [debug] 23696#0: accept on 127.0.0.1:8081, ready: 0
2009/09/03 20:12:57 [debug] 23696#0: malloc: 000000000151FF60:256
2009/09/03 20:12:57 [debug] 23696#0: *11 accept: 127.0.0.1 fd:12
2009/09/03 20:12:57 [debug] 23696#0: *11 event timer add: 12: 60000:1252005237745
2009/09/03 20:12:57 [debug] 23696#0: *11 epoll add event: fd:12 op:1 ev:80000001
2009/09/03 20:12:57 [debug] 23696#0: *11 post event 00000000015549C8
2009/09/03 20:12:57 [debug] 23696#0: *11 delete posted event 00000000015549C8
2009/09/03 20:12:57 [debug] 23696#0: *11 malloc: 000000000152DFD0:1248
2009/09/03 20:12:57 [debug] 23696#0: *11 malloc: 0000000001520250:256
2009/09/03 20:12:57 [debug] 23696#0: *11 malloc: 0000000001550470:1024
2009/09/03 20:12:57 [debug] 23696#0: *11 malloc: 000000000152E4C0:4096
2009/09/03 20:12:57 [debug] 23696#0: *11 http process request line
2009/09/03 20:12:57 [debug] 23696#0: *11 recv: fd:12 447 of 1024
2009/09/03 20:12:57 [debug] 23696#0: *11 http request line: "GET / HTTP/1.1"
2009/09/03 20:12:57 [debug] 23696#0: *11 http uri: "/"
2009/09/03 20:12:57 [debug] 23696#0: *11 http args: ""
2009/09/03 20:12:57 [debug] 23696#0: *11 http exten: ""
2009/09/03 20:12:57 [debug] 23696#0: *11 http process request header line
2009/09/03 20:12:57 [debug] 23696#0: *11 http header: "Host: localhost:8081"
2009/09/03 20:12:57 [debug] 23696#0: *11 http header: "User-Agent: Mozilla/5.0 (X11; U; Linux x86_64; en-US; rv:1.9.0.11) Gecko/2009061317 Iceweasel/3.0.12 (Debian-3.0.12-1)"
2009/09/03 20:12:57 [debug] 23696#0: *11 http header: "Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8"
2009/09/03 20:12:57 [debug] 23696#0: *11 http header: "Accept-Language: en,pt;q=0.7,eo;q=0.3"
2009/09/03 20:12:57 [debug] 23696#0: *11 http header: "Accept-Encoding: gzip,deflate"
2009/09/03 20:12:57 [debug] 23696#0: *11 http header: "Accept-Charset: UTF-8,*"
2009/09/03 20:12:57 [debug] 23696#0: *11 http header: "Keep-Alive: 300"
2009/09/03 20:12:57 [debug] 23696#0: *11 http header: "Connection: keep-alive"
2009/09/03 20:12:57 [debug] 23696#0: *11 http header: "Cookie: PHPSESSID=ea9ce76311e730647d393ae721cc74f7"
2009/09/03 20:12:57 [debug] 23696#0: *11 http header: "Cache-Control: max-age=0"
2009/09/03 20:12:57 [debug] 23696#0: *11 http header done
2009/09/03 20:12:57 [debug] 23696#0: *11 event timer del: 12: 1252005237745
2009/09/03 20:12:57 [debug] 23696#0: *11 generic phase: 0
2009/09/03 20:12:57 [debug] 23696#0: *11 test location: "/"
2009/09/03 20:12:57 [debug] 23696#0: *11 using configuration "/"
2009/09/03 20:12:57 [debug] 23696#0: *11 http cl:-1 max:1048576
2009/09/03 20:12:57 [debug] 23696#0: *11 generic phase: 2
2009/09/03 20:12:57 [debug] 23696#0: *11 post rewrite phase: 3
2009/09/03 20:12:57 [debug] 23696#0: *11 generic phase: 4
2009/09/03 20:12:57 [debug] 23696#0: *11 generic phase: 5
2009/09/03 20:12:57 [debug] 23696#0: *11 access phase: 6
2009/09/03 20:12:57 [debug] 23696#0: *11 access phase: 7
2009/09/03 20:12:57 [debug] 23696#0: *11 post access phase: 8
2009/09/03 20:12:57 [debug] 23696#0: *11 try files phase: 9
2009/09/03 20:12:57 [debug] 23696#0: *11 http script var: "/"
2009/09/03 20:12:57 [debug] 23696#0: *11 try to use file: "/" "/var/www/nginx-default/reagentes/"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script var: "/"
2009/09/03 20:12:57 [debug] 23696#0: *11 try to use file: "/" "/var/www/nginx-default/reagentes/"
2009/09/03 20:12:57 [debug] 23696#0: *11 try file uri: "/"
2009/09/03 20:12:57 [debug] 23696#0: *11 content phase: 10
2009/09/03 20:12:57 [debug] 23696#0: *11 open index "/var/www/nginx-default/reagentes/index.html"
2009/09/03 20:12:57 [debug] 23696#0: *11 internal redirect: "/index.html?"
2009/09/03 20:12:57 [debug] 23696#0: *11 generic phase: 0
2009/09/03 20:12:57 [debug] 23696#0: *11 test location: "/"
2009/09/03 20:12:57 [debug] 23696#0: *11 test location: "50x.html"
2009/09/03 20:12:57 [debug] 23696#0: *11 test location: "favicon.ico"
2009/09/03 20:12:57 [debug] 23696#0: *11 test location: ~ "\.(php|html)$"
2009/09/03 20:12:57 [debug] 23696#0: *11 using configuration "\.(php|html)$"
2009/09/03 20:12:57 [debug] 23696#0: *11 http cl:-1 max:1048576
2009/09/03 20:12:57 [debug] 23696#0: *11 generic phase: 2
2009/09/03 20:12:57 [debug] 23696#0: *11 post rewrite phase: 3
2009/09/03 20:12:57 [debug] 23696#0: *11 generic phase: 4
2009/09/03 20:12:57 [debug] 23696#0: *11 generic phase: 5
2009/09/03 20:12:57 [debug] 23696#0: *11 access phase: 6
2009/09/03 20:12:57 [debug] 23696#0: *11 access phase: 7
2009/09/03 20:12:57 [debug] 23696#0: *11 post access phase: 8
2009/09/03 20:12:57 [debug] 23696#0: *11 try files phase: 9
2009/09/03 20:12:57 [debug] 23696#0: *11 malloc: 0000000001524BE0:4096
2009/09/03 20:12:57 [debug] 23696#0: *11 http init upstream, client timer: 0
2009/09/03 20:12:57 [debug] 23696#0: *11 epoll add event: fd:12 op:3 ev:80000005
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "SCRIPT_FILENAME"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "/var/www/nginx-default/reagentes"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script var: "/index.html"
2009/09/03 20:12:57 [debug] 23696#0: *11 fastcgi param: "SCRIPT_FILENAME: /var/www/nginx-default/reagentes/index.html"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "DOCUMENT_ROOT/var/www/nginx-default/reagentes"
2009/09/03 20:12:57 [debug] 23696#0: *11 fastcgi param: "DOCUMENT_ROOT: /var/www/nginx-default/reagentes"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "QUERY_STRING"
2009/09/03 20:12:57 [debug] 23696#0: *11 fastcgi param: "QUERY_STRING: "
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "REQUEST_METHOD"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script var: "GET"
2009/09/03 20:12:57 [debug] 23696#0: *11 fastcgi param: "REQUEST_METHOD: GET"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "CONTENT_TYPE"
2009/09/03 20:12:57 [debug] 23696#0: *11 fastcgi param: "CONTENT_TYPE: "
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "CONTENT_LENGTH"
2009/09/03 20:12:57 [debug] 23696#0: *11 fastcgi param: "CONTENT_LENGTH: "
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "SCRIPT_NAME"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script var: "/index.html"
2009/09/03 20:12:57 [debug] 23696#0: *11 fastcgi param: "SCRIPT_NAME: /index.html"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "REQUEST_URI"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script var: "/"
2009/09/03 20:12:57 [debug] 23696#0: *11 fastcgi param: "REQUEST_URI: /"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "DOCUMENT_URI"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script var: "/index.html"
2009/09/03 20:12:57 [debug] 23696#0: *11 fastcgi param: "DOCUMENT_URI: /index.html"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "SERVER_PROTOCOL"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script var: "HTTP/1.1"
2009/09/03 20:12:57 [debug] 23696#0: *11 fastcgi param: "SERVER_PROTOCOL: HTTP/1.1"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "GATEWAY_INTERFACECGI/1.1"
2009/09/03 20:12:57 [debug] 23696#0: *11 fastcgi param: "GATEWAY_INTERFACE: CGI/1.1"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "SERVER_SOFTWARE"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "nginx/"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script var: "0.8.10"
2009/09/03 20:12:57 [debug] 23696#0: *11 fastcgi param: "SERVER_SOFTWARE: nginx/0.8.10"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "REMOTE_ADDR"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script var: "127.0.0.1"
2009/09/03 20:12:57 [debug] 23696#0: *11 fastcgi param: "REMOTE_ADDR: 127.0.0.1"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "REMOTE_PORT"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script var: "43275"
2009/09/03 20:12:57 [debug] 23696#0: *11 fastcgi param: "REMOTE_PORT: 43275"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "SERVER_ADDR"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script var: "127.0.0.1"
2009/09/03 20:12:57 [debug] 23696#0: *11 fastcgi param: "SERVER_ADDR: 127.0.0.1"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "SERVER_PORT"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script var: "8081"
2009/09/03 20:12:57 [debug] 23696#0: *11 fastcgi param: "SERVER_PORT: 8081"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "SERVER_NAME"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script var: "localhost"
2009/09/03 20:12:57 [debug] 23696#0: *11 fastcgi param: "SERVER_NAME: localhost"
2009/09/03 20:12:57 [debug] 23696#0: *11 http script copy: "REDIRECT_STATUS200"
2009/09/03 20:12:57 [debug] 23696#0: *11 fastcgi param: "REDIRECT_STATUS: 200"
2009/09/03 20:12:57 [debug] 23696#0: *11 http cleanup add: 000000000152F4A8
2009/09/03 20:12:57 [debug] 23696#0: *11 get rr peer, try: 1
2009/09/03 20:12:57 [debug] 23696#0: *11 socket 13
2009/09/03 20:12:57 [debug] 23696#0: *11 epoll add connection: fd:13 ev:80000005
2009/09/03 20:12:57 [debug] 23696#0: *11 connect to 127.0.0.1:8888, fd:13 #12
2009/09/03 20:12:57 [debug] 23696#0: *11 http upstream connect: -2
2009/09/03 20:12:57 [debug] 23696#0: *11 event timer add: 13: 60000:1252005237745
2009/09/03 20:12:57 [debug] 23696#0: *11 post event 000000000156E9D8
2009/09/03 20:12:57 [debug] 23696#0: *11 post event 000000000156EA40
2009/09/03 20:12:57 [debug] 23696#0: *11 delete posted event 000000000156EA40
2009/09/03 20:12:57 [debug] 23696#0: *11 http upstream request: "/index.html?"
2009/09/03 20:12:57 [debug] 23696#0: *11 http upstream send request handler
2009/09/03 20:12:57 [debug] 23696#0: *11 http upstream send request
2009/09/03 20:12:57 [debug] 23696#0: *11 chain writer buf fl:0 s:936
2009/09/03 20:12:57 [debug] 23696#0: *11 chain writer in: 0000000001525128
2009/09/03 20:12:57 [debug] 23696#0: *11 writev: 936
2009/09/03 20:12:57 [debug] 23696#0: *11 chain writer out: 0000000000000000
2009/09/03 20:12:57 [debug] 23696#0: *11 event timer del: 13: 1252005237745
2009/09/03 20:12:57 [debug] 23696#0: *11 event timer add: 13: 60000:1252005237747
2009/09/03 20:12:57 [debug] 23696#0: *11 delete posted event 000000000156E9D8
2009/09/03 20:12:57 [debug] 23696#0: *11 http run request: "/index.html?"
2009/09/03 20:12:57 [debug] 23696#0: *11 http upstream check client, write event:1, "/index.html"
2009/09/03 20:12:57 [debug] 23696#0: *11 http upstream recv(): -1 (11: Resource temporarily unavailable)
2009/09/03 20:12:57 [debug] 23696#0: *11 post event 0000000001554A30
2009/09/03 20:12:57 [debug] 23696#0: *11 delete posted event 0000000001554A30
2009/09/03 20:12:57 [debug] 23696#0: *11 http upstream request: "/index.html?"
2009/09/03 20:12:57 [debug] 23696#0: *11 http upstream process header
2009/09/03 20:12:57 [debug] 23696#0: *11 malloc: 0000000001525BF0:4096
2009/09/03 20:12:57 [debug] 23696#0: *11 recv: fd:13 -1 of 4096
2009/09/03 20:12:57 [error] 23696#0: *11 recv() failed (104: Connection reset by peer) while reading response header from upstream, client: 127.0.0.1, server: localhost, request: "GET / HTTP/1.1", upstream: "fastcgi://127.0.0.1:8888", host: "localhost:8081"
2009/09/03 20:12:57 [debug] 23696#0: *11 http next upstream, 2
2009/09/03 20:12:57 [debug] 23696#0: *11 free rr peer 1 4
2009/09/03 20:12:57 [debug] 23696#0: *11 finalize http upstream request: 502
2009/09/03 20:12:57 [debug] 23696#0: *11 finalize http fastcgi request
2009/09/03 20:12:57 [debug] 23696#0: *11 free rr peer 0 0
2009/09/03 20:12:57 [debug] 23696#0: *11 close http upstream connection: 13
2009/09/03 20:12:57 [debug] 23696#0: *11 event timer del: 13: 1252005237747
2009/09/03 20:12:57 [debug] 23696#0: *11 http finalize request: 502, "/index.html?" 1
2009/09/03 20:12:57 [debug] 23696#0: *11 http special response: 502, "/index.html?"
2009/09/03 20:12:57 [debug] 23696#0: *11 internal redirect: "/50x.html?"
2009/09/03 20:12:57 [debug] 23696#0: *11 generic phase: 0
2009/09/03 20:12:57 [debug] 23696#0: *11 test location: "/"
2009/09/03 20:12:57 [debug] 23696#0: *11 test location: "50x.html"
2009/09/03 20:12:57 [debug] 23696#0: *11 using configuration "=/50x.html"
2009/09/03 20:12:57 [debug] 23696#0: *11 http cl:-1 max:1048576
2009/09/03 20:12:57 [debug] 23696#0: *11 generic phase: 2
2009/09/03 20:12:57 [debug] 23696#0: *11 post rewrite phase: 3
2009/09/03 20:12:57 [debug] 23696#0: *11 generic phase: 4
2009/09/03 20:12:57 [debug] 23696#0: *11 generic phase: 5
2009/09/03 20:12:57 [debug] 23696#0: *11 access phase: 6
2009/09/03 20:12:57 [debug] 23696#0: *11 access phase: 7
2009/09/03 20:12:57 [debug] 23696#0: *11 post access phase: 8
2009/09/03 20:12:57 [debug] 23696#0: *11 try files phase: 9
2009/09/03 20:12:57 [debug] 23696#0: *11 content phase: 10
2009/09/03 20:12:57 [debug] 23696#0: *11 content phase: 11
2009/09/03 20:12:57 [debug] 23696#0: *11 content phase: 12
2009/09/03 20:12:57 [debug] 23696#0: *11 http filename: "/var/www/nginx-default/reagentes/50x.html.gz"
2009/09/03 20:12:57 [debug] 23696#0: *11 add cleanup: 00000000015252F8
2009/09/03 20:12:57 [debug] 23696#0: *11 content phase: 13
2009/09/03 20:12:57 [debug] 23696#0: *11 http filename: "/var/www/nginx-default/reagentes/50x.html"
2009/09/03 20:12:57 [debug] 23696#0: *11 add cleanup: 0000000001525358
2009/09/03 20:12:57 [debug] 23696#0: *11 http static fd: 13
2009/09/03 20:12:57 [debug] 23696#0: *11 http set discard body
2009/09/03 20:12:57 [debug] 23696#0: *11 mod_zip: entering header filter
2009/09/03 20:12:57 [debug] 23696#0: *11 xslt filter header
2009/09/03 20:12:57 [debug] 23696#0: *11 charset: "" > "utf-8"
2009/09/03 20:12:57 [debug] 23696#0: *11 HTTP/1.1 502 Bad Gateway
Server: nginx
Date: Thu, 03 Sep 2009 19:12:57 GMT
Content-Type: text/html; charset=utf-8
Content-Length: 54
Connection: keep-alive
Vary: Accept-Encoding

2009/09/03 20:12:57 [debug] 23696#0: *11 write new buf t:1 f:0 0000000001525510, pos 0000000001525510, size: 187 file: 0, size: 0
2009/09/03 20:12:57 [debug] 23696#0: *11 http write filter: l:0 f:0 s:187
2009/09/03 20:12:57 [debug] 23696#0: *11 http output filter "/50x.html?"
2009/09/03 20:12:57 [debug] 23696#0: *11 copy filter: "/50x.html?"
2009/09/03 20:12:57 [debug] 23696#0: *11 mod_zip: entering body filter
2009/09/03 20:12:57 [debug] 23696#0: *11 xslt filter body
2009/09/03 20:12:57 [debug] 23696#0: *11 http postpone filter "/50x.html?" 00007FFF661C15C0
2009/09/03 20:12:57 [debug] 23696#0: *11 write old buf t:1 f:0 0000000001525510, pos 0000000001525510, size: 187 file: 0, size: 0
2009/09/03 20:12:57 [debug] 23696#0: *11 write new buf t:0 f:1 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 54
2009/09/03 20:12:57 [debug] 23696#0: *11 http write filter: l:1 f:0 s:241
2009/09/03 20:12:57 [debug] 23696#0: *11 http write filter limit 0
2009/09/03 20:12:57 [debug] 23696#0: *11 tcp_nopush
2009/09/03 20:12:57 [debug] 23696#0: *11 writev: 187
2009/09/03 20:12:57 [debug] 23696#0: *11 sendfile: @0 54
2009/09/03 20:12:57 [debug] 23696#0: *11 sendfile: 54, @0 54:54
2009/09/03 20:12:57 [debug] 23696#0: *11 http write filter 0000000000000000
2009/09/03 20:12:57 [debug] 23696#0: *11 copy filter: 0 "/50x.html?"
2009/09/03 20:12:57 [debug] 23696#0: *11 http finalize request: 0, "/50x.html?" 1
2009/09/03 20:12:57 [debug] 23696#0: *11 set http keepalive handler
2009/09/03 20:12:57 [debug] 23696#0: *11 http close request
2009/09/03 20:12:57 [debug] 23696#0: *11 http log handler
2009/09/03 20:12:57 [debug] 23696#0: *11 run cleanup: 0000000001525358
2009/09/03 20:12:57 [debug] 23696#0: *11 file cleanup: fd:13
2009/09/03 20:12:57 [debug] 23696#0: *11 free: 0000000001525BF0
2009/09/03 20:12:57 [debug] 23696#0: *11 free: 000000000152E4C0, unused: 0
2009/09/03 20:12:57 [debug] 23696#0: *11 free: 0000000001524BE0, unused: 1165
2009/09/03 20:12:57 [debug] 23696#0: *11 event timer add: 12: 75000:1252005252751
2009/09/03 20:12:57 [debug] 23696#0: *11 free: 000000000152DFD0
2009/09/03 20:12:57 [debug] 23696#0: *11 free: 0000000001550470
2009/09/03 20:12:57 [debug] 23696#0: *11 hc free: 0000000000000000 0
2009/09/03 20:12:57 [debug] 23696#0: *11 hc busy: 0000000000000000 0
2009/09/03 20:12:57 [debug] 23696#0: *11 post event 00000000015549C8
2009/09/03 20:12:57 [debug] 23696#0: *11 delete posted event 00000000015549C8
2009/09/03 20:12:57 [debug] 23696#0: *11 http keepalive handler
2009/09/03 20:12:57 [debug] 23696#0: *11 malloc: 0000000001550470:1024
2009/09/03 20:12:57 [debug] 23696#0: *11 recv: fd:12 -1 of 1024
2009/09/03 20:12:57 [debug] 23696#0: *11 recv() not ready (11: Resource temporarily unavailable)
2009/09/03 20:14:12 [debug] 23696#0: *11 event timer del: 12: 1252005252751
2009/09/03 20:14:12 [debug] 23696#0: *11 http keepalive handler
2009/09/03 20:14:12 [debug] 23696#0: *11 close http connection: 12
2009/09/03 20:14:12 [debug] 23696#0: *11 free: 0000000001550470
2009/09/03 20:14:12 [debug] 23696#0: *11 free: 0000000000000000
2009/09/03 20:14:12 [debug] 23696#0: *11 free: 000000000151FF60, unused: 8
2009/09/03 20:14:12 [debug] 23696#0: *11 free: 0000000001520250, unused: 128