zero size buf for static content

["Alexander Azarov" <azarov@xxxxxx>]

Добрый день, Игорь!

Происходит zero size buf при запросе статического PDF файла "напрямую" из
браузера, IE6 и IE7, к сожалению, в списке пострадавших.

Nginx + ядро:

2007/02/02 17:52:35 [notice] 17712#0: using the "epoll" event method
2007/02/02 17:52:35 [notice] 17712#0: nginx/0.5.10
2007/02/02 17:52:35 [notice] 17712#0: built by gcc 4.1.2 20061028
(prerelease) (Debian 4.1.1-19)
2007/02/02 17:52:35 [notice] 17712#0: OS: Linux 2.6.18-3-vserver-amd64

Ошибка в логе:

2007/02/02 17:54:21 [alert] 17715#0: *8157 zero size buf in output t:0 r:0
f:1 0000000000000000 0000000000000000-0000000000000000 00000000007B99C0 0-0
while sending response to client, client: 62.117.85.87, server:
www.osinka.ru, URL: "/Sewing/Books/pdf/LineJaqe.pdf", host: "www.osinka.ru"

Отладочный лог в аттаче.

С уважением,
Александр

2007/02/02 18:10:52 [debug] 20208#0: *17663 accept: 62.117.85.87 fd:54
2007/02/02 18:10:52 [debug] 20208#0: *17663 event timer add: 54: 
60000:2198008405
2007/02/02 18:10:52 [debug] 20208#0: *17663 epoll add event: fd:54 op:1 
ev:80000001
2007/02/02 18:10:52 [debug] 20208#0: *17663 post event 00002AF964179D10
2007/02/02 18:10:52 [debug] 20208#0: *17663 delete posted event 00002AF964179D10
2007/02/02 18:10:52 [debug] 20208#0: *17663 malloc: 00000000005B68E0:1160
2007/02/02 18:10:52 [debug] 20208#0: *17663 malloc: 00000000005B6D70:256
2007/02/02 18:10:52 [debug] 20208#0: *17663 malloc: 00000000005BB200:2048
2007/02/02 18:10:52 [debug] 20208#0: *17663 malloc: 00000000005C8AD0:4096
2007/02/02 18:10:52 [debug] 20208#0: *17663 http process request line
2007/02/02 18:10:52 [debug] 20208#0: *17663 recv: fd:54 1134 of 2048
2007/02/02 18:10:52 [debug] 20208#0: *17663 http request line: "GET 
/Sewing/Books/pdf/LineJaqe.pdf HTTP/1.1"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http uri: 
"/Sewing/Books/pdf/LineJaqe.pdf"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http args: ""
2007/02/02 18:10:52 [debug] 20208#0: *17663 http exten: "pdf"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http process request header line
2007/02/02 18:10:52 [debug] 20208#0: *17663 http header: "Accept: */*"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http header: "Range: 
bytes=5763587-5764688, 5777807-5778647, 72099-280988"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http header: "UA-CPU: x86"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http header: "Accept-Encoding: 
gzip, deflate"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http header: "User-Agent: 
Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 1.1.4322; .NET CLR 
2.0.50727)"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http header: "Host: www.osinka.ru"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http header: "Connection: 
Keep-Alive"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http header: "Cache-Control: 
no-cache"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http header: "Cookie: 
phpbb2mysql_sid=08235ab9acb385b359d3c22d64c198da; 
phpbb2mysql_t=a%3A5%3A%7Bi%3A3590%3Bi%3A1170413517%3Bi%3A25494%3Bi%3A1170413523%3Bi%3A10688%3Bi%3A1170418987%3Bi%3A25499%3Bi%3A1170418997%3Bi%3A816%3Bi%3A1170426340%3B%7D;
 phpAds_capAd[161]=24019; 
