<html>
<head>
</head>
<body>Thanks. I have a new thoery. When i test on my local machine it's fast enough to get the request off. But on a remote machine the request takes longer and the object is being destroyed before all the data can be sent. I'll look into it... <br>
<br>
Thanks again. <br>
-- <br>
Sent from my Android phone with GMX Mail. Please excuse my brevity.<br><br><div class="gmail_quote">Maxim Dounin <mdounin@mdounin.ru> wrote:<blockquote class="gmail_quote" style="margin: 0pt 0pt 0pt 0.8ex; border-left: 1px solid rgb(204, 204, 204); padding-left: 1ex;">
Hello!<br/>
<br/>
On Mon, Dec 15, 2014 at 04:21:07PM +0100, Jason H wrote:<br/>
<br/>
> As requested. 100k debug log attached. I didn't see anything obviously wrong in the log.<br/>
<br/>
Some comments about the log below.<br/>
<br/>
[...]<br/>
<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http process request line<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http request line: "POST /debug/visit/files HTTP/1.1"<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http uri: "/debug/visit/files"<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http args: ""<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http exten: ""<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http process request header line<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http header: "Content-Type: multipart/form-data; boundary="boundary_.oOo._MTMyNDQwMjY1MA==MTgwNjg0NzQyMQ==MTM2OTc5MjA=""<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http header: "MIME-Version: 1.0"<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http header: "Content-Length: 216619"<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http header: "Connection: Keep-Alive"<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http header: "Accept-Encoding: gzip, deflate"<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http header: "Accept-Language: en-US,*"<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http header: "User-Agent: Mozilla/5.0"<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http header: "Host: dev.tissue-analytics.com"<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http header done<br/>
<br/>
Good so far, Content-Length is 216619 bytes.<br/>
<br/>
[...]<br/>
<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http request body content length filter<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 malloc: 0000000000E7E610:216619<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http read client request body<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 recv: fd:10 -1 of 216619<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 recv() not ready (11: Resource temporarily unavailable)<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http client request body recv -2<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http client request body rest 216619<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 event timer add: 10: 60000:1418656297413<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http finalize request: -4, "/debug/visit/files?" a:1, c:2<br/>
<br/>
Here nginx started reading the request body.<br/>
<br/>
[...]<br/>
<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http run request: "/debug/visit/files?"<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http read client request body<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 recv: fd:10 1424 of 216619<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http client request body recv 1424<br/>
> 2014/12/15 15:10:37 [debug] 21192#0: *10 http client request body rest 216619<br/>
<br/>
First 1424 bytes received.<br/>
<br/>
[...]<br/>
<br/>
> 2014/12/15 15:10:38 [debug] 21192#0: epoll timer: 58359<br/>
> 2014/12/15 15:10:38 [debug] 21192#0: epoll: fd:10 ev:0001 d:00007F5631AD21C1<br/>
> 2014/12/15 15:10:38 [debug] 21192#0: *10 http run request: "/debug/visit/files?"<br/>
> 2014/12/15 15:10:38 [debug] 21192#0: *10 http read client request body<br/>
> 2014/12/15 15:10:38 [debug] 21192#0: *10 recv: fd:10 944 of 119259<br/>
> 2014/12/15 15:10:38 [debug] 21192#0: *10 http client request body recv 944<br/>
> 2014/12/15 15:10:38 [debug] 21192#0: *10 http client request body rest 216619<br/>
<br/>
And here is the last successful recv(), of 944 bytes. Counting<br/>
all the recvs in total, nginx was able to recieve 98304 bytes of<br/>
the body.<br/>
<br/>
> 2014/12/15 15:10:38 [debug] 21192#0: *10 recv: fd:10 -1 of 118315<br/>
> 2014/12/15 15:10:38 [debug] 21192#0: *10 recv() not ready (11: Resource temporarily unavailable)<br/>
> 2014/12/15 15:10:38 [debug] 21192#0: *10 http client request body recv -2<br/>
> 2014/12/15 15:10:38 [debug] 21192#0: *10 http client request body rest 216619<br/>
> 2014/12/15 15:10:38 [debug] 21192#0: *10 event timer: 10, old: 1418656298021, new: 1418656298162<br/>
> 2014/12/15 15:10:38 [debug] 21192#0: timer delta: 6<br/>
> 2014/12/15 15:10:38 [debug] 21192#0: posted events 0000000000000000<br/>
> 2014/12/15 15:10:38 [debug] 21192#0: worker cycle<br/>
> 2014/12/15 15:10:38 [debug] 21192#0: epoll timer: 58353<br/>
<br/>
As expected, nginx still tries to read the rest of the body,<br/>
118315 bytes.<br/>
<br/>
[...]<br/>
<br/>
> 2014/12/15 15:11:38 [debug] 21192#0: timer delta: 1450<br/>
> 2014/12/15 15:11:38 [debug] 21192#0: *10 event timer del: 10: 1418656298021<br/>
> 2014/12/15 15:11:38 [debug] 21192#0: *10 http run request: "/debug/visit/files?"<br/>
> 2014/12/15 15:11:38 [debug] 21192#0: *10 http finalize request: 408, "/debug/visit/files?" a:1, c:1<br/>
<br/>
The client_body_timeout is triggered and the connection is closed.<br/>
<br/>
That is, from nginx point of view the client failed to send the<br/>
request and unexpectedly stopped at some point. It's not clear<br/>
why this happened, but undersized last packet suggests that it's<br/>
the client's fault.<br/>
<br/>
--<br/>
Maxim Dounin<br/>
<a href="http://nginx.org/" target="_blank">http://nginx.org/</a><br/>
<br/>
_______________________________________________<br/>
nginx mailing list<br/>
nginx@nginx.org<br/>
<a href="http://mailman.nginx.org/mailman/listinfo/nginx" target="_blank">http://mailman.nginx.org/mailman/listinfo/nginx</a><br/>
</blockquote></div></body>
</html>