# Epoll error with http1.1 and keep-alive enabed

**URL:** <https://www.ruby-forum.com/t/epoll-error-with-http1-1-and-keep-alive-enabed/210876>\
**Category:** NGINX\
**Created:** [August 19, 2011, 9:02am UTC](https://www.ruby-forum.com/t/epoll-error-with-http1-1-and-keep-alive-enabed/210876 "2011-08-19T09:02:48Z")\
**Posts on this page:** 2\
**Page:** 1

<div class="post-metadata">

**Author:** ![dubstep](https://www.ruby-forum.com/letter_avatar_proxy/v4/letter/d/cdc98d/32.png) [@dubstep](https://www.ruby-forum.com/u/dubstep)\
**Post date:** [August 19, 2011, 9:02am UTC](https://www.ruby-forum.com/t/epoll-error-with-http1-1-and-keep-alive-enabed/210876/1 "2011-08-19T09:02:48Z")

</div>

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](http://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](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](http://abc.com)  
Accept: _/_  
Referer: [http://abc.com/Server/default.aspx](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](http://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](http://192.168.2.10:80/Server/extjs/resources/css/ext-all-embedded-css/ext.axd?v=29344)",  
host: “[abc.com](http://abc.com)”, referrer: “[http://abc.com/Server/default.aspx](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](http://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](http://192.168.2.10:80/Server/extjs/resources/css/xtheme-slate-embedded-css/ext.axd?v=29344)",  
host: “[abc.com](http://abc.com)”, referrer: “[http://abc.com/Server/default.aspx](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

---

<div class="post-metadata">

**Author:** ![quan\_nexthop](https://www.ruby-forum.com/letter_avatar_proxy/v4/letter/q/7feea3/32.png) [@quan\_nexthop](https://www.ruby-forum.com/u/quan_nexthop)\
**Post date:** [August 19, 2011, 10:07am UTC](https://www.ruby-forum.com/t/epoll-error-with-http1-1-and-keep-alive-enabed/210876/2 "2011-08-19T10:07:47Z")

</div>

Hello!

On Fri, Aug 19, 2011 at 03:02:14PM +0800, quan nexthop wrote:

> 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)

These are debugging messages. The one in your log which  
isn’t debugging explains what is happening:

> 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](http://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](http://192.168.2.10:80/Server/extjs/resources/css/ext-all-embedded-css/ext.axd?v=29344)",

> host: “[abc.com](http://abc.com)”, referrer: “[http://abc.com/Server/default.aspx](http://abc.com/Server/default.aspx)”

That is, client closed connection before nginx was able to get  
response from upstream server.

Maxim D.