Lexa Software: Nginx-ru@sysoev.ru archive

		Apache-Talk @lexa.ru
		Inet-Admins @info.east.ru
		Filmscanners @halftone.co.uk
		Security-alerts @yandex-team.ru
		nginx-ru @sysoev.ru

СТАТЬИ

ПЕРСОНАЛЬНОЕ

ПРОГРАММЫ

ПИШИТЕ
ПИСЬМА

АРХИВ :: nginx-ru

Nginx-ru mailing list archive (nginx-ru@sysoev.ru)

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

Re: $body bytes sent всегда 0, но не в логах.

To: nginx-ru@xxxxxxxxx
Subject: Re: $body bytes sent всегда 0, но не в логах.
From: step <st@xxxxxxxxxx>
Date: Fri, 27 May 2011 16:17:24 +0400
In-reply-to: <20110527115232.GG95514@xxxxxxxxx>
References: <302ab787be1381384506ccc9ddbfd4c8.NginxMailingListRussian@xxxxxxxxxxxxxxx> <20110527073412.GH79260@xxxxxxxxx> <1306496473.1445.4.camel@step> <20110527115232.GG95514@xxxxxxxxx>

В Птн, 27/05/2011 в 15:52 +0400, Igor Sysoev пишет:
> > В конфиге nginx:
> > 
> > location ~ ^/(test_post)\.php$ {
> >     fastcgi_pass    unix:/var/run/php-fpm.socket;
> >     fastcgi_param
> > SCRIPT_FILENAME  /www/server/www/$fastcgi_script_name;
> >     fastcgi_param   BBBBBBB $body_bytes_sent;
> >     include         /etc/nginx/fastcgi_params;
> > }
> > 
> > location ~ ^/(test)\.php$ {
> >     fastcgi_pass    unix:/var/run/php-fpm.socket;
> >     fastcgi_param
> > SCRIPT_FILENAME  /www/server/www/$fastcgi_script_name;
> >     include         /etc/nginx/fastcgi_params;
> > 
> >     post_action /test_post.php;
> > }
> > 
> > И в этом случае у меня все равно в PHP $_SERVER['BBBBBBB'] равен 0.
> 
> Нужен отладочный лог:
> http://nginx.org/en/docs/debugging_log.html
> 
> Вместо ненужного регулярного выражения "~ ^/(test)\.php$" можно написать
> "= /test.php". Чем меньше регулярных выражений в конфиге, тем лучше.
> Оптимальное число - ноль.
> 
> 

Отладочный лог в приложении.

