Attachment 36403 Details for Bug 28650 – Gabble debug log

Gabble debug log

empathy-log.txt (text/plain), 14.96 KB, created by Mad Fish on 2010-06-21 09:23:52 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Mad Fish

Created: 2010-06-21 09:23:52 UTC

Size: 14.96 KB

patch

obsolete

>tp-glib-DEBUG: 06/20/2010 20:46:24.68083: started version 0.8.14 (telepathy-glib version 0.10.6)
>gabble/connection-DEBUG: 06/20/2010 20:46:24.68502: gabble_connection_class_init: Initializing (GabbleConnectionClass *)0xeffb20
>gabble/connection-DEBUG: 06/20/2010 20:46:24.68625: gabble_connection_init: Initializing (GabbleConnection *)0xf06000
>gabble/muc-DEBUG: 06/20/2010 20:46:24.68794: gabble_roomlist_manager_constructed: 0xf03a80
>gabble/connection-DEBUG: 06/20/2010 20:46:24.68974: gabble_connection_constructor: Post-construction: (GabbleConnection *)0xf06000
>gabble/jid-DEBUG: 06/20/2010 20:46:24.69117: gabble_signal_connect_weak: connecting to 0xef2f40:item-found with context 0xf14080
>gabble/jid-DEBUG: 06/20/2010 20:46:24.69120: gabble_signal_connect_weak: connecting to 0xf06000:status-changed with context 0xf14120
>gabble/connection-DEBUG: 06/20/2010 20:46:24.69155: gabble_connection_constructed: defaulted resource to 68facb17
>gabble/connection-DEBUG: 06/20/2010 20:46:24.71036: _gabble_connection_connect: letting SRV lookup decide server and port
>gabble/connection-DEBUG: 06/20/2010 20:46:24.71043: do_connect: calling lm_connection_open
>LM-DEBUG: 06/20/2010 20:46:24.73090: Going to connect to jabber.kiev.ua:5222
>LM-DEBUG: 06/20/2010 20:46:24.75134: Trying 91.202.128.79 port 5222...
>gabble/roster-DEBUG: 06/20/2010 20:46:24.75189: connection_status_changed_cb: adding callbacks
>gabble/im-DEBUG: 06/20/2010 20:46:24.75192: connection_status_changed_cb: adding callbacks
>gabble/muc-DEBUG: 06/20/2010 20:46:24.75196: connection_status_changed_cb: adding callbacks
>LM-DEBUG: 06/20/2010 20:46:24.76308: Connection success (2).
>LM-DEBUG: 06/20/2010 20:46:24.76318: 
>SEND:
>LM-DEBUG: 06/20/2010 20:46:24.76319: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.76322: <?xml version='1.0' encoding='UTF-8'?>
>LM-DEBUG: 06/20/2010 20:46:24.76323: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.76371: 
>SEND:
>LM-DEBUG: 06/20/2010 20:46:24.76373: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.76375: <stream:stream version="1.0" xmlns="jabber:client" xmlns:stream="http://etherx.jabber.org/streams" to="jabber.kiev.ua" id="56076343">
>LM-DEBUG: 06/20/2010 20:46:24.76376: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.178730: 
>RECV [170]:
>LM-DEBUG: 06/20/2010 20:46:24.178739: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.178741: '<?xml version='1.0'?><stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='17169152' from='jabber.kiev.ua' version='1.0' xml:lang='en'>'
>LM-DEBUG: 06/20/2010 20:46:24.178744: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.178761: ATTRIBUTE: xmlns = jabber:client
>LM-DEBUG: 06/20/2010 20:46:24.178764: ATTRIBUTE: xmlns:stream = http://etherx.jabber.org/streams
>LM-DEBUG: 06/20/2010 20:46:24.178766: ATTRIBUTE: id = 17169152
>LM-DEBUG: 06/20/2010 20:46:24.178767: ATTRIBUTE: from = jabber.kiev.ua
>LM-DEBUG: 06/20/2010 20:46:24.178770: ATTRIBUTE: version = 1.0
>LM-DEBUG: 06/20/2010 20:46:24.178771: ATTRIBUTE: xml:lang = en
>LM-DEBUG: 06/20/2010 20:46:24.178774: Trying to close node: stream:stream
>LM-DEBUG: 06/20/2010 20:46:24.178780: Have a new message
>gabble/connection-DEBUG: 06/20/2010 20:46:24.178812: do_auth: authenticating with username: madfish, password: <hidden>, resource: 68facb17
>LM-DEBUG: 06/20/2010 20:46:24.179100: 
>RECV [363]:
>LM-DEBUG: 06/20/2010 20:46:24.179102: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.179105: '<stream:features><starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/><compression xmlns='http://jabber.org/features/compress'><method>zlib</method></compression><mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>PLAIN</mechanism><mechanism>DIGEST-MD5</mechanism></mechanisms><register xmlns='http://jabber.org/features/iq-register'/></stream:features>'
>LM-DEBUG: 06/20/2010 20:46:24.179105: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.179110: ATTRIBUTE: xmlns = urn:ietf:params:xml:ns:xmpp-tls
>LM-DEBUG: 06/20/2010 20:46:24.179111: Trying to close node: starttls
>LM-DEBUG: 06/20/2010 20:46:24.179114: ATTRIBUTE: xmlns = http://jabber.org/features/compress
>LM-DEBUG: 06/20/2010 20:46:24.179121: Trying to close node: method
>LM-DEBUG: 06/20/2010 20:46:24.179122: Trying to close node: compression
>LM-DEBUG: 06/20/2010 20:46:24.179125: ATTRIBUTE: xmlns = urn:ietf:params:xml:ns:xmpp-sasl
>LM-DEBUG: 06/20/2010 20:46:24.179126: Trying to close node: mechanism
>LM-DEBUG: 06/20/2010 20:46:24.179128: Trying to close node: mechanism
>LM-DEBUG: 06/20/2010 20:46:24.179131: Trying to close node: mechanisms
>LM-DEBUG: 06/20/2010 20:46:24.179132: ATTRIBUTE: xmlns = http://jabber.org/features/iq-register
>LM-DEBUG: 06/20/2010 20:46:24.179133: Trying to close node: register
>LM-DEBUG: 06/20/2010 20:46:24.179136: Trying to close node: stream:features
>LM-DEBUG: 06/20/2010 20:46:24.179136: Have a new message
>LM-DEBUG: 06/20/2010 20:46:24.179150: 
>SEND:
>LM-DEBUG: 06/20/2010 20:46:24.179151: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.179152: <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls" id="136129179143"></starttls>
>LM-DEBUG: 06/20/2010 20:46:24.179153: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.180720: 
>RECV [50]:
>LM-DEBUG: 06/20/2010 20:46:24.180721: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.180723: '<proceed xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>'
>LM-DEBUG: 06/20/2010 20:46:24.180726: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.180730: ATTRIBUTE: xmlns = urn:ietf:params:xml:ns:xmpp-tls
>LM-DEBUG: 06/20/2010 20:46:24.180732: Trying to close node: proceed
>LM-DEBUG: 06/20/2010 20:46:24.180733: Have a new message
>gabble/connection-DEBUG: 06/20/2010 20:46:24.370745: connection_ssl_cb: called: The certificate can not be trusted.
>LM-DEBUG: 06/20/2010 20:46:24.371154: 
>SEND:
>LM-DEBUG: 06/20/2010 20:46:24.371155: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.371157: <stream:stream version="1.0" xmlns="jabber:client" xmlns:stream="http://etherx.jabber.org/streams" to="jabber.kiev.ua" id="262594371139">
>LM-DEBUG: 06/20/2010 20:46:24.371161: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.373466: 
>RECV [172]:
>LM-DEBUG: 06/20/2010 20:46:24.373469: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.373471: '<?xml version='1.0'?><stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='1254029381' from='jabber.kiev.ua' version='1.0' xml:lang='en'>'
>LM-DEBUG: 06/20/2010 20:46:24.373473: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.373486: ATTRIBUTE: xmlns = jabber:client
>LM-DEBUG: 06/20/2010 20:46:24.373488: ATTRIBUTE: xmlns:stream = http://etherx.jabber.org/streams
>LM-DEBUG: 06/20/2010 20:46:24.373491: ATTRIBUTE: id = 1254029381
>LM-DEBUG: 06/20/2010 20:46:24.373492: ATTRIBUTE: from = jabber.kiev.ua
>LM-DEBUG: 06/20/2010 20:46:24.373493: ATTRIBUTE: version = 1.0
>LM-DEBUG: 06/20/2010 20:46:24.373495: ATTRIBUTE: xml:lang = en
>LM-DEBUG: 06/20/2010 20:46:24.373497: Trying to close node: stream:stream
>LM-DEBUG: 06/20/2010 20:46:24.373497: Have a new message
>LM-DEBUG: 06/20/2010 20:46:24.373825: 
>RECV [312]:
>LM-DEBUG: 06/20/2010 20:46:24.373826: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.373828: '<stream:features><compression xmlns='http://jabber.org/features/compress'><method>zlib</method></compression><mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>PLAIN</mechanism><mechanism>DIGEST-MD5</mechanism></mechanisms><register xmlns='http://jabber.org/features/iq-register'/></stream:features>'
>LM-DEBUG: 06/20/2010 20:46:24.373830: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.373833: ATTRIBUTE: xmlns = http://jabber.org/features/compress
>LM-DEBUG: 06/20/2010 20:46:24.373836: Trying to close node: method
>LM-DEBUG: 06/20/2010 20:46:24.373838: Trying to close node: compression
>LM-DEBUG: 06/20/2010 20:46:24.373841: ATTRIBUTE: xmlns = urn:ietf:params:xml:ns:xmpp-sasl
>LM-DEBUG: 06/20/2010 20:46:24.373842: Trying to close node: mechanism
>LM-DEBUG: 06/20/2010 20:46:24.373845: Trying to close node: mechanism
>LM-DEBUG: 06/20/2010 20:46:24.373846: Trying to close node: mechanisms
>LM-DEBUG: 06/20/2010 20:46:24.373847: ATTRIBUTE: xmlns = http://jabber.org/features/iq-register
>LM-DEBUG: 06/20/2010 20:46:24.373848: Trying to close node: register
>LM-DEBUG: 06/20/2010 20:46:24.373852: Trying to close node: stream:features
>LM-DEBUG: 06/20/2010 20:46:24.373853: Have a new message
>LM-DEBUG: 06/20/2010 20:46:24.373863: Stream features received
>LM-DEBUG: 06/20/2010 20:46:24.373873: 
>SEND:
>LM-DEBUG: 06/20/2010 20:46:24.373876: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.373877: <auth mechanism="DIGEST-MD5" xmlns="urn:ietf:params:xml:ns:xmpp-sasl" id="266339373867"></auth>
>LM-DEBUG: 06/20/2010 20:46:24.373878: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.399262: 
>RECV [148]:
>LM-DEBUG: 06/20/2010 20:46:24.399266: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.399267: '<challenge xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>bm9uY2U9IjQxMjI3MTk1NDgiLHFvcD0iYXV0aCIsY2hhcnNldD11dGYtOCxhbGdvcml0aG09bWQ1LXNlc3M=</challenge>'
>LM-DEBUG: 06/20/2010 20:46:24.399270: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.399279: ATTRIBUTE: xmlns = urn:ietf:params:xml:ns:xmpp-sasl
>LM-DEBUG: 06/20/2010 20:46:24.399281: Trying to close node: challenge
>LM-DEBUG: 06/20/2010 20:46:24.399283: Have a new message
>LM-DEBUG: 06/20/2010 20:46:24.399403: 
>SEND:
>LM-DEBUG: 06/20/2010 20:46:24.399405: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.399406: <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl" id="397348399392">dXNlcm5hbWU9Im1hZGZpc2giLHJlYWxtPSIobnVsbCkiLGRpZ2VzdC11cmk9InhtcHAvKG51bGwpIixub25jZT0iNDEyMjcxOTU0OCIsbmM9MDAwMDAwMDEsY25vbmNlPSJHcnVIUEdraVJTWDRhbUhmRlBWa1pESXhYcjcvNHBuWGxXazJJbGtJSmNVPSIscW9wPWF1dGgsY2hhcnNldD11dGYtOCxyZXNwb25zZT03MDkyZjJmNGU4YzQ1YzgxYTViNmIwY2U5Y2JhZTliZA==</response>
>LM-DEBUG: 06/20/2010 20:46:24.399408: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.423048: 
>RECV [77]:
>LM-DEBUG: 06/20/2010 20:46:24.423049: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.423053: '<failure xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><not-authorized/></failure>'
>LM-DEBUG: 06/20/2010 20:46:24.423053: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.423063: ATTRIBUTE: xmlns = urn:ietf:params:xml:ns:xmpp-sasl
>LM-DEBUG: 06/20/2010 20:46:24.423067: Trying to close node: not-authorized
>LM-DEBUG: 06/20/2010 20:46:24.423070: Trying to close node: failure
>LM-DEBUG: 06/20/2010 20:46:24.423072: Have a new message
>LM-DEBUG: 06/20/2010 20:46:24.423079: sasl_failure_cb: SASL authentication failed: unknown reason
>gabble/connection-DEBUG: 06/20/2010 20:46:24.423084: connection_auth_cb: failed
>gabble/roster-DEBUG: 06/20/2010 20:46:24.423098: gabble_roster_close_all: closing channels
>gabble/roster-DEBUG: 06/20/2010 20:46:24.423105: gabble_roster_close_all: removing callbacks
>gabble/im-DEBUG: 06/20/2010 20:46:24.423111: gabble_im_factory_close_all: closing channels
>gabble/im-DEBUG: 06/20/2010 20:46:24.423114: gabble_im_factory_close_all: removing callbacks
>gabble/muc-DEBUG: 06/20/2010 20:46:24.423116: connection_status_changed_cb: disconnected, closing room lists
>gabble/muc-DEBUG: 06/20/2010 20:46:24.423119: gabble_roomlist_manager_close_all: 0xf03a80
>gabble/muc-DEBUG: 06/20/2010 20:46:24.423124: gabble_muc_factory_close_all: closing channels
>gabble/muc-DEBUG: 06/20/2010 20:46:24.423126: gabble_muc_factory_close_all: removing callbacks
>gabble/tubes-DEBUG: 06/20/2010 20:46:24.423130: gabble_private_tubes_factory_close_all: closing 1-1 tubes channels
>gabble/media-channel-DEBUG: 06/20/2010 20:46:24.423135: gabble_media_factory_close_all: closing channels
>gabble/connection-DEBUG: 06/20/2010 20:46:24.423186: connection_shut_down: still open; calling lm_connection_close
>LM-DEBUG: 06/20/2010 20:46:24.423188: 
>SEND:
>LM-DEBUG: 06/20/2010 20:46:24.423190: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.423191: </stream:stream>
>LM-DEBUG: 06/20/2010 20:46:24.423192: -----------------------------------
>LM-DEBUG: 06/20/2010 20:46:24.423218: Freeing up IOChannel and file descriptor
>gabble/connection-DEBUG: 06/20/2010 20:46:24.424923: connection_disconnected_cb: called with reason 0
>gabble/connection-DEBUG: 06/20/2010 20:46:24.424926: connection_disconnected_cb: expected; emitting DISCONNECTED
>gabble/connection-DEBUG: 06/20/2010 20:46:24.424940: gabble_connection_dispose: called
>gabble/bytestream-DEBUG: 06/20/2010 20:46:24.424946: gabble_bytestream_factory_dispose: dispose called
>gabble/jid-DEBUG: 06/20/2010 20:46:24.424953: user_data_destroyed_cb: user_data for 0xf14080 destroyed; disconnecting
>gabble/jid-DEBUG: 06/20/2010 20:46:24.424958: user_data_destroyed_cb: user_data for 0xf14120 destroyed; disconnecting
>gabble/disco-DEBUG: 06/20/2010 20:46:24.424963: gabble_disco_dispose: dispose called
>gabble/disco-DEBUG: 06/20/2010 20:46:24.424968: gabble_disco_finalize: called with 0xef2f40
>gabble/pipeline-DEBUG: 06/20/2010 20:46:24.425667: gabble_request_pipeline_dispose: disposing request-pipeline
>gabble/vcard-DEBUG: 06/20/2010 20:46:24.425671: gabble_vcard_manager_dispose: 0xf03c30
>gabble/vcard-DEBUG: 06/20/2010 20:46:24.425678: gabble_vcard_manager_finalize: 0xf03c30
>gabble/media-channel-DEBUG: 06/20/2010 20:46:24.425681: gabble_jingle_factory_dispose: dispose called
>gabble/presence-DEBUG: 06/20/2010 20:46:24.425687: gabble_presence_cache_dispose: dispose called
>gabble/presence-DEBUG: 06/20/2010 20:46:24.425698: gabble_presence_cache_finalize: called with 0xf0c8a0
>gabble/roster-DEBUG: 06/20/2010 20:46:24.426369: gabble_roster_dispose: dispose called
>gabble/roster-DEBUG: 06/20/2010 20:46:24.426373: gabble_roster_close_all: closing channels
>gabble/roster-DEBUG: 06/20/2010 20:46:24.426378: gabble_roster_finalize: called with 0xec9b30
>gabble/im-DEBUG: 06/20/2010 20:46:24.426382: gabble_im_factory_dispose: dispose called
>gabble/muc-DEBUG: 06/20/2010 20:46:24.426386: gabble_roomlist_manager_dispose: running
>gabble/muc-DEBUG: 06/20/2010 20:46:24.426387: gabble_roomlist_manager_close_all: 0xf03a80
>gabble/muc-DEBUG: 06/20/2010 20:46:24.426390: gabble_muc_factory_dispose: dispose called
>gabble/muc-DEBUG: 06/20/2010 20:46:24.426393: gabble_muc_factory_close_all: closing channels
>gabble/tubes-DEBUG: 06/20/2010 20:46:24.426398: gabble_private_tubes_factory_dispose: dispose called
>gabble/tubes-DEBUG: 06/20/2010 20:46:24.426399: gabble_private_tubes_factory_close_all: closing 1-1 tubes channels
>gabble/media-channel-DEBUG: 06/20/2010 20:46:24.426403: gabble_media_factory_dispose: dispose called
>gabble/media-channel-DEBUG: 06/20/2010 20:46:24.426405: gabble_media_factory_close_all: closing channels
>gabble/olpc-DEBUG: 06/20/2010 20:46:24.426409: gabble_olpc_gadget_manager_dispose: running
>gabble/olpc-DEBUG: 06/20/2010 20:46:24.426410: gabble_olpc_gadget_manager_close_all: 0xef2e40
>gabble/connection-DEBUG: 06/20/2010 20:46:24.426446: gabble_connection_finalize: called with 0xf06000
>tp-glib-DEBUG: 06/20/2010 20:46:29.427998: no connections, and timed out
>

Actions: View

Attachments on bug 28650: 36403

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.