Adium

Ticket #9492: success-debug-log

File success-debug-log, 28.6 kB (added by raeburn, 8 months ago)

log from 1.2.3-debug, successful login, up through start of contact list retrieval

Line 
1 22:14:14: adiumPurpleCoreDebugInit()
2 22:14:14: adiumPurpleCore: load_all_plugins()
3 22:14:14: (Libpurple: prefs) removing pref /plugins/prpl/jabber/avoid_sasl_for_plain_auth
4 22:14:14: (Libpurple: prefs) removing pref /plugins/prpl/jabber
5 22:14:14: (Libpurple: util) Reading file xmpp-caps.xml from directory /Users/raeburn/Library/Application Support/Adium 2.0/Users/Default/libpurple
6 22:14:14: (Libpurple: prefs) /purple/status/scores/offline changed, scheduling save.
7 22:14:14: (Libpurple: prefs) /purple/status/scores/available changed, scheduling save.
8 22:14:14: (Libpurple: prefs) /purple/status/scores/invisible changed, scheduling save.
9 22:14:14: (Libpurple: prefs) /purple/status/scores/away changed, scheduling save.
10 22:14:14: (Libpurple: prefs) /purple/status/scores/extended_away changed, scheduling save.
11 22:14:14: (Libpurple: prefs) /purple/status/scores/idle changed, scheduling save.
12 22:14:14: (Libpurple: prefs) /purple/status/scores/offline_msg changed, scheduling save.
13 22:14:14: (Libpurple: util) Reading file accounts.xml from directory /Users/raeburn/Library/Application Support/Adium 2.0/Users/Default/libpurple
14 22:14:14: (Libpurple: util) Reading file status.xml from directory /Users/raeburn/Library/Application Support/Adium 2.0/Users/Default/libpurple
15 22:14:14: (Libpurple: util) File /Users/raeburn/Library/Application Support/Adium 2.0/Users/Default/libpurple/status.xml does not exist (this is not necessarily an error)
16 22:14:14: (Libpurple: certificate) CertificateVerifier x509, singleuse requested but not found.
17 22:14:14: (Libpurple: certificate) CertificateVerifier singleuse registered
18 22:14:14: (Libpurple: certificate) CertificatePool x509, ca requested but not found.
19 22:14:14: (Libpurple: certificate) CertificateScheme x509 requested but not found.
20 22:14:14: (Libpurple: certificate/x509/ca) Lazy init failed because an X.509 Scheme is not yet registered. Maybe it will be better later.
21 22:14:14: (Libpurple: certificate/x509/ca) Init failed, probably because a dependency is not yet registered. It has been deferred to later.
22 22:14:14: (Libpurple: certificate) CertificatePool ca registered
23 22:14:14: (Libpurple: certificate) CertificatePool x509, tls_peers requested but not found.
24 22:14:14: (Libpurple: certificate) CertificatePool tls_peers registered
25 22:14:14: (Libpurple: certificate) CertificateVerifier x509, tls_cached requested but not found.
26 22:14:14: (Libpurple: certificate) CertificateVerifier tls_cached registered
27 22:14:14: (Libpurple: prefs) /purple/logging/format changed, scheduling save.
28 22:14:14: (Libpurple: prefs) /purple/logging/format changed, scheduling save.
29 22:14:14: (Libpurple: prefs) /purple/proxy/type changed, scheduling save.
30 22:14:14: (Libpurple: prefs) /purple/proxy/host changed, scheduling save.
31 22:14:14: (Libpurple: prefs) /purple/proxy/port changed, scheduling save.
32 22:14:14: (Libpurple: prefs) /purple/proxy/username changed, scheduling save.
33 22:14:14: (Libpurple: prefs) /purple/proxy/password changed, scheduling save.
34 22:14:14: (Libpurple: stun) using server
35 22:14:14: (Libpurple: nat-pmp) found a default gateway
36 22:14:14: (Libpurple: nat-pmp) Attempting to retrieve the public ip address for the NAT device at: 69.25.196.97
37 22:14:14: (Libpurple: nat-pmp)  Timeout: 0s 250000us
38 22:14:14: (Libpurple: nat-pmp) Response was not received from our gateway! Instead from: 216.236.255.191
39 22:14:14: adiumPurpleCoreUiInit
40 22:14:14: adiumPurpleCore: purple_blist_load()...
41 22:14:14: (Libpurple: util) Reading file blist.xml from directory /Users/raeburn/Library/Application Support/Adium 2.0/Users/Default/libpurple
42 22:14:14: Created PurpleAccount 0x8f6e670 with UID raeburn@mit.edu and protocolPlugin prpl-jabber
43 22:14:14: <ESPurpleJabberAccount:7b1ed10 11>:raeburn@mit.edu: Updating status for key: User Icon
44 22:14:14: (Libpurple: prefs) /plugins/prpl/jabber/avoid_sasl_for_plain_auth changed, scheduling save.
45 22:14:14: Adium: Connect: raeburn@mit.edu initiating connection using status state <AIStatus: 7b08bd0 [Available]> ((null)).
46 22:14:14: Warning; could not determine value of 0 for key priority, statusID available
47 22:14:14: Setting status on 8f6e670 (raeburn@mit.edu/Adium78435): ID available, isActive 1, attributes {buzz = 1; priority = 0; }
48 22:14:14: (Libpurple: account) Connecting to account raeburn@mit.edu/Adium78435
49 22:14:14: (Libpurple: connection) Connecting. gc = 0x8f79380
50 22:14:14: Connecting: gc=0x8f79380 (Connecting) 1 / 5
51 22:14:14: (Libpurple: dns) DNS query for 'jabber.mit.edu' queued
52 22:14:14: ************ raeburn@mit.edu --step-- 1
53 22:14:14: (Libpurple: util) requested to fetch (http://192.168.0.1:5678/igd.xml), full=1, user_agent=((null)), http11=1
54 22:14:14: (Libpurple: dns) DNS query for '192.168.0.1' queued
55 22:14:14: Performing DNS resolve: jabber.mit.edu:5222
56 22:14:14: Performing DNS resolve: 192.168.0.1:5678
57 22:14:14: DNS resolve complete for 192.168.0.1:5678
58 22:14:14: (Libpurple: dnsquery) IP resolved for 192.168.0.1
59 22:14:14: (Libpurple: proxy) Attempting connection to 192.168.0.1
60 22:14:14: (Libpurple: proxy) Connecting to 192.168.0.1:5678 with no proxy
61 22:14:14: (Libpurple: proxy) Connection in progress
62 22:14:14: (Libpurple: proxy) Connected to 192.168.0.1:5678.
63 22:14:14: (Libpurple: util) Request: 'GET /igd.xml HTTP/1.1
64
65 Connection: close
66
67 Host: 192.168.0.1:5678
68
69
70
71 '
72 22:14:14: Called write with no write_tag (read_tag 10 write_tag 0) for 8f51130
73 22:14:15: DNS resolve complete for jabber.mit.edu:5222
74 22:14:15: (Libpurple: dnsquery) IP resolved for jabber.mit.edu
75 22:14:15: (Libpurple: proxy) Attempting connection to 18.7.21.91
76 22:14:15: (Libpurple: proxy) Connecting to jabber.mit.edu:5222 with no proxy
77 22:14:15: (Libpurple: proxy) Connection in progress
78 22:14:15: (Libpurple: proxy) Connected to jabber.mit.edu:5222.
79 22:14:15: (Libpurple: jabber) Sending: <?xml version='1.0' ?>
80 22:14:15: Connecting: gc=0x8f79380 (Initializing Stream) 2 / 5
81 22:14:15: (Libpurple: jabber) Sending: <stream:stream to='mit.edu' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
82 22:14:15: ************ raeburn@mit.edu --step-- 2
83 22:14:15: (Libpurple: jabber) Recv (180): <?xml version='1.0' encoding='UTF-8'?><stream:stream xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client" from="mit.edu" id="18f1961b" xml:lang="en" version="1.0">
84 22:14:15: Connecting: gc=0x8f79380 (Authenticating) 3 / 5
85 22:14:15: ************ raeburn@mit.edu --step-- 3
86 22:14:15: (Libpurple: jabber) Recv (230): <stream:features><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>GSSAPI</mechanism><mechanism>PLAIN</mechanism></mechanisms></stream:features>
87 22:14:15: (Libpurple: jabber) Sending: <starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
88 22:14:15: (Libpurple: jabber) Recv (50): <proceed xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>
89 22:14:15: (Libpurple: cdsa) Connecting
90 22:14:15: (Libpurple: cdsa) Connecting
91 22:14:15: Called write with no write_tag (read_tag 13 write_tag 0) for 8f5ea70
92 22:14:15: (Libpurple: cdsa) Connecting
93 22:14:15: (Libpurple: cdsa) Connecting
94 22:14:15: (Libpurple: cdsa) SSL_connect: verifying certificate
95 22:14:15: (Libpurple: cdsa) SSL_connect complete
96 22:14:15: Connecting: gc=0x8f79380 (Initializing Stream) 5 / 9
97 22:14:15: (Libpurple: jabber) XML parser error for JabberStream 0x0: Domain 1, code 5, level 3: Extra content at the end of the document
98
99 22:14:15: (Libpurple: jabber) Sending (ssl): <stream:stream to='mit.edu' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
100 22:14:15: Connecting: gc=0x8f79380 (Initializing SSL/TLS) 6 / 9
101 22:14:15: Called write with no write_tag (read_tag 14 write_tag 0) for 8f7b9f0
102 22:14:15: (Libpurple: jabber) Recv (ssl)(480): <?xml version='1.0' encoding='UTF-8'?><stream:stream xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client" from="mit.edu" id="18f1961b" xml:lang="en" version="1.0"><stream:features><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>GSSAPI</mechanism><mechanism>PLAIN</mechanism></mechanisms><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><auth xmlns="http://jabber.org/features/iq-auth"/></stream:features>
103 22:14:15: Connecting: gc=0x8f79380 (Authenticating) 7 / 9
104 22:14:15: (Libpurple: sasl) Mechs found: GSSAPI PLAIN
105 22:14:15: (Libpurple: sasl) GSSAPI Error: Miscellaneous failure (Server not found in Kerberos database)
106 22:14:15: (Libpurple: sasl) sasl_state is -1, failing the mech and trying again
107 22:14:15: (Libpurple: sasl) Mechs found:  PLAIN
108 22:14:15: (Libpurple: 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'>AHJhZWJ1cm4A</auth>
109 22:14:15: ************ raeburn@mit.edu --step-- 5
110 22:14:15: ************ raeburn@mit.edu --step-- 6
111 22:14:15: ************ raeburn@mit.edu --step-- 7
112 22:14:15: (Libpurple: cdsa) receive failed (-9805): Unknown error: 0
113 22:14:15: Connection Disconnected: gc=8f79380 (Read Error)
114 22:14:15: <ESPurpleJabberAccount:7b1ed10 11>:raeburn@mit.edu accountConnectionReportDisconnect: Read Error
115 22:14:15: (Libpurple: cdsa) receive failed (-9805): Unknown error: 0
116 22:14:15: (Libpurple: cdsa) receive failed (-9805): Unknown error: 0
117 22:14:15: (Libpurple: cdsa) receive failed (-9805): Unknown error: 0
118 22:14:15: (Libpurple: cdsa) receive failed (-9805): Unknown error: 0
119 22:14:15: (Libpurple: account) Disconnecting account 0x8f6e670
120 22:14:15: (Libpurple: connection) Disconnecting connection 0x8f79380
121 22:14:15: (Libpurple: jabber) XML parser error for JabberStream 0x0: Domain 1, code 5, level 3: Extra content at the end of the document
122
123 22:14:15: Disconnected: gc=8f79380
124 22:14:15: <ESPurpleJabberAccount:7b1ed10 11>:raeburn@mit.edu: Telling the core we disconnected
125 22:14:15: <ESPurpleJabberAccount:7b1ed10 11>:raeburn@mit.edu: Disconnected ("Read Error"): Automatically reconnecting immediately
126 22:14:15: <ESPurpleJabberAccount:7b1ed10 11>:raeburn@mit.edu: Updating status for key: Online
127 22:14:15: (Libpurple: connection) Destroying connection 0x8f79380
128 22:14:15: (Libpurple: util) Response headers: 'HTTP/1.1 200 OK
129
130 Server: Embedded HTTP Server 1.23
131
132 Content-Type: text/xml
133
134 Content-Length: 2881
135
136 Connection: close
137
138
139
140 '
141 22:14:15: (Libpurple: util) parsed 2881
142 22:14:15: (Libpurple: util) requested to fetch (http://192.168.0.1:5678/WANIPConnection), full=0, user_agent=((null)), http11=1
143 22:14:15: (Libpurple: dns) DNS query for '192.168.0.1' queued
144 22:14:15: (Libpurple: dns) DNS query for '192.168.0.1' queued
145 22:14:16: Performing DNS resolve: 192.168.0.1:5678
146 22:14:16: DNS resolve complete for 192.168.0.1:5678
147 22:14:16: (Libpurple: dnsquery) IP resolved for 192.168.0.1
148 22:14:16: (Libpurple: proxy) Attempting connection to 192.168.0.1
149 22:14:16: (Libpurple: proxy) Connecting to 192.168.0.1:5678 with no proxy
150 22:14:16: (Libpurple: proxy) Connection in progress
151 22:14:16: Performing DNS resolve: 192.168.0.1:5678
152 22:14:16: DNS resolve complete for 192.168.0.1:5678
153 22:14:16: (Libpurple: dnsquery) IP resolved for 192.168.0.1
154 22:14:16: (Libpurple: proxy) Attempting connection to 192.168.0.1
155 22:14:16: (Libpurple: proxy) Connecting to 192.168.0.1:5678 with no proxy
156 22:14:16: (Libpurple: proxy) Connection in progress
157 22:14:16: (Libpurple: proxy) Connected to 192.168.0.1:5678.
158 22:14:16: (Libpurple: util) Request: 'POST /WANIPConnection HTTP/1.1
159
160 HOST: 192.168.0.1:5678
161
162 SOAPACTION: "urn:schemas-upnp-org:service:WANIPConnection:1#GetExternalIPAddress"
163
164 CONTENT-TYPE: text/xml ; charset="utf-8"
165
166 CONTENT-LENGTH: 310
167
168
169
170 <?xml version="1.0" encoding="utf-8"?>
171
172 <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
173
174 <s:Body>
175
176 <u:GetExternalIPAddress xmlns:u="urn:schemas-upnp-org:service:WANIPConnection:1">
177
178 </u:GetExternalIPAddress>
179
180 </s:Body>
181
182 </s:Envelope>'
183 22:14:16: Called write with no write_tag (read_tag 21 write_tag 0) for a54c4f0
184 22:14:16: (Libpurple: proxy) Connected to 192.168.0.1:5678.
185 22:14:16: (Libpurple: upnp) Local IP: 69.25.196.101
186 22:14:16: (Libpurple: util) Response headers: 'HTTP/1.1 200 OK
187
188 CONNECTION: CLOSE
189
190 CONTENT-LENGTH:457
191
192 CONTENT-TYPE:text/xml
193
194 DATE: Thu, 18 Apr 2002 20:30:10 GMT
195
196 SERVER: Embedded UPnP/1.0
197
198
199
200 '
201 22:14:16: (Libpurple: upnp) NAT Returned IP: 0.0.0.0
202 22:14:19: (Libpurple: util) Writing file prefs.xml to directory /Users/raeburn/Library/Application Support/Adium 2.0/Users/Default/libpurple
203 22:14:19: (Libpurple: util) Writing file /Users/raeburn/Library/Application Support/Adium 2.0/Users/Default/libpurple/prefs.xml
204 22:14:19: (Libpurple: util) Writing file accounts.xml to directory /Users/raeburn/Library/Application Support/Adium 2.0/Users/Default/libpurple
205 22:14:19: (Libpurple: util) Writing file /Users/raeburn/Library/Application Support/Adium 2.0/Users/Default/libpurple/accounts.xml
206 22:14:19: (Libpurple: util) Writing file blist.xml to directory /Users/raeburn/Library/Application Support/Adium 2.0/Users/Default/libpurple
207 22:14:19: (Libpurple: util) Writing file /Users/raeburn/Library/Application Support/Adium 2.0/Users/Default/libpurple/blist.xml
208 22:14:25: <ESPurpleJabberAccount:7b1ed10 11>:raeburn@mit.edu: Updating status for key: User Icon
209 22:14:25: Adium: Connect: raeburn@mit.edu initiating connection using status state <AIStatus: 7b08bd0 [Available]> ((null)).
210 22:14:25: Warning; could not determine value of 0 for key priority, statusID available
211 22:14:25: Setting status on 8f6e670 (raeburn@mit.edu/Adium78435): ID available, isActive 1, attributes {buzz = 1; priority = 0; }
212 22:14:25: (Libpurple: account) Connecting to account raeburn@mit.edu/Adium78435
213 22:14:25: (Libpurple: connection) Connecting. gc = 0x8fa2830
214 22:14:25: Connecting: gc=0x8fa2830 (Connecting) 1 / 5
215 22:14:25: (Libpurple: dns) DNS query for 'jabber.mit.edu' queued
216 22:14:25: ************ raeburn@mit.edu --step-- 1
217 22:14:25: Performing DNS resolve: jabber.mit.edu:5222
218 22:14:25: DNS resolve complete for jabber.mit.edu:5222
219 22:14:25: (Libpurple: dnsquery) IP resolved for jabber.mit.edu
220 22:14:25: (Libpurple: proxy) Attempting connection to 18.7.21.91
221 22:14:25: (Libpurple: proxy) Connecting to jabber.mit.edu:5222 with no proxy
222 22:14:25: (Libpurple: proxy) Connection in progress
223 22:14:25: (Libpurple: proxy) Connected to jabber.mit.edu:5222.
224 22:14:25: (Libpurple: jabber) Sending: <?xml version='1.0' ?>
225 22:14:25: Connecting: gc=0x8fa2830 (Initializing Stream) 2 / 5
226 22:14:25: (Libpurple: jabber) Sending: <stream:stream to='mit.edu' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
227 22:14:25: ************ raeburn@mit.edu --step-- 2
228 22:14:25: (Libpurple: jabber) Recv (180): <?xml version='1.0' encoding='UTF-8'?><stream:stream xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client" from="mit.edu" id="22961524" xml:lang="en" version="1.0">
229 22:14:25: Connecting: gc=0x8fa2830 (Authenticating) 3 / 5
230 22:14:25: ************ raeburn@mit.edu --step-- 3
231 22:14:25: (Libpurple: jabber) Recv (230): <stream:features><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>GSSAPI</mechanism><mechanism>PLAIN</mechanism></mechanisms></stream:features>
232 22:14:25: (Libpurple: jabber) Sending: <starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
233 22:14:25: (Libpurple: jabber) Recv (50): <proceed xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>
234 22:14:25: (Libpurple: cdsa) Connecting
235 22:14:25: (Libpurple: cdsa) Connecting
236 22:14:25: Called write with no write_tag (read_tag 27 write_tag 0) for 8f33120
237 22:14:25: (Libpurple: cdsa) Connecting
238 22:14:25: (Libpurple: cdsa) Connecting
239 22:14:25: (Libpurple: cdsa) SSL_connect: verifying certificate
240 22:14:25: (Libpurple: cdsa) SSL_connect complete
241 22:14:25: Connecting: gc=0x8fa2830 (Initializing Stream) 5 / 9
242 22:14:25: (Libpurple: jabber) XML parser error for JabberStream 0x0: Domain 1, code 5, level 3: Extra content at the end of the document
243
244 22:14:25: (Libpurple: jabber) Sending (ssl): <stream:stream to='mit.edu' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
245 22:14:25: Connecting: gc=0x8fa2830 (Initializing SSL/TLS) 6 / 9
246 22:14:25: ************ raeburn@mit.edu --step-- 5
247 22:14:25: ************ raeburn@mit.edu --step-- 6
248 22:14:25: (Libpurple: jabber) Recv (ssl)(480): <?xml version='1.0' encoding='UTF-8'?><stream:stream xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client" from="mit.edu" id="22961524" xml:lang="en" version="1.0"><stream:features><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>GSSAPI</mechanism><mechanism>PLAIN</mechanism></mechanisms><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><auth xmlns="http://jabber.org/features/iq-auth"/></stream:features>
249 22:14:25: Connecting: gc=0x8fa2830 (Authenticating) 7 / 9
250 22:14:25: (Libpurple: sasl) Mechs found: GSSAPI PLAIN
251 22:14:25: (Libpurple: sasl) GSSAPI Error: Miscellaneous failure (Server not found in Kerberos database)
252 22:14:25: (Libpurple: sasl) sasl_state is -1, failing the mech and trying again
253 22:14:25: (Libpurple: sasl) Mechs found:  PLAIN
254 22:14:25: (Libpurple: 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'>AHJhZWJ1cm4Ad29uNDJLbw==</auth>
255 22:14:25: Called write with no write_tag (read_tag 28 write_tag 0) for 8fa2ad0
256 22:14:25: ************ raeburn@mit.edu --step-- 7
257 22:14:26: (Libpurple: jabber) Recv (ssl)(51): <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/>
258 22:14:26: Connecting: gc=0x8fa2830 (Re-initializing Stream) 8 / 9
259 22:14:26: (Libpurple: jabber) XML parser error for JabberStream 0x0: Domain 1, code 5, level 3: Extra content at the end of the document
260
261 22:14:26: (Libpurple: jabber) Sending (ssl): <stream:stream to='mit.edu' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
262 22:14:26: ************ raeburn@mit.edu --step-- 8
263 22:14:26: (Libpurple: jabber) Recv (ssl)(409): <?xml version='1.0' encoding='UTF-8'?><stream:stream xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client" from="mit.edu" id="22961524" xml:lang="en" version="1.0"><stream:features><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></stream:features>
264 22:14:26: (Libpurple: jabber) Sending (ssl): <iq type='set' id='purpleffbeb180'><bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'><resource>Adium78435</resource></bind></iq>
265 22:14:26: (Libpurple: jabber) Recv (ssl)(156): <iq type="result" id="purpleffbeb180" to="mit.edu/22961524"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>raeburn@mit.edu/Adium78435</jid></bind></iq>
266 22:14:26: (Libpurple: jabber) Sending (ssl): <iq type='set' id='purpleffbeb181'><session xmlns='urn:ietf:params:xml:ns:xmpp-session'/></iq>
267 22:14:26: (Libpurple: jabber) Recv (ssl)(129): <iq type="result" id="purpleffbeb181" to="raeburn@mit.edu/Adium78435"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>
268 22:14:26: Connected: gc=8fa2830
269 22:14:26: ************ raeburn@mit.edu CONNECTED ***********
270 22:14:26: Getting accountActionMenuItems for <ESPurpleJabberAccount:7b1ed10 11>:raeburn@mit.edu
271 22:14:26: (Libpurple: jabber) jabber_actions: have pep: NO
272 22:14:26: <ESPurpleJabberAccount:7b1ed10 11>:raeburn@mit.edu: Updating status for key: IdleSince
273 22:14:26: (Libpurple: connection) Activating keepalive.
274 22:14:26: (Libpurple: jabber) Sending (ssl): <iq type='get' id='purpleffbeb182' to='mit.edu'><query xmlns='http://jabber.org/protocol/disco#items'/></iq>
275 22:14:26: (Libpurple: jabber) Sending (ssl): <iq type='get' id='purpleffbeb183' to='mit.edu'><query xmlns='http://jabber.org/protocol/disco#info'/></iq>
276 22:14:26: (Libpurple: jabber) Recv (ssl)(627): <iq type="result" id="purpleffbeb182" from="mit.edu" to="raeburn@mit.edu/Adium78435"><query xmlns="http://jabber.org/protocol/disco#items"><item jid="conference.mit.edu" name="Public Chatrooms"/><item jid="pubsub.mit.edu" name="Publish-Subscribe service"/><item jid="proxy.mit.edu" name="Socks 5 Bytestreams Proxy"/><item jid="workgroup.mit.edu" name="Fastpath"/><item jid="manager.mit.edu" name="Enterprise Manager"/><item jid="updater.mit.edu" name="Spark Updater"/><item jid="sipark.mit.edu" name="SIP Controller"/><item jid="logger.mit.edu" name="Remote Logger"/><item jid="search.mit.edu" name="User Search"/></query></iq>
277 22:14:26: (Libpurple: jabber) Sending (ssl): <iq type='get' id='purpleffbeb184' to='conference.mit.edu'><query xmlns='http://jabber.org/protocol/disco#info'/></iq>
278 22:14:26: (Libpurple: jabber) Sending (ssl): <iq type='get' id='purpleffbeb185' to='pubsub.mit.edu'><query xmlns='http://jabber.org/protocol/disco#info'/></iq>
279 22:14:26: (Libpurple: jabber) Sending (ssl): <iq type='get' id='purpleffbeb186' to='proxy.mit.edu'><query xmlns='http://jabber.org/protocol/disco#info'/></iq>
280 22:14:26: (Libpurple: jabber) Sending (ssl): <iq type='get' id='purpleffbeb187' to='workgroup.mit.edu'><query xmlns='http://jabber.org/protocol/disco#info'/></iq>
281 22:14:26: (Libpurple: jabber) Sending (ssl): <iq type='get' id='purpleffbeb188' to='manager.mit.edu'><query xmlns='http://jabber.org/protocol/disco#info'/></iq>
282 22:14:26: (Libpurple: jabber) Sending (ssl): <iq type='get' id='purpleffbeb189' to='updater.mit.edu'><query xmlns='http://jabber.org/protocol/disco#info'/></iq>
283 22:14:26: (Libpurple: jabber) Sending (ssl): <iq type='get' id='purpleffbeb18a' to='sipark.mit.edu'><query xmlns='http://jabber.org/protocol/disco#info'/></iq>
284 22:14:26: (Libpurple: jabber) Sending (ssl): <iq type='get' id='purpleffbeb18b' to='logger.mit.edu'><query xmlns='http://jabber.org/protocol/disco#info'/></iq>
285 22:14:26: (Libpurple: jabber) Sending (ssl): <iq type='get' id='purpleffbeb18c' to='search.mit.edu'><query xmlns='http://jabber.org/protocol/disco#info'/></iq>
286 22:14:26: (Libpurple: jabber) Recv (ssl)(769): <iq type="result" id="purpleffbeb183" from="mit.edu" to="raeburn@mit.edu/Adium78435"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="server" name="Openfire Server" type="im"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/address"/><feature var="vcard-temp"/><feature var="jabber:iq:private"/><feature var="jabber:iq:register"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:time"/><feature var="jabber:iq:version"/><feature var="jabber:iq:last"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="http://jabber.org/protocol/offline"/><feature var="http://jabber.org/protocol/commands"/><feature var="jabber:iq:privacy"/><feature var="google:jingleinfo"/></query></iq>
287 22:14:26: (Libpurple: jabber) Sending (ssl): <iq type='get' id='purpleffbeb18d'><query xmlns='vcard-temp'/></iq>
288 22:14:26: (Libpurple: jabber) Sending (ssl): <iq type='get' id='purpleffbeb18e'><query xmlns='jabber:iq:roster'/></iq>
289 22:14:26: (Libpurple: jabber) attempt to send presence before roster retrieved
290 22:14:26: (Libpurple: jabber) Sending (ssl): <iq type='get' id='purpleffbeb18f' to='mit.edu'><query xmlns='http://jabber.org/protocol/disco#items' node='http://jabber.org/protocol/commands'/></iq>
291 22:14:26: (Libpurple: jabber) Recv (ssl)(387): <iq type="result" id="purpleffbeb184" from="conference.mit.edu" to="raeburn@mit.edu/Adium78435"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="conference" name="Public Chatrooms" type="text"/><feature var="http://jabber.org/protocol/muc"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/></query></iq>
292 22:14:26: (Libpurple: jabber) Recv (ssl)(2008): <iq type="result" id="purpleffbeb185" from="pubsub.mit.edu" to="raeburn@mit.edu/Adium78435"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="pubsub" name="Publish-Subscribe service" type="service"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#collections"/><feature var="http://jabber.org/protocol/pubsub#config-node"/><feature var="http://jabber.org/protocol/pubsub#create-and-configure"/><feature var="http://jabber.org/protocol/pubsub#create-nodes"/><feature var="http://jabber.org/protocol/pubsub#delete-nodes"/><feature var="http://jabber.org/protocol/pubsub#get-pending"/><feature var="http://jabber.org/protocol/pubsub#instant-nodes"/><feature var="http://jabber.org/protocol/pubsub#item-ids"/><feature var="http://jabber.org/protocol/pubsub#meta-data"/><feature var="http://jabber.org/protocol/pubsub#modify-affiliations"/><feature var="http://jabber.org/protocol/pubsub#manage-subscriptions"/><feature var="http://jabber.org/protocol/pubsub#multi-subscribe"/><feature var="http://jabber.org/protocol/pubsub#outcast-affiliation"/><feature var="http://jabber.org/protocol/pubsub#persistent-items"/><feature var="http://jabber.org/protocol/pubsub#presence-notifications"/><feature var="http://jabber.org/protocol/pubsub#publish"/><feature var="http://jabber.org/protocol/pubsub#publisher-affiliation"/><feature var="http://jabber.org/protocol/pubsub#purge-nodes"/><feature var="http://jabber.org/protocol/pubsub#retract-items"/><feature var="http://jabber.org/protocol/pubsub#retrieve-affiliations"/><feature var="http://jabber.org/protocol/pubsub#retrieve-default"/><feature var="http://jabber.org/protocol/pubsub#retrieve-items"/><feature var="http://jabber.org/protocol/pubsub#retrieve-subscriptions"/><feature var="http://jabber.org/protocol/pubsub#subscribe"/><feature var="http://jabber.org/protocol/pubsub#subscription-options"/><feature var="http://jabber.org/protocol/pubsub#default_access_model_open"/></query></iq>
293 22:14:26: (Libpurple: jabber) Recv (ssl)(347): <iq type="result" id="purpleffbeb186" from="proxy.mit.edu" to="raeburn@mit.edu/Adium78435"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="proxy" name="SOCKS5 Bytestreams Service" type="bytestreams"/><feature var="http://jabber.org/protocol/bytestreams"/><feature var="http://jabber.org/protocol/disco#info"/></query></iq>
294 22:14:26: (Libpurple: jabber) Found bytestream proxy server: proxy.mit.edu
295 22:14:26: (Libpurple: jabber) Sending (ssl): <iq type='get' id='purpleffbeb190' to='proxy.mit.edu'><query xmlns='http://jabber.org/protocol/bytestreams'/></iq>
296 22:14:26: (Libpurple: jabber) Recv (ssl)(423): <iq type="result" id="purpleffbeb187" from="workgroup.mit.edu" to="raeburn@mit.edu/Adium78435"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="collaboration" name="Fastpath" type="workgroup"/><feature var="http://jabber.org/protocol/workgroup"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="jabber:iq:version"/><feature var="http://jabber.org/protocol/commands"/></query></iq>
297 22:14:26: (Libpurple: jabber) Recv (ssl)(325): <iq type="result" id="purpleffbeb188" from="manager.mit.edu" to="raeburn@mit.edu/Adium78435"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="manager" type="text" name="Enterprise Manager"/><feature var="broadcast"/><feature var="file-transfer"/><feature var="muc"/><feature var="vcard"/></query></iq>
298 22:14:26: (Libpurple: jabber) Recv (ssl)(256): <iq type="result" id="purpleffbeb189" from="updater.mit.edu" to="raeburn@mit.edu/Adium78435"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="updater" type="text" name="Spark Updater"/><feature var="jabber:iq:updater"/></query></iq>
299 22:14:26: (Libpurple: jabber) Recv (ssl)(341): <iq type="result" id="purpleffbeb18a" from="sipark.mit.edu" to="raeburn@mit.edu/Adium78435"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="generic" name="SIP Controller"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://www.jivesoftware.com/protocol/sipark"/></query></iq>
300 22:14:26: (Libpurple: jabber) Recv (ssl)(337): <iq type="result" id="purpleffbeb18b" from="logger.mit.edu" to="raeburn@mit.edu/Adium78435"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="generic" name="Remote Logger"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://www.jivesoftware.com/protocol/log"/></query></iq>
301 22:14:26: (Libpurple: jabber) Recv (ssl)(254): <iq type="result" id="purpleffbeb18c" from="search.mit.edu" to="raeburn@mit.edu/Adium78435"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="directory" type="user" name="User Search"/><feature var="jabber:iq:search"/></query></iq>
302 22:14:26: (Libpurple: jabber) Recv (ssl)(166): <iq type="result" id="purpleffbeb18d" to="raeburn@mit.edu/Adium78435"><vCard xmlns="vcard-temp" prodid="-//Handgen//NONSGML vGen v1.0//EN" version="2.0"></vCard></iq>
303 22:14:26: (Libpurple: jabber) XML parser error for JabberStream 0x0: Domain 1, code 100, level 1: xmlns: URI vcard-temp is not absolute
304
305 22:14:26: (Libpurple: jabber) Recv (ssl)(4095): <iq type="result" id="purpleffbeb18e" to="raeburn@mit.edu/Adium78435"><query xmlns="jabber:iq:roster"><item jid="jander@mit.edu" subscription="both">