Epoll error with http1.1 and keep-alive enabed

quan nexthop quan.nexthop at gmail.com
Fri Aug 19 07:02:14 UTC 2011 

Hi all:

I use nginx 1.0.5 with Maxidom's patch. NGINX works as reverse proxy,
backend is IIS 6.0.

we can  not list the data from server, I checked the nginx error_log, there
is problem as following:
there are two error, anyone can give some help or clue?


1) epoll_wait() error on fd:10 ev:001D
2011/08/19 06:17:20 [debug] 1780#0: epoll timer: 599985
2011/08/19 06:17:20 [debug] 1780#0: epoll: fd:10 ev:001D d:080E4FF0
2011/08/19 06:17:20 [debug] 1780#0: epoll_wait() error on fd:10 ev:001D


2) recv() not ready (11: Resource temporarily unavailable)


2011/08/19 06:17:17 [debug] 1780#0: *1 http client request body recv -2
 2011/08/19 06:17:17 [debug] 1780#0: *1 http read client request body
2011/08/19 06:17:17 [debug] 1780#0: *1 recv: fd:3 -1 of 669
2011/08/19 06:17:17 [debug] 1780#0: *1 recv() not ready (11: Resource
temporarily unavailable)
2011/08/19 06:17:17 [debug] 1780#0: *1 http client request body recv -2
2011/08/19 06:17:17 [debug] 1780#0: *1 http client request body rest 669
2011/08/19 06:17:17 [debug] 1780#0: *1 event timer add: 3: 60000:3740872109
2011/08/19 06:17:17 [debug] 1780#0: *1 http finalize request: -4,
"/Server/default.aspx?_dc=1313730693234" a:1, c:2
2011/08/19 06:17:17 [debug] 1780#0: *1 http request count:2 blk:0
============================================
2011/08/19 06:17:20 [debug] 1780#0: *3 http init upstream, client timer: 0
2011/08/19 06:17:20 [debug] 1780#0: *3 epoll add event: fd:10 op:3
ev:80000005
2011/08/19 06:17:20 [debug] 1780#0: *3 http script copy: "Connection:
keep-alive
"
2011/08/19 06:17:20 [debug] 1780#0: *3 http script copy: "Host: "
2011/08/19 06:17:20 [debug] 1780#0: *3 http script var: "abc.com"
2011/08/19 06:17:20 [debug] 1780#0: *3 http script copy: "
"
2011/08/19 06:17:20 [debug] 1780#0: *3 http proxy header: "Accept: */*"
2011/08/19 06:17:20 [debug] 1780#0: *3 http proxy header: "Referer:
http://abc.com/Server/default.aspx"
2011/08/19 06:17:20 [debug] 1780#0: *3 http proxy header: "Accept-Language:
zh-cn"
2011/08/19 06:17:20 [debug] 1780#0: *3 http proxy header: "Accept-Encoding:
gzip, deflate"
2011/08/19 06:17:20 [debug] 1780#0: *3 http proxy header: "User-Agent:
Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1)"
2011/08/19 06:17:20 [debug] 1780#0: *3 http proxy header: "Cookie:
ASP.NET_SessionId=szycsjby3z0pxs3kqjrmcd55"
2011/08/19 06:17:20 [debug] 1780#0: *3 http proxy header:
"GET /Server/extjs/resources/css/xtheme-slate-embedded-css/ext.axd?v=29344
HTTP/1.1
Connection: keep-alive
Host: abc.com
Accept: */*
Referer: http://abc.com/Server/default.aspx
Accept-Language: zh-cn
Accept-Encoding: gzip, deflate
User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1)
Cookie: ASP.NET_SessionId=szycsjby3z0pxs3kqjrmcd55

"
2011/08/19 06:17:20 [debug] 1780#0: *3 http cleanup add: 08117F00
2011/08/19 06:17:20 [debug] 1780#0: *3 init keepalive peer
2011/08/19 06:17:20 [debug] 1780#0: *3 get keepalive peer
2011/08/19 06:17:20 [debug] 1780#0: *3 get rr peer, try: 1
2011/08/19 06:17:20 [debug] 1780#0: *3 socket 11
011/08/19 06:17:20 [debug] 1780#0: *3 epoll add onnection: fd:11 ev:80000005
2011/08/19 06:17:20 [debug] 1780#0: *3 connect to 218.91.37.197:80, fd:11 #4
2011/08/19 06:17:20 [debug] 1780#0: *3 http upstream connect: -2
2011/08/19 06:17:20 [debug] 1780#0: *3 event timer add: 11:
600000:3741414780
2011/08/19 06:17:20 [debug] 1780#0: *3 http finalize request: -4,
"/Server/extjs/resources/css/xtheme-slate-embedded-css/ext.axd?v=29344" a:1,
c:2
2011/08/19 06:17:20 [debug] 1780#0: *3 http request count:2 blk:0
2011/08/19 06:17:20 [debug] 1780#0: timer delta: 1
2011/08/19 06:17:20 [debug] 1780#0: posted events 00000000
2011/08/19 06:17:20 [debug] 1780#0: worker cycle
2011/08/19 06:17:20 [debug] 1780#0: epoll timer: 59997
2011/08/19 06:17:20 [debug] 1780#0: epoll: fd:10 ev:0004 d:080E4FF0
2011/08/19 06:17:20 [debug] 1780#0: *3 http run request:
"/Server/extjs/resources/css/xtheme-slate-embedded-css/ext.axd?v=29344"
2011/08/19 06:17:20 [debug] 1780#0: *3 http upstream check client, write
event:1, "/Server/extjs/resources/css/xtheme-slate-embedded-css/ext.axd"
2011/08/19 06:17:20 [debug] 1780#0: *3 http upstream recv(): -1 (11:
Resource temporarily unavailable)
2011/08/19 06:17:20 [debug] 1780#0: timer delta: 1
2011/08/19 06:17:20 [debug] 1780#0: posted events 00000000
2011/08/19 06:17:20 [debug] 1780#0: worker cycle
2011/08/19 06:17:20 [debug] 1780#0: epoll timer: 59996
2011/08/19 06:17:20 [debug] 1780#0: epoll: fd:3 ev:001D d:080E4F30
2011/08/19 06:17:20 [debug] 1780#0: epoll_wait() error on fd:3 ev:001D
2011/08/19 06:17:20 [debug] 1780#0: *1 http run request:
"/Server/extjs/resources/css/ext-all-embedded-css/ext.axd?v=29344"
2011/08/19 06:17:20 [debug] 1780#0: *1 http upstream check client, write
event:0, "/Server/extjs/resources/css/ext-all-embedded-css/ext.axd"
2011/08/19 06:17:20 [debug] 1780#0: *1 http upstream recv(): -1 (104:
Connection reset by peer)
2011/08/19 06:17:20 [info] 1780#0: *1 client closed prematurely connection,
so upstream connection is closed too (104: Connection reset by peer) while
reading response header from upstream, client: 192.168.226.200, server:
abc.com, request: "GET
/Server/extjs/resources/css/ext-all-embedded-css/ext.axd?v=29344 HTTP/1.1",
upstream: "
http://192.168.2.10:80/Server/extjs/resources/css/ext-all-embedded-css/ext.axd?v=29344",
host: "abc.com", referrer: "http://abc.com/Server/default.aspx"
2011/08/19 06:17:20 [debug] 1780#0: *1 finalize http upstream request: 499
2011/08/19 06:17:20 [debug] 1780#0: *1 finalize http proxy request
2011/08/19 06:17:20 [debug] 1780#0: *1 free keepalive peer
2011/08/19 06:17:20 [debug] 1780#0: *1 free rr peer 1 0
2011/08/19 06:17:20 [debug] 1780#0: *1 close http upstream connection: 9
2011/08/19 06:17:20 [debug] 1780#0: *1 event timer del: 9: 3740874777
2011/08/19 06:17:20 [debug] 1780#0: *1 reusable connection: 0
2011/08/19 06:17:20 [debug] 1780#0: *1 http finalize request: 499,
"/Server/extjs/resources/css/ext-all-embedded-css/ext.axd?v=29344" a:1, c:1
2011/08/19 06:17:20 [debug] 1780#0: *1 http terminate request count:1
2011/08/19 06:17:20 [debug] 1780#0: *1 http terminate cleanup count:1 blk:0
2011/08/19 06:17:20 [debug] 1780#0: *1 http posted request:
"/Server/extjs/resources/css/ext-all-embedded-css/ext.axd?v=29344"
2011/08/19 06:17:20 [debug] 1780#0: *1 http terminate handler count:1
2011/08/19 06:17:20 [debug] 1780#0: *1 http request count:1 blk:0
2011/08/19 06:17:20 [debug] 1780#0: *1 http close request
2011/08/19 06:17:20 [debug] 1780#0: *1 http log handler
2011/08/19 06:17:20 [debug] 1780#0: *1 free: 080D4D88
2011/08/19 06:17:20 [debug] 1780#0: *1 free: 080D3D70, unused: 1087
2011/08/19 06:17:20 [debug] 1780#0: *1 close http connection: 3
2011/08/19 06:17:20 [debug] 1780#0: *1 reusable connection: 0
2011/08/19 06:17:20 [debug] 1780#0: *1 free: 080D36B8
2011/08/19 06:17:20 [debug] 1780#0: *1 free: 080D3AC0
2011/08/19 06:17:20 [debug] 1780#0: *1 free: 080CDC00, unused: 56
2011/08/19 06:17:20 [debug] 1780#0: timer delta: 14
2011/08/19 06:17:20 [debug] 1780#0: posted events 00000000
2011/08/19 06:17:20 [debug] 1780#0: worker cycle
2011/08/19 06:17:20 [debug] 1780#0: epoll timer: 599985
2011/08/19 06:17:20 [debug] 1780#0: epoll: fd:10 ev:001D d:080E4FF0
2011/08/19 06:17:20 [debug] 1780#0: epoll_wait() error on fd:10 ev:001D
<====================Here
2011/08/19 06:17:20 [debug] 1780#0: *3 http run request:
"/Server/extjs/resources/css/xtheme-slate-embedded-css/ext.axd?v=29344"
2011/08/19 06:17:20 [debug] 1780#0: *3 http upstream check client, write
event:0, "/Server/extjs/resources/css/xtheme-slate-embedded-css/ext.axd"
2011/08/19 06:17:20 [debug] 1780#0: *3 http upstream recv(): -1 (104:
Connection reset by peer)
2011/08/19 06:17:20 [info] 1780#0: *3 client closed prematurely connection,
so upstream connection is closed too (104: Connection reset by peer) while
connecting to upstream, client: 192.168.226.200, server: abc.com, request:
"GET /Server/extjs/resources/css/xtheme-slate-embedded-css/ext.axd?v=29344
HTTP/1.1", upstream: "
http://192.168.2.10:80/Server/extjs/resources/css/xtheme-slate-embedded-css/ext.axd?v=29344",
host: "abc.com", referrer: "http://abc.com/Server/default.aspx"
2011/08/19 06:17:20 [debug] 1780#0: *3 finalize http upstream request: 499
2011/08/19 06:17:20 [debug] 1780#0: *3 finalize http proxy request
2011/08/19 06:17:20 [debug] 1780#0: *3 free keepalive peer
2011/08/19 06:17:20 [debug] 1780#0: *3 free rr peer 1 0
2011/08/19 06:17:20 [debug] 1780#0: *3 close http upstream connection: 11
2011/08/19 06:17:20 [debug] 1780#0: *3 event timer del: 11: 3741414780
2011/08/19 06:17:20 [debug] 1780#0: *3 reusable connection: 0
2011/08/19 06:17:20 [debug] 1780#0: *3 http finalize request: 499,
"/Server/extjs/resources/css/xtheme-slate-embedded-css/ext.axd?v=29344" a:1,
c:1
2011/08/19 06:17:20 [debug] 1780#0: *3 http terminate request count:1
2011/08/19 06:17:20 [debug] 1780#0: *3 http terminate cleanup count:1 blk:0
2011/08/19 06:17:20 [debug] 1780#0: *3 http posted request:
"/Server/extjs/resources/css/xtheme-slate-embedded-css/ext.axd?v=29344"
2011/08/19 06:17:20 [debug] 1780#0: *3 http terminate handler count:1
2011/08/19 06:17:20 [debug] 1780#0: *3 http request count:1 blk:0
2011/08/19 06:17:20 [debug] 1780#0: *3 http close request
2011/08/19 06:17:20 [debug] 1780#0: *3 http log handler
2011/08/19 06:17:20 [debug] 1780#0: *3 free: 08117540, unused: 1286
2011/08/19 06:17:20 [debug] 1780#0: *3 close http connection: 10
2011/08/19 06:17:20 [debug] 1780#0: *3 reusable connection: 0
2011/08/19 06:17:20 [debug] 1780#0: *3 free: 08117120
2011/08/19 06:17:20 [debug] 1780#0: *3 free: 08116E88
2011/08/19 06:17:20 [debug] 1780#0: *3 free: 080D5D90, unused: 56
2011/08/19 06:17:20 [debug] 1780#0: timer delta: 1
2011/08/19 06:17:20 [debug] 1780#0: posted events 00000000
2011/08/19 06:17:20 [debug] 1780#0: worker cycle
2011/08/19 06:17:20 [debug] 1780#0: epoll timer: -1
2011/08/19 06:17:20 [debug] 1780#0: epoll: fd:6 ev:0001 d:080E4E70
2011/08/19 06:17:20 [debug] 1780#0: accept on 0.0.0.0:60420, ready: 0
2011/08/19 06:17:20 [debug] 1780#0: posix_memalign: 080CDC00:256 @16
2011/08/19 06:17:20 [debug] 1780#0: *5 accept: 192.168.226.200 fd:3
2011/08/19 06:17:20 [debug] 1780#0: *5 event timer add: 3: 60000:3740874798
2011/08/19 06:17:20 [debug] 1780#0: *5 epoll add event: fd:3 op:1
ev:80000001
2011/08/19 06:17:20 [debug] 1780#0: timer delta: 2
2011/08/19 06:17:20 [debug] 1780#0: posted events 00000000
2011/08/19 06:17:20 [debug] 1780#0: worker cycle
2011/08/19 06:17:20 [debug] 1780#0: epoll timer: 60000
2011/08/19 06:17:20 [debug] 1780#0: epoll: fd:3 ev:0001 d:0
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://mailman.nginx.org/pipermail/nginx/attachments/20110819/16f94e78/attachment-0001.html>

More information about the nginx mailing list