Empty reply from server или данные не получены
test.b
nginx-forum at nginx.us
Tue May 19 14:40:46 UTC 2015
nginx 1.8 extras
При входе на страницу браузером получаем ошибку "данные не получены" или
если спросить курлом "curl: (52) Empty reply from server".
Включен уровень лога дебаг.
запрос curl -om на который nginx отдает "Empty reply from server" отличается
от запроса без ошибки только блоком
2015/05/19 03:21:32 [debug] 12269#0: *310584 http cl:-1 max:20971520
2015/05/19 03:21:32 [debug] 12269#0: *310584 rewrite phase: 3
2015/05/19 03:21:32 [debug] 12269#0: *310584 rewrite phase: 4
2015/05/19 03:21:32 [debug] 12269#0: *310584 post rewrite phase: 5
2015/05/19 03:21:32 [debug] 12269#0: *310584 generic phase: 6
2015/05/19 03:21:32 [debug] 12269#0: *310584 generic phase: 7
2015/05/19 03:21:32 [debug] 12269#0: *310584 generic phase: 8
2015/05/19 03:21:32 [debug] 12269#0: *310584 access phase: 9
2015/05/19 03:21:32 [debug] 12269#0: *310584 access phase: 10
2015/05/19 03:21:32 [debug] 12269#0: *310584 access phase: 11
2015/05/19 03:21:32 [debug] 12269#0: *310584 access phase: 12
2015/05/19 03:21:32 [debug] 12269#0: *310584 post access phase: 13
2015/05/19 03:21:32 [debug] 12269#0: *310584 try files phase: 14
2015/05/19 03:21:32 [debug] 12269#0: *310584 event timer add: -55801232:
5000:1432020097980
2015/05/19 03:21:32 [debug] 12269#0: *310584 http cleanup add:
00007F1DFCEBACE0
2015/05/19 03:21:32 [debug] 12269#0: *310584 http finalize request: -4,
"/stat?" a:1, c:2
2015/05/19 03:21:32 [debug] 12269#0: *310584 http request count:2 blk:0
скрин: http://i.imgur.com/rJURxEd.png
В запросе с ошибкой этот блок повторяется на один раз больше чем в запросе
без ошибки. Смотрите скриншот. Полные логи в файлах.
лог ошибки:
Server: nginx/1.8.0
Date: Tue, 19 May 2015 07:21:12 GMT
Content-Type: application/octet-stream
Transfer-Encoding: chunked
Connection: close
Strict-Transport-Security: max-age=15552000;
Public-Key-Pins: pin-sha256="***************************************";
pin-sha256="***************************************"; max-age=3600;
includeSubDomains
2015/05/19 03:21:12 [debug] 11847#0: *309227 write new buf t:1 f:0
00007F1DFD0364D8, pos 00007F1DFD0364D8, size: 379 file: 0, size: 0
2015/05/19 03:21:12 [debug] 11847#0: *309227 http write filter: l:0 f:0
s:379
2015/05/19 03:21:12 [debug] 11847#0: *309227 http output filter "/stat?"
2015/05/19 03:21:12 [debug] 11847#0: *309227 http copy filter: "/stat?"
2015/05/19 03:21:12 [debug] 11847#0: *309227 image filter
2015/05/19 03:21:12 [debug] 11847#0: *309227 xslt filter body
2015/05/19 03:21:12 [debug] 11847#0: *309227 http postpone filter "/stat?"
00007F1DFD0366D0
2015/05/19 03:21:12 [debug] 11847#0: *309227 http chunk: 7
2015/05/19 03:21:12 [debug] 11847#0: *309227 http chunk: 1
2015/05/19 03:21:12 [debug] 11847#0: *309227 write old buf t:1 f:0
00007F1DFD0364D8, pos 00007F1DFD0364D8, size: 379 file: 0, size: 0
2015/05/19 03:21:12 [debug] 11847#0: *309227 write new buf t:1 f:0
00007F1DFD036770, pos 00007F1DFD036770, size: 3 file: 0, size: 0
2015/05/19 03:21:12 [debug] 11847#0: *309227 write new buf t:0 f:0
00007F1DFCB411FE, pos 00007F1DFCB411FE, size: 7 file: 0, size: 0
2015/05/19 03:21:12 [debug] 11847#0: *309227 write new buf t:0 f:0
00007F1DFCA90368, pos 00007F1DFCA90368, size: 1 file: 0, size: 0
2015/05/19 03:21:12 [debug] 11847#0: *309227 write new buf t:0 f:0
0000000000000000, pos 00007F1DFC838F3D, size: 2 file: 0, size: 0
2015/05/19 03:21:12 [debug] 11847#0: *309227 http write filter: l:0 f:0
s:392
2015/05/19 03:21:12 [debug] 11847#0: *309227 http copy filter: 0 "/stat?"
2015/05/19 03:21:12 [debug] 11847#0: *309227 http output filter "/stat?"
2015/05/19 03:21:12 [debug] 11847#0: *309227 http copy filter: "/stat?"
2015/05/19 03:21:12 [debug] 11847#0: *309227 image filter
2015/05/19 03:21:12 [debug] 11847#0: *309227 xslt filter body
2015/05/19 03:21:12 [debug] 11847#0: *309227 http postpone filter "/stat?"
00007F1DFD036878
2015/05/19 03:21:12 [debug] 11847#0: *309227 http chunk: 0
2015/05/19 03:21:12 [debug] 11847#0: *309227 write old buf t:1 f:0
00007F1DFD0364D8, pos 00007F1DFD0364D8, size: 379 file: 0, size: 0
2015/05/19 03:21:12 [debug] 11847#0: *309227 write old buf t:1 f:0
00007F1DFD036770, pos 00007F1DFD036770, size: 3 file: 0, size: 0
2015/05/19 03:21:12 [debug] 11847#0: *309227 write old buf t:0 f:0
00007F1DFCB411FE, pos 00007F1DFCB411FE, size: 7 file: 0, size: 0
2015/05/19 03:21:12 [debug] 11847#0: *309227 write old buf t:0 f:0
00007F1DFCA90368, pos 00007F1DFCA90368, size: 1 file: 0, size: 0
2015/05/19 03:21:12 [debug] 11847#0: *309227 write old buf t:0 f:0
0000000000000000, pos 00007F1DFC838F3D, size: 2 file: 0, size: 0
2015/05/19 03:21:12 [debug] 11847#0: *309227 write new buf t:0 f:0
0000000000000000, pos 00007F1DFC838F3A, size: 5 file: 0, size: 0
2015/05/19 03:21:12 [debug] 11847#0: *309227 http write filter: l:1 f:0
s:397
2015/05/19 03:21:12 [debug] 11847#0: *309227 http write filter limit 0
2015/05/19 03:21:12 [debug] 11847#0: *309227 writev: 397 of 397
2015/05/19 03:21:12 [debug] 11847#0: *309227 http write filter
0000000000000000
2015/05/19 03:21:12 [debug] 11847#0: *309227 http copy filter: 0 "/stat?"
2015/05/19 03:21:12 [debug] 11847#0: *309227 http set discard body
2015/05/19 03:21:12 [debug] 11847#0: *309227 http finalize request: 0,
"/stat?" a:1, c:1
2015/05/19 03:21:12 [debug] 11847#0: *309227 event timer add: 118:
5000:1432020077307
2015/05/19 03:21:12 [debug] 11847#0: *309227 http lingering close handler
2015/05/19 03:21:12 [debug] 11847#0: *309227 recv: fd:118 -1 of 4096
2015/05/19 03:21:12 [debug] 11847#0: *309227 recv() not ready (11: Resource
temporarily unavailable)
2015/05/19 03:21:12 [debug] 11847#0: *309227 lingering read: -2
2015/05/19 03:21:12 [debug] 11847#0: *309227 event timer: 118, old:
1432020077307, new: 1432020077307
2015/05/19 03:21:12 [debug] 11847#0: *309227 http lingering close handler
2015/05/19 03:21:12 [debug] 11847#0: *309227 recv: fd:118 0 of 4096
2015/05/19 03:21:12 [debug] 11847#0: *309227 lingering read: 0
2015/05/19 03:21:12 [debug] 11847#0: *309227 http request count:1 blk:0
2015/05/19 03:21:12 [debug] 11847#0: *309227 http close request
2015/05/19 03:21:12 [debug] 11847#0: *309227 http log handler
2015/05/19 03:21:12 [debug] 11847#0: *309227 free: 00007F1DFCF84020, unused:
8
2015/05/19 03:21:12 [debug] 11847#0: *309227 free: 00007F1DFD035EA0, unused:
1322
2015/05/19 03:21:12 [debug] 11847#0: *309227 close http connection: 118
2015/05/19 03:21:12 [debug] 11847#0: *309227 event timer del: 118:
1432020077307
2015/05/19 03:21:12 [debug] 11847#0: *309227 reusable connection: 0
2015/05/19 03:21:12 [debug] 11847#0: *309227 free: 00007F1DFCF94650
2015/05/19 03:21:12 [debug] 11847#0: *309227 free: 00007F1DFCC90A50, unused:
8
2015/05/19 03:21:12 [debug] 11847#0: *309227 free: 00007F1DFCF56690, unused:
72
2015/05/19 03:21:23 [debug] 12075#0: *310289 http cl:-1 max:20971520
2015/05/19 03:21:23 [debug] 12075#0: *310289 rewrite phase: 3
2015/05/19 03:21:23 [debug] 12075#0: *310289 rewrite phase: 4
2015/05/19 03:21:23 [debug] 12075#0: *310289 post rewrite phase: 5
2015/05/19 03:21:23 [debug] 12075#0: *310289 generic phase: 6
2015/05/19 03:21:23 [debug] 12075#0: *310289 generic phase: 7
2015/05/19 03:21:23 [debug] 12075#0: *310289 generic phase: 8
2015/05/19 03:21:23 [debug] 12075#0: *310289 access phase: 9
2015/05/19 03:21:23 [debug] 12075#0: *310289 access phase: 10
2015/05/19 03:21:23 [debug] 12075#0: *310289 access phase: 11
2015/05/19 03:21:23 [debug] 12075#0: *310289 access phase: 12
2015/05/19 03:21:23 [debug] 12075#0: *310289 post access phase: 13
2015/05/19 03:21:23 [debug] 12075#0: *310289 try files phase: 14
2015/05/19 03:21:23 [debug] 12075#0: *310289 event timer add: -55801232:
5000:1432020088523
2015/05/19 03:21:23 [debug] 12075#0: *310289 http cleanup add:
00007F1DFCC7A370
2015/05/19 03:21:23 [debug] 12075#0: *310289 http finalize request: -4,
"/stat?" a:1, c:2
2015/05/19 03:21:23 [debug] 12075#0: *310289 http request count:2 blk:0
2015/05/19 03:21:32 [debug] 12269#0: *310584 http cl:-1 max:20971520
2015/05/19 03:21:32 [debug] 12269#0: *310584 rewrite phase: 3
2015/05/19 03:21:32 [debug] 12269#0: *310584 rewrite phase: 4
2015/05/19 03:21:32 [debug] 12269#0: *310584 post rewrite phase: 5
2015/05/19 03:21:32 [debug] 12269#0: *310584 generic phase: 6
2015/05/19 03:21:32 [debug] 12269#0: *310584 generic phase: 7
2015/05/19 03:21:32 [debug] 12269#0: *310584 generic phase: 8
2015/05/19 03:21:32 [debug] 12269#0: *310584 access phase: 9
2015/05/19 03:21:32 [debug] 12269#0: *310584 access phase: 10
2015/05/19 03:21:32 [debug] 12269#0: *310584 access phase: 11
2015/05/19 03:21:32 [debug] 12269#0: *310584 access phase: 12
2015/05/19 03:21:32 [debug] 12269#0: *310584 post access phase: 13
2015/05/19 03:21:32 [debug] 12269#0: *310584 try files phase: 14
2015/05/19 03:21:32 [debug] 12269#0: *310584 event timer add: -55801232:
5000:1432020097980
2015/05/19 03:21:32 [debug] 12269#0: *310584 http cleanup add:
00007F1DFCEBACE0
2015/05/19 03:21:32 [debug] 12269#0: *310584 http finalize request: -4,
"/stat?" a:1, c:2
2015/05/19 03:21:32 [debug] 12269#0: *310584 http request count:2 blk:0
2015/05/19 03:21:37 [debug] 12269#0: *310584 event timer del: -55801232:
1432020097980
2015/05/19 03:21:37 [debug] 12269#0: *310584 echo sleep handler: "/stat?"
2015/05/19 03:21:37 [debug] 12269#0: *310584 uploadprogress error-tracker
error: 0
2015/05/19 03:21:37 [debug] 12269#0: *310584 xslt filter header
2015/05/19 03:21:37 [debug] 12269#0: *310584 HTTP/1.1 200 OK
а вот лог без ошибки, когда сервер отдает страницу правильно.
Server: nginx/1.8.0
Date: Tue, 19 May 2015 07:21:37 GMT
Content-Type: application/octet-stream
Transfer-Encoding: chunked
Connection: close
Strict-Transport-Security: max-age=15552000;
Public-Key-Pins: pin-sha256="***************************************";
pin-sha256="***************************************"; max-age=3600;
includeSubDomains
2015/05/19 03:21:37 [debug] 12269#0: *310584 write new buf t:1 f:0
00007F1DFCB97AF8, pos 00007F1DFCB97AF8, size: 379 file: 0, size: 0
2015/05/19 03:21:37 [debug] 12269#0: *310584 http write filter: l:0 f:0
s:379
2015/05/19 03:21:37 [debug] 12269#0: *310584 http output filter "/stat?"
2015/05/19 03:21:37 [debug] 12269#0: *310584 http copy filter: "/stat?"
2015/05/19 03:21:37 [debug] 12269#0: *310584 image filter
2015/05/19 03:21:37 [debug] 12269#0: *310584 xslt filter body
2015/05/19 03:21:37 [debug] 12269#0: *310584 http postpone filter "/stat?"
00007F1DFCB97CF0
2015/05/19 03:21:37 [debug] 12269#0: *310584 http chunk: 7
2015/05/19 03:21:37 [debug] 12269#0: *310584 http chunk: 1
2015/05/19 03:21:37 [debug] 12269#0: *310584 write old buf t:1 f:0
00007F1DFCB97AF8, pos 00007F1DFCB97AF8, size: 379 file: 0, size: 0
2015/05/19 03:21:37 [debug] 12269#0: *310584 write new buf t:1 f:0
00007F1DFCB97D90, pos 00007F1DFCB97D90, size: 3 file: 0, size: 0
2015/05/19 03:21:37 [debug] 12269#0: *310584 write new buf t:0 f:0
00007F1DFCB411FE, pos 00007F1DFCB411FE, size: 7 file: 0, size: 0
2015/05/19 03:21:37 [debug] 12269#0: *310584 write new buf t:0 f:0
00007F1DFCA90368, pos 00007F1DFCA90368, size: 1 file: 0, size: 0
2015/05/19 03:21:37 [debug] 12269#0: *310584 write new buf t:0 f:0
0000000000000000, pos 00007F1DFC838F3D, size: 2 file: 0, size: 0
2015/05/19 03:21:37 [debug] 12269#0: *310584 http write filter: l:0 f:0
s:392
2015/05/19 03:21:37 [debug] 12269#0: *310584 http copy filter: 0 "/stat?"
2015/05/19 03:21:37 [debug] 12269#0: *310584 http output filter "/stat?"
2015/05/19 03:21:37 [debug] 12269#0: *310584 http copy filter: "/stat?"
2015/05/19 03:21:37 [debug] 12269#0: *310584 image filter
2015/05/19 03:21:37 [debug] 12269#0: *310584 xslt filter body
2015/05/19 03:21:37 [debug] 12269#0: *310584 http postpone filter "/stat?"
00007F1DFCB97E98
2015/05/19 03:21:37 [debug] 12269#0: *310584 http chunk: 0
2015/05/19 03:21:37 [debug] 12269#0: *310584 write old buf t:1 f:0
00007F1DFCB97AF8, pos 00007F1DFCB97AF8, size: 379 file: 0, size: 0
2015/05/19 03:21:37 [debug] 12269#0: *310584 write old buf t:1 f:0
00007F1DFCB97D90, pos 00007F1DFCB97D90, size: 3 file: 0, size: 0
2015/05/19 03:21:37 [debug] 12269#0: *310584 write old buf t:0 f:0
00007F1DFCB411FE, pos 00007F1DFCB411FE, size: 7 file: 0, size: 0
2015/05/19 03:21:37 [debug] 12269#0: *310584 write old buf t:0 f:0
00007F1DFCA90368, pos 00007F1DFCA90368, size: 1 file: 0, size: 0
2015/05/19 03:21:37 [debug] 12269#0: *310584 write old buf t:0 f:0
0000000000000000, pos 00007F1DFC838F3D, size: 2 file: 0, size: 0
2015/05/19 03:21:37 [debug] 12269#0: *310584 write new buf t:0 f:0
0000000000000000, pos 00007F1DFC838F3A, size: 5 file: 0, size: 0
2015/05/19 03:21:37 [debug] 12269#0: *310584 http write filter: l:1 f:0
s:397
2015/05/19 03:21:37 [debug] 12269#0: *310584 http write filter limit 0
2015/05/19 03:21:37 [debug] 12269#0: *310584 writev: 397 of 397
2015/05/19 03:21:37 [debug] 12269#0: *310584 http write filter
0000000000000000
2015/05/19 03:21:37 [debug] 12269#0: *310584 http copy filter: 0 "/stat?"
2015/05/19 03:21:37 [debug] 12269#0: *310584 http set discard body
2015/05/19 03:21:37 [debug] 12269#0: *310584 http finalize request: 0,
"/stat?" a:1, c:1
2015/05/19 03:21:37 [debug] 12269#0: *310584 event timer add: 82:
5000:1432020102980
2015/05/19 03:21:37 [debug] 12269#0: *310584 http lingering close handler
2015/05/19 03:21:37 [debug] 12269#0: *310584 recv: fd:82 -1 of 4096
2015/05/19 03:21:37 [debug] 12269#0: *310584 recv() not ready (11: Resource
temporarily unavailable)
2015/05/19 03:21:37 [debug] 12269#0: *310584 lingering read: -2
2015/05/19 03:21:37 [debug] 12269#0: *310584 event timer: 82, old:
1432020102980, new: 1432020102980
2015/05/19 03:21:37 [debug] 12269#0: *310584 http lingering close handler
2015/05/19 03:21:37 [debug] 12269#0: *310584 recv: fd:82 0 of 4096
2015/05/19 03:21:37 [debug] 12269#0: *310584 lingering read: 0
2015/05/19 03:21:37 [debug] 12269#0: *310584 http request count:1 blk:0
2015/05/19 03:21:37 [debug] 12269#0: *310584 http close request
2015/05/19 03:21:37 [debug] 12269#0: *310584 http log handler
2015/05/19 03:21:37 [debug] 12269#0: *310584 free: 00007F1DFCEB9D00, unused:
8
2015/05/19 03:21:37 [debug] 12269#0: *310584 free: 00007F1DFCB974C0, unused:
1322
2015/05/19 03:21:37 [debug] 12269#0: *310584 close http connection: 82
2015/05/19 03:21:37 [debug] 12269#0: *310584 event timer del: 82:
1432020102980
2015/05/19 03:21:37 [debug] 12269#0: *310584 reusable connection: 0
2015/05/19 03:21:37 [debug] 12269#0: *310584 free: 00007F1DFCCB80C0
2015/05/19 03:21:37 [debug] 12269#0: *310584 free: 00007F1DFCDF2080, unused:
8
2015/05/19 03:21:37 [debug] 12269#0: *310584 free: 00007F1DFCCB7ED0, unused:
72
2015/05/19 03:22:11 [debug] 12130#0: *313115 http cl:-1 max:20971520
2015/05/19 03:22:11 [debug] 12130#0: *313115 rewrite phase: 3
2015/05/19 03:22:11 [debug] 12130#0: *313115 rewrite phase: 4
2015/05/19 03:22:11 [debug] 12130#0: *313115 post rewrite phase: 5
2015/05/19 03:22:11 [debug] 12130#0: *313115 generic phase: 6
2015/05/19 03:22:11 [debug] 12130#0: *313115 generic phase: 7
2015/05/19 03:22:11 [debug] 12130#0: *313115 generic phase: 8
2015/05/19 03:22:11 [debug] 12130#0: *313115 access phase: 9
2015/05/19 03:22:11 [debug] 12130#0: *313115 access phase: 10
2015/05/19 03:22:11 [debug] 12130#0: *313115 access phase: 11
2015/05/19 03:22:11 [debug] 12130#0: *313115 access phase: 12
2015/05/19 03:22:11 [debug] 12130#0: *313115 post access phase: 13
2015/05/19 03:22:11 [debug] 12130#0: *313115 try files phase: 14
2015/05/19 03:22:11 [debug] 12130#0: *313115 event timer add: -55801232:
5000:1432020136263
2015/05/19 03:22:11 [debug] 12130#0: *313115 http cleanup add:
00007F1DFCB984A0
2015/05/19 03:22:11 [debug] 12130#0: *313115 http finalize request: -4,
"/stat?" a:1, c:2
2015/05/19 03:22:11 [debug] 12130#0: *313115 http request count:2 blk:0
2015/05/19 03:22:16 [debug] 12130#0: *313115 event timer del: -55801232:
1432020136263
2015/05/19 03:22:16 [debug] 12130#0: *313115 echo sleep handler: "/stat?"
2015/05/19 03:22:16 [debug] 12130#0: *313115 uploadprogress error-tracker
error: 0
2015/05/19 03:22:16 [debug] 12130#0: *313115 xslt filter header
2015/05/19 03:22:16 [debug] 12130#0: *313115 HTTP/1.1 200 OK
в какую сторону копать, где искать ошибку?
Posted at Nginx Forum: http://forum.nginx.org/read.php?21,258986,258986#msg-258986
Подробная информация о списке рассылки nginx-ru