search

dequis / purple-facebook

Facebook protocol plugin for libpurple (moved from jgeboski/purple-facebook)
GNU General Public License v2.0
954 stars 88 forks source link

disconnected: Connection timed out #355

Open brianjmurrell opened 7 years ago

brianjmurrell commented 7 years ago

I am lately, quite frequently, getting a dialog in Pidgin which reports:

[account name redacted] disconnected
Connection timed out

with Modify Account and Reconnect buttons in it.

When this happens the debug log reports:

(00:46:07) connection: Connection error on 0x55fd2b155fe0 (reason: 0 description: Connection timed out)
(00:46:07) account: Disconnecting account [redacted] (0x55fd27b872e0)
(00:46:07) connection: Disconnecting connection 0x55fd2b155fe0
(00:46:07) connection: Destroying connection 0x55fd2b155fe0

and then sometimes, a short while later, without my needing to do anything the dialog disappear and the log reports:

(00:46:34) autorecon: do_signon called
(00:46:34) autorecon: calling purple_account_connect
(00:46:34) account: Connecting to account [redacted].
(00:46:34) connection: Connecting. gc = 0x55fd30326a00
(00:46:34) http: Performing new request 0x55fd30070010 to graph.facebook.com.
(00:46:34) autorecon: done calling purple_account_connect
(00:46:34) dnsquery: Performing DNS lookup for graph.facebook.com
(00:46:34) dns: Successfully sent DNS request to child 7396
(00:46:34) dns: Got response for 'graph.facebook.com'
(00:46:34) dnsquery: IP resolved for graph.facebook.com
(00:46:34) proxy: Attempting connection to 31.13.69.197
(00:46:34) proxy: Connecting to graph.facebook.com:443 with no proxy
(00:46:34) proxy: Connection in progress
(00:46:34) proxy: Connecting to graph.facebook.com:443.
(00:46:34) proxy: Connected to graph.facebook.com:443.
(00:46:34) nss: SSL version 3.3 using 128-bit AES-GCM with 128-bit AEAD MAC
Server Auth: 256-bit ECDSA, Key Exchange: 256-bit ECDHE, Compression: NULL
Cipher Suite Name: TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256
(00:46:34) nss: subject=CN=*.facebook.com,O="Facebook, Inc.",L=Menlo Park,ST=California,C=US issuer=CN=DigiCert SHA2 High Assurance Server CA,OU=www.digicert.com,O=DigiCert Inc,C=US
(00:46:34) nss: partial certificate chain
(00:46:34) certificate/x509/tls_cached: Starting verify for graph.facebook.com
(00:46:34) certificate/x509/tls_cached: Checking for cached cert...
(00:46:34) certificate/x509/tls_cached: ...Found cached cert
(00:46:34) nss/x509: Loading certificate from /home/brian/.purple/certificates/x509/tls_peers/graph.facebook.com
(00:46:34) certificate/x509/tls_cached: Peer cert matched cached
(00:46:34) nss/x509: Exporting certificate to /home/brian/.purple/certificates/x509/tls_peers/graph.facebook.com
(00:46:34) util: Writing file /home/brian/.purple/certificates/x509/tls_peers/graph.facebook.com
(00:46:34) nss: Trusting CN=*.facebook.com,O="Facebook, Inc.",L=Menlo Park,ST=California,C=US
(00:46:34) certificate: Successfully verified certificate for graph.facebook.com
(00:46:34) http: Request 0x55fd30070010 performed successfully.
(00:46:34) http: Performing new request 0x55fd2e354b80 to graph.facebook.com.
(00:46:34) dnsquery: Performing DNS lookup for graph.facebook.com
(00:46:34) dns: Successfully sent DNS request to child 7396
(00:46:34) dns: Got response for 'graph.facebook.com'
(00:46:34) dnsquery: IP resolved for graph.facebook.com
(00:46:34) proxy: Attempting connection to 31.13.69.197
(00:46:34) proxy: Connecting to graph.facebook.com:443 with no proxy
(00:46:34) proxy: Connection in progress
(00:46:34) proxy: Connecting to graph.facebook.com:443.
(00:46:34) proxy: Connected to graph.facebook.com:443.
(00:46:34) nss: SSL version 3.3 using 128-bit AES-GCM with 128-bit AEAD MAC
Server Auth: 256-bit ECDSA, Key Exchange: 256-bit ECDHE, Compression: NULL
Cipher Suite Name: TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256
(00:46:34) nss: subject=CN=*.facebook.com,O="Facebook, Inc.",L=Menlo Park,ST=California,C=US issuer=CN=DigiCert SHA2 High Assurance Server CA,OU=www.digicert.com,O=DigiCert Inc,C=US
(00:46:34) nss: partial certificate chain
(00:46:34) certificate/x509/tls_cached: Starting verify for graph.facebook.com
(00:46:34) certificate/x509/tls_cached: Checking for cached cert...
(00:46:34) certificate/x509/tls_cached: ...Found cached cert
(00:46:34) nss/x509: Loading certificate from /home/brian/.purple/certificates/x509/tls_peers/graph.facebook.com
(00:46:34) certificate/x509/tls_cached: Peer cert matched cached
(00:46:34) nss/x509: Exporting certificate to /home/brian/.purple/certificates/x509/tls_peers/graph.facebook.com
(00:46:34) util: Writing file /home/brian/.purple/certificates/x509/tls_peers/graph.facebook.com
(00:46:34) nss: Trusting CN=*.facebook.com,O="Facebook, Inc.",L=Menlo Park,ST=California,C=US
(00:46:34) certificate: Successfully verified certificate for graph.facebook.com
(00:46:35) http: Request 0x55fd2e354b80 performed successfully.
(00:46:35) dnsquery: Performing DNS lookup for mqtt.facebook.com
(00:46:35) dns: Successfully sent DNS request to child 7396
(00:46:35) dns: Got response for 'mqtt.facebook.com'
(00:46:35) dnsquery: IP resolved for mqtt.facebook.com
(00:46:35) proxy: Attempting connection to 2a03:2880:f027:209:face:b00c:0:8e
(00:46:35) proxy: Connecting to mqtt.facebook.com:443 with no proxy
(00:46:35) proxy: Connection in progress
(00:47:06) proxy: Connecting to mqtt.facebook.com:443.
(00:47:06) proxy: Connected to mqtt.facebook.com:443.
(00:47:06) nss: SSL version 3.3 using 128-bit AES-GCM with 128-bit AEAD MAC
Server Auth: 256-bit ECDSA, Key Exchange: 256-bit ECDHE, Compression: NULL
Cipher Suite Name: TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256
(00:47:06) nss: subject=CN=*.facebook.com,O="Facebook, Inc.",L=Menlo Park,ST=California,C=US issuer=CN=DigiCert SHA2 High Assurance Server CA,OU=www.digicert.com,O=DigiCert Inc,C=US
(00:47:06) nss: partial certificate chain
(00:47:06) certificate/x509/tls_cached: Starting verify for mqtt.facebook.com
(00:47:06) certificate/x509/tls_cached: Checking for cached cert...
(00:47:06) certificate/x509/tls_cached: ...Found cached cert
(00:47:06) nss/x509: Loading certificate from /home/brian/.purple/certificates/x509/tls_peers/mqtt.facebook.com
(00:47:06) certificate/x509/tls_cached: Peer cert matched cached
(00:47:06) nss/x509: Exporting certificate to /home/brian/.purple/certificates/x509/tls_peers/mqtt.facebook.com
(00:47:06) util: Writing file /home/brian/.purple/certificates/x509/tls_peers/mqtt.facebook.com
(00:47:06) nss: Trusting CN=*.facebook.com,O="Facebook, Inc.",L=Menlo Park,ST=California,C=US
(00:47:06) certificate: Successfully verified certificate for mqtt.facebook.com
(00:47:06) http: Performing new request 0x55fd2af84f50 to graph.facebook.com.
(00:47:06) dnsquery: Performing DNS lookup for graph.facebook.com
(00:47:06) dns: Wait for DNS child 7396 failed: No child processes
(00:47:06) dns: Created new DNS child 7444, there are now 1 children.
(00:47:06) dns: Successfully sent DNS request to child 7444
(00:47:06) dns: Got response for 'graph.facebook.com'
(00:47:06) dnsquery: IP resolved for graph.facebook.com
(00:47:06) proxy: Attempting connection to 31.13.69.197
(00:47:06) proxy: Connecting to graph.facebook.com:443 with no proxy
(00:47:06) proxy: Connection in progress
(00:47:06) proxy: Connecting to graph.facebook.com:443.
(00:47:06) proxy: Connected to graph.facebook.com:443.
(00:47:07) facebook: Presence: [redacted] (0)
(00:47:07) facebook: Presence: [redacted] (0)
(00:47:07) facebook: Presence: [redacted] (0)
(00:47:07) facebook: Presence: [redacted] (0)
(00:47:07) facebook: Presence: [redacted] (0)
(00:47:07) facebook: Presence: [redacted] (0)
(00:47:07) blist: Updating buddy status for [redacted] (Facebook)
(00:47:07) blist: Updating buddy status for [redacted] (Facebook)
(00:47:07) blist: Updating buddy status for [redacted] (Facebook)
(00:47:07) blist: Updating buddy status for [redacted] (Facebook)
(00:47:07) blist: Updating buddy status for [redacted] (Facebook)
(00:47:07) nss: SSL version 3.3 using 128-bit AES-GCM with 128-bit AEAD MAC
Server Auth: 256-bit ECDSA, Key Exchange: 256-bit ECDHE, Compression: NULL
Cipher Suite Name: TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256
(00:47:07) nss: subject=CN=*.facebook.com,O="Facebook, Inc.",L=Menlo Park,ST=California,C=US issuer=CN=DigiCert SHA2 High Assurance Server CA,OU=www.digicert.com,O=DigiCert Inc,C=US
(00:47:07) nss: partial certificate chain
(00:47:07) certificate/x509/tls_cached: Starting verify for graph.facebook.com
(00:47:07) certificate/x509/tls_cached: Checking for cached cert...
(00:47:07) certificate/x509/tls_cached: ...Found cached cert
(00:47:07) nss/x509: Loading certificate from /home/brian/.purple/certificates/x509/tls_peers/graph.facebook.com
(00:47:07) certificate/x509/tls_cached: Peer cert matched cached
(00:47:07) nss/x509: Exporting certificate to /home/brian/.purple/certificates/x509/tls_peers/graph.facebook.com
(00:47:07) util: Writing file /home/brian/.purple/certificates/x509/tls_peers/graph.facebook.com
(00:47:07) nss: Trusting CN=*.facebook.com,O="Facebook, Inc.",L=Menlo Park,ST=California,C=US
(00:47:07) certificate: Successfully verified certificate for graph.facebook.com
(00:47:07) http: Request 0x55fd2af84f50 performed successfully.

Other times the dialog doesn't go away on it's own and I have to press the Reconnect button in the dialog. When I do simply hit the Reconnect button, things go back to working again, until it times out again and this cycle restarts.

When this dialog presents itself but doesn't go away, is Pidgin constantly trying to reconnect in the background, and the amount of time the dialog is present is representing how long Pidgin is taking to get reconnected? If so, why does the dialog go away when I press Reconnect? Why isn't Pidgin doing that automatically as it seems to have above?

Have Facebook's services just gotten overloaded and are now spongy?

Has anyone else seen a marked increase in these connection timeouts?

dequis commented 7 years ago

Maybe it's just your internet connection.

The last log doesn't seem to have any errors, so can't do much about it.