nginx 0.8.47 php-fpm error_page not working

nginx_newbie nginx-forum at nginx.us
Mon Aug 9 17:11:27 MSD 2010


Dear Igor,

Thanks for taking the time to look into my problem!
Since I wanted error404.htm to be parsed by PHP, I removed the
error_page line
and corresponding location block from the configuration file and placed
a new
error_page line within the location block that is passing all HTM files
to PHP

While at present error404.htm is plain html file, it will ultimately by
a PHP file later.
On slowly trying to understand the debug output, I see that nginx is
going all the way
to evoke parsing error404.htm and also send in the headers to the
browser. But nothing
is visible there after. All that I get on the browser (Google Chrome) as
output is standard
error from Chrome "Oops! This link appears to be broken.".

Since the problem still remains unsolved, I am attaching the fresh
server config
file for your reference along with the debug output after all these
changes.

Server Config
--------------------------------------------------
server {
        listen 80;
        server_name www.mutually4u.com mutually4u.com;
        access_log /srv/www/mutually4u.com/logs/access.log;
        error_log /srv/www/mutually4u.com/logs/error.log debug;

        location = /favicon.ico {
            return 204;
        }

        location / {
                root   /srv/www/mutually4u.com/public_html;
                index index.htm;
        }

        location ~ \.htm$
        {
		root /srv/www/mutually4u.com/public_html;
		error_page 404 /error404.htm;
                fastcgi_pass 127.0.0.1:9000;
                fastcgi_param SCRIPT_FILENAME
/srv/www/mutually4u.com/public_html$fastcgi_script_name;
                fastcgi_param PATH_INFO $fastcgi_script_name;
                include /etc/nginx/fastcgi_params;
        }
}
--------------------------------------------------

