А РХИВ :: nginx-ru
Nginx-ru mailing list archive (nginx-ru@sysoev.ru)
[Date Prev ][Date Next ][Thread Prev ][Thread Next ][Date Index ][Thread Index ]
Re: Периодические проблемы с f astcgi, неполный/пустой ответ от fastcg i php backend
To : nginx-ru@xxxxxxxxx
Subject : Re: Периодические проблемы с f astcgi, неполный/пустой ответ от fastcg i php backend
From : Noon es Shadow <noonesshadow@xxxxxxxxx >
Date: Fri, 5 Jun 2009 22:59:17 +0300
Dkim-signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=gamma; h=domainkey-signature:mime-version:received:in-reply-to:references :date:message-id:subject:from:to:content-type; bh=4wK80BBjjpvakJeRquzM6FK6zKHP1G7wBukNya7L4bA=; b=UtYJ5D6ki3e98ZEZiOouxM4fxab8HvrSGBvr2QEq7+QLT06hQaGTNjHg/NWytR5AL3 DJTBD8/XuyJT19IhY05GLsSTLjVOoj+3XpdnYMDkk6HGm1La8UdjkZLy4zizheQzwXSM aUH3vyo1eWNA+cbhqFBATeHmQfT8fM2l7LKrQ=
Domainkey-signature: a=rsa-sha1; c=nofws; d=gmail.com; s=gamma; h=mime-version:in-reply-to:references:date:message-id:subject:from:to :content-type; b=NI9UmvBuKKBW2DWK283I5yGhjxrdyNKssJip1OvBUPrH7+OxIg+s/GXEOuYKQzLkTn 8nb0DqTouheacqqp6xHrKq26ydB9lA+fdEaP7xyB9/Pcbdh2np3Xlb0WUAYmNw0j0j2t quRendzdGqiwtqCwrYkGRp6uhjs/VIZYSdLG8=
In-reply-to: <b775c71f0906050258u45caf34dwa63a7475be5591de@xxxxxxxxxxxxxx >
References: <b775c71f0906050258u45caf34dwa63a7475be5591de@xxxxxxxxxxxxxx >
Несколько возможно важных моментов 1) По кэшу видно, что ответ от бэкенда приходит правильный 2) Переключил php и nginx на юникс-сокеты, проблема пропала5 июня 2009 г. 12:58 пользователь Noon es Shadow
<noonesshadow@xxxxxxxxx > написал:
Схема: Клиент посылает запрос, если это динамика то Нгинк пересылает запрос на бэкенд php по fastcgi, тип ответа text/xml, и выполняет трансформацию по заданному шаблону XSL
Изредка, примерно раз из 100, случается следующее:
Клиенту выдается ошибка 500 (internal server error), в лог пишется not well formed XML document while sending to client При повторном обновлении страницы все срабатывает как надо. Система: Linux 2.6.25-14.fc9.i686, epoll,
configure arguments: --with-http_xslt_module --without-http_rewrite_module --without-http_memcached_module --without-http_upstream_ip_hash_module --with-debug --with-pcre=./pcre-7.9 (аналогично происходит и при configure arguments: --with-http_xslt_module --with-pcre=./pcre-7.9)
--with-debug: 2009/06/05 12:30:46 [debug] 17496#0: *5 http upstream request: "/info/s32.html?" 2009/06/05 12:30:46 [debug] 17496#0: *5 http upstream dummy handler 2009/06/05 12:30:46 [debug] 17496#0: timer delta: 1
2009/06/05 12:30:46 [debug] 17496#0: posted events 00000000 2009/06/05 12:30:46 [debug] 17496#0: worker cycle 2009/06/05 12:30:46 [debug] 17496#0: epoll timer: 52840 2009/06/05 12:30:46 [debug] 17496#0: epoll: fd:18 ev:0005 d:09A082C1
2009/06/05 12:30:46 [debug] 17496#0: *5 http upstream request: "/info/s32.html?" 2009/06/05 12:30:46 [debug] 17496#0: *5 http upstream process upstream 2009/06/05 12:30:46 [debug] 17496#0: *5 pipe read upstream: 1
2009/06/05 12:30:46 [debug] 17496#0: *5 readv: 5:4096 2009/06/05 12:30:46 [debug] 17496#0: *5 pipe recv chain: 0 2009/06/05 12:30:46 [debug] 17496#0: *5 pipe buf free s:0 t:1 f:0 09A39F78, pos 09A39F78, size: 1616 file: 0, size: 0
2009/06/05 12:30:46 [debug] 17496#0: *5 pipe buf free s:0 t:1 f:0 09A3CF90, pos 09A3CF90, size: 0 file: 0, size: 0 2009/06/05 12:30:46 [debug] 17496#0: *5 pipe buf free s:0 t:1 f:0 09A3AF80, pos 09A3AF80, size: 0 file: 0, size: 0
2009/06/05 12:30:46 [debug] 17496#0: *5 pipe buf free s:0 t:1 f:0 09A37F68, pos 09A37F68, size: 0 file: 0, size: 0 2009/06/05 12:30:46 [debug] 17496#0: *5 pipe buf free s:0 t:1 f:0 09A38F70, pos 09A38F70, size: 0 file: 0, size: 0
2009/06/05 12:30:46 [debug] 17496#0: *5 input buf #5 09A39F78 2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi record byte: 01 2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi record byte: 06 2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi record byte: 00
2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi record byte: 01 2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi record byte: 00 2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi record byte: 01 2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi record byte: 07
2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi record byte: 00 2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi record length: 1 2009/06/05 12:30:46 [debug] 17496#0: *5 input buf #5 09A3A5B0 2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi record byte: 01
2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi record byte: 03 2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi record byte: 00 2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi record byte: 01 2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi record byte: 00
2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi record byte: 08 2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi record byte: 00 2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi record byte: 00 2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi record length: 8
2009/06/05 12:30:46 [debug] 17496#0: *5 http fastcgi sent end request 2009/06/05 12:30:46 [debug] 17496#0: *5 input buf 09A3A5B0 1 2009/06/05 12:30:46 [debug] 17496#0: *5 free: 09A3CF90 2009/06/05 12:30:46 [debug] 17496#0: *5 free: 09A3AF80
2009/06/05 12:30:46 [debug] 17496#0: *5 free: 09A37F68 2009/06/05 12:30:46 [debug] 17496#0: *5 free: 09A38F70 2009/06/05 12:30:46 [debug] 17496#0: *5 pipe write downstream: 1 2009/06/05 12:30:46 [debug] 17496#0: *5 pipe write downstream flush in
2009/06/05 12:30:46 [debug] 17496#0: *5 http output filter "/info/s32.html?" 2009/06/05 12:30:46 [debug] 17496#0: *5 copy filter: "/info/s32.html?" 2009/06/05 12:30:46 [debug] 17496#0: *5 xslt filter body
2009/06/05 12:30:46 [debug] 17496#0: *5 copy filter: 0 "/info/s32.html?" 2009/06/05 12:30:46 [debug] 17496#0: *5 pipe write downstream done 2009/06/05 12:30:46 [debug] 17496#0: *5 event timer: 18, old: 2948757598, new: 2948757635
2009/06/05 12:30:46 [debug] 17496#0: *5 http upstream exit: 00000000 2009/06/05 12:30:46 [debug] 17496#0: *5 finalize http upstream request: 0 2009/06/05 12:30:46 [debug] 17496#0: *5 finalize http fastcgi request
2009/06/05 12:30:46 [debug] 17496#0: *5 free rr peer 1 0 2009/06/05 12:30:46 [debug] 17496#0: *5 close http upstream connection: 18 2009/06/05 12:30:46 [debug] 17496#0: *5 event timer del: 18: 2948757598 2009/06/05 12:30:46 [debug] 17496#0: *5 http upstream temp fd: -1
2009/06/05 12:30:46 [debug] 17496#0: *5 http output filter "/info/s32.html?" 2009/06/05 12:30:46 [debug] 17496#0: *5 copy filter: "/info/s32.html?" 2009/06/05 12:30:46 [debug] 17496#0: *5 xslt filter body
2009/06/05 12:30:46 [error] 17496#0: *5 not well formed XML document while sending to client, client: ***.***.***.***, server: ***.***.***.***, request: "GET /info/s32.html HTTP/1.1", upstream: "fastcgi://127.0.0.1:9000 ", host: "***.***.***.***"
2009/06/05 12:30:46 [debug] 17496#0: *5 http special response: 500, "/info/s32.html?" 2009/06/05 12:30:46 [debug] 17496#0: *5 http set discard body 2009/06/05 12:30:46 [debug] 17496#0: *5 xslt filter header
2009/06/05 12:30:46 [debug] 17496#0: *5 HTTP/1.1 500 Internal Server Error