SOAP POST request lag

Alexey V. Karagodov kav at karagodov.name
Thu May 21 14:03:45 MSD 2009


правила пф кривые
на шлюзе:
netstat -rn
cat /etc/rc.conf
cat /etc/pf.conf
traceroute -n 81.222.111.222
traceroute -n внешний_ип

на внутреннем сервере:
что там?
traceroute -n 81.222.111.222
traceroute -n внешний_ип

если нечто слушает на шлюзовом сервере  
и приходит запрос на внешний ИП из  
внутренней сети, то ответ всё равно  
должен уходить куда надо и как надо
что за сервис? хттп?
как внутренний сервер достигает  
интернета? нат?

On 21.05.2009, at 11:53, Vitaliy Okulov wrote:

> Докопался, у меня 2-а сервера, на 1-ом - 1  
> real ip, 1 lan ip. На 2-м сервере
> 1 lan ip. Соотвественно, когда 2-ой сервер  
> делает запрос на real ip адрес
> первого сервера, то данный сервер  
> отправляет ответные пакеты не через  
> шлюз,
> а напрямую на 2-ой сервер, но с real ip  
> адресом, как адрес отправителя.
> То есть получается такой треугольник  
> при запросе.
>
> Шлюзовому оборудованию видимо это не  
> нравится. Пойду посмотрю может быть в
> pf дело.
>
> 20 мая 2009 г. 16:39 пользователь Igor Sysoev  
> <is at rambler-co.ru> написал:
>
>> On Sun, May 17, 2009 at 06:56:55PM +0400, Vitaliy Okulov wrote:
>>
>>> Коллеги, кто может подсказать почему  
>>> возникает лаг в 50 секунд при POST
>>> запросе от SOAP клиента, может ли в  
>>> этом виноват nginx? При томже запросе
>>> напрямую (то есть без nginx сервера) все  
>>> работает без проблем.
>>
>> Насколько я могу судить из  
>> приведённого лога, ядро в первом  
>> случае не
>> присылает новые события в течение 50  
>> секунд, а потом и 60 (после второго
>> таймаута соединение закрывается). То  
>> есть, nginx вроде отрабатывает
>> нормально.
>>
>>> Debug лог:
>>>
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 event timer del: 206:
>>> 1242571455407
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 generic phase: 0
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 generic phase: 1
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 find location for
>>> "/webservices.php/user_content"
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 find location: "/"
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 using configuration "/"
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http cl:258589 max: 
>>> 62914560
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 generic phase: 3
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 post rewrite phase: 4
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 generic phase: 5
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 generic phase: 6
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 access phase: 7
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 access phase: 8
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 access phase: 9
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 post access phase: 10
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 malloc:  
>>> 000000000094B000:4096
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http client request body
>> preread
>>> 819
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 malloc:  
>>> 000000000118B000:131072
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http read client request  
>>> body
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 recv: eof:0, avail:3320,  
>>> err:0
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 recv: fd:206 7664 of  
>>> 131072
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http client request body  
>>> recv
>> 7664
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 250106
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 event timer add: 206:
>>> 60000:1242571455407
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 post event  
>>> 0000000000D2B690
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 delete posted event
>>> 0000000000D2B690
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http read client request  
>>> body
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 recv: eof:0, avail:8688,  
>>> err:0
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 recv: fd:206 8688 of  
>>> 123408
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http client request body  
>>> recv
>> 8688
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 241418
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 event timer: 206, old:
>>> 1242571455407, new: 1242571455408
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 post event  
>>> 0000000000D2B690
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 delete posted event
>>> 0000000000D2B690
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http read client request  
>>> body
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 recv: eof:0, avail:4344,  
>>> err:0
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 recv: fd:206 13032 of  
>>> 114720
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http client request body  
>>> recv
>>> 13032
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 228386
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 event timer: 206, old:
>>> 1242571455407, new: 1242571455409
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 post event  
>>> 0000000000D2B690
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 delete posted event
>>> 0000000000D2B690
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http read client request  
>>> body
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 recv: eof:0, avail:8688,  
>>> err:0
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 recv: fd:206 20272 of  
>>> 101688
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http client request body  
>>> recv
>>> 20272
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 208114
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 event timer: 206, old:
>>> 1242571455407, new: 1242571455409
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 post event  
>>> 0000000000D2B690
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 delete posted event
>>> 0000000000D2B690
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http read client request  
>>> body
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 recv: eof:0, avail: 
>>> 11584, err:0
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 recv: fd:206 11584 of  
>>> 81416
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http client request body  
>>> recv
>>> 11584
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 196530
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 event timer: 206, old:
>>> 1242571455407, new: 1242571455409
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 post event  
>>> 0000000000D2B690
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 delete posted event
>>> 0000000000D2B690
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http read client request  
>>> body
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 recv: eof:0, avail:4344,  
>>> err:0
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 recv: fd:206 4344 of 69832
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http client request body  
>>> recv
>> 4344
>>> 2009/05/17 18:43:15 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 192186
>>> 2009/05/17 *18:43:15* [debug] 20128#0: *598 event timer: 206, old:
>>> 1242571455407, new: 1242571455409
>>> 2009/05/17 *18:44:06* [debug] 20128#0: *598 http read client  
>>> request body
>>> 2009/05/17 18:44:06 [debug] 20128#0: *598 recv: eof:0, avail:1448,  
>>> err:0
>>> 2009/05/17 18:44:06 [debug] 20128#0: *598 recv: fd:206 1448 of 65488
>>> 2009/05/17 18:44:06 [debug] 20128#0: *598 http client request body  
>>> recv
>> 1448
>>> 2009/05/17 18:44:06 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 190738
>>> 2009/05/17 18:44:06 [debug] 20128#0: *598 event timer del: 206:
>>> 1242571455407
>>> 2009/05/17 18:44:06 [debug] 20128#0: *598 event timer add: 206:
>>> 60000:1242571506913
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http read client request  
>>> body
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: eof:0, avail:2896,  
>>> err:0
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: fd:206 2896 of 64040
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> recv
>> 2896
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 187842
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 event timer: 206, old:
>>> 1242571506913, new: 1242571507013
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http read client request  
>>> body
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: eof:0, avail:2896,  
>>> err:0
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: fd:206 4344 of 61144
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> recv
>> 4344
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 183498
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 event timer: 206, old:
>>> 1242571506913, new: 1242571507014
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http read client request  
>>> body
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: eof:0, avail:5792,  
>>> err:0
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: fd:206 5792 of 56800
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> recv
>> 5792
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 177706
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 event timer: 206, old:
>>> 1242571506913, new: 1242571507014
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http read client request  
>>> body
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: eof:0, avail:1448,  
>>> err:0
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: fd:206 1448 of 51008
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> recv
>> 1448
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 176258
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 event timer: 206, old:
>>> 1242571506913, new: 1242571507014
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http read client request  
>>> body
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: eof:0, avail:5792,  
>>> err:0
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: fd:206 5792 of 49560
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> recv
>> 5792
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 170466
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 event timer: 206, old:
>>> 1242571506913, new: 1242571507014
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http read client request  
>>> body
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: eof:0, avail:2896,  
>>> err:0
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: fd:206 2896 of 43768
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> recv
>> 2896
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 167570
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 event timer: 206, old:
>>> 1242571506913, new: 1242571507014
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http read client request  
>>> body
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: eof:0, avail:5296,  
>>> err:0
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: fd:206 5296 of 40872
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> recv
>> 5296
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 162274
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 event timer: 206, old:
>>> 1242571506913, new: 1242571507014
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http read client request  
>>> body
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: eof:0, avail:8688,  
>>> err:0
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: fd:206 8688 of 35576
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> recv
>> 8688
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 153586
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 event timer: 206, old:
>>> 1242571506913, new: 1242571507014
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http read client request  
>>> body
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: eof:0, avail:1448,  
>>> err:0
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: fd:206 7240 of 26888
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> recv
>> 7240
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 146346
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 event timer: 206, old:
>>> 1242571506913, new: 1242571507015
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http read client request  
>>> body
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: eof:0, avail:2896,  
>>> err:0
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: fd:206 7240 of 19648
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> recv
>> 7240
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 139106
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 event timer: 206, old:
>>> 1242571506913, new: 1242571507015
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http read client request  
>>> body
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: eof:0, avail:1448,  
>>> err:0
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: fd:206 8688 of 12408
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> recv
>> 8688
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 130418
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 event timer: 206, old:
>>> 1242571506913, new: 1242571507015
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http read client request  
>>> body
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: eof:0, avail:5792,  
>>> err:0
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: fd:206 3720 of 3720
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> recv
>> 3720
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 add cleanup:  
>>> 000000000094B238
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 hashed path:
>>> /var/nginx/client_body_temp/0000000003
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 temp fd:211
>>> 2009/05/17 18:44:07 [warn] 20128#0: *598 a client request body is
>> buffered
>>> to a temporary file /var/nginx/client_body_temp/0000000003, client:
>> x.x.x.x,
>>> server: example.com, request: "POST /webservices.php/user_content
>> HTTP/1.1",
>>> host: "example.com"
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 write: 211,  
>>> 000000000118B000,
>>> 131072, 0
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: eof:0, avail:2072,  
>>> err:0
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 recv: fd:206 2072 of  
>>> 126698
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> recv
>> 2072
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 http client request body  
>>> rest
>>> 124626
>>> 2009/05/17 18:44:07 [debug] 20128#0: *598 event timer: 206, old:
>>> 1242571506913, new: 1242571507015
>>> 2009/05/17 18:45:06 [debug] 20128#0: *598 event timer del: 206:
>>> 1242571506913
>>> 2009/05/17 18:45:06 [debug] 20128#0: *598 http finalize request:  
>>> 408,
>>> "/webservices.php/user_content?"
>>> 2009/05/17 18:45:06 [debug] 20128#0: *598 http close request
>>> 2009/05/17 18:45:06 [debug] 20128#0: *598 http log handler
>>> 2009/05/17 18:45:06 [debug] 20128#0: *598 run cleanup:  
>>> 000000000094B238
>>> 2009/05/17 18:45:06 [debug] 20128#0: *598 file cleanup: fd:211
>>> 2009/05/17 18:45:06 [debug] 20128#0: *598 free: 000000000118B000
>>> 2009/05/17 18:45:06 [debug] 20128#0: *598 free: 000000000094A000,  
>>> unused:
>> 8
>>> 2009/05/17 18:45:06 [debug] 20128#0: *598 free: 000000000094B000,  
>>> unused:
>>> 3344
>>> 2009/05/17 18:45:06 [debug] 20128#0: *598 close http connection: 206
>>> 2009/05/17 18:45:06 [debug] 20128#0: *598 free: 00000000005EA400
>>> 2009/05/17 18:45:06 [debug] 20128#0: *598 free: 00000000005E2800
>>> 2009/05/17 18:45:06 [debug] 20128#0: *598 free: 0000000000918600,  
>>> unused:
>> 8
>>> 2009/05/17 18:45:06 [debug] 20128#0: *598 free: 0000000000918700,  
>>> unused:
>>> 112
>>
>> --
>> Игорь Сысоев
>> http://sysoev.ru
>>
>>






More information about the nginx-ru mailing list