Re:Проблема с быстрым disconnect

beholderk beholderk at yandex.ru
Sat Feb 5 17:51:43 MSK 2005


Ниже логи 2х запросов
1й в 17:37:29, sendHeader идет через 3 секунды после соединения,
2й в 17:39:55-56, sendHeader идет через 1 секунду после соединения

2005/02/05 17:37:29 [debug] 10275#0: accept on 0.0.0.0:80, ready: 1
2005/02/05 17:37:29 [debug] 10275#0: malloc: 0809A100:256
2005/02/05 17:37:29 [debug] 10275#0: accept: fd:9 c:4741
2005/02/05 17:37:29 [debug] 10275#0: *4741 event timer add: 9: 192664
2005/02/05 17:37:29 [debug] 10275#0: *4741 kevent set event: 9: ft:-1 fl:0025
-----------------------------------------------
2005/02/05 17:39:55 [debug] 10311#0: accept on 0.0.0.0:80, ready: 1
2005/02/05 17:39:55 [debug] 10311#0: malloc: 0809A100:256
2005/02/05 17:39:55 [debug] 10311#0: accept: fd:8 c:4788
2005/02/05 17:39:55 [debug] 10311#0: *4788 event timer add: 8: 183515
2005/02/05 17:39:55 [debug] 10311#0: *4788 kevent set event: 8: ft:-1 fl:0025
2005/02/05 17:39:56 [debug] 10311#0: *4788 post event 080BB1C0
2005/02/05 17:39:56 [debug] 10311#0: *4788 delete posted event 080BB1C0
2005/02/05 17:39:56 [debug] 10311#0: *4788 malloc: 0808D000:676
2005/02/05 17:39:56 [debug] 10311#0: *4788 malloc: 0809C800:2048
2005/02/05 17:39:56 [debug] 10311#0: *4788 malloc: 080A0000:4096
2005/02/05 17:39:56 [debug] 10311#0: *4788 http process request line
2005/02/05 17:39:56 [debug] 10311#0: *4788 recv: eof:0, avail:57, err:0
2005/02/05 17:39:56 [debug] 10311#0: *4788 recv: fd:8 57 of 2048
2005/02/05 17:39:56 [debug] 10311#0: *4788 http request line: "GET / HTTP/1.0"
2005/02/05 17:39:56 [debug] 10311#0: *4788 http uri: "/"
2005/02/05 17:39:56 [debug] 10311#0: *4788 http args: ""
2005/02/05 17:39:56 [debug] 10311#0: *4788 http exten: ""
2005/02/05 17:39:56 [debug] 10311#0: *4788 http process request header line
2005/02/05 17:39:56 [debug] 10311#0: *4788 http header: "Host: 212.24.38.229"
2005/02/05 17:39:56 [debug] 10311#0: *4788 http header: "Usef-Agent: test"
2005/02/05 17:39:56 [debug] 10311#0: *4788 http header done
2005/02/05 17:39:56 [debug] 10311#0: *4788 event timer del: 8: 183515
2005/02/05 17:39:56 [debug] 10311#0: *4788 http rewrite handler
2005/02/05 17:39:56 [debug] 10311#0: *4788 find location
2005/02/05 17:39:56 [debug] 10311#0: *4788 find location: "/"
2005/02/05 17:39:56 [debug] 10311#0: *4788 find location: "/css/"
2005/02/05 17:39:56 [debug] 10311#0: *4788 find location: "/ico/"
2005/02/05 17:39:56 [debug] 10311#0: *4788 find location: "/images/"
2005/02/05 17:39:56 [debug] 10311#0: *4788 find location: "/img/"
2005/02/05 17:39:56 [debug] 10311#0: *4788 find location: "/preview/"
2005/02/05 17:39:56 [debug] 10311#0: *4788 find location: "/wap_content/"
2005/02/05 17:39:56 [debug] 10311#0: *4788 http cl:-1 max:2097152
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy init upstream, client ti
mer: 0
2005/02/05 17:39:56 [debug] 10311#0: *4788 kevent set event: 8: ft:-2 fl:0025
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy header: "Usef-Agent: test"
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy header:
"GET / HTTP/1.0^M
Connection: close^M
Host: 212.24.38.229^M
X-Real-IP: 212.24.38.229^M
Usef-Agent: test^M
^M
"
2005/02/05 17:39:56 [debug] 10311#0: *4788 connect to 127.0.0.1:80, #4789
2005/02/05 17:39:56 [debug] 10311#0: *4788 kevent set event: 9: ft:-1 fl:0025
2005/02/05 17:39:56 [debug] 10311#0: *4788 kevent set event: 9: ft:-2 fl:0025
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy connect: -2
2005/02/05 17:39:56 [debug] 10311#0: *4788 event timer add: 9: 184525
2005/02/05 17:39:56 [debug] 10311#0: *4788 post event 080C91C0
2005/02/05 17:39:56 [debug] 10311#0: *4788 post event 080C91F8
2005/02/05 17:39:56 [debug] 10311#0: *4788 delete posted event 080C91F8
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy send request handler
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy send request
2005/02/05 17:39:56 [debug] 10311#0: *4788 chain writer buf size: 102
2005/02/05 17:39:56 [debug] 10311#0: *4788 chain writer in: 080A0568
2005/02/05 17:39:56 [debug] 10311#0: *4788 writev: 102 of 102
2005/02/05 17:39:56 [debug] 10311#0: *4788 chain writer out: 00000000
2005/02/05 17:39:56 [debug] 10311#0: *4788 event timer del: 9: 184525
2005/02/05 17:39:56 [debug] 10311#0: *4788 event timer add: 9: 364525
2005/02/05 17:39:56 [debug] 10311#0: *4788 delete posted event 080C91C0
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy check client, write event:1
2005/02/05 17:39:56 [debug] 10311#0: *4788 post event 080C91F8
2005/02/05 17:39:56 [debug] 10311#0: *4788 post event 080BB1F8
2005/02/05 17:39:56 [debug] 10311#0: *4788 delete posted event 080BB1F8
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy process status line
2005/02/05 17:39:56 [debug] 10311#0: *4788 malloc: 080D7000:4096
2005/02/05 17:39:56 [debug] 10311#0: *4788 recv: eof:0, avail:1476, err:0
2005/02/05 17:39:56 [debug] 10311#0: *4788 recv: fd:9 1476 of 4096
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy status 200 "200 OK"
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy process header line
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy header: "Date: Sat, 05 Feb 2005 14:39:56 GMT"
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy header: "Server: Apache/1.3.33 (Unix) PHP/4.3.10 mod_perl/1.29"
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy header: "Last-Modified: Sat, 05 Feb 2005 12:37:52 GMT"
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy header: "ETag: "7e80d-4ac-4204be20""
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy header: "Accept-Ranges: bytes"
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy header: "Content-Length: 1196"
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy header: "Connection: close"
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy header: "Content-Type: text/vnd.wap.wml"
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy header done
2005/02/05 17:39:56 [debug] 10311#0: *4788 HTTP/1.1 200 OK^M
Server: nginx/0.1.16^M
Date: Sat, 05 Feb 2005 14:39:56 GMT^M
Content-Type: text/vnd.wap.wml^M
Connection: close^M
Last-Modified: Sat, 05 Feb 2005 12:37:52 GMT^M
ETag: "7e80d-4ac-4204be20"^M
Content-Length: 1196^M