Latest Debug Output
--------------------------------------------------
2010/08/09 18:26:13 [debug] 19232#0: *7 event timer del: 3: 1458379076
2010/08/09 18:26:13 [debug] 19232#0: *7 rewrite phase: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 test location: "/"
2010/08/09 18:26:13 [debug] 19232#0: *7 test location: "favicon.ico"
2010/08/09 18:26:13 [debug] 19232#0: *7 test location: ~ "\.htm$"
2010/08/09 18:26:13 [debug] 19232#0: *7 using configuration "\.htm$"
2010/08/09 18:26:13 [debug] 19232#0: *7 http cl:-1 max:1048576
2010/08/09 18:26:13 [debug] 19232#0: *7 rewrite phase: 2
2010/08/09 18:26:13 [debug] 19232#0: *7 post rewrite phase: 3
2010/08/09 18:26:13 [debug] 19232#0: *7 generic phase: 4
2010/08/09 18:26:13 [debug] 19232#0: *7 generic phase: 5
2010/08/09 18:26:13 [debug] 19232#0: *7 generic phase: 6
2010/08/09 18:26:13 [debug] 19232#0: *7 access phase: 7
2010/08/09 18:26:13 [debug] 19232#0: *7 access phase: 8
2010/08/09 18:26:13 [debug] 19232#0: *7 post access phase: 9
2010/08/09 18:26:13 [debug] 19232#0: *7 http init upstream, client
timer: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 epoll add event: fd:3 op:3
ev:80000005
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"SCRIPT_FILENAME"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"/srv/www/mutually4u.com/public_html"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var:
"/test_htm.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "SCRIPT_FILENAME:
/srv/www/mutually4u.com/public_html/test_htm.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "PATH_INFO"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var:
"/test_htm.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "PATH_INFO:
/test_htm.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"QUERY_STRING"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "QUERY_STRING: "
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"REQUEST_METHOD"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var: "GET"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "REQUEST_METHOD:
GET"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"CONTENT_TYPE"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "CONTENT_TYPE: "
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"CONTENT_LENGTH"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "CONTENT_LENGTH:
"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "SCRIPT_NAME"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var:
"/test_htm.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "SCRIPT_NAME:
/test_htm.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "REQUEST_URI"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var:
"/test_htm.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "REQUEST_URI:
/test_htm.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"DOCUMENT_URI"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var:
"/test_htm.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "DOCUMENT_URI:
/test_htm.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"DOCUMENT_ROOT"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var:
"/srv/www/mutually4u.com/public_html"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "DOCUMENT_ROOT:
/srv/www/mutually4u.com/public_html"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"SERVER_PROTOCOL"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var: "HTTP/1.1"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "SERVER_PROTOCOL:
HTTP/1.1"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"GATEWAY_INTERFACE"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "CGI/1.1"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param:
"GATEWAY_INTERFACE: CGI/1.1"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"SERVER_SOFTWARE"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "nginx/"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var: "0.8.47"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "SERVER_SOFTWARE:
nginx/0.8.47"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "REMOTE_ADDR"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var:
"122.174.127.181"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "REMOTE_ADDR:
122.174.127.181"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "REMOTE_PORT"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var: "1201"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "REMOTE_PORT:
1201"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "SERVER_ADDR"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var:
"173.230.153.171"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "SERVER_ADDR:
173.230.153.171"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "SERVER_PORT"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var: "80"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "SERVER_PORT:
80"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "SERVER_NAME"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var:
"www.mutually4u.com"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "SERVER_NAME:
www.mutually4u.com"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"REDIRECT_STATUS"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "200"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "REDIRECT_STATUS:
200"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "HTTP_HOST:
www.mutually4u.com"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "HTTP_CONNECTION:
keep-alive"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param:
"HTTP_CACHE_CONTROL: max-age=0"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "HTTP_ACCEPT:
application/xml,application/xhtml+xml,text/html;q=0.9,text/plain;q=0.8,image/png,*/*;q=0.5"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "HTTP_USER_AGENT:
Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US) AppleWebKit/534.3
(KHTML, like Gecko) Chrome/6.0.466.0 Safari/534.3"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param:
"HTTP_ACCEPT_ENCODING: gzip,deflate,sdch"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param:
"HTTP_ACCEPT_LANGUAGE: en-US,en;q=0.8"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param:
"HTTP_ACCEPT_CHARSET: ISO-8859-1,utf-8;q=0.7,*;q=0.3"
2010/08/09 18:26:13 [debug] 19232#0: *7 http cleanup add: 082EE024
2010/08/09 18:26:13 [debug] 19232#0: *7 get rr peer, try: 1
2010/08/09 18:26:13 [debug] 19232#0: *7 socket 11
2010/08/09 18:26:13 [debug] 19232#0: *7 epoll add connection: fd:11
ev:80000005
2010/08/09 18:26:13 [debug] 19232#0: *7 connect to 127.0.0.1:9000, fd:11
#8
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream connect: -2
2010/08/09 18:26:13 [debug] 19232#0: *7 event timer add: 11:
60000:1458379081
2010/08/09 18:26:13 [debug] 19232#0: *7 http finalize request: -4,
"/test_htm.htm?" a:1, c:2
2010/08/09 18:26:13 [debug] 19232#0: *7 http request count:2 blk:0
2010/08/09 18:26:13 [debug] 19232#0: *7 http run request:
"/test_htm.htm?"
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream check client,
write event:1, "/test_htm.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream recv(): -1 (11:
Resource temporarily unavailable)
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream request:
"/test_htm.htm?"
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream send request
handler
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream send request
2010/08/09 18:26:13 [debug] 19232#0: *7 chain writer buf fl:0 s:992
2010/08/09 18:26:13 [debug] 19232#0: *7 chain writer in: 082EE040
2010/08/09 18:26:13 [debug] 19232#0: *7 writev: 992
2010/08/09 18:26:13 [debug] 19232#0: *7 chain writer out: 00000000
2010/08/09 18:26:13 [debug] 19232#0: *7 event timer del: 11: 1458379081
2010/08/09 18:26:13 [debug] 19232#0: *7 event timer add: 11:
180000:1458499082
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream request:
"/test_htm.htm?"
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream process header
2010/08/09 18:26:13 [debug] 19232#0: *7 malloc: 08332E10:131072
2010/08/09 18:26:13 [debug] 19232#0: *7 recv: fd:11 112 of 131072
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 01
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 06
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 00
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 01
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 00
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 65
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 03
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 00
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record length: 101
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi parser: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi header: "Status:
404 Not Found"
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi parser: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi header:
"X-Powered-By: PHP/5.2.14"
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi parser: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi header:
"Content-type: text/html"
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi parser: 1
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi header done
2010/08/09 18:26:13 [debug] 19232#0: *7 finalize http upstream request:
404
2010/08/09 18:26:13 [debug] 19232#0: *7 finalize http fastcgi request
2010/08/09 18:26:13 [debug] 19232#0: *7 free rr peer 1 0
2010/08/09 18:26:13 [debug] 19232#0: *7 close http upstream connection:
11
2010/08/09 18:26:13 [debug] 19232#0: *7 event timer del: 11: 1458499082
2010/08/09 18:26:13 [debug] 19232#0: *7 http finalize request: 404,
"/test_htm.htm?" a:1, c:1
2010/08/09 18:26:13 [debug] 19232#0: *7 http special response: 404,
"/test_htm.htm?"
2010/08/09 18:26:13 [debug] 19232#0: *7 internal redirect:
"/error404.htm?"
2010/08/09 18:26:13 [debug] 19232#0: *7 rewrite phase: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 test location: "/"
2010/08/09 18:26:13 [debug] 19232#0: *7 test location: "favicon.ico"
2010/08/09 18:26:13 [debug] 19232#0: *7 test location: ~ "\.htm$"
2010/08/09 18:26:13 [debug] 19232#0: *7 using configuration "\.htm$"
2010/08/09 18:26:13 [debug] 19232#0: *7 http cl:-1 max:1048576
2010/08/09 18:26:13 [debug] 19232#0: *7 rewrite phase: 2
2010/08/09 18:26:13 [debug] 19232#0: *7 post rewrite phase: 3
2010/08/09 18:26:13 [debug] 19232#0: *7 generic phase: 4
2010/08/09 18:26:13 [debug] 19232#0: *7 generic phase: 5
2010/08/09 18:26:13 [debug] 19232#0: *7 generic phase: 6
2010/08/09 18:26:13 [debug] 19232#0: *7 access phase: 7
2010/08/09 18:26:13 [debug] 19232#0: *7 access phase: 8
2010/08/09 18:26:13 [debug] 19232#0: *7 post access phase: 9
2010/08/09 18:26:13 [debug] 19232#0: *7 posix_memalign: 082EE3C0:4096
@16
2010/08/09 18:26:13 [debug] 19232#0: *7 http init upstream, client
timer: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"SCRIPT_FILENAME"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"/srv/www/mutually4u.com/public_html"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var:
"/error404.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "SCRIPT_FILENAME:
/srv/www/mutually4u.com/public_html/error404.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "PATH_INFO"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var:
"/error404.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "PATH_INFO:
/error404.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"QUERY_STRING"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "QUERY_STRING: "
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"REQUEST_METHOD"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var: "GET"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "REQUEST_METHOD:
GET"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"CONTENT_TYPE"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "CONTENT_TYPE: "
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"CONTENT_LENGTH"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "CONTENT_LENGTH:
"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "SCRIPT_NAME"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var:
"/error404.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "SCRIPT_NAME:
/error404.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "REQUEST_URI"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var:
"/test_htm.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "REQUEST_URI:
/test_htm.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"DOCUMENT_URI"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var:
"/error404.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "DOCUMENT_URI:
/error404.htm"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"DOCUMENT_ROOT"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var:
"/srv/www/mutually4u.com/public_html"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "DOCUMENT_ROOT:
/srv/www/mutually4u.com/public_html"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"SERVER_PROTOCOL"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var: "HTTP/1.1"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "SERVER_PROTOCOL:
HTTP/1.1"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"GATEWAY_INTERFACE"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "CGI/1.1"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param:
"GATEWAY_INTERFACE: CGI/1.1"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"SERVER_SOFTWARE"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "nginx/"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var: "0.8.47"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "SERVER_SOFTWARE:
nginx/0.8.47"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "REMOTE_ADDR"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var:
"122.174.127.181"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "REMOTE_ADDR:
122.174.127.181"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "REMOTE_PORT"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var: "1201"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "REMOTE_PORT:
1201"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "SERVER_ADDR"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var:
"173.230.153.171"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "SERVER_ADDR:
173.230.153.171"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "SERVER_PORT"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var: "80"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "SERVER_PORT:
80"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "SERVER_NAME"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script var:
"www.mutually4u.com"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "SERVER_NAME:
www.mutually4u.com"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy:
"REDIRECT_STATUS"
2010/08/09 18:26:13 [debug] 19232#0: *7 http script copy: "200"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "REDIRECT_STATUS:
200"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "HTTP_HOST:
www.mutually4u.com"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "HTTP_CONNECTION:
keep-alive"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param:
"HTTP_CACHE_CONTROL: max-age=0"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "HTTP_ACCEPT:
application/xml,application/xhtml+xml,text/html;q=0.9,text/plain;q=0.8,image/png,*/*;q=0.5"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param: "HTTP_USER_AGENT:
Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US) AppleWebKit/534.3
(KHTML, like Gecko) Chrome/6.0.466.0 Safari/534.3"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param:
"HTTP_ACCEPT_ENCODING: gzip,deflate,sdch"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param:
"HTTP_ACCEPT_LANGUAGE: en-US,en;q=0.8"
2010/08/09 18:26:13 [debug] 19232#0: *7 fastcgi param:
"HTTP_ACCEPT_CHARSET: ISO-8859-1,utf-8;q=0.7,*;q=0.3"
2010/08/09 18:26:13 [debug] 19232#0: *7 http cleanup add: 082EE878
2010/08/09 18:26:13 [debug] 19232#0: *7 get rr peer, try: 1
2010/08/09 18:26:13 [debug] 19232#0: *7 socket 11
2010/08/09 18:26:13 [debug] 19232#0: *7 epoll add connection: fd:11
ev:80000005
2010/08/09 18:26:13 [debug] 19232#0: *7 connect to 127.0.0.1:9000, fd:11
#9
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream connect: -2
2010/08/09 18:26:13 [debug] 19232#0: *7 event timer add: 11:
60000:1458379083
2010/08/09 18:26:13 [debug] 19232#0: *7 http finalize request: -4,
"/error404.htm?" a:1, c:3
2010/08/09 18:26:13 [debug] 19232#0: *7 http request count:3 blk:0
2010/08/09 18:26:13 [debug] 19232#0: *7 http finalize request: -4,
"/error404.htm?" a:1, c:2
2010/08/09 18:26:13 [debug] 19232#0: *7 http request count:2 blk:0
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream request:
"/error404.htm?"
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream send request
handler
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream send request
2010/08/09 18:26:13 [debug] 19232#0: *7 chain writer buf fl:0 s:992
2010/08/09 18:26:13 [debug] 19232#0: *7 chain writer in: 082EE398
2010/08/09 18:26:13 [debug] 19232#0: *7 writev: 992
2010/08/09 18:26:13 [debug] 19232#0: *7 chain writer out: 00000000
2010/08/09 18:26:13 [debug] 19232#0: *7 event timer del: 11: 1458379083
2010/08/09 18:26:13 [debug] 19232#0: *7 event timer add: 11:
180000:1458499083
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream request:
"/error404.htm?"
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream dummy handler
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream request:
"/error404.htm?"
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream process header
2010/08/09 18:26:13 [debug] 19232#0: *7 malloc: 08352E18:131072
2010/08/09 18:26:13 [debug] 19232#0: *7 recv: fd:11 200 of 131072
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 01
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 06
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 00
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 01
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 00
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: AA
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 06
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 00
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record length: 170
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi parser: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi header:
"X-Powered-By: PHP/5.2.14"
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi parser: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi header:
"Content-type: text/html"
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi parser: 1
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi header done
2010/08/09 18:26:13 [debug] 19232#0: *7 HTTP/1.1 404 Not Found
Server: nginx/0.8.47
Date: Mon, 09 Aug 2010 12:56:13 GMT
Content-Type: text/html
Transfer-Encoding: chunked
Connection: keep-alive
X-Powered-By: PHP/5.2.14
Content-Encoding: gzip

