Nginx/Unicorn/Rails 连接超时答案

【问题标题】：Nginx/Unicorn/Rails connection timed outNginx/Unicorn/Rails 连接超时
【发布时间】：2015-03-28 19:21:46
【问题描述】：

我正在使用 Nginx/Unicorn 运行 Rails 应用程序。链接到我的nginx.conf 和unicorn.rb

我正在通过 capistrano 进行部署，最初该应用运行良好。但是，在没有请求的一段时间（即 10-20 分钟）之后，传入的请求就会遇到问题。（返回错误 500 出错页面）。

Rails production.log 显示它收到了请求，但我没有做任何其他事情：

I, [2015-01-29T13:12:03.725959 #24176]  INFO -- : Started GET "/" for xxx.18.170.101 at 2015-01-29 13:12:03 +0000

nginx 的 access.log

xxx.18.170.101 - - [29/Jan/2015:13:14:33 +0000] "GET / HTTP/1.1" 504 1477 "-" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/40.0.2214.91 Safari/537.36"

nginx 的错误日志

2015/01/29 13:14:33 [error] 23786#0: *20 upstream timed out (110: Connection timed out) while reading response header from upstream, client: xxx.18.170.101, server: , request: "GET / HTTP/1.1", upstream: "http://unix:/tmp/unicorn.my_app.sock/", host: "my_server"

unicorn的error.log显示超时并杀死worker

D, [2015-01-29T13:01:45.386741 #22987] DEBUG -- : waiting 121.0s after suspend/hibernation
E, [2015-01-29T13:16:04.784404 #22987] ERROR -- : worker=0 PID:24176 timeout (241s > 240s), killing
E, [2015-01-29T13:16:04.794715 #22987] ERROR -- : reaped #<Process::Status: pid 24176 SIGKILL (signal 9)> worker=0
I, [2015-01-29T13:16:05.797775 #24377]  INFO -- : worker=0 spawned pid=24377
I, [2015-01-29T13:16:05.827508 #24377]  INFO -- : worker=0 ready

奇怪的是，在提出几个请求后，它又会恢复正常：正常生产.log

I, [2015-01-29T13:34:39.054539 #24666]  INFO -- : Started GET "/" for xx.18.170.101 at 2015-01-29 13:34:39 +0000
I, [2015-01-29T13:34:39.122488 #24666]  INFO -- : Processing by ProductsController#index as HTML
....

然后如果一段时间没有请求，错误会再次发生。

查看时间戳以及成功请求和错误请求的production.log之间的区别，我认为nginx，独角兽设置没有任何问题。但是，通常当一个请求到达 rails 应用程序时，它会被处理processing by ...Controller，但是在没有请求的一段时间后，下一个请求被卡在了某个地方。

任何建议如何解决，或如何调试这个问题？我尝试了 strace 但结果很难弄清楚。最耗时的是：

[pid 12649] 06:34:02 select(8, [7], NULL, NULL, {44, 88867}) = 0 (Timeout) <44.105805>

【问题讨论】：

标签： ruby-on-rails debugging nginx server unicorn

【解决方案1】：

我不能 100% 确定这个答案，但我还不能离开 cmets。 所以我不能确切地告诉你为什么你的请求需要这么长时间，但重要的错误消息是你的独角兽工人被杀的地方。

来自您的 unicorn.rb：timeout 240

来自您的 unicorn_error.log：

ERROR -- : worker=0 PID:24176 timeout (241s > 240s), killing

因此该请求所用时间超过 240 秒。尝试增加您的独角兽超时，以便请求可以通过，然后查看您的日志文件以了解需要这么长时间的内容。您认为独角兽不是问题是正确的，因为除非您正在做某事并且用户期望花费很长时间，否则您不应该花费 +240s。

【讨论】：