ssl - 几个小时后，通过 GCE 上的套接字的 APNS 停止工作

Question

这是我从 Heroku 迁移到 Google Container Engine 后开始面临的一个有趣的问题:

自从迁移到 GCE 后，在服务器启动/重启/部署几个小时后，突然之间，我的 Elixir 应用程序无法再向 APNS 发送推送通知。我正在使用 apns4ex图书馆。到目前为止，我大致发现了以下内容:

在 init 内部，库打开一个到 APNS 的 :ssl (erlang) 套接字，并在 GenServer 进程中不断回收它

def connect_socket(host, port, opts, timeout_seconds) do
    address = "#{host}:#{port}"

    case :ssl.connect(host, port, opts, timeout_seconds * 1000) do
      {:ok, socket} ->
        APNS.Logger.debug("successfully connected to #{address}")
        {:ok, socket}
      {:error, reason} ->
        APNS.Logger.error("failed to connect to push socket #{address}, reason given: #{inspect(reason)}")
        {:error, {:connection_failed, address}}
    end
end

现在，从 x 小时开始，在尝试发送消息后，库开始接收 :ssl_closed 消息/回调以指示 SSL 连接已关闭

def handle_info({:ssl_closed, socket}, %{socket_apple: socket} = state) do
  APNS.Logger.debug("ssl socket closed, returning :connect")
  {:connect, {:error, "ssl_closed"}, %{state | socket_apple: nil}}
end

它的处理方式是让连接关闭并返回 :connect，然后重新连接到 APNS (here)

一旦推送通知停止工作，调试日志总是在每条消息上报告以下模式。

1. 尝试发送消息
2. 报告“发送成功”(没有任何东西被发送到手机。此消息是由 :ssl.send 报告 :ok 引起的)
3. 然后收到ssl套接字关闭消息
4. 重新连接到 gateway.push.apple.com(:ssl.connect 返回 :ok)
5. 重复

发送包代码:

def send_package(socket, packet) do
  result = :ssl.send(socket, [packet])

  case result do
    :ok ->
      APNS.Logger.debug("success sending ssl package")
    {:error, reason} ->
      APNS.Logger.warn("error #{reason} sending ssl package")
  end

  result
end

相比之下，发送成功后它会在第 2 点停止。

这是发送推送时我的应用程序的一些原始日志输出(注意最后 9 行显示了我描述的模式)

01:41:14.820 request_id=fecds3h3s1so2825c44qfestvvvpv707 [debug] [APNS] #PID<0.20135.97> 23303051:1ad798 sending in poolboy transaction :myapp
01:41:14.821 request_id=fecds3h3s1so2825c44qfestvvvpv707 [debug] [APNS] #PID<0.20135.97> 23303051:1ad798 sending message
01:41:14.821 request_id=fecds3h3s1so2825c44qfestvvvpv707 [debug] [APNS] #PID<0.20135.97> 62064556:b12e98 sending in poolboy transaction :myapp
01:41:14.821 [debug] [APNS] #PID<0.349.0> 23303051:1ad798 handling cast :send
01:41:14.821 [debug] [APNS] #PID<0.349.0> 23303051:1ad798 message's payload looks good
01:41:14.821 request_id=fecds3h3s1so2825c44qfestvvvpv707 [debug] [APNS] #PID<0.20135.97> 62064556:b12e98 sending message
01:41:14.821 request_id=fecds3h3s1so2825c44qfestvvvpv707 [debug] [APNS] #PID<0.20135.97> 19048099:b3ed8e sending in poolboy transaction :myapp
01:41:14.822 [debug] [APNS] #PID<0.349.0> success sending ssl package
01:41:14.822 [debug] [APNS] #PID<0.349.0> 23303051:1ad798 success sending
01:41:14.822 [debug] [APNS] #PID<0.349.0> 23303051:1ad798 handle call :send received :ok
01:41:14.822 [debug] [APNS] #PID<0.348.0> 62064556:b12e98 handling cast :send
01:41:14.822 [debug] [APNS] #PID<0.348.0> 62064556:b12e98 message's payload looks good
01:41:14.823 request_id=fecds3h3s1so2825c44qfestvvvpv707 [debug] [APNS] #PID<0.20135.97> 19048099:b3ed8e sending message
01:41:14.823 request_id=fecds3h3s1so2825c44qfestvvvpv707 [info] Sent 200 in 22ms
01:41:14.823 [debug] [APNS] #PID<0.348.0> success sending ssl package
01:41:14.823 [debug] [APNS] #PID<0.348.0> 62064556:b12e98 success sending
01:41:14.823 [debug] [APNS] #PID<0.348.0> 62064556:b12e98 handle call :send received :ok
01:41:14.823 [debug] [APNS] #PID<0.347.0> 19048099:b3ed8e handling cast :send
01:41:14.824 [debug] [APNS] #PID<0.347.0> 19048099:b3ed8e message's payload looks good
01:41:14.824 [debug] [APNS] #PID<0.347.0> success sending ssl package
01:41:14.824 [debug] [APNS] #PID<0.347.0> 19048099:b3ed8e success sending
01:41:14.824 [debug] [APNS] #PID<0.347.0> 19048099:b3ed8e handle call :send received :ok
01:41:15.027 [debug] [APNS] #PID<0.348.0> ssl socket closed, returning :connect
01:41:15.029 [debug] [APNS] #PID<0.347.0> ssl socket closed, returning :connect
01:41:15.043 [debug] [APNS] #PID<0.349.0> ssl socket closed, returning :connect
01:41:15.207 [debug] [APNS] #PID<0.348.0> successfully connected to gateway.push.apple.com:2195
01:41:15.207 [debug] [APNS] #PID<0.348.0> successfully connected to socket
01:41:15.209 [debug] [APNS] #PID<0.347.0> successfully connected to gateway.push.apple.com:2195
01:41:15.209 [debug] [APNS] #PID<0.347.0> successfully connected to socket
01:41:15.214 [debug] [APNS] #PID<0.349.0> successfully connected to gateway.push.apple.com:2195
01:41:15.214 [debug] [APNS] #PID<0.349.0> successfully connected to socket

一种理论认为 GCE 因空闲而关闭连接，但这并不能解释为什么重新连接后的另一条消息会立即导致相同的模式。另外，为什么套接字仅在使用 :ssl.send 发送后才关闭？

Answer 1

我和 apns4erl 有同样的问题，当套接字在尝试发送消息后关闭时，但问题出在我这边，不记得了，它要么在错误的证书文件中，要么在错误的消息中