Adium

Ticket #8643: gtalk failure to connect.txt

File gtalk failure to connect.txt, 16.6 kB (added by jas8522, 1 year ago)
Line 
1 19:44:37: adiumPurpleCoreDebugInit()
2 19:44:37: adiumPurpleCore: load_all_plugins()
3 19:44:37: (Libpurple: prefs) removing pref /plugins/prpl/jabber/avoid_sasl_for_plain_and_digest_md5_auth
4 19:44:37: (Libpurple: prefs) removing pref /plugins/prpl/jabber
5 19:44:37: (Libpurple: util) Reading file xmpp-caps.xml from directory /Users/jordan/Library/Application Support/Adium 2.0/Users/Default/libpurple
6 19:44:37: (Libpurple: prefs) /purple/status/scores/offline changed, scheduling save.
7 19:44:37: (Libpurple: prefs) /purple/status/scores/available changed, scheduling save.
8 19:44:37: (Libpurple: prefs) /purple/status/scores/invisible changed, scheduling save.
9 19:44:37: (Libpurple: prefs) /purple/status/scores/away changed, scheduling save.
10 19:44:37: (Libpurple: prefs) /purple/status/scores/extended_away changed, scheduling save.
11 19:44:37: (Libpurple: prefs) /purple/status/scores/idle changed, scheduling save.
12 19:44:37: (Libpurple: prefs) /purple/status/scores/offline_msg changed, scheduling save.
13 19:44:38: (Libpurple: util) Reading file accounts.xml from directory /Users/jordan/Library/Application Support/Adium 2.0/Users/Default/libpurple
14 19:44:38: (Libpurple: util) Reading file status.xml from directory /Users/jordan/Library/Application Support/Adium 2.0/Users/Default/libpurple
15 19:44:38: (Libpurple: util) File /Users/jordan/Library/Application Support/Adium 2.0/Users/Default/libpurple/status.xml does not exist (this is not necessarily an error)
16 19:44:38: (Libpurple: certificate) CertificateVerifier x509, singleuse requested but not found.
17 19:44:38: (Libpurple: certificate) CertificateVerifier singleuse registered
18 19:44:38: (Libpurple: certificate) CertificatePool x509, ca requested but not found.
19 19:44:38: (Libpurple: certificate) CertificateScheme x509 requested but not found.
20 19:44:38: (Libpurple: certificate/x509/ca) Lazy init failed because an X.509 Scheme is not yet registered. Maybe it will be better later.
21 19:44:38: (Libpurple: certificate/x509/ca) Init failed, probably because a dependency is not yet registered. It has been deferred to later.
22 19:44:38: (Libpurple: certificate) CertificatePool ca registered
23 19:44:38: (Libpurple: certificate) CertificatePool x509, tls_peers requested but not found.
24 19:44:38: (Libpurple: certificate) CertificatePool tls_peers registered
25 19:44:38: (Libpurple: certificate) CertificateVerifier x509, tls_cached requested but not found.
26 19:44:38: (Libpurple: certificate) CertificateVerifier tls_cached registered
27 19:44:38: (Libpurple: prefs) /purple/logging/format changed, scheduling save.
28 19:44:38: (Libpurple: prefs) /purple/logging/format changed, scheduling save.
29 19:44:38: (Libpurple: prefs) /purple/proxy/type changed, scheduling save.
30 19:44:38: (Libpurple: prefs) /purple/proxy/host changed, scheduling save.
31 19:44:38: (Libpurple: prefs) /purple/proxy/port changed, scheduling save.
32 19:44:38: (Libpurple: prefs) /purple/proxy/username changed, scheduling save.
33 19:44:38: (Libpurple: prefs) /purple/proxy/password changed, scheduling save.
34 19:44:38: (Libpurple: stun) using server
35 19:44:38: (Libpurple: nat-pmp) found a default gateway
36 19:44:38: (Libpurple: nat-pmp) Attempting to retrieve the public ip address for the NAT device at: 192.168.1.254
37 19:44:38: (Libpurple: nat-pmp)  Timeout: 0s 250000us
38 19:44:38: (Libpurple: nat-pmp) Response was not received from our gateway! Instead from: 216.233.255.191
39 19:44:38: adiumPurpleCoreUiInit
40 19:44:38: adiumPurpleCore: purple_blist_load()...
41 19:44:38: (Libpurple: util) Reading file blist.xml from directory /Users/jordan/Library/Application Support/Adium 2.0/Users/Default/libpurple
42 19:44:40: Created PurpleAccount 0x13d1b4e0 with UID jas8522@gmail.com and protocolPlugin prpl-jabber
43 19:44:40: <AIPurpleGTalkAccount:ef71fb0 16>:jas8522@gmail.com: Updating status for key: User Icon
44 19:44:40: Original image of size 218.000000 218.000000
45 19:44:40: <AIPurpleGTalkAccount:ef71fb0 16>:jas8522@gmail.com: Scaled image to size {96, 96}
46 19:44:40: Image 18178 is larger than 8191!
47 19:44:40: (Libpurple: buddyicon) Wrote cache file: /Users/jordan/Library/Caches/Adium/Default/fe5e917cbb90d710a6e1d5f7a66fbe705ba5fc3d.png
48 19:44:40: Adium: Connect: jas8522@gmail.com initiating connection using status state <AIStatus: ef323f0 [Away]> ((null)).
49 19:44:40: Warning; could not determine value of 0 for key priority, statusID away
50 19:44:40: Setting status on 13d1b4e0 (jas8522@gmail.com/Adium): ID away, isActive 1, attributes {
51     buzz = 1;
52     message = Away;
53     priority = 0;
54 }
55 19:44:40: (Libpurple: account) Connecting to account jas8522@gmail.com/Adium
56 19:44:40: (Libpurple: connection) Connecting. gc = 0x13dd95c0
57 19:44:40: Connecting: gc=0x13dd95c0 (Connecting) 1 / 5
58 19:44:40: (Libpurple: dns) DNS query for 'talk.google.com' queued
59 19:44:40: ************ jas8522@gmail.com --step-- 1
60 19:44:40: Called write with no write_tag (read_tag 5 write_tag 0) for 11af6700
61 19:44:40: Performing DNS resolve: talk.google.com:5222
62 19:44:40: Called write with no write_tag (read_tag 8 write_tag 0) for 11af4b90
63 19:44:40: DNS resolve complete for talk.google.com:5222
64 19:44:40: (Libpurple: dnsquery) IP resolved for talk.google.com
65 19:44:40: (Libpurple: proxy) Attempting connection to 72.14.253.125
66 19:44:40: (Libpurple: proxy) Connecting to talk.google.com:5222 with no proxy
67 19:44:40: (Libpurple: proxy) Connection in progress
68 19:44:40: (Libpurple: proxy) Connected to talk.google.com:5222.
69 19:44:40: (Libpurple: jabber) Sending: <?xml version='1.0' ?>
70 19:44:40: Connecting: gc=0x13dd95c0 (Initializing Stream) 2 / 5
71 19:44:40: (Libpurple: jabber) Sending: <stream:stream to='gmail.com' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
72 19:44:40: ************ jas8522@gmail.com --step-- 2
73 19:44:41: (Libpurple: jabber) Recv (176): <?xml version="1.0" encoding="UTF-8"?><stream:stream from="gmail.com" id="AFE47220FBCB954E" version="1.0" xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client">
74 19:44:41: Connecting: gc=0x13dd95c0 (Authenticating) 3 / 5
75 19:44:41: ************ jas8522@gmail.com --step-- 3
76 19:44:41: (Libpurple: 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>
77 19:44:41: (Libpurple: jabber) Sending: <starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
78 19:44:41: (Libpurple: jabber) Recv (50): <proceed xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>
79 19:44:41: (Libpurple: cdsa) Connecting
80 19:44:41: Called write with no write_tag (read_tag 11 write_tag 0) for 11aeb180
81 19:44:41: (Libpurple: cdsa) Connecting
82 19:44:41: (Libpurple: cdsa) Connecting
83 19:44:41: (Libpurple: cdsa) SSL_connect: verifying certificate
84 19:44:41: (Libpurple: cdsa) SSL_connect complete
85 19:44:41: Connecting: gc=0x13dd95c0 (Initializing Stream) 5 / 9
86 19:44:41: (Libpurple: jabber) Sending (ssl): <stream:stream to='gmail.com' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
87 19:44:41: (Libpurple: cdsa) SSLWrite(0xa2a200, 0x11af4b90 114)
88 19:44:41: Connecting: gc=0x13dd95c0 (Initializing SSL/TLS) 6 / 9
89 19:44:41: ************ jas8522@gmail.com --step-- 5
90 19:44:41: ************ jas8522@gmail.com --step-- 6
91 19:44:41: Called write with no write_tag (read_tag 13 write_tag 0) for 11af4b90
92 19:44:41: (Libpurple: jabber) Recv (ssl)(176): <?xml version="1.0" encoding="UTF-8"?><stream:stream from="gmail.com" id="B67D7B5436A5CB20" version="1.0" xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client">
93 19:44:41: Connecting: gc=0x13dd95c0 (Authenticating) 7 / 9
94 19:44:41: ************ jas8522@gmail.com --step-- 7
95 19:44:41: (Libpurple: 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>
96 19:44:41: (Libpurple: jabber) using sasl
97 19:44:41: (Libpurple: sasl) Mechs found: PLAIN X-GOOGLE-TOKEN
98 19:44:41: (Libpurple: jabber) jabber_sasl_cb_simple() got id of 16385, which is SASL_CB_USER
99 19:44:41: (Libpurple: jabber) Sending (ssl): <auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>amFzODUyMgBqYXM4NTIyAEJsYWNrSDBsZQ==</auth>
100 19:44:41: (Libpurple: cdsa) SSLWrite(0xa2a200, 0x11af4220 108)
101 19:44:41: (Libpurple: jabber) Recv (ssl)(78): <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><invalid-authzid/></failure>
102 19:44:41: Connection Disconnected: gc=13dd95c0 (Invalid authzid)
103 19:44:41: <AIPurpleGTalkAccount:ef71fb0 16>:jas8522@gmail.com accountConnectionReportDisconnect: Invalid authzid
104 19:44:41: (Libpurple: jabber) Recv (ssl)(16): </stream:stream>
105 19:44:41: (Libpurple: account) Disconnecting account 0x13d1b4e0
106 19:44:41: (Libpurple: connection) Disconnecting connection 0x13dd95c0
107 19:44:41: (Libpurple: cdsa) Closing PurpleSslConnection 0x140354e0
108 19:44:41: (Libpurple: cdsa) SSLDisposeContext(0xa2a200)
109 19:44:41: Disconnected: gc=13dd95c0
110 19:44:41: <AIPurpleGTalkAccount:ef71fb0 16>:jas8522@gmail.com: Telling the core we disconnected
111 19:44:41: <AIPurpleGTalkAccount:ef71fb0 16>:jas8522@gmail.com: Disconnected ("Invalid authzid"): Automatically reconnecting in 5.000000 seconds (0 attempts performed)
112 19:44:41: (Libpurple: connection) Destroying connection 0x13dd95c0
113 19:44:43: (Libpurple: util) Writing file prefs.xml to directory /Users/jordan/Library/Application Support/Adium 2.0/Users/Default/libpurple
114 19:44:43: (Libpurple: util) Writing file /Users/jordan/Library/Application Support/Adium 2.0/Users/Default/libpurple/prefs.xml
115 19:44:43: (Libpurple: util) Writing file accounts.xml to directory /Users/jordan/Library/Application Support/Adium 2.0/Users/Default/libpurple
116 19:44:43: (Libpurple: util) Writing file /Users/jordan/Library/Application Support/Adium 2.0/Users/Default/libpurple/accounts.xml
117 19:44:43: (Libpurple: util) Writing file blist.xml to directory /Users/jordan/Library/Application Support/Adium 2.0/Users/Default/libpurple
118 19:44:43: (Libpurple: util) Writing file /Users/jordan/Library/Application Support/Adium 2.0/Users/Default/libpurple/blist.xml
119 19:44:48: [AIChat: 14678920 initForAccount]
120 19:44:48: Updating security details for <AIPreviewChat: 0x14678920>:<new>
121 19:44:48: <AIPurpleGTalkAccount:ef71fb0 16>:jas8522@gmail.com: Updating status for key: User Icon
122 19:44:48: (Libpurple: buddyicon) Deleted cache file: /Users/jordan/Library/Caches/Adium/Default/fe5e917cbb90d710a6e1d5f7a66fbe705ba5fc3d.png
123 19:44:48: Original image of size 218.000000 218.000000
124 19:44:48: <AIPurpleGTalkAccount:ef71fb0 16>:jas8522@gmail.com: Scaled image to size {96, 96}
125 19:44:48: Image 18178 is larger than 8191!
126 19:44:48: (Libpurple: buddyicon) Wrote cache file: /Users/jordan/Library/Caches/Adium/Default/fe5e917cbb90d710a6e1d5f7a66fbe705ba5fc3d.png
127 19:44:48: Adium: Connect: jas8522@gmail.com initiating connection using status state <AIStatus: ef323f0 [Away]> ((null)).
128 19:44:48: Warning; could not determine value of 0 for key priority, statusID away
129 19:44:48: Setting status on 13d1b4e0 (jas8522@gmail.com/Adium): ID away, isActive 1, attributes {
130     buzz = 1;
131     message = Away;
132     priority = 0;
133 }
134 19:44:48: (Libpurple: account) Connecting to account jas8522@gmail.com/Adium
135 19:44:48: (Libpurple: connection) Connecting. gc = 0x144d87a0
136 19:44:48: Connecting: gc=0x144d87a0 (Connecting) 1 / 5
137 19:44:48: (Libpurple: dns) DNS query for 'talk.google.com' queued
138 19:44:48: <AIPurpleGTalkAccount:ef71fb0 16>:jas8522@gmail.com: Updating status for key: Online
139 19:44:48: ************ jas8522@gmail.com --step-- 1
140 19:44:49: Performing DNS resolve: talk.google.com:5222
141 19:44:49: DNS resolve complete for talk.google.com:5222
142 19:44:49: (Libpurple: dnsquery) IP resolved for talk.google.com
143 19:44:49: (Libpurple: proxy) Attempting connection to 72.14.253.125
144 19:44:49: (Libpurple: proxy) Connecting to talk.google.com:5222 with no proxy
145 19:44:49: (Libpurple: proxy) Connection in progress
146 19:44:49: (Libpurple: proxy) Connected to talk.google.com:5222.
147 19:44:49: (Libpurple: jabber) Sending: <?xml version='1.0' ?>
148 19:44:49: Connecting: gc=0x144d87a0 (Initializing Stream) 2 / 5
149 19:44:49: (Libpurple: jabber) Sending: <stream:stream to='gmail.com' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
150 19:44:49: ************ jas8522@gmail.com --step-- 2
151 19:44:49: (Libpurple: jabber) Recv (176): <?xml version="1.0" encoding="UTF-8"?><stream:stream from="gmail.com" id="043722A389BD2BE4" version="1.0" xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client">
152 19:44:49: Connecting: gc=0x144d87a0 (Authenticating) 3 / 5
153 19:44:49: ************ jas8522@gmail.com --step-- 3
154 19:44:49: (Libpurple: 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>
155 19:44:49: (Libpurple: jabber) Sending: <starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
156 19:44:49: (Libpurple: jabber) Recv (50): <proceed xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>
157 19:44:49: (Libpurple: cdsa) Connecting
158 19:44:49: Called write with no write_tag (read_tag 20 write_tag 0) for 14005a40
159 19:44:49: (Libpurple: cdsa) Connecting
160 19:44:50: (Libpurple: cdsa) Connecting
161 19:44:50: (Libpurple: cdsa) SSL_connect: verifying certificate
162 19:44:50: (Libpurple: cdsa) SSL_connect complete
163 19:44:50: Connecting: gc=0x144d87a0 (Initializing Stream) 5 / 9
164 19:44:50: (Libpurple: jabber) Sending (ssl): <stream:stream to='gmail.com' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
165 19:44:50: (Libpurple: cdsa) SSLWrite(0xbaac00, 0x13ed6470 114)
166 19:44:50: Connecting: gc=0x144d87a0 (Initializing SSL/TLS) 6 / 9
167 19:44:50: ************ jas8522@gmail.com --step-- 5
168 19:44:50: ************ jas8522@gmail.com --step-- 6
169 19:44:50: Called write with no write_tag (read_tag 21 write_tag 0) for 13ed6470
170 19:44:50: (Libpurple: jabber) Recv (ssl)(176): <?xml version="1.0" encoding="UTF-8"?><stream:stream from="gmail.com" id="94779FC7DEC66521" version="1.0" xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client">
171 19:44:50: Connecting: gc=0x144d87a0 (Authenticating) 7 / 9
172 19:44:50: ************ jas8522@gmail.com --step-- 7
173 19:44:50: (Libpurple: 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>
174 19:44:50: (Libpurple: jabber) using sasl
175 19:44:50: (Libpurple: sasl) Mechs found: PLAIN X-GOOGLE-TOKEN
176 19:44:50: (Libpurple: jabber) jabber_sasl_cb_simple() got id of 16385, which is SASL_CB_USER
177 19:44:50: (Libpurple: jabber) Sending (ssl): <auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>amFzODUyMgBqYXM4NTIyAEJsYWNrSDBsZQ==</auth>
178 19:44:50: (Libpurple: cdsa) SSLWrite(0xbaac00, 0x11af0370 108)
179 19:44:50: (Libpurple: jabber) Recv (ssl)(78): <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><invalid-authzid/></failure>
180 19:44:50: Connection Disconnected: gc=144d87a0 (Invalid authzid)
181 19:44:50: <AIPurpleGTalkAccount:ef71fb0 16>:jas8522@gmail.com accountConnectionReportDisconnect: Invalid authzid
182 19:44:50: (Libpurple: jabber) Recv (ssl)(16): </stream:stream>
183 19:44:50: (Libpurple: cdsa) receive failed (-9805): Unknown error: 0
184 19:44:50: (Libpurple: cdsa) receive failed (-9805): Unknown error: 0
185 19:44:50: (Libpurple: cdsa) receive failed (-9805): Unknown error: 0
186 19:44:50: (Libpurple: cdsa) receive failed (-9805): Unknown error: 0
187 19:44:50: (Libpurple: cdsa) receive failed (-9805): Unknown error: 0
188 19:44:50: (Libpurple: cdsa) receive failed (-9805): Unknown error: 0
189 19:44:50: (Libpurple: cdsa) receive failed (-9805): Unknown error: 0
190 19:44:50: (Libpurple: cdsa) receive failed (-9805): Unknown error: 0
191 19:44:50: (Libpurple: cdsa) receive failed (-9805): Unknown error: 0
192 19:44:50: (Libpurple: account) Disconnecting account 0x13d1b4e0
193 19:44:50: (Libpurple: connection) Disconnecting connection 0x144d87a0
194 19:44:50: (Libpurple: cdsa) Closing PurpleSslConnection 0x146befa0
195 19:44:50: (Libpurple: cdsa) SSLDisposeContext(0xbaac00)
196 19:44:50: Disconnected: gc=144d87a0
197 19:44:50: <AIPurpleGTalkAccount:ef71fb0 16>:jas8522@gmail.com: Telling the core we disconnected
198 19:44:50: <AIPurpleGTalkAccount:ef71fb0 16>:jas8522@gmail.com: Disconnected ("Invalid authzid"): Automatically reconnecting in 5.000000 seconds (1 attempts performed)
199 19:44:50: (Libpurple: connection) Destroying connection 0x144d87a0
200 19:44:53: (Libpurple: util) Writing file accounts.xml to directory /Users/jordan/Library/Application Support/Adium 2.0/Users/Default/libpurple
201 19:44:53: (Libpurple: util) Writing file /Users/jordan/Library/Application Support/Adium 2.0/Users/Default/libpurple/accounts.xml
202 19:44:53: (Libpurple: util) Writing file blist.xml to directory /Users/jordan/Library/Application Support/Adium 2.0/Users/Default/libpurple
203 19:44:53: (Libpurple: util) Writing file /Users/jordan/Library/Application Support/Adium 2.0/Users/Default/libpurple/blist.xml