ruby - 连接到单独 AWS 实例上的 PostGres 数据库时出现 "could not receive data from server: Connection timed out"或 "connection not open"错误

Question

我在 AWS EC2 实例上运行的应用程序服务器中使用 Ruby 1.9.3。我的 Postgres 数据库在单独的 EC2 实例上运行，但两个实例都在同一个安全组中。当 m Ruby 代码连接到数据库时，它使用 Sequel ORM gem ( http://sequel.rubyforge.org/ )。

现在，我已将 Postgres 9.1.4 数据库配置为能够正确接受来自应用服务器实例的连接。

但是，我时不时地在应用服务器的日志中注意到它在连接到 Postgres 数据库实例时会遇到问题，并且我会看到如下错误消息:

PG::Error: could not receive data from server: Connection timed out

或

PG::Error: connection not open

所以我去了 Postgres 数据库实例并查看了/var/log/postgresql/postgresql-9.1-main.log，我看到了一堆这样的消息:

2012-11-07 08:15:17 UTC LOG:  could not receive data from client: Connection timed out
2012-11-07 08:15:17 UTC LOG:  unexpected EOF on client connection

我确实在网络上进行了搜索，包括堆栈溢出，并确保我的 PostgreSQL 没有启用 SSL(我的 postgresql.conf 文件中有“ssl=off”)

在这一点上，我不确定 Postgres 配置中的问题到底是什么。如果没有充分证明的理由，我不会搞乱生产服务器上的最大连接数或最大超时值。

应用服务器大部分时间都可以连接到数据库，这个问题只是间歇性出现。

在 Ruby 端，这是进行 Postgres 调用时“连接未打开”的错误跟踪:

PG::Error: connection not open
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:145:in `async_exec'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:145:in `block in execute_query'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/database/logging.rb:33:in `log_yield'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:145:in `execute_query'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:132:in `block in execute'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:111:in `check_disconnect_errors'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:132:in `execute'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:372:in `_execute'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:234:in `block (2 levels) in execute'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:379:in `check_database_errors'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:234:in `block in execute'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/database/connecting.rb:229:in `block in synchronize'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/connection_pool/threaded.rb:105:in `hold'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/database/connecting.rb:229:in `synchronize'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:234:in `execute'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/dataset/actions.rb:744:in `execute'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:483:in `fetch_rows'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/model/base.rb:785:in `primary_key_lookup'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/model/base.rb:124:in `[]'

同样，这是“无法从服务器接收数据”的跟踪:

    PG::Error: could not receive data from server: Connection timed out
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:124:in `block'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:124:in `ensure in check_disconnect_errors'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:124:in `check_disconnect_errors'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:132:in `execute'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:372:in `_execute'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:234:in `block (2 levels) in execute'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:379:in `check_database_errors'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:234:in `block in execute'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/database/connecting.rb:229:in `block in synchronize'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/connection_pool/threaded.rb:105:in `hold'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/database/connecting.rb:229:in `synchronize'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:234:in `execute'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/dataset/actions.rb:744:in `execute'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/adapters/postgres.rb:483:in `fetch_rows'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/model/base.rb:785:in `primary_key_lookup'
/var/lib/gems/1.9.1/gems/sequel-3.38.0/lib/sequel/model/base.rb:124:in `[]'

我观察到，如果我在同一个实例上同时运行应用程序服务器和 Postgres 数据库，那么就不存在连接问题，至少目前还没有。也许 Postgres 对非本地数据库连接不太宽容？

请让我知道我可能遗漏了什么，非常感谢!

是

Answer 1

对此的通常解释是连接问题。

或者，如果不是连接性问题，则可能是协议(protocol)同步问题。看起来两端可能都在尝试从套接字中读取，但都没有尝试写入。所以也许客户端期望服务器发送响应，而服务器期望客户端发送数据。

如果它是间歇性的和偶尔的，这将很难调试，因为你不能真的只是 tcpdump 它并分析它。

我会在服务器端添加更多日志记录 - log_statement = 'all'，以及一个显示客户端 IP、后端开始时间和后端 pid 的 log_line_prefix。这样您就可以开始尝试将这些失败与失败之前发生的 session 事件相匹配，确定它是特定的客户、特定的工作，还是真的只是随机的。

这个Sequel ORM gem是底层使用了libpq，还是自己的PostgreSQL协议(protocol)实现？如果是后者，那很可能就是罪魁祸首。

更新:看起来它可以使用pg gem(基于libpq)、postgres gem，或者可能是 postgres-pr (不管是什么)。如果已安装，它将更喜欢 pg。

因为您似乎已经在使用 pg gem，您可能需要做一些诊断工作来追踪问题出现的位置 - 特定查询、特定客户端等 -并尝试找到重现问题的方法。 PostgreSQL 的 csvlog 可能很有用，因此您可以更轻松地加载和分析日志。