2005/02/05 17:39:56 [debug] 10311#0: *4788 write new buf t:1 f:0 080A0828, pos 080A0828, size: 225 file: 0, size: 0
2005/02/05 17:39:56 [debug] 10311#0: *4788 http write filter: l:0 f:0 s:225
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy process upstream
2005/02/05 17:39:56 [debug] 10311#0: *4788 pipe read upstream: 0
2005/02/05 17:39:56 [debug] 10311#0: *4788 pipe preread: 1196
2005/02/05 17:39:56 [debug] 10311#0: *4788 pipe buf free s:0 t:1 f:0 080D7000,pos 080D7118, size: 1196 file: 0, size: 0
2005/02/05 17:39:56 [debug] 10311#0: *4788 pipe write downstream: 1
2005/02/05 17:39:56 [debug] 10311#0: *4788 pipe write busy: 0
2005/02/05 17:39:56 [debug] 10311#0: *4788 pipe write: out:00000000, f:0
2005/02/05 17:39:56 [debug] 10311#0: *4788 pipe read upstream: 0
2005/02/05 17:39:56 [debug] 10311#0: *4788 pipe buf free s:0 t:1 f:0 080D7000,pos 080D7118, size: 1196 file: 0, size: 0
2005/02/05 17:39:56 [debug] 10311#0: *4788 pipe write downstream: 1
2005/02/05 17:39:56 [debug] 10311#0: *4788 pipe write busy: 0
2005/02/05 17:39:56 [debug] 10311#0: *4788 pipe write: out:00000000, f:0
2005/02/05 17:39:56 [debug] 10311#0: *4788 pipe read upstream: 0
2005/02/05 17:39:56 [debug] 10311#0: *4788 pipe buf free s:0 t:1 f:0 080D7000, pos 080D7118, size: 1196 file: 0, size: 0
2005/02/05 17:39:56 [debug] 10311#0: *4788 event timer: 9, old: 364525, new: 364526
2005/02/05 17:39:56 [debug] 10311#0: *4788 event timer add: 8: 184526
2005/02/05 17:39:56 [debug] 10311#0: *4788 delete posted event 080C91F8
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy dummy handler
2005/02/05 17:39:56 [debug] 10311#0: *4788 post event 080BB1F8
2005/02/05 17:39:56 [debug] 10311#0: *4788 delete posted event 080BB1F8
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy process upstream
2005/02/05 17:39:56 [debug] 10311#0: *4788 pipe read upstream: 1
2005/02/05 17:39:56 [debug] 10311#0: *4788 pipe buf free s:0 t:1 f:0 080D7000, pos 080D7118, size: 1196 file: 0, size: 0
2005/02/05 17:39:56 [debug] 10311#0: *4788 input buf #0
2005/02/05 17:39:56 [debug] 10311#0: *4788 pipe write downstream: 1
2005/02/05 17:39:56 [debug] 10311#0: *4788 pipe write downstream flush in
2005/02/05 17:39:56 [debug] 10311#0: *4788 write old buf t:1 f:0 080A0828, pos 080A0828, size: 225 file: 0, size: 0
2005/02/05 17:39:56 [debug] 10311#0: *4788 write new buf t:1 f:0 080D7000, pos 080D7118, size: 1196 file: 0, size: 0
2005/02/05 17:39:56 [debug] 10311#0: *4788 http write filter: l:0 f:1421 s:1421
2005/02/05 17:39:56 [debug] 10311#0: *4788 writev: 1421 of 1421
2005/02/05 17:39:56 [debug] 10311#0: *4788 http write filter 00000000
2005/02/05 17:39:56 [debug] 10311#0: *4788 pipe write downstream done
2005/02/05 17:39:56 [debug] 10311#0: *4788 event timer: 9, old: 364525, new: 364526
2005/02/05 17:39:56 [debug] 10311#0: *4788 event timer: 8, old: 184526, new: 184526
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy upstream exit: 00000000
2005/02/05 17:39:56 [debug] 10311#0: *4788 finalize http proxy request
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy close connection: 9
2005/02/05 17:39:56 [debug] 10311#0: *4788 event timer del: 9: 364525
2005/02/05 17:39:56 [debug] 10311#0: *4788 http proxy temp fd: -1
2005/02/05 17:39:56 [debug] 10311#0: *4788 write new buf t:0 f:0 00000000, pos 00000000, size: 0 file: 0, size: 0
2005/02/05 17:39:56 [debug] 10311#0: *4788 http write filter: l:1 f:0 s:0
2005/02/05 17:39:56 [debug] 10311#0: *4788 http finalize request: 0
2005/02/05 17:39:56 [debug] 10311#0: *4788 event timer del: 8: 184526
2005/02/05 17:39:56 [debug] 10311#0: *4788 http close request
2005/02/05 17:39:56 [debug] 10311#0: *4788 http log handler