2010/08/09 18:26:13 [debug] 19232#0: *7 write new buf t:1 f:0 082EEAE8,
pos 082EEAE8, size: 212 file: 0, size: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 http write filter: l:0 f:0
s:212
2010/08/09 18:26:13 [debug] 19232#0: *7 http cacheable: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream process upstream
2010/08/09 18:26:13 [debug] 19232#0: *7 pipe read upstream: 1
2010/08/09 18:26:13 [debug] 19232#0: *7 pipe preread: 139
2010/08/09 18:26:13 [debug] 19232#0: *7 readv: 1:130872
2010/08/09 18:26:13 [debug] 19232#0: *7 pipe recv chain: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 pipe buf free s:0 t:1 f:0
08352E18, pos 08352E55, size: 139 file: 0, size: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 input buf #0 08352E55
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 01
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 03
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 00
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 01
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 00
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 08
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 00
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record byte: 00
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi record length: 8
2010/08/09 18:26:13 [debug] 19232#0: *7 http fastcgi sent end request
2010/08/09 18:26:13 [debug] 19232#0: *7 input buf 08352E55 117
2010/08/09 18:26:13 [debug] 19232#0: *7 pipe write downstream: 1
2010/08/09 18:26:13 [debug] 19232#0: *7 pipe write downstream flush in
2010/08/09 18:26:13 [debug] 19232#0: *7 http output filter
"/error404.htm?"
2010/08/09 18:26:13 [debug] 19232#0: *7 http copy filter:
"/error404.htm?"
2010/08/09 18:26:13 [debug] 19232#0: *7 http gzip filter
2010/08/09 18:26:13 [debug] 19232#0: *7 malloc: 08372E20:270336
2010/08/09 18:26:13 [debug] 19232#0: *7 gzip alloc: n:1 s:5824 a:8192
p:08372E20
2010/08/09 18:26:13 [debug] 19232#0: *7 gzip alloc: n:32768 s:2 a:65536
p:08374E20
2010/08/09 18:26:13 [debug] 19232#0: *7 gzip alloc: n:32768 s:2 a:65536
p:08384E20
2010/08/09 18:26:13 [debug] 19232#0: *7 gzip alloc: n:32768 s:2 a:65536
p:08394E20
2010/08/09 18:26:13 [debug] 19232#0: *7 gzip alloc: n:16384 s:4 a:65536
p:083A4E20
2010/08/09 18:26:13 [debug] 19232#0: *7 gzip in: 082EECE8
2010/08/09 18:26:13 [debug] 19232#0: *7 gzip in_buf:082EEC6C ni:08352E55
ai:117
2010/08/09 18:26:13 [debug] 19232#0: *7 malloc: 082EF3D8:4096
2010/08/09 18:26:13 [debug] 19232#0: *7 deflate in: ni:08352E55
no:082EF3D8 ai:117 ao:4096 fl:0 redo:0
2010/08/09 18:26:13 [debug] 19232#0: *7 deflate out: ni:08352ECA
no:082EF3D8 ai:0 ao:4096 rc:0
2010/08/09 18:26:13 [debug] 19232#0: *7 gzip in_buf:082EEC6C
pos:08352E55
2010/08/09 18:26:13 [debug] 19232#0: *7 gzip in: 00000000
2010/08/09 18:26:13 [debug] 19232#0: *7 http copy filter: 0
"/error404.htm?"
2010/08/09 18:26:13 [debug] 19232#0: *7 pipe write downstream done
2010/08/09 18:26:13 [debug] 19232#0: *7 event timer: 11, old:
1458499083, new: 1458499084
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream exit: 00000000
2010/08/09 18:26:13 [debug] 19232#0: *7 finalize http upstream request:
0
2010/08/09 18:26:13 [debug] 19232#0: *7 finalize http fastcgi request
2010/08/09 18:26:13 [debug] 19232#0: *7 free rr peer 1 0
2010/08/09 18:26:13 [debug] 19232#0: *7 close http upstream connection:
11
2010/08/09 18:26:13 [debug] 19232#0: *7 event timer del: 11: 1458499083
2010/08/09 18:26:13 [debug] 19232#0: *7 http upstream temp fd: -1
2010/08/09 18:26:13 [debug] 19232#0: *7 http output filter
"/error404.htm?"
2010/08/09 18:26:13 [debug] 19232#0: *7 http copy filter:
"/error404.htm?"
2010/08/09 18:26:13 [debug] 19232#0: *7 http gzip filter
2010/08/09 18:26:13 [debug] 19232#0: *7 gzip in: 082EED60
2010/08/09 18:26:13 [debug] 19232#0: *7 gzip in_buf:082EED2C ni:00000000
ai:0
2010/08/09 18:26:13 [debug] 19232#0: *7 deflate in: ni:00000000
no:082EF3D8 ai:0 ao:4096 fl:4 redo:0
2010/08/09 18:26:13 [debug] 19232#0: *7 deflate out: ni:00000000
no:082EF42E ai:0 ao:4010 rc:1
2010/08/09 18:26:13 [debug] 19232#0: *7 gzip in_buf:082EED2C
pos:00000000
2010/08/09 18:26:13 [debug] 19232#0: *7 free: 08372E20
2010/08/09 18:26:13 [debug] 19232#0: *7 http chunk: 10
2010/08/09 18:26:13 [debug] 19232#0: *7 http chunk: 94
2010/08/09 18:26:13 [debug] 19232#0: *7 write old buf t:1 f:0 082EEAE8,
pos 082EEAE8, size: 212 file: 0, size: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 write new buf t:1 f:0 00000000,
pos 082EEDF0, size: 4 file: 0, size: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 write new buf t:0 f:0 00000000,
pos 080B6BC4, size: 10 file: 0, size: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 write new buf t:1 f:0 082EF3D8,
pos 082EF3D8, size: 94 file: 0, size: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 write new buf t:0 f:0 00000000,
pos 080AEA9C, size: 7 file: 0, size: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 http write filter: l:1 f:1
s:327
2010/08/09 18:26:13 [debug] 19232#0: *7 http write filter limit 0
2010/08/09 18:26:13 [debug] 19232#0: *7 writev: 327
2010/08/09 18:26:13 [debug] 19232#0: *7 http write filter 00000000
2010/08/09 18:26:13 [debug] 19232#0: *7 http copy filter: 0
"/error404.htm?"
2010/08/09 18:26:13 [debug] 19232#0: *7 http finalize request: 0,
"/error404.htm?" a:1, c:1
2010/08/09 18:26:13 [debug] 19232#0: *7 set http keepalive handler
2010/08/09 18:26:13 [debug] 19232#0: *7 http close request
2010/08/09 18:26:13 [debug] 19232#0: *7 http log handler
2010/08/09 18:26:13 [debug] 19232#0: *7 free: 082EF3D8
2010/08/09 18:26:13 [debug] 19232#0: *7 free: 00000000
2010/08/09 18:26:13 [debug] 19232#0: *7 free: 08352E18
2010/08/09 18:26:13 [debug] 19232#0: *7 free: 08332E10
2010/08/09 18:26:13 [debug] 19232#0: *7 free: 082ED3A0, unused: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 free: 082EE3C0, unused: 1159
2010/08/09 18:26:13 [debug] 19232#0: *7 event timer add: 3:
65000:1458384084
2010/08/09 18:26:13 [debug] 19232#0: *7 free: 082F9990
2010/08/09 18:26:13 [debug] 19232#0: *7 free: 082ECF98
2010/08/09 18:26:13 [debug] 19232#0: *7 hc free: 00000000 0
2010/08/09 18:26:13 [debug] 19232#0: *7 hc busy: 00000000 0
2010/08/09 18:26:13 [debug] 19232#0: *7 tcp_nodelay
2010/08/09 18:26:13 [debug] 19232#0: *7 post event 08318E68
2010/08/09 18:26:13 [debug] 19232#0: *7 delete posted event 08318E68
2010/08/09 18:26:13 [debug] 19232#0: *7 http keepalive handler
2010/08/09 18:26:13 [debug] 19232#0: *7 malloc: 082F9990:1024
2010/08/09 18:26:13 [debug] 19232#0: *7 recv: fd:3 -1 of 1024
2010/08/09 18:26:13 [debug] 19232#0: *7 recv() not ready (11: Resource
temporarily unavailable)
2010/08/09 18:26:13 [debug] 19232#0: *7 http keepalive handler
2010/08/09 18:26:13 [debug] 19232#0: *7 recv: fd:3 338 of 1024
2010/08/09 18:26:13 [debug] 19232#0: *7 malloc: 082ECF98:656
2010/08/09 18:26:13 [debug] 19232#0: *7 event timer del: 3: 1458384084
2010/08/09 18:26:13 [debug] 19232#0: *7 rewrite phase: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 test location: "/"
2010/08/09 18:26:13 [debug] 19232#0: *7 test location: "favicon.ico"
2010/08/09 18:26:13 [debug] 19232#0: *7 using configuration
"=/favicon.ico"
2010/08/09 18:26:13 [debug] 19232#0: *7 http cl:-1 max:1048576
2010/08/09 18:26:13 [debug] 19232#0: *7 rewrite phase: 2
2010/08/09 18:26:13 [debug] 19232#0: *7 HTTP/1.1 204 No Content
Server: nginx/0.8.47
Date: Mon, 09 Aug 2010 12:56:13 GMT
Connection: keep-alive