phpbb2mysql_data=a%3A2%3A%7Bs%3A11%3A%22autologinid%22%3Bs%3A32%3A%22270647aae648cba765499eaf6d1dca06%22%3Bs%3A6%3A%22userid%22%3Bi%3A128%3B%7D;
 phpAds_newCap[d2ece64c91b23b5e1cbaec8d2291521f]=204; phpAds_capAd[204]=6036; 
phpAds_newCap[bf56938e3b451a2733183ef444451fea]=206; phpAds_capAd[206]=5914; 
phpAds_newCap[1bfa727c6965ba37ed50a4d8ef2145e7]=207; phpAds_capAd[207]=5896; 
phpAds_newCap[40a03b41d79b2955fcf348fb563f210f]=210; phpAds_capAd[210]=3598; 
phpAds_newCap[a118e4fb36e66a79773fd16a475941e5]=209; phpAds_capAd[209]=3565; 
hotlog=1"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http header done
2007/02/02 18:10:52 [debug] 20208#0: *17663 event timer del: 54: 2198008405
2007/02/02 18:10:52 [debug] 20208#0: *17663 generic phase: 0
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Zhurnaly.*$"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Moda/Pretaporter/2003_bridal.*$"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Moda/Pretaporter/"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Moda/Trend/(black2002|floral2002|bw2002|evcolor|evblack|Suits2001|Leto2001|poncho).*$"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Pattern/Free/f-(1(2[5-9]|3.)).*$"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Archiv/Zhurnaly"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: "^/Classifieds/"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: "^/Deti/"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: "^/Health/"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: "^/Life/"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: "^/Mistress/"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: "^/Moda/Master/"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Pattern/(Links|Women)/"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Pattern/"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Zhurnaly/"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Zhurnaly/(2001_01|2001_04|Special_01)/"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Materials/SKukla"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Materials/Style-moda"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Materials/Dekolte"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Advices/(.*)"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Master-class/05_01-(.*).html"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Master-class/04_06-(.*).html"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Master-class/04_04-(.*).html"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Master-class/04_03-(.*).html"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Master-class/03_04-(.*).html"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Master-class/04_01-(.*).html"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Dekor/(0[0-9]_.*\.html)"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Dekor/Img/(0[0-9]_.*\.jpg)"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Needlework/05_01.html"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Needlework/AdelaidaRozhkova.html"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Needlework/"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Master-class/(03_0[123]|04_0[2578]|05_0[234])-(.*).html"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Sewing/Master-class/"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Pattern/Payment/(.*)$"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Zhurnaly/.*Diana0[1-45-8]_.._.\.html"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Zhurnaly/.*(diana|shik|iren|sabrina|susanna).*\.html"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Zhurnaly/.*(diana|shik|iren|sabrina|susanna).*\.jpg"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http script regex: 
"^/Zhurnaly/.*(diana|shik|iren|sabrina|susanna).*\.gif"
2007/02/02 18:10:52 [debug] 20208#0: *17663 find location for 
"/Sewing/Books/pdf/LineJaqe.pdf"
2007/02/02 18:10:52 [debug] 20208#0: *17663 find location: "/"
2007/02/02 18:10:52 [debug] 20208#0: *17663 find location: 
"/Moda/Defile/2004-fall_FashionWeek/"
2007/02/02 18:10:52 [debug] 20208#0: *17663 find location: 
"/Moda/Defile/2005-spring_FashionWeek/"
2007/02/02 18:10:52 [debug] 20208#0: *17663 find location: "/Sewing/Materials"
2007/02/02 18:10:52 [debug] 20208#0: *17663 find location: ~ "^.+\.(php)$"
2007/02/02 18:10:52 [debug] 20208#0: *17663 find location: ~ "/\.ht"
2007/02/02 18:10:52 [debug] 20208#0: *17663 find location: ~ "^.*/favicon.ico$"
2007/02/02 18:10:52 [debug] 20208#0: *17663 find location: ~ 
"^.+\.(exe|zip|pdf)$"
2007/02/02 18:10:52 [debug] 20208#0: *17663 using configuration 
"^.+\.(exe|zip|pdf)$"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http cl:-1 max:10485760
2007/02/02 18:10:52 [debug] 20208#0: *17663 generic phase: 2
2007/02/02 18:10:52 [debug] 20208#0: *17663 post rewrite phase: 3
2007/02/02 18:10:52 [debug] 20208#0: *17663 generic phase: 4
2007/02/02 18:10:52 [debug] 20208#0: *17663 access phase: 5
2007/02/02 18:10:52 [debug] 20208#0: *17663 access phase: 6
2007/02/02 18:10:52 [debug] 20208#0: *17663 post access phase: 7
2007/02/02 18:10:52 [debug] 20208#0: *17663 content phase: 8
2007/02/02 18:10:52 [debug] 20208#0: *17663 content phase: 9
2007/02/02 18:10:52 [debug] 20208#0: *17663 content phase: 10
2007/02/02 18:10:52 [debug] 20208#0: *17663 http set discard body
2007/02/02 18:10:52 [debug] 20208#0: *17663 http filename: 
"/home/osinka/osinka.ru/www/Sewing/Books/pdf/LineJaqe.pdf"
2007/02/02 18:10:52 [debug] 20208#0: *17663 add cleanup: 00000000005C9780
2007/02/02 18:10:52 [debug] 20208#0: *17663 http static fd: 56
2007/02/02 18:10:52 [debug] 20208#0: *17663 malloc: 00000000005C9AE0:4096
2007/02/02 18:10:52 [debug] 20208#0: *17663 HTTP/1.1 206 Partial Content
Server: nginx/0.5.10
Date: Fri, 02 Feb 2007 15:10:52 GMT
Content-Type: multipart/byteranges; boundary=00000000000000000000
Content-Length: 211173
Last-Modified: Mon, 20 Jan 2003 17:38:19 GMT
Connection: keep-alive
Keep-Alive: timeout=15
Expires: Tue, 06 Feb 2007 15:10:52 GMT
Cache-Control: max-age=345600