>Нет, таких строчек не наблюдается вообще, не только в этом конкретном запросе
>>On Sat, 5 Feb 2005, beholderk wrote:
>>
>>> В логах ничего особенного не видно:(
>>> 2005/02/05 15:50:01 [debug] 7375#0: *1010 http log handler
>>> 2005/02/05 15:50:01 [debug] 7376#0: kevent set event: 6: ft:-1 fl:0005
>>> 2005/02/05 15:50:01 [debug] 7376#0: accept on 0.0.0.0:80, ready: 1
>>> 2005/02/05 15:50:01 [debug] 7376#0: malloc: 0809A100:256
>>> 2005/02/05 15:50:01 [debug] 7376#0: accept: fd:9 c:1011
>>> 2005/02/05 15:50:01 [debug] 7376#0: *1011 event timer add: 9: 183389
>>> 2005/02/05 15:50:01 [debug] 7376#0: *1011 kevent set event: 9: ft:-1 fl:0025
>>> 2005/02/05 15:50:04 [debug] 7376#0: accept on 0.0.0.0:80, ready: 1
>>> 2005/02/05 15:50:04 [debug] 7376#0: malloc: 0809A200:256
>>> 2005/02/05 15:50:04 [debug] 7376#0: accept: fd:10 c:1012
>>> 2005/02/05 15:50:04 [debug] 7376#0: *1012 event timer add: 10: 186103
>>> 2005/02/05 15:50:04 [debug] 7376#0: *1012 kevent set event: 10: ft:-1 fl:0025
>>> 2005/02/05 15:50:04 [debug] 7376#0: *1012 post event 080BB230
>>>
>>> Мое соединение - это 1011, дисконнектнуло через 1 сек
>>
>>Лог какой-то неполный. Перед "accept on 0.0.0.0:80, ready: 1" должно
>>быть что-то вроде:
>>
>>2005/02/05 16:04:18 [debug] 61822#0: worker cycle
>>2005/02/05 16:04:18 [debug] 61822#0: kevent timer: -1, changes: 7
>>2005/02/05 16:04:22 [debug] 61822#0: kevent events: 1
>>2005/02/05 16:04:22 [debug] 61822#0: kevent: 4: ft:-1 fl:0005 ff:00000000 d:1 ud:080C90E0
>>
>>
>>
>>Игорь Сысоев
>>http://sysoev.ru
>>
>
>






More information about the nginx-ru mailing list