Re: Re[2]: Постоянные обрывы кон нектов

[Anton Kuznetsov <maybe@xxxxxxxxxxxx>]

Ну раз уж взялся ковырять проблему хотел и обновиться.. Ну делать нечего, пропатчил 0.7.61

Собственно  возвращаясь к сути проблемы - на разных серверах все сильно по разному.
Вот логи с сервера где проблему побороть не удалось.
FREEBSD 7.0-RELEASE
nginx version: nginx/0.7.61
Патч наложен.

конфиг:
limit_req_zone $binary_remote_addr  zone=avi:10m   rate=2r/m;
limit_req   zone=avi  burst=22;


Хочу чтобы нжинкс просто подвешивал все мульти-хотелки и не устраивал перестрелку в 503.
После перезапуска с этим конфигом лог взрывается от строчек с "206 65536".

Например,
95.24.28.67 - - [14/Jul/2009:23:02:58 +0400] GET /multiki/ostrov.oshibok.avi HTTP/1.0 1.400 ZZ 206 64877


2009/07/14 23:02:57 [debug] 89383#0: *969 accept: 95.24.28.67 fd:80
2009/07/14 23:02:57 [debug] 89383#0: *969 event timer add: 80: 30000:2057691717
2009/07/14 23:02:57 [debug] 89383#0: *969 kevent set event: 80: ft:-1 fl:0025
2009/07/14 23:02:57 [debug] 89383#0: *969 post event 284BBB7C
2009/07/14 23:02:57 [debug] 89383#0: *969 delete posted event 284BBB7C
2009/07/14 23:02:57 [debug] 89383#0: *969 malloc: 29004C00:660
2009/07/14 23:02:57 [debug] 89383#0: *969 malloc: 29005000:1024
2009/07/14 23:02:57 [debug] 89383#0: *969 malloc: 2900F000:4096
2009/07/14 23:02:57 [debug] 89383#0: *969 http process request line
2009/07/14 23:02:57 [debug] 89383#0: *969 recv: eof:0, avail:473, err:0
2009/07/14 23:02:57 [debug] 89383#0: *969 recv: fd:80 473 of 1024
2009/07/14 23:02:57 [debug] 89383#0: *969 http request line: "GET /multiki/ostrov.oshibok.avi HTTP/1.0"
2009/07/14 23:02:57 [debug] 89383#0: *969 http uri: "/multiki/ostrov.oshibok.avi"
2009/07/14 23:02:57 [debug] 89383#0: *969 http args: ""
2009/07/14 23:02:57 [debug] 89383#0: *969 http exten: "avi"
2009/07/14 23:02:57 [debug] 89383#0: *969 http process request header line
2009/07/14 23:02:57 [debug] 89383#0: *969 http header: "User-Agent: Download Master"
2009/07/14 23:02:57 [debug] 89383#0: *969 http header: "Cookie: b=b; __utma=24853414.223958894.1247162475.1247581104.1247592162.18; __utmz=24853414.1247592162.18.17.utmccn=(referral)|utmcsr=film.arjlover.net|utmcct=/film/|utmcmd=referral; __utmb=24853414; __utmc=24853414; hotlog=1"
2009/07/14 23:02:57 [debug] 89383#0: *969 http header: "Accept: */*"
2009/07/14 23:02:57 [debug] 89383#0: *969 http header: "Referer: http://multiki.arjlover.net/info/ostrov.oshibok.avi.html"
2009/07/14 23:02:57 [debug] 89383#0: *969 http header: "Range: bytes=116294291-"
2009/07/14 23:02:57 [debug] 89383#0: *969 http header: "Pragma: no-cache"
2009/07/14 23:02:57 [debug] 89383#0: *969 http header: "Cache-Control: no-cache"
2009/07/14 23:02:57 [debug] 89383#0: *969 http header: "Host: inka.arjlover.net"
2009/07/14 23:02:57 [debug] 89383#0: *969 http header done
2009/07/14 23:02:57 [debug] 89383#0: *969 event timer del: 80: 2057691717
2009/07/14 23:02:57 [debug] 89383#0: *969 generic phase: 0
2009/07/14 23:02:57 [debug] 89383#0: *969 add cleanup: 2900F590
2009/07/14 23:02:57 [debug] 89383#0: *969 generic phase: 1
2009/07/14 23:02:57 [debug] 89383#0: *969 test location: "/"
2009/07/14 23:02:57 [debug] 89383#0: *969 test location: "50x.html"
2009/07/14 23:02:57 [debug] 89383#0: *969 test location: "statn"
2009/07/14 23:02:57 [debug] 89383#0: *969 test location: "no.html"
2009/07/14 23:02:57 [debug] 89383#0: *969 test location: ~ "/php2/"
2009/07/14 23:02:57 [debug] 89383#0: *969 test location: ~ "/mrtg/.*"
2009/07/14 23:02:57 [debug] 89383#0: *969 test location: ~ "\.(html|php|pl)$"
2009/07/14 23:02:57 [debug] 89383#0: *969 test location: ~ "/(stat|session)/"
2009/07/14 23:02:57 [debug] 89383#0: *969 test location: ~ "^/(film|filmiki|multiki)/.*\.(avi|mpg|gif|jpg)$"
2009/07/14 23:02:57 [debug] 89383#0: *969 using configuration "^/(film|filmiki|multiki)/.*\.(avi|mpg|gif|jpg)$"
2009/07/14 23:02:57 [debug] 89383#0: *969 http cl:-1 max:1048576
2009/07/14 23:02:57 [debug] 89383#0: *969 generic phase: 3
2009/07/14 23:02:57 [debug] 89383#0: *969 http script var
2009/07/14 23:02:57 [debug] 89383#0: *969 http script var: ""
2009/07/14 23:02:57 [debug] 89383#0: *969 http script if
2009/07/14 23:02:57 [debug] 89383#0: *969 http script if: false
2009/07/14 23:02:57 [debug] 89383#0: *969 http script var
2009/07/14 23:02:57 [debug] 89383#0: *969 http script var: "/multiki/ostrov.oshibok.avi"
2009/07/14 23:02:57 [debug] 89383#0: *969 http script regex: "\.(gif|jpg)$"
2009/07/14 23:02:57 [notice] 89383#0: *969 "\.(gif|jpg)$" does not match "/multiki/ostrov.oshibok.avi", client: 95.24.28.67, server: inka.arjlover.net, request: "GET /multiki/ostrov.oshibok.avi HTTP/1.0", host: "inka.arjlover.net", referrer: "http://multiki.arjlover.net/info/ostrov.oshibok.avi.html"
2009/07/14 23:02:57 [debug] 89383#0: *969 http script if
2009/07/14 23:02:57 [debug] 89383#0: *969 http script if: false
2009/07/14 23:02:57 [debug] 89383#0: *969 post rewrite phase: 4
2009/07/14 23:02:57 [debug] 89383#0: *969 generic phase: 5
2009/07/14 23:02:57 [debug] 89383#0: *969 add cleanup: 2900F6A0
2009/07/14 23:02:57 [debug] 89383#0: *969 generic phase: 6
2009/07/14 23:02:57 [debug] 89383#0: *969 limit_req: -2 1.000
2009/07/14 23:02:57 [warn] 89383#0: *969 delaying request, excess: 1.000, by zone "avi", client: 95.24.28.67, server: inka.arjlover.net, request: "GET /multiki/ostrov.osh
ibok.avi HTTP/1.0", host: "inka.arjlover.net", referrer: "http://multiki.arjlover.net/info/ostrov.oshibok.avi.html"
2009/07/14 23:02:57 [debug] 89383#0: *969 event timer add: 80: 1000:2057662719
2009/07/14 23:02:58 [debug] 89383#0: *969 event timer del: 80: 2057662719
2009/07/14 23:02:58 [debug] 89383#0: *969 http run request: "/multiki/ostrov.oshibok.avi?"
2009/07/14 23:02:58 [debug] 89383#0: *969 limit_req delay
2009/07/14 23:02:58 [debug] 89383#0: *969 generic phase: 6
2009/07/14 23:02:58 [debug] 89383#0: *969 generic phase: 7
2009/07/14 23:02:58 [debug] 89383#0: *969 add cleanup: 2900F6BC
2009/07/14 23:02:58 [debug] 89383#0: *969 limit zone: A9BD7F57 2
2009/07/14 23:02:58 [debug] 89383#0: *969 access phase: 8
2009/07/14 23:02:58 [debug] 89383#0: *969 access phase: 9
2009/07/14 23:02:58 [debug] 89383#0: *969 post access phase: 10
2009/07/14 23:02:58 [debug] 89383#0: *969 content phase: 11
2009/07/14 23:02:58 [debug] 89383#0: *969 content phase: 12
2009/07/14 23:02:58 [debug] 89383#0: *969 content phase: 13
2009/07/14 23:02:58 [debug] 89383#0: *969 content phase: 14
2009/07/14 23:02:58 [debug] 89383#0: *969 content phase: 15
2009/07/14 23:02:58 [debug] 89383#0: *969 http filename: "/data/multiki/ostrov.oshibok.avi"
2009/07/14 23:02:58 [debug] 89383#0: *969 add cleanup: 2900F6F4
2009/07/14 23:02:58 [debug] 89383#0: *969 http static fd: 156
2009/07/14 23:02:58 [debug] 89383#0: *969 http set discard body
2009/07/14 23:02:58 [debug] 89383#0: *969 HTTP/1.1 206 Partial Content
2009/07/14 23:02:58 [debug] 89383#0: *969 write new buf t:1 f:0 2900F890, pos 2900F890, size: 293 file: 0, size: 0
2009/07/14 23:02:58 [debug] 89383#0: *969 http write filter: l:0 f:0 s:293
2009/07/14 23:02:58 [debug] 89383#0: *969 http output filter "/multiki/ostrov.oshibok.avi?"
2009/07/14 23:02:58 [debug] 89383#0: *969 http range body buf: 0-232466432
2009/07/14 23:02:58 [debug] 89383#0: *969 copy filter: "/multiki/ostrov.oshibok.avi?"
2009/07/14 23:02:58 [debug] 89383#0: *969 http postpone filter "/multiki/ostrov.oshibok.avi?" 7FBFE910
2009/07/14 23:02:58 [debug] 89383#0: *969 write old buf t:1 f:0 2900F890, pos 2900F890, size: 293 file: 0, size: 0
2009/07/14 23:02:58 [debug] 89383#0: *969 write new buf t:0 f:1 00000000, pos 00000000, size: 0 file: 116294291, size: 116172141
2009/07/14 23:02:58 [debug] 89383#0: *969 http write filter: l:1 f:0 s:116172434
2009/07/14 23:02:58 [debug] 89383#0: *969 http write filter limit 65536
2009/07/14 23:02:58 [debug] 89383#0: *969 tcp_nopush
2009/07/14 23:02:58 [debug] 89383#0: *969 sendfile() sent only 65170 bytes (35: Resource temporarily unavailable)
2009/07/14 23:02:58 [debug] 89383#0: *969 sendfile: -1, @116294291 65170:68973
2009/07/14 23:02:58 [debug] 89383#0: *969 http write filter 2900FA08
2009/07/14 23:02:58 [debug] 89383#0: *969 copy filter: -2 "/multiki/ostrov.oshibok.avi?"
2009/07/14 23:02:58 [debug] 89383#0: *969 http finalize request: -2, "/multiki/ostrov.oshibok.avi?" 1
2009/07/14 23:02:58 [debug] 89383#0: *969 event timer add: 80: 30000:2057692721
2009/07/14 23:02:58 [debug] 89383#0: *969 kevent set event: 80: ft:-2 fl:0025
2009/07/14 23:02:58 [debug] 89383#0: *969 http run request: "/multiki/ostrov.oshibok.avi?"
2009/07/14 23:02:58 [debug] 89383#0: *969 http writer handler: "/multiki/ostrov.oshibok.avi?"
2009/07/14 23:02:58 [info] 89383#0: *969 client timed out (60: Operation timed out) while sending response to client, client: 95.24.28.67, server: inka.arjlover.net, request: "GET /multiki/ostrov.oshibok.avi HTTP/1.0", host: "inka.arjlover.net", referrer: "http://multiki.arjlover.net/info/ostrov.oshibok.avi.html"
2009/07/14 23:02:58 [debug] 89383#0: *969 http finalize request: 408, "/multiki/ostrov.oshibok.avi?" 1
2009/07/14 23:02:58 [debug] 89383#0: *969 http close request
2009/07/14 23:02:58 [debug] 89383#0: *969 http log handler
2009/07/14 23:02:58 [debug] 89383#0: *969 http geo started: 95.24.28.67
2009/07/14 23:02:58 [debug] 89383#0: *969 http geo: ZZ
2009/07/14 23:02:58 [debug] 89383#0: *969 run cleanup: 2900F6F4
2009/07/14 23:02:58 [debug] 89383#0: *969 file cleanup: fd:156
2009/07/14 23:02:58 [debug] 89383#0: *969 run cleanup: 2900F6BC
2009/07/14 23:02:58 [debug] 89383#0: *969 free: 2900F000, unused: 1390
2009/07/14 23:02:58 [debug] 89383#0: *969 close http connection: 80
2009/07/14 23:02:58 [debug] 89383#0: *969 event timer del: 80: 2057692721
2009/07/14 23:02:58 [debug] 89383#0: *969 free: 29005000
2009/07/14 23:02:58 [debug] 89383#0: *969 free: 29004C00
2009/07/14 23:02:58 [debug] 89383#0: *969 free: 284DBF00, unused: 56

Какой у него timed out?? Сервер запущен в 55 секунд, в 58 коннект уже устал?
Есть еще пара дебагов на этот айпи, где он пытается устроить долбежку и медленно получает 503. Там вроде все верно. Непонятно что случилось с этим "основным" коннектом.

Антон.

2009/7/14 Михаил Монашёв <postmaster@xxxxxxxxxxxxx>

Здравствуйте, Антон.

А зачем Вы гонитесь за последними версиями? Если всё работает, то
делать этого не стоит.

AK> Я правильно понимаю, что старый патч не накатывается на 0.8.5? :)

--

С уважением,
Михаил Монашёв, SoftSearch.ru
mailto:postmaster@xxxxxxxxxxxxx
ICQ# 166233339
http://michael.mindmix.ru/
Без бэкапа по жизни.

-- 
Best regards,
Anton Kuznetsov.