2011/05/27 16:12:06 [debug] 32512#0: epoll add event: fd:10 op:1
ev:00000001
2011/05/27 16:12:37 [debug] 32512#0: accept on 192.168.10.35:80, ready:
0
2011/05/27 16:12:37 [debug] 32512#0: posix_memalign:
0000000000ACB8D0:256 @16
2011/05/27 16:12:37 [debug] 32512#0: *1 accept: 192.168.10.35 fd:3
2011/05/27 16:12:37 [debug] 32512#0: *1 event timer add: 3:
600000:1306498957418
2011/05/27 16:12:37 [debug] 32512#0: *1 epoll add event: fd:3 op:1
ev:80000001
2011/05/27 16:12:37 [debug] 32512#0: *1 malloc: 0000000000AE3B80:1264
2011/05/27 16:12:37 [debug] 32512#0: *1 posix_memalign:
0000000000AE4080:256 @16
2011/05/27 16:12:37 [debug] 32512#0: *1 malloc: 0000000000AD9890:1024
2011/05/27 16:12:37 [debug] 32512#0: *1 posix_memalign:
0000000000AD9CA0:4096 @16
2011/05/27 16:12:37 [debug] 32512#0: *1 http process request line
2011/05/27 16:12:37 [debug] 32512#0: *1 recv: fd:3 430 of 1024
2011/05/27 16:12:37 [debug] 32512#0: *1 http request line:
"GET /test.php HTTP/1.1"
2011/05/27 16:12:37 [debug] 32512#0: *1 http uri: "/test.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 http args: ""
2011/05/27 16:12:37 [debug] 32512#0: *1 http exten: "php"
2011/05/27 16:12:37 [debug] 32512#0: *1 http process request header line
2011/05/27 16:12:37 [debug] 32512#0: *1 http header: "Host:
192.168.10.35"
2011/05/27 16:12:37 [debug] 32512#0: *1 http header: "Connection:
keep-alive"
2011/05/27 16:12:37 [debug] 32512#0: *1 http header: "Cache-Control:
max-age=0"
2011/05/27 16:12:37 [debug] 32512#0: *1 http header: "User-Agent:
Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/534.24 (KHTML, like Gecko)
Chrome/11.0.696.68 Safari/534.24"
2011/05/27 16:12:37 [debug] 32512#0: *1 http header: "Accept:
application/xml,application/xhtml
+xml,text/html;q=0.9,text/plain;q=0.8,image/png,*/*;q=0.5"
2011/05/27 16:12:37 [debug] 32512#0: *1 http header: "Accept-Encoding:
gzip,deflate,sdch"
2011/05/27 16:12:37 [debug] 32512#0: *1 http header: "Accept-Language:
ru,en;q=0.8"
2011/05/27 16:12:37 [debug] 32512#0: *1 http header: "Accept-Charset:
windows-1251,utf-8;q=0.7,*;q=0.3"
2011/05/27 16:12:37 [debug] 32512#0: *1 http header done
2011/05/27 16:12:37 [debug] 32512#0: *1 event timer del: 3:
1306498957418
2011/05/27 16:12:37 [debug] 32512#0: *1 generic phase: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 rewrite phase: 1
2011/05/27 16:12:37 [debug] 32512#0: *1 test location: "/test_post.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 test location: "/test.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 using configuration "/test.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 http cl:-1 max:1048576
2011/05/27 16:12:37 [debug] 32512#0: *1 rewrite phase: 3
2011/05/27 16:12:37 [debug] 32512#0: *1 post rewrite phase: 4
2011/05/27 16:12:37 [debug] 32512#0: *1 generic phase: 5
2011/05/27 16:12:37 [debug] 32512#0: *1 generic phase: 6
2011/05/27 16:12:37 [debug] 32512#0: *1 generic phase: 7
2011/05/27 16:12:37 [debug] 32512#0: *1 access phase: 8
2011/05/27 16:12:37 [debug] 32512#0: *1 access phase: 9
2011/05/27 16:12:37 [debug] 32512#0: *1 post access phase: 10
2011/05/27 16:12:37 [debug] 32512#0: *1 http init upstream, client
timer: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 epoll add event: fd:3 op:3
ev:80000005
2011/05/27 16:12:37 [debug] 32512#0: *1 posix_memalign:
0000000000AD0450:4096 @16
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy:
"SCRIPT_FILENAME"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy:
"/var/www/ldap/htdocs/"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "/test.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"SCRIPT_FILENAME: /var/www/ldap/htdocs//test.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "BBBBBBB"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "0"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "BBBBBBB: 0"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "QUERY_STRING"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "QUERY_STRING: "
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy:
"REQUEST_METHOD"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "GET"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "REQUEST_METHOD:
GET"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "CONTENT_TYPE"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "CONTENT_TYPE: "
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy:
"CONTENT_LENGTH"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "CONTENT_LENGTH:
"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "SCRIPT_NAME"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "/test.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"SCRIPT_NAME: /test.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "REQUEST_URI"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "/test.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"REQUEST_URI: /test.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "DOCUMENT_URI"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "/test.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"DOCUMENT_URI: /test.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy:
"DOCUMENT_ROOT"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "/var/www/deb"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"DOCUMENT_ROOT: /var/www/deb"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy:
"SERVER_PROTOCOL"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "HTTP/1.1"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "SERVER_PROTOCOL:
HTTP/1.1"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy:
"GATEWAY_INTERFACE"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "CGI/1.1"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"GATEWAY_INTERFACE: CGI/1.1"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy:
"SERVER_SOFTWARE"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "nginx/"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "1.0.0"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "SERVER_SOFTWARE:
nginx/1.0.0"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "REMOTE_ADDR"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "192.168.10.35"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "REMOTE_ADDR:
192.168.10.35"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "REMOTE_PORT"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "59541"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "REMOTE_PORT:
59541"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "SERVER_ADDR"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "192.168.10.35"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "SERVER_ADDR:
192.168.10.35"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "SERVER_PORT"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "80"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "SERVER_PORT: 80"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "SERVER_NAME"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: ""
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "SERVER_NAME: "
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy:
"REDIRECT_STATUS"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "200"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "REDIRECT_STATUS:
200"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "HTTP_HOST:
192.168.10.35"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "HTTP_CONNECTION:
keep-alive"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"HTTP_CACHE_CONTROL: max-age=0"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "HTTP_USER_AGENT:
Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/534.24 (KHTML, like Gecko)
Chrome/11.0.696.68 Safari/534.24"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "HTTP_ACCEPT:
application/xml,application/xhtml
+xml,text/html;q=0.9,text/plain;q=0.8,image/png,*/*;q=0.5"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"HTTP_ACCEPT_ENCODING: gzip,deflate,sdch"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"HTTP_ACCEPT_LANGUAGE: ru,en;q=0.8"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"HTTP_ACCEPT_CHARSET: windows-1251,utf-8;q=0.7,*;q=0.3"
2011/05/27 16:12:37 [debug] 32512#0: *1 http cleanup add:
0000000000ADAC88
2011/05/27 16:12:37 [debug] 32512#0: *1 get rr peer, try: 1
2011/05/27 16:12:37 [debug] 32512#0: *1 socket 13
2011/05/27 16:12:37 [debug] 32512#0: *1 epoll add connection: fd:13
ev:80000005
2011/05/27 16:12:37 [debug] 32512#0: *1 connect to 127.0.0.1:1234, fd:13
#2
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream connect: -2
2011/05/27 16:12:37 [debug] 32512#0: *1 event timer add: 13:
60000:1306498417427
2011/05/27 16:12:37 [debug] 32512#0: *1 http finalize request: -4,
"/test.php?" a:1, c:2
2011/05/27 16:12:37 [debug] 32512#0: *1 http request count:2 blk:0
2011/05/27 16:12:37 [debug] 32512#0: *1 http run request: "/test.php?"
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream check client,
write event:1, "/test.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream recv(): -1 (11:
Resource temporarily unavailable)
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream request:
"/test.php?"
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream send request
handler
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream send request
2011/05/27 16:12:37 [debug] 32512#0: *1 chain writer buf fl:0 s:880
2011/05/27 16:12:37 [debug] 32512#0: *1 chain writer in:
0000000000AD08B0
2011/05/27 16:12:37 [debug] 32512#0: *1 writev: 880
2011/05/27 16:12:37 [debug] 32512#0: *1 chain writer out:
0000000000000000
2011/05/27 16:12:37 [debug] 32512#0: *1 event timer del: 13:
1306498417427
2011/05/27 16:12:37 [debug] 32512#0: *1 event timer add: 13:
60000:1306498417428
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream request:
"/test.php?"
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream process header
2011/05/27 16:12:37 [debug] 32512#0: *1 malloc: 0000000000AD1460:4096
2011/05/27 16:12:37 [debug] 32512#0: *1 recv: fd:13 4096 of 4096
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 01
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 07
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 00
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 01
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 01
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: C0
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 00
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 00
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record length: 448
2011/05/27 16:12:37 [error] 32512#0: *1 FastCGI sent in stderr: "PHP
Warning:  date() [<a href='function.date'>function.date</a>]: It is not
safe to rely on the system's timezone settings. You are *required* to
use the date.timezone setting or the date_default_timezone_set()
function. In case you used any of those methods and you are still
getting this warning, you most likely misspelled the timezone
identifier. We selected 'Europe/Moscow' for 'MSD/4.0/DST' instead
in /var/www/ldap/htdocs/test.php on line 2" while reading response
header from upstream, client: 192.168.10.35, server: , request:
"GET /test.php HTTP/1.1", upstream: "fastcgi://127.0.0.1:1234", host:
"192.168.10.35"
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 01
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 06
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 00
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 01
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 15
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 5E
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 02
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 00
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record length: 5470
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi parser: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi header:
"Content-type: text/html"
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi parser: 1
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi header done
2011/05/27 16:12:37 [debug] 32512#0: *1 HTTP/1.1 200 OK
Server: nginx/1.0.0
Date: Fri, 27 May 2011 12:12:37 GMT
Content-Type: text/html
Transfer-Encoding: chunked
Connection: keep-alive
Keep-Alive: timeout=20
Content-Encoding: gzip

