Возможный баг в nginx с 0 телом

[Igor Sysoev]

On Thu, 26 Oct 2006 toto at darkside.tomsk.ru wrote:

>> Можно сделать отладочный лог первого умирающего запроса и прислать мне ?
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http process request line
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http request line: "GET /nginx.php 
> HTTP/1.1"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http uri: "/nginx.php"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http args: ""
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http exten: "php"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http process request header line
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http header: "Host: 
> mail.darkside.tomsk.ru"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http header: "Connection: Close"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http header done
> 2006/10/26 18:16:49 [debug] 10553#0: *1 generic phase: 0
> 2006/10/26 18:16:49 [debug] 10553#0: *1 find location for "/nginx.php"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 find location: "/"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 using configuration "/"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http cl:-1 max:1992294400
> 2006/10/26 18:16:49 [debug] 10553#0: *1 generic phase: 2
> 2006/10/26 18:16:49 [debug] 10553#0: *1 post rewrite phase: 3
> 2006/10/26 18:16:49 [debug] 10553#0: *1 access phase: 4
> 2006/10/26 18:16:49 [debug] 10553#0: *1 access phase: 5
> 2006/10/26 18:16:49 [debug] 10553#0: *1 post access phase: 6
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http init upstream, client timer: 0
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http proxy header:
> "GET /nginx.php HTTP/1.0
> Host: mail.darkside.tomsk.ru
> X-Real-IP: 213.210.101.88
> X-Forwarded-For: 213.210.101.88
> Connection: close
>
> "
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http cleanup add: 080E6000
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http upstream connect: -2
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http upstream send request handler
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http upstream send request
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http upstream check client, write 
> event:1, "/nginx.php"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http upstream process header
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http proxy status 0 "401 
> Authorization Required"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http proxy header: "Date: Thu, 26 Oct 
> 2006 11:16:49 GMT"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http proxy header: "Server: Apache"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http proxy header: "X-Powered-By: 
> PHP/5.1.6-pl6-gentoo"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http proxy header: "WWW-Authenticate: 
> Basic realm="asd""
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http proxy header: "Content-Length: 
> 0"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http proxy header: "Connection: 
> close"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http proxy header: "Content-Type: 
> text/html"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http proxy header done
> 2006/10/26 18:16:49 [debug] 10553#0: *1 HTTP/1.1 401 Authorization Required
> Server: nginx/0.4.10
> Date: Thu, 26 Oct 2006 11:16:49 GMT
> Content-Type: text/html
> Connection: close
> X-Powered-By: PHP/5.1.6-pl6-gentoo
> WWW-Authenticate: Basic realm="asd"
> Content-Length: 0
>
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http write filter: l:0 f:0 s:234
> 2006/10/26 18:16:49 [debug] 10553#0: *1 tcp_nodelay
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http output filter "/nginx.php?"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 copy filter: "/nginx.php?"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http postpone filter "/nginx.php?" 
> BF88698C
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http postpone filter out 
> "/nginx.php?"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http write filter: l:0 f:1 s:234
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http write filter to send 0
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http write filter 00000000
> 2006/10/26 18:16:49 [debug] 10553#0: *1 copy filter: 0 "/nginx.php?"
> 2006/10/26 18:16:49 [debug] 10553#0: *1 http upstream dummy handler
> 2006/10/26 18:17:04 [debug] 10553#0: *1 http upstream check client, write 
> event:0, "/nginx.php"
> 2006/10/26 18:17:04 [info] 10553#0: *1 client closed prematurely connection, 
> so upstream connection is closed too while reading response header from 
> upstream,
> client: 213.210.101.88, server: localhost, URL: "/nginx.php", upstream: 
> "http://192.168.1.14:80/nginx.php", host: "mail.darkside.tomsk.ru"
> 2006/10/26 18:17:04 [debug] 10553#0: *1 finalize http upstream request: 499
> 2006/10/26 18:17:04 [debug] 10553#0: *1 finalize http proxy request
> 2006/10/26 18:17:04 [debug] 10553#0: *1 close http upstream connection: 13
> 2006/10/26 18:17:04 [debug] 10553#0: *1 http output filter "/nginx.php?"
> 2006/10/26 18:17:04 [debug] 10553#0: *1 copy filter: "/nginx.php?"
> 2006/10/26 18:17:04 [debug] 10553#0: *1 http postpone filter "/nginx.php?" 
> BF88695C
> 2006/10/26 18:17:04 [debug] 10553#0: *1 http postpone filter out 
> "/nginx.php?"
> 2006/10/26 18:17:04 [debug] 10553#0: *1 copy filter: -1 "/nginx.php?"
> 2006/10/26 18:17:04 [debug] 10553#0: *1 http finalize request: -1, 
> "/nginx.php?"
> 2006/10/26 18:17:04 [debug] 10553#0: *1 http close request
> 2006/10/26 18:17:04 [debug] 10553#0: *1 http log handler
> 2006/10/26 18:17:04 [debug] 10553#0: *1 close http connection: 10
>
>
> я завершил процесс, так как он замёрз...

Нужно сделать полный дебаг лог на глобальном уровне, а не на уровне сервера
и дождаться таймаута.


Игорь Сысоев
http://sysoev.ru