2010/08/09 18:26:13 [debug] 19232#0: *7 write new buf t:1 f:0 082ED85C,
pos 082ED85C, size: 110 file: 0, size: 0
2010/08/09 18:26:13 [debug] 19232#0: *7 http write filter: l:1 f:0
s:110
2010/08/09 18:26:13 [debug] 19232#0: *7 http write filter limit 0
2010/08/09 18:26:13 [debug] 19232#0: *7 writev: 110
2010/08/09 18:26:13 [debug] 19232#0: *7 http write filter 00000000
2010/08/09 18:26:13 [debug] 19232#0: *7 http finalize request: 0,
"/favicon.ico?" a:1, c:1
2010/08/09 18:26:13 [debug] 19232#0: *7 set http keepalive handler
2010/08/09 18:26:13 [debug] 19232#0: *7 http close request
2010/08/09 18:26:13 [debug] 19232#0: *7 http log handler
2010/08/09 18:26:13 [debug] 19232#0: *7 free: 082ED230, unused: 2172
2010/08/09 18:26:13 [debug] 19232#0: *7 event timer add: 3:
65000:1458384585
2010/08/09 18:26:13 [debug] 19232#0: *7 free: 082ECF98
2010/08/09 18:26:13 [debug] 19232#0: *7 free: 082F9990
2010/08/09 18:26:13 [debug] 19232#0: *7 hc free: 00000000 0
2010/08/09 18:26:13 [debug] 19232#0: *7 hc busy: 00000000 0
2010/08/09 18:26:13 [debug] 19232#0: *7 post event 08318E68
2010/08/09 18:26:13 [debug] 19232#0: *7 http empty handler
2010/08/09 18:26:13 [debug] 19232#0: *7 delete posted event 08318E68
2010/08/09 18:26:13 [debug] 19232#0: *7 http keepalive handler
2010/08/09 18:26:13 [debug] 19232#0: *7 malloc: 082F9990:1024
2010/08/09 18:26:13 [debug] 19232#0: *7 recv: fd:3 -1 of 1024
2010/08/09 18:26:13 [debug] 19232#0: *7 recv() not ready (11: Resource
temporarily unavailable)
--------------------------------------------------

Please guide me if anything else needs to be done to try to find the
potential problem.
Many thanks for taking the efforts to help me. Looking forward to your
valuable
advise in solving this problem.

Regards
Ganesh

Posted at Nginx Forum: http://forum.nginx.org/read.php?2,117436,117817#msg-117817




More information about the nginx mailing list