2011/05/27 16:12:37 [debug] 32512#0: *1 write new buf t:1 f:0
0000000000AD0BD8, pos 0000000000AD0BD8, size: 202 file: 0, size: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 http write filter: l:0 f:0 s:202
2011/05/27 16:12:37 [debug] 32512#0: *1 http cacheable: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream process upstream
2011/05/27 16:12:37 [debug] 32512#0: *1 pipe read upstream: 1
2011/05/27 16:12:37 [debug] 32512#0: *1 pipe preread: 3605
2011/05/27 16:12:37 [debug] 32512#0: *1 input buf #0 0000000000AD164B
2011/05/27 16:12:37 [debug] 32512#0: *1 input buf 0000000000AD164B 3605
2011/05/27 16:12:37 [debug] 32512#0: *1 malloc: 0000000000AD2470:4096
2011/05/27 16:12:37 [debug] 32512#0: *1 readv: 1:4096
2011/05/27 16:12:37 [debug] 32512#0: *1 pipe recv chain: 1856
2011/05/27 16:12:37 [debug] 32512#0: *1 readv: 1:2240
2011/05/27 16:12:37 [debug] 32512#0: *1 pipe recv chain: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 pipe buf in   s:1 t:1 f:0
0000000000AD1460, pos 0000000000AD164B, size: 3605 file: 0, size: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 pipe buf free s:0 t:1 f:0
0000000000AD2470, pos 0000000000AD2470, size: 1856 file: 0, size: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 input buf #1 0000000000AD2470
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 01
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 03
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 00
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 01
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 00
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 08
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 00
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 00
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record length: 8
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi sent end request
2011/05/27 16:12:37 [debug] 32512#0: *1 input buf 0000000000AD2470 1838
2011/05/27 16:12:37 [debug] 32512#0: *1 pipe write downstream: 1
2011/05/27 16:12:37 [debug] 32512#0: *1 pipe write downstream flush in
2011/05/27 16:12:37 [debug] 32512#0: *1 http output filter "/test.php?"
2011/05/27 16:12:37 [debug] 32512#0: *1 http copy filter: "/test.php?"
2011/05/27 16:12:37 [debug] 32512#0: *1 http postpone filter
"/test.php?" 0000000000AD0F58
2011/05/27 16:12:37 [debug] 32512#0: *1 http gzip filter
2011/05/27 16:12:37 [debug] 32512#0: *1 malloc: 0000000000B5D5C0:270336
2011/05/27 16:12:37 [debug] 32512#0: *1 gzip alloc: n:1 s:5936 a:8192
p:0000000000B5D5C0
2011/05/27 16:12:37 [debug] 32512#0: *1 gzip alloc: n:32768 s:2 a:65536
p:0000000000B5F5C0
2011/05/27 16:12:37 [debug] 32512#0: *1 gzip alloc: n:32768 s:2 a:65536
p:0000000000B6F5C0
2011/05/27 16:12:37 [debug] 32512#0: *1 gzip alloc: n:32768 s:2 a:65536
p:0000000000B7F5C0
2011/05/27 16:12:37 [debug] 32512#0: *1 gzip alloc: n:16384 s:4 a:65536
p:0000000000B8F5C0
2011/05/27 16:12:37 [debug] 32512#0: *1 gzip in: 0000000000AD0F88
2011/05/27 16:12:37 [debug] 32512#0: *1 gzip in_buf:0000000000AD0DD0
ni:0000000000AD164B ai:3605
2011/05/27 16:12:37 [debug] 32512#0: *1 malloc: 0000000000B9F5D0:8192
2011/05/27 16:12:37 [debug] 32512#0: *1 deflate in: ni:0000000000AD164B
no:0000000000B9F5D0 ai:3605 ao:8192 fl:0 redo:0
2011/05/27 16:12:37 [debug] 32512#0: *1 deflate out: ni:0000000000AD2460
no:0000000000B9F5D0 ai:0 ao:8192 rc:0
2011/05/27 16:12:37 [debug] 32512#0: *1 gzip in_buf:0000000000AD0DD0
pos:0000000000AD164B
2011/05/27 16:12:37 [debug] 32512#0: *1 gzip in: 0000000000AD0F98
2011/05/27 16:12:37 [debug] 32512#0: *1 gzip in_buf:0000000000AD0E90
ni:0000000000AD2470 ai:1838
2011/05/27 16:12:37 [debug] 32512#0: *1 deflate in: ni:0000000000AD2470
no:0000000000B9F5D0 ai:1838 ao:8192 fl:0 redo:0
2011/05/27 16:12:37 [debug] 32512#0: *1 deflate out: ni:0000000000AD2B9E
no:0000000000B9F5D0 ai:0 ao:8192 rc:0
2011/05/27 16:12:37 [debug] 32512#0: *1 gzip in_buf:0000000000AD0E90
pos:0000000000AD2470
2011/05/27 16:12:37 [debug] 32512#0: *1 gzip in: 0000000000000000
2011/05/27 16:12:37 [debug] 32512#0: *1 http copy filter: 0 "/test.php?"
2011/05/27 16:12:37 [debug] 32512#0: *1 pipe write downstream done
2011/05/27 16:12:37 [debug] 32512#0: *1 event timer: 13, old:
1306498417428, new: 1306498417429
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream exit:
0000000000000000
2011/05/27 16:12:37 [debug] 32512#0: *1 finalize http upstream request:
0
2011/05/27 16:12:37 [debug] 32512#0: *1 finalize http fastcgi request
2011/05/27 16:12:37 [debug] 32512#0: *1 free rr peer 1 0
2011/05/27 16:12:37 [debug] 32512#0: *1 close http upstream connection:
13
2011/05/27 16:12:37 [debug] 32512#0: *1 event timer del: 13:
1306498417428
2011/05/27 16:12:37 [debug] 32512#0: *1 reusable connection: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream temp fd: -1
2011/05/27 16:12:37 [debug] 32512#0: *1 http output filter "/test.php?"
2011/05/27 16:12:37 [debug] 32512#0: *1 http copy filter: "/test.php?"
2011/05/27 16:12:37 [debug] 32512#0: *1 http postpone filter
"/test.php?" 00007FFFC1AEB3B0
2011/05/27 16:12:37 [debug] 32512#0: *1 http gzip filter
2011/05/27 16:12:37 [debug] 32512#0: *1 gzip in: 0000000000AD1058
2011/05/27 16:12:37 [debug] 32512#0: *1 gzip in_buf:0000000000AD1008
ni:0000000000000000 ai:0
2011/05/27 16:12:37 [debug] 32512#0: *1 deflate in: ni:0000000000000000
no:0000000000B9F5D0 ai:0 ao:8192 fl:4 redo:0
2011/05/27 16:12:37 [debug] 32512#0: *1 deflate out: ni:0000000000000000
no:0000000000B9FAB1 ai:0 ao:6943 rc:1
2011/05/27 16:12:37 [debug] 32512#0: *1 gzip in_buf:0000000000AD1008
pos:0000000000000000
2011/05/27 16:12:37 [debug] 32512#0: *1 free: 0000000000B5D5C0
2011/05/27 16:12:37 [debug] 32512#0: *1 http chunk: 10
2011/05/27 16:12:37 [debug] 32512#0: *1 http chunk: 1257
2011/05/27 16:12:37 [debug] 32512#0: *1 write old buf t:1 f:0
0000000000AD0BD8, pos 0000000000AD0BD8, size: 202 file: 0, size: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 write new buf t:1 f:0
0000000000000000, pos 0000000000AD1148, size: 5 file: 0, size: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 write new buf t:0 f:0
0000000000000000, pos 000000000068EC08, size: 10 file: 0, size: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 write new buf t:1 f:0
0000000000B9F5D0, pos 0000000000B9F5D0, size: 1257 file: 0, size: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 write new buf t:0 f:0
0000000000000000, pos 0000000000476EE8, size: 7 file: 0, size: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 http write filter: l:1 f:1
s:1481
2011/05/27 16:12:37 [debug] 32512#0: *1 http write filter limit 0
2011/05/27 16:12:37 [debug] 32512#0: *1 writev: 1481
2011/05/27 16:12:37 [debug] 32512#0: *1 http write filter
0000000000000000
2011/05/27 16:12:37 [debug] 32512#0: *1 http copy filter: 0 "/test.php?"
2011/05/27 16:12:37 [debug] 32512#0: *1 http finalize request: 0,
"/test.php?" a:1, c:1
2011/05/27 16:12:37 [debug] 32512#0: *1 post action: "/test_post.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 internal redirect:
"/test_post.php?"
2011/05/27 16:12:37 [debug] 32512#0: *1 rewrite phase: 1
2011/05/27 16:12:37 [debug] 32512#0: *1 test location: "/test_post.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 using configuration
"/test_post.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 http cl:-1 max:1048576
2011/05/27 16:12:37 [debug] 32512#0: *1 rewrite phase: 3
2011/05/27 16:12:37 [debug] 32512#0: *1 post rewrite phase: 4
2011/05/27 16:12:37 [debug] 32512#0: *1 generic phase: 5
2011/05/27 16:12:37 [debug] 32512#0: *1 generic phase: 6
2011/05/27 16:12:37 [debug] 32512#0: *1 generic phase: 7
2011/05/27 16:12:37 [debug] 32512#0: *1 access phase: 8
2011/05/27 16:12:37 [debug] 32512#0: *1 access phase: 9
2011/05/27 16:12:37 [debug] 32512#0: *1 post access phase: 10
2011/05/27 16:12:37 [debug] 32512#0: *1 posix_memalign:
0000000000AD3480:4096 @16
2011/05/27 16:12:37 [debug] 32512#0: *1 http init upstream, client
timer: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy:
"SCRIPT_FILENAME"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy:
"/var/www/ldap/htdocs/"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var:
"/test_post.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"SCRIPT_FILENAME: /var/www/ldap/htdocs//test_post.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "BBBBBBB"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "0"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "BBBBBBB: 0"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "QUERY_STRING"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "QUERY_STRING: "
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy:
"REQUEST_METHOD"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "GET"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "REQUEST_METHOD:
GET"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "CONTENT_TYPE"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "CONTENT_TYPE: "
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy:
"CONTENT_LENGTH"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "CONTENT_LENGTH:
"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "SCRIPT_NAME"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var:
"/test_post.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"SCRIPT_NAME: /test_post.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "REQUEST_URI"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "/test.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"REQUEST_URI: /test.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "DOCUMENT_URI"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var:
"/test_post.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"DOCUMENT_URI: /test_post.php"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy:
"DOCUMENT_ROOT"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "/var/www/deb"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"DOCUMENT_ROOT: /var/www/deb"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy:
"SERVER_PROTOCOL"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "HTTP/1.1"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "SERVER_PROTOCOL:
HTTP/1.1"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy:
"GATEWAY_INTERFACE"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "CGI/1.1"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"GATEWAY_INTERFACE: CGI/1.1"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy:
"SERVER_SOFTWARE"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "nginx/"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "1.0.0"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "SERVER_SOFTWARE:
nginx/1.0.0"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "REMOTE_ADDR"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "192.168.10.35"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "REMOTE_ADDR:
192.168.10.35"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "REMOTE_PORT"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "59541"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "REMOTE_PORT:
59541"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "SERVER_ADDR"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "192.168.10.35"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "SERVER_ADDR:
192.168.10.35"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "SERVER_PORT"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: "80"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "SERVER_PORT: 80"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "SERVER_NAME"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script var: ""
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "SERVER_NAME: "
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy:
"REDIRECT_STATUS"
2011/05/27 16:12:37 [debug] 32512#0: *1 http script copy: "200"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "REDIRECT_STATUS:
200"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "HTTP_HOST:
192.168.10.35"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "HTTP_CONNECTION:
keep-alive"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"HTTP_CACHE_CONTROL: max-age=0"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "HTTP_USER_AGENT:
Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/534.24 (KHTML, like Gecko)
Chrome/11.0.696.68 Safari/534.24"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param: "HTTP_ACCEPT:
application/xml,application/xhtml
+xml,text/html;q=0.9,text/plain;q=0.8,image/png,*/*;q=0.5"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"HTTP_ACCEPT_ENCODING: gzip,deflate,sdch"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"HTTP_ACCEPT_LANGUAGE: ru,en;q=0.8"
2011/05/27 16:12:37 [debug] 32512#0: *1 fastcgi param:
"HTTP_ACCEPT_CHARSET: windows-1251,utf-8;q=0.7,*;q=0.3"
2011/05/27 16:12:37 [debug] 32512#0: *1 http cleanup add:
0000000000AD1410
2011/05/27 16:12:37 [debug] 32512#0: *1 get rr peer, try: 1
2011/05/27 16:12:37 [debug] 32512#0: *1 socket 13
2011/05/27 16:12:37 [debug] 32512#0: *1 epoll add connection: fd:13
ev:80000005
2011/05/27 16:12:37 [debug] 32512#0: *1 connect to 127.0.0.1:1234, fd:13
#3
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream connect: -2
2011/05/27 16:12:37 [debug] 32512#0: *1 event timer add: 13:
60000:1306498417429
2011/05/27 16:12:37 [debug] 32512#0: *1 http finalize request: -4,
"/test_post.php?" a:1, c:2
2011/05/27 16:12:37 [debug] 32512#0: *1 http request count:2 blk:0
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream request:
"/test_post.php?"
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream send request
handler
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream send request
2011/05/27 16:12:37 [debug] 32512#0: *1 chain writer buf fl:0 s:896
2011/05/27 16:12:37 [debug] 32512#0: *1 chain writer in:
0000000000AD11D0
2011/05/27 16:12:37 [debug] 32512#0: *1 writev: 896
2011/05/27 16:12:37 [debug] 32512#0: *1 chain writer out:
0000000000000000
2011/05/27 16:12:37 [debug] 32512#0: *1 event timer del: 13:
1306498417429
2011/05/27 16:12:37 [debug] 32512#0: *1 event timer add: 13:
60000:1306498417429
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream request:
"/test_post.php?"
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream dummy handler
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream request:
"/test_post.php?"
2011/05/27 16:12:37 [debug] 32512#0: *1 http upstream process header
2011/05/27 16:12:37 [debug] 32512#0: *1 malloc: 0000000000B5D5C0:4096
2011/05/27 16:12:37 [debug] 32512#0: *1 recv: fd:13 1504 of 4096
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 01
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 07
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 00
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 01
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 05
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 9A
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 06
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 00
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record length: 1434
2011/05/27 16:12:37 [error] 32512#0: *1 FastCGI sent in stderr:
"TESTPOSTACTION  -- > array (
  'PHP_FCGI_CHILDREN' => '5',
  'PHP_FCGI_MAX_REQUESTS' => '500',
  'USER' => 'root',
  'HOME' => '/root',
  'PATH' =>
'/bin:/sbin:/usr/bin:/usr/sbin:/usr/local/bin:/usr/local/sbin',
  'FCGI_ROLE' => 'RESPONDER',
  'SCRIPT_FILENAME' => '/var/www/ldap/htdocs//test_post.php',
  'BBBBBBB' => '0',
  'QUERY_STRING' => '',
  'REQUEST_METHOD' => 'GET',
  'CONTENT_TYPE' => '',
  'CONTENT_LENGTH' => '',
  'SCRIPT_NAME' => '/test_post.php',
  'REQUEST_URI' => '/test.php',
  'DOCUMENT_URI' => '/test_post.php',
  'DOCUMENT_ROOT' => '/var/www/deb',
  'SERVER_PROTOCOL' => 'HTTP/1.1',
  'GATEWAY_INTERFACE' => 'CGI/1.1',
  'SERVER_SOFTWARE' => 'nginx/1.0.0',
  'REMOTE_ADDR' => '192.168.10.35',
  'REMOTE_PORT' => '59541',
  'SERVER_ADDR' => '192.168.10.35',
  'SERVER_PORT' => '80',
  'SERVER_NAME' => '',
  'REDIRECT_STATUS' => '200',
  'HTTP_HOST' => '192.168.10.35',
  'HTTP_CONNECTION' => 'keep-alive',
  'HTTP_CACHE_CONTROL' => 'max-age=0',
  'HTTP_USER_AGENT' => 'Mozilla/5.0 (X11; Linux x86_64)
AppleWebKit/534.24 (KHTML, like Gecko) Chrome/11.0.696.68
Safari/534.24',
  'HTTP_ACCEPT' => 'application/xml,application/xhtml
+xml,text/html;q=0.9,text/plain;q=0.8,image/png,*/*;q=0.5',
  'HTTP_ACCEPT_ENCODING' => 'gzip,deflate,sdch',
  'HTTP_ACCEPT_LANGUAGE' => 'ru,en;q=0.8',
  'HTTP_ACCEPT_CHARSET' => 'windows-1251,utf-8;q=0.7,*;q=0.3',
  'PHP_SELF' => '/test_post.php',
  'REQUEST_TIME' => 1306498357,
)" while reading response header from upstream, client: 192.168.10.35,
server: , request: "GET /test.php HTTP/1.1", upstream:
"fastcgi://127.0.0.1:1234", host: "192.168.10.35"
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 01
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 06
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 00
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 01
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 00
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 1B
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 05
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record byte: 00
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi record length: 27
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi parser: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi header:
"Content-type: text/html"
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi parser: 1
2011/05/27 16:12:37 [debug] 32512#0: *1 http fastcgi header done
2011/05/27 16:12:37 [debug] 32512#0: *1 finalize http upstream request:
0
2011/05/27 16:12:37 [debug] 32512#0: *1 finalize http fastcgi request
2011/05/27 16:12:37 [debug] 32512#0: *1 free rr peer 1 0
2011/05/27 16:12:37 [debug] 32512#0: *1 close http upstream connection:
13
2011/05/27 16:12:37 [debug] 32512#0: *1 event timer del: 13:
1306498417429
2011/05/27 16:12:37 [debug] 32512#0: *1 reusable connection: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 http output filter
"/test_post.php?"
2011/05/27 16:12:37 [debug] 32512#0: *1 http copy filter:
"/test_post.php?"
2011/05/27 16:12:37 [debug] 32512#0: *1 http postpone filter
"/test_post.php?" 00007FFFC1AEB450
2011/05/27 16:12:37 [debug] 32512#0: *1 write new buf t:0 f:0
0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 http write filter: l:0 f:0 s:0
2011/05/27 16:12:37 [debug] 32512#0: *1 http copy filter: 0
"/test_post.php?"
2011/05/27 16:12:37 [debug] 32512#0: *1 http finalize request: 0,
"/test_post.php?" a:1, c:1
2011/05/27 16:12:37 [debug] 32512#0: *1 http request count:1 blk:0
2011/05/27 16:12:37 [debug] 32512#0: *1 http close request
2011/05/27 16:12:37 [debug] 32512#0: *1 http log handler
2011/05/27 16:12:37 [debug] 32512#0: *1 free: 0000000000B9F5D0
2011/05/27 16:12:37 [debug] 32512#0: *1 free: 0000000000B5D5C0
2011/05/27 16:12:37 [debug] 32512#0: *1 free: 0000000000AD2470
2011/05/27 16:12:37 [debug] 32512#0: *1 free: 0000000000AD1460
2011/05/27 16:12:37 [debug] 32512#0: *1 free: 0000000000AD9CA0, unused:
0
2011/05/27 16:12:37 [debug] 32512#0: *1 free: 0000000000AD0450, unused:
8
2011/05/27 16:12:37 [debug] 32512#0: *1 free: 0000000000AD3480, unused:
1287
2011/05/27 16:12:37 [debug] 32512#0: *1 close http connection: 3
2011/05/27 16:12:37 [debug] 32512#0: *1 reusable connection: 0
2011/05/27 16:12:37 [debug] 32512#0: *1 free: 0000000000AD9890
2011/05/27 16:12:37 [debug] 32512#0: *1 free: 0000000000AE3B80
2011/05/27 16:12:37 [debug] 32512#0: *1 free: 0000000000ACB8D0, unused:
8
2011/05/27 16:12:37 [debug] 32512#0: *1 free: 0000000000AE4080, unused:
128
2011/05/27 16:12:37 [debug] 32512#0: accept on 192.168.10.35:80, ready:
0
2011/05/27 16:12:37 [debug] 32512#0: posix_memalign:
0000000000ACB8D0:256 @16
2011/05/27 16:12:37 [debug] 32512#0: *4 accept: 192.168.10.35 fd:3
2011/05/27 16:12:37 [debug] 32512#0: *4 event timer add: 3:
600000:1306498957486
2011/05/27 16:12:37 [debug] 32512#0: *4 epoll add event: fd:3 op:1
ev:80000001
2011/05/27 16:12:37 [debug] 32512#0: *4 malloc: 0000000000AE3B80:1264
2011/05/27 16:12:37 [debug] 32512#0: *4 posix_memalign:
0000000000AE4080:256 @16
2011/05/27 16:12:37 [debug] 32512#0: *4 malloc: 0000000000AD9890:1024
2011/05/27 16:12:37 [debug] 32512#0: *4 posix_memalign:
0000000000AD9CA0:4096 @16
2011/05/27 16:12:37 [debug] 32512#0: *4 http process request line
2011/05/27 16:12:37 [debug] 32512#0: *4 recv: fd:3 320 of 1024
2011/05/27 16:12:37 [debug] 32512#0: *4 http request line:
"GET /favicon.ico HTTP/1.1"
2011/05/27 16:12:37 [debug] 32512#0: *4 http uri: "/favicon.ico"
2011/05/27 16:12:37 [debug] 32512#0: *4 http args: ""
2011/05/27 16:12:37 [debug] 32512#0: *4 http exten: "ico"
2011/05/27 16:12:37 [debug] 32512#0: *4 http process request header line
2011/05/27 16:12:37 [debug] 32512#0: *4 http header: "Host:
192.168.10.35"
2011/05/27 16:12:37 [debug] 32512#0: *4 http header: "Connection:
keep-alive"
2011/05/27 16:12:37 [debug] 32512#0: *4 http header: "Accept: */*"
2011/05/27 16:12:37 [debug] 32512#0: *4 http header: "User-Agent:
Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/534.24 (KHTML, like Gecko)
Chrome/11.0.696.68 Safari/534.24"
2011/05/27 16:12:37 [debug] 32512#0: *4 http header: "Accept-Encoding:
gzip,deflate,sdch"
2011/05/27 16:12:37 [debug] 32512#0: *4 http header: "Accept-Language:
ru,en;q=0.8"
2011/05/27 16:12:37 [debug] 32512#0: *4 http header: "Accept-Charset:
windows-1251,utf-8;q=0.7,*;q=0.3"
2011/05/27 16:12:37 [debug] 32512#0: *4 http header done
2011/05/27 16:12:37 [debug] 32512#0: *4 event timer del: 3:
1306498957486
2011/05/27 16:12:37 [debug] 32512#0: *4 generic phase: 0
2011/05/27 16:12:37 [debug] 32512#0: *4 rewrite phase: 1
2011/05/27 16:12:37 [debug] 32512#0: *4 test location: "/test_post.php"
2011/05/27 16:12:37 [debug] 32512#0: *4 test location: "/test.php"
2011/05/27 16:12:37 [debug] 32512#0: *4 using configuration ""
2011/05/27 16:12:37 [debug] 32512#0: *4 http cl:-1 max:1048576
2011/05/27 16:12:37 [debug] 32512#0: *4 rewrite phase: 3
2011/05/27 16:12:37 [debug] 32512#0: *4 post rewrite phase: 4
2011/05/27 16:12:37 [debug] 32512#0: *4 generic phase: 5
2011/05/27 16:12:37 [debug] 32512#0: *4 generic phase: 6
2011/05/27 16:12:37 [debug] 32512#0: *4 generic phase: 7
2011/05/27 16:12:37 [debug] 32512#0: *4 access phase: 8
2011/05/27 16:12:37 [debug] 32512#0: *4 access phase: 9
2011/05/27 16:12:37 [debug] 32512#0: *4 post access phase: 10
2011/05/27 16:12:37 [debug] 32512#0: *4 content phase: 11
2011/05/27 16:12:37 [debug] 32512#0: *4 content phase: 12
2011/05/27 16:12:37 [debug] 32512#0: *4 content phase: 13
2011/05/27 16:12:37 [debug] 32512#0: *4 http filename:
"/var/www/deb/favicon.ico"
2011/05/27 16:12:37 [debug] 32512#0: *4 add cleanup: 0000000000ADA780
2011/05/27 16:12:37 [error] 32512#0: *4 open()
"/var/www/deb/favicon.ico" failed (2: No such file or directory),
client: 192.168.10.35, server: , request: "GET /favicon.ico HTTP/1.1",
host: "192.168.10.35"
2011/05/27 16:12:37 [debug] 32512#0: *4 http finalize request: 404,
"/favicon.ico?" a:1, c:1
2011/05/27 16:12:37 [debug] 32512#0: *4 http special response: 404,
"/favicon.ico?"
2011/05/27 16:12:37 [debug] 32512#0: *4 http set discard body
2011/05/27 16:12:37 [debug] 32512#0: *4 HTTP/1.1 404 Not Found
Server: nginx/1.0.0
Date: Fri, 27 May 2011 12:12:37 GMT
Content-Type: text/html
Content-Length: 570
Connection: keep-alive
Keep-Alive: timeout=20

