Nginx-ru mailing list archive (nginx-ru@sysoev.ru)
[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]
Re:Проблема с быстрым disconnect
Ниже логи 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
>>
>>
>>
>>Игорь Сысоев
>>
>>
>
>
|
