[Pidgin] #8432: Google Talk will not connect.

Fri Feb 20 12:17:02 EST 2009

#8432: Google Talk will not connect.
---------------------+------------------------------------------------------
 Reporter:  jss.knx  |           Owner:  seanegan   
     Type:  defect   |          Status:  pending    
Milestone:           |       Component:  Google Talk
  Version:  2.5.4    |      Resolution:             
 Keywords:           |   Launchpad_bug:             
---------------------+------------------------------------------------------

Comment(by sagi):

 OK,
 If I enable just google talk account it takes about 5 minutes to initiate
 a connection.
 If I enable 1 or more other accounts I can't connect to google talk.

 Below is the latest debug log :

 {{{
 (18:50:45) autorecon: do_signon called
 (18:50:45) autorecon: calling purple_account_connect
 (18:50:45) account: Connecting to account XXXX at gmail.com/Home
 (18:50:45) connection: Connecting. gc = 0xb87a8538
 (18:50:45) dns: DNS query for 'talk.google.com' queued
 (18:50:45) autorecon: done calling purple_account_connect
 (18:50:45) dns: Wait for DNS child 22602 failed: No child processes
 (18:50:45) dns: Created new DNS child 22949, there are now 1 children.
 (18:50:45) dns: Successfully sent DNS request to child 22949
 (18:50:48) msn: C: NS 000: PNG
 (18:50:49) msn: S: NS 000: QNG 50
 (18:50:50) dns: Got response for 'talk.google.com'
 (18:50:50) dnsquery: IP resolved for talk.google.com
 (18:50:50) proxy: Attempting connection to 209.85.137.125
 (18:50:50) proxy: Connecting to talk.google.com:5222 with no proxy
 (18:50:50) proxy: Connection in progress
 (18:50:57) msn: C: NS 000: PNG
 (18:50:58) msn: S: NS 000: QNG 44
 (18:51:11) proxy: Connecting to talk.google.com:5222.
 (18:51:11) jabber: Sending: <?xml version='1.0' ?>
 (18:51:11) jabber: Sending: <stream:stream to='gmail.com'
 xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'
 version='1.0'>
 (18:51:12) jabber: Recv (138): <stream:stream from="gmail.com"
 id="BCF3195FF768BCFA" version="1.0"
 xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client">
 (18:51:14) jabber: Recv (210): <stream:features><starttls
 xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><mechanisms
 xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>X-GOOGLE-
 TOKEN</mechanism></mechanisms></stream:features>
 (18:51:14) jabber: Sending: <starttls xmlns='urn:ietf:params:xml:ns:xmpp-
 tls'/>
 (18:51:16) jabber: Recv (50): <proceed xmlns="urn:ietf:params:xml:ns:xmpp-
 tls"/>
 (18:51:16) gnutls: Starting handshake with talk.google.com
 (18:51:22) gnutls: Handshake complete
 (18:51:22) gnutls/x509: Key print:
 9f:f8:3b:da:2c:a3:12:55:24:d5:b9:d6:fc:49:69:8f:0a:91:d8:cd
 (18:51:22) gnutls: Peer provided 1 certs
 (18:51:22) gnutls: Lvl 0 SHA1 fingerprint:
 9f:f8:3b:da:2c:a3:12:55:24:d5:b9:d6:fc:49:69:8f:0a:91:d8:cd
 (18:51:22) gnutls: Serial: 07:3f:92
 (18:51:22) gnutls: Cert DN: C=US,ST=California,L=Mountain View,O=Google
 Inc.,CN=gmail.com
 (18:51:22) gnutls: Cert Issuer DN: C=US,O=Equifax,OU=Equifax Secure
 Certificate Authority
 (18:51:22) certificate/x509/tls_cached: Starting verify for
 talk.google.com
 (18:51:22) certificate/x509/tls_cached: Checking for cached cert...
 (18:51:22) certificate/x509/tls_cached: ...Found cached cert
 (18:51:22) gnutls: Attempting to load X.509 certificate from
 /home/sagi/.purple/certificates/x509/tls_peers/talk.google.com
 (18:51:22) certificate/x509/tls_cached: Peer cert matched cached
 (18:51:22) certificate: Successfully verified certificate for
 talk.google.com
 (18:51:22) jabber: XML parser error for JabberStream 0xb87b26b8: Domain 1,
 code 5, level 3: Extra content at the end of the document
 (18:51:22) jabber: Sending (ssl): <stream:stream to='gmail.com'
 xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'
 version='1.0'>
 (18:51:25) jabber: Recv (ssl)(138): <stream:stream from="gmail.com"
 id="EBD5B76D325B0D78" version="1.0"
 xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client">
 (18:51:25) jabber: Recv (ssl)(166): <stream:features><mechanisms
 xmlns="urn:ietf:params:xml:ns:xmpp-
 sasl"><mechanism>PLAIN</mechanism><mechanism>X-GOOGLE-
 TOKEN</mechanism></mechanisms></stream:features>
 (18:51:25) jabber: Sending (ssl): <auth xmlns='urn:ietf:params:xml:ns
 :xmpp-sasl' xmlns:ga='http://www.google.com/talk/protocol/auth' ga:client-
 uses-full-bind-result='true' mechanism='PLAIN'>password removed</auth>

 ...a lot of msn messages ...

 (18:53:25) msn: Switchboard:auth:{1424444554.1637648.8424250}
 socket:{65.54.171.20:1863}
 (18:53:25) dns: DNS query for '65.54.171.20' queued
 (18:53:25) dns: Wait for DNS child 22949 failed: No child processes
 (18:53:25) dns: Created new DNS child 23033, there are now 1 children.
 (18:53:25) dns: Successfully sent DNS request to child 23033
 (18:53:25) dns: Got response for '65.54.171.20'
 (18:53:25) dnsquery: IP resolved for 65.54.171.20
 (18:53:25) proxy: Attempting connection to 65.54.171.20
 (18:53:25) proxy: Connecting to 65.54.171.20:1863 with no proxy
 (18:53:25) proxy: Connection in progress
 (18:53:26) proxy: Connecting to 65.54.171.20:1863.

 ... more msn messages ... and then

 (19:00:39) gnutls: receive failed: A TLS packet with unexpected length was
 received.
 (19:00:39) jabber: Disconnected: Input/output error
 (19:00:39) gnutls: receive failed: The specified session has been
 invalidated for some reason.
 (19:00:39) jabber: Disconnected: Input/output error
 (19:00:39) account: Disconnecting account 0xb80bca00
 (19:00:39) connection: Disconnecting connection 0xb87a8538
 (19:00:39) jabber: XML parser error for JabberStream 0xb87b26b8: Domain 1,
 code 5, level 3: Extra content at the end of the document
 (19:00:39) connection: Destroying connection 0xb87a8538
 }}}

 and I'm not connected ... I used a different PC with a different account
 to verify that I'm not connected.

 Here's dig output :
 {{{
 dig +short SRV _xmpp-client._tcp.gmail.com
 20 0 5222 talk2.l.google.com.
 20 0 5222 talk3.l.google.com.
 20 0 5222 talk4.l.google.com.
 5 0 5222 talk.l.google.com.
 20 0 5222 talk1.l.google.com.
 }}}

 While writing this post, my google talk account got connected ... it took
 only 22 minutes to accomplish this task.

 I can post the log from 19:00 until 19:12 if it is needed.
 I opened pidgin on 18:50 and my google talk account got connected on
 19:12.

-- 
Ticket URL: <http://developer.pidgin.im/ticket/8432#comment:21>
Pidgin <http://pidgin.im>
Pidgin