2011/05/27 16:12:37 [debug] 32512#0: *4 write new buf t:1 f:0
0000000000ADA800, pos 0000000000ADA800, size: 178 file: 0, size: 0
2011/05/27 16:12:37 [debug] 32512#0: *4 http write filter: l:0 f:0 s:178
2011/05/27 16:12:37 [debug] 32512#0: *4 http output filter
"/favicon.ico?"
2011/05/27 16:12:37 [debug] 32512#0: *4 http copy filter:
"/favicon.ico?"
2011/05/27 16:12:37 [debug] 32512#0: *4 http postpone filter
"/favicon.ico?" 0000000000ADAA40
2011/05/27 16:12:37 [debug] 32512#0: *4 write old buf t:1 f:0
0000000000ADA800, pos 0000000000ADA800, size: 178 file: 0, size: 0
2011/05/27 16:12:37 [debug] 32512#0: *4 write new buf t:0 f:0
0000000000000000, pos 000000000068B120, size: 116 file: 0, size: 0
2011/05/27 16:12:37 [debug] 32512#0: *4 write new buf t:0 f:0
0000000000000000, pos 000000000068AB20, size: 52 file: 0, size: 0
2011/05/27 16:12:37 [debug] 32512#0: *4 write new buf t:0 f:0
0000000000000000, pos 000000000068ABA0, size: 402 file: 0, size: 0
2011/05/27 16:12:37 [debug] 32512#0: *4 http write filter: l:1 f:0 s:748
2011/05/27 16:12:37 [debug] 32512#0: *4 http write filter limit 0
2011/05/27 16:12:37 [debug] 32512#0: *4 writev: 748
2011/05/27 16:12:37 [debug] 32512#0: *4 http write filter
0000000000000000
2011/05/27 16:12:37 [debug] 32512#0: *4 http copy filter: 0
"/favicon.ico?"
2011/05/27 16:12:37 [debug] 32512#0: *4 http finalize request: 0,
"/favicon.ico?" a:1, c:1
2011/05/27 16:12:37 [debug] 32512#0: *4 set http keepalive handler
2011/05/27 16:12:37 [debug] 32512#0: *4 http close request
2011/05/27 16:12:37 [debug] 32512#0: *4 http log handler
2011/05/27 16:12:37 [debug] 32512#0: *4 free: 0000000000AD9CA0, unused:
292
2011/05/27 16:12:37 [debug] 32512#0: *4 event timer add: 3:
75000:1306498432487
2011/05/27 16:12:37 [debug] 32512#0: *4 free: 0000000000AE3B80
2011/05/27 16:12:37 [debug] 32512#0: *4 free: 0000000000AD9890
2011/05/27 16:12:37 [debug] 32512#0: *4 hc free: 0000000000000000 0
2011/05/27 16:12:37 [debug] 32512#0: *4 hc busy: 0000000000000000 0
2011/05/27 16:12:37 [debug] 32512#0: *4 tcp_nodelay
2011/05/27 16:12:37 [debug] 32512#0: *4 reusable connection: 1
2011/05/27 16:12:37 [debug] 32512#0: *4 post event 0000000000B296D8
2011/05/27 16:12:37 [debug] 32512#0: *4 delete posted event
0000000000B296D8
2011/05/27 16:12:37 [debug] 32512#0: *4 http keepalive handler
2011/05/27 16:12:37 [debug] 32512#0: *4 malloc: 0000000000AE3B80:1024
2011/05/27 16:12:37 [debug] 32512#0: *4 recv: fd:3 -1 of 1024
2011/05/27 16:12:37 [debug] 32512#0: *4 recv() not ready (11: Resource
temporarily unavailable)

_______________________________________________
nginx-ru mailing list
nginx-ru@xxxxxxxxx
http://nginx.org/mailman/listinfo/nginx-ru

Follow-Ups:
- Re: $body bytes sent всегда 0, но не в логах.
  - From: Igor Sysoev

References:
- $body bytes sent всегда 0, но не в л огах.
  - From: stopka
- Re: $body bytes sent всегда 0, но не в логах.
  - From: Igor Sysoev
- Re: $body bytes sent всегда 0, но не в логах.
  - From: step
- Re: $body bytes sent всегда 0, но не в логах.
  - From: Igor Sysoev

Prev by Date: Re: $body bytes sent всегда 0, но не в логах.
Next by Date: Re: $body bytes sent всегда 0, но не в логах.
Previous by thread: Re: $body bytes sent всегда 0, но не в логах.
Next by thread: Re: $body bytes sent всегда 0, но не в логах.
Index(es):
- Date
- Thread