2007/02/02 18:10:52 [debug] 20208#0: *17663 write new buf t:1 f:0 
00000000005C9BB8, pos 00000000005C9BB8, size: 347 file: 0, size: 0
2007/02/02 18:10:52 [debug] 20208#0: *17663 http write filter: l:0 f:0 s:347
2007/02/02 18:10:52 [debug] 20208#0: *17663 http output filter 
"/Sewing/Books/pdf/LineJaqe.pdf?"
2007/02/02 18:10:52 [debug] 20208#0: *17663 copy filter: 
"/Sewing/Books/pdf/LineJaqe.pdf?"
2007/02/02 18:10:52 [alert] 20208#0: *17663 zero size buf in output t:0 r:0 f:1 
0000000000000000 0000000000000000-0000000000000000 00000000005C9800 0-0 while 
sending response to client, client: 62.117.85.87, server: www.osinka.ru, URL: 
"/Sewing/Books/pdf/LineJaqe.pdf", host: "www.osinka.ru"
2007/02/02 18:10:52 [alert] 20208#0: *17663 zero size buf in output t:0 r:0 f:1 
0000000000000000 0000000000000000-0000000000000000 00000000005C9800 0-0 while 
sending response to client, client: 62.117.85.87, server: www.osinka.ru, URL: 
"/Sewing/Books/pdf/LineJaqe.pdf", host: "www.osinka.ru"
2007/02/02 18:10:52 [alert] 20208#0: *17663 zero size buf in output t:0 r:0 f:1 
0000000000000000 0000000000000000-0000000000000000 00000000005C9800 0-0 while 
sending response to client, client: 62.117.85.87, server: www.osinka.ru, URL: 
"/Sewing/Books/pdf/LineJaqe.pdf", host: "www.osinka.ru"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http postpone filter 
"/Sewing/Books/pdf/LineJaqe.pdf?" 00000000005CA190
2007/02/02 18:10:52 [debug] 20208#0: *17663 http postpone filter out 
"/Sewing/Books/pdf/LineJaqe.pdf?"
2007/02/02 18:10:52 [debug] 20208#0: *17663 write old buf t:1 f:0 
00000000005C9BB8, pos 00000000005C9BB8, size: 347 file: 0, size: 0
2007/02/02 18:10:52 [debug] 20208#0: *17663 write new buf t:0 f:0 
0000000000000000, pos 00000000005C99B0, size: 78 file: 0, size: 0
2007/02/02 18:10:52 [debug] 20208#0: *17663 write new buf t:1 f:0 
0000000000000000, pos 00000000005C9A40, size: 27 file: 0, size: 0
2007/02/02 18:10:52 [debug] 20208#0: *17663 write new buf t:0 f:0 
0000000000000000, pos 00000000005C99B0, size: 78 file: 0, size: 0
2007/02/02 18:10:52 [debug] 20208#0: *17663 write new buf t:1 f:0 
0000000000000000, pos 00000000005C9A88, size: 27 file: 0, size: 0
2007/02/02 18:10:52 [debug] 20208#0: *17663 write new buf t:0 f:0 
0000000000000000, pos 00000000005C99B0, size: 78 file: 0, size: 0
2007/02/02 18:10:52 [debug] 20208#0: *17663 write new buf t:1 f:0 
0000000000000000, pos 00000000005C9B20, size: 24 file: 0, size: 0
2007/02/02 18:10:52 [debug] 20208#0: *17663 write new buf t:1 f:0 
0000000000000000, pos 00000000005CA0F8, size: 18 file: 0, size: 0
2007/02/02 18:10:52 [debug] 20208#0: *17663 http write filter: l:1 f:0 s:677
2007/02/02 18:10:52 [debug] 20208#0: *17663 http write filter to send 0
2007/02/02 18:10:52 [debug] 20208#0: *17663 writev: 677
2007/02/02 18:10:52 [debug] 20208#0: *17663 http write filter 0000000000000000
2007/02/02 18:10:52 [debug] 20208#0: *17663 copy filter: 0 
"/Sewing/Books/pdf/LineJaqe.pdf?"
2007/02/02 18:10:52 [debug] 20208#0: *17663 http finalize request: 0, 
"/Sewing/Books/pdf/LineJaqe.pdf?"
2007/02/02 18:10:52 [debug] 20208#0: *17663 set http keepalive handler
2007/02/02 18:10:52 [debug] 20208#0: *17663 http close request
2007/02/02 18:10:52 [debug] 20208#0: *17663 http log handler
2007/02/02 18:10:52 [debug] 20208#0: *17663 run cleanup: 00000000005C9798, fd:56
2007/02/02 18:10:52 [debug] 20208#0: *17663 free: 00000000005C8AD0, unused: 6
2007/02/02 18:10:52 [debug] 20208#0: *17663 free: 00000000005C9AE0, unused: 2112
2007/02/02 18:10:52 [debug] 20208#0: *17663 event timer add: 54: 
35000:2197983407
2007/02/02 18:10:52 [debug] 20208#0: *17663 free: 00000000005B68E0
2007/02/02 18:10:52 [debug] 20208#0: *17663 free: 00000000005BB200
2007/02/02 18:10:52 [debug] 20208#0: *17663 hc free: 0000000000000000 0
2007/02/02 18:10:52 [debug] 20208#0: *17663 hc busy: 0000000000000000 0
2007/02/02 18:10:52 [debug] 20208#0: *17663 tcp_nodelay
2007/02/02 18:10:52 [debug] 20208#0: *17663 http keepalive handler
2007/02/02 18:10:52 [debug] 20208#0: *17663 malloc: 00000000005BB200:2048
2007/02/02 18:10:52 [debug] 20208#0: *17663 recv: fd:54 -1 of 2048
2007/02/02 18:10:52 [debug] 20208#0: *17663 recv() not ready (11: Resource 
temporarily unavailable)