gabble/plugins-DEBUG: 11/29/2011 15:07:50.356081: gabble_plugin_loader_probe (plugin-loader.c:124): probing /usr/lib/telepathy/gabble-0 gabble/plugins-DEBUG: 11/29/2011 15:07:50.356674: plugin_loader_try_to_load (plugin-loader.c:99): loaded 'Gateway registration plugin' version 0.13.7 (/usr/lib/telepathy/gabble-0/gateways.so), implementing these sidecars: org.freedesktop.Telepathy.Gabble.Plugin.Gateways tp-glib-DEBUG: 11/29/2011 15:07:50.362636: started version 0.13.7 (telepathy-glib version 0.16.1) gabble/connection-DEBUG: 11/29/2011 15:07:50.366518: gabble_connection_class_init (connection.c:920): Initializing (GabbleConnectionClass *)0x8205800 gabble/connection-DEBUG: 11/29/2011 15:07:50.367166: gabble_connection_init (connection.c:519): Initializing (GabbleConnection *)0x820a080 gabble/presence-DEBUG: 11/29/2011 15:07:50.367254: gabble_capabilities_init (capabilities.c:233): 0x820a080 gabble/muc-DEBUG: 11/29/2011 15:07:50.368036: gabble_roomlist_manager_constructed (roomlist-manager.c:210): 0x81eca00 gabble/jid-DEBUG: 11/29/2011 15:07:50.368074: gabble_signal_connect_weak (util.c:966): connecting to 0x820a080:status-changed with context 0x81ec050 gabble/jid-DEBUG: 11/29/2011 15:07:50.368177: gabble_signal_connect_weak (util.c:966): connecting to 0x820a080:status-changed with context 0x81ec120 gabble/tls-DEBUG: 11/29/2011 15:07:50.368297: gabble_server_tls_manager_constructed (server-tls-manager.c:418): Server TLS Manager constructed gabble/jid-DEBUG: 11/29/2011 15:07:50.368308: gabble_signal_connect_weak (util.c:966): connecting to 0x820a080:status-changed with context 0x81ec070 gabble/jid-DEBUG: 11/29/2011 15:07:50.368453: gabble_signal_connect_weak (util.c:966): connecting to 0x820a080:status-changed with context 0x81ebeb0 gabble/jid-DEBUG: 11/29/2011 15:07:50.368483: gabble_signal_connect_weak (util.c:966): connecting to 0x820a080:porter-available with context 0x81ebe50 gabble/connection-DEBUG: 11/29/2011 15:07:50.372318: gabble_connection_constructor (connection.c:390): Post-construction: (GabbleConnection *)0x820a080 gabble/jid-DEBUG: 11/29/2011 15:07:50.376832: gabble_signal_connect_weak (util.c:966): connecting to 0x82100a0:item-found with context 0x8216680 gabble/jid-DEBUG: 11/29/2011 15:07:50.376852: gabble_signal_connect_weak (util.c:966): connecting to 0x820a080:status-changed with context 0x82166a0 gabble/connection-DEBUG: 11/29/2011 15:07:50.377007: gabble_connection_constructed (connection.c:502): defaulted resource to a61fabea gabble/connection-DEBUG: 11/29/2011 15:07:50.412808: gabble_connection_update_capabilities (connection.c:3393): enter gabble/tubes-DEBUG: 11/29/2011 15:07:50.412867: gabble_private_tubes_factory_add_cap (private-tubes-factory.c:602): org.freedesktop.Telepathy.Client.Vinagre: adding capability http://telepathy.freedesktop.org/xmpp/tubes/stream#rfb gabble/media-channel-DEBUG: 11/29/2011 15:07:50.412902: gabble_media_factory_add_caps (media-factory.c:1018): Client org.freedesktop.Telepathy.Client.Vinagre media capabilities: gabble/connection-DEBUG: 11/29/2011 15:07:50.412921: gabble_connection_update_capabilities (connection.c:3485): client org.freedesktop.Telepathy.Client.Vinagre has no interesting data forms gabble/media-channel-DEBUG: 11/29/2011 15:07:50.412951: gabble_media_factory_add_caps (media-factory.c:1018): Client org.freedesktop.Telepathy.Client.Empathy.Auth media capabilities: gabble/connection-DEBUG: 11/29/2011 15:07:50.412962: gabble_connection_update_capabilities (connection.c:3463): client org.freedesktop.Telepathy.Client.Empathy.Auth has no interesting capabilities gabble/connection-DEBUG: 11/29/2011 15:07:50.412976: gabble_connection_update_capabilities (connection.c:3485): client org.freedesktop.Telepathy.Client.Empathy.Auth has no interesting data forms gabble/connection-DEBUG: 11/29/2011 15:07:50.412986: gabble_connection_update_capabilities (connection.c:3411): client org.freedesktop.Telepathy.Client.Empathy.AuthEventManager can't do anything gabble/connection-DEBUG: 11/29/2011 15:07:50.412995: gabble_connection_update_capabilities (connection.c:3411): client org.freedesktop.Telepathy.Client.Logger can't do anything gabble/connection-DEBUG: 11/29/2011 15:07:50.413003: gabble_connection_update_capabilities (connection.c:3411): client org.freedesktop.Telepathy.Client.Empathy.ChatroomManager._3a1_2e181.n0 can't do anything gabble/media-channel-DEBUG: 11/29/2011 15:07:50.413047: gabble_media_factory_add_caps (media-factory.c:1018): Client org.freedesktop.Telepathy.Client.Empathy.AudioVideo media capabilities: audio video gtalk-p2p ice-udp H.264 gabble/connection-DEBUG: 11/29/2011 15:07:50.413106: gabble_connection_update_capabilities (connection.c:3485): client org.freedesktop.Telepathy.Client.Empathy.AudioVideo has no interesting data forms gabble/media-channel-DEBUG: 11/29/2011 15:07:50.413134: gabble_media_factory_add_caps (media-factory.c:1018): Client org.freedesktop.Telepathy.Client.Vino media capabilities: gabble/connection-DEBUG: 11/29/2011 15:07:50.413145: gabble_connection_update_capabilities (connection.c:3463): client org.freedesktop.Telepathy.Client.Vino has no interesting capabilities gabble/connection-DEBUG: 11/29/2011 15:07:50.413155: gabble_connection_update_capabilities (connection.c:3485): client org.freedesktop.Telepathy.Client.Vino has no interesting data forms gabble/connection-DEBUG: 11/29/2011 15:07:50.413165: gabble_connection_update_capabilities (connection.c:3411): client org.freedesktop.Telepathy.Client.Empathy.CallObserver can't do anything gabble/media-channel-DEBUG: 11/29/2011 15:07:50.413189: gabble_media_factory_add_caps (media-factory.c:1018): Client org.freedesktop.Telepathy.Client.Empathy.FileTransfer media capabilities: gabble/file-transfer-DEBUG: 11/29/2011 15:07:50.413202: gabble_ft_manager_represent_client (ft-manager.c:846): client org.freedesktop.Telepathy.Client.Empathy.FileTransfer supports file transfer gabble/connection-DEBUG: 11/29/2011 15:07:50.413216: gabble_connection_update_capabilities (connection.c:3485): client org.freedesktop.Telepathy.Client.Empathy.FileTransfer has no interesting data forms gabble/connection-DEBUG: 11/29/2011 15:07:50.413224: gabble_connection_update_capabilities (connection.c:3411): client org.freedesktop.Telepathy.Client.Empathy.EventManager can't do anything gabble/media-channel-DEBUG: 11/29/2011 15:07:50.413250: gabble_media_factory_add_caps (media-factory.c:1018): Client org.freedesktop.Telepathy.Client.GnomeShell._3a1_2e25.n0 media capabilities: gabble/connection-DEBUG: 11/29/2011 15:07:50.413261: gabble_connection_update_capabilities (connection.c:3463): client org.freedesktop.Telepathy.Client.GnomeShell._3a1_2e25.n0 has no interesting capabilities gabble/connection-DEBUG: 11/29/2011 15:07:50.413270: gabble_connection_update_capabilities (connection.c:3485): client org.freedesktop.Telepathy.Client.GnomeShell._3a1_2e25.n0 has no interesting data forms gabble/media-channel-DEBUG: 11/29/2011 15:07:50.413300: gabble_media_factory_add_caps (media-factory.c:1018): Client org.freedesktop.Telepathy.Client.Empathy.Chat media capabilities: gabble/connection-DEBUG: 11/29/2011 15:07:50.413311: gabble_connection_update_capabilities (connection.c:3463): client org.freedesktop.Telepathy.Client.Empathy.Chat has no interesting capabilities gabble/connection-DEBUG: 11/29/2011 15:07:50.413321: gabble_connection_update_capabilities (connection.c:3485): client org.freedesktop.Telepathy.Client.Empathy.Chat has no interesting data forms gabble/presence-DEBUG: 11/29/2011 15:07:50.413386: gabble_presence_set_capabilities (presence.c:336): about to add caps to resource a61fabea with serial 1 gabble/presence-DEBUG: 11/29/2011 15:07:50.413397: gabble_presence_set_capabilities (presence.c:347): found resource a61fabea gabble/presence-DEBUG: 11/29/2011 15:07:50.413403: gabble_presence_set_capabilities (presence.c:352): new serial 1, old 0, clearing caps gabble/presence-DEBUG: 11/29/2011 15:07:50.413413: gabble_presence_set_capabilities (presence.c:360): updating caps for resource a61fabea gabble/connection-DEBUG: 11/29/2011 15:07:50.413451: gabble_connection_refresh_capabilities (connection.c:2478): not emitting self-presence stanza: not connected yet gabble/connection-DEBUG: 11/29/2011 15:07:50.422185: gabble_connection_update_capabilities (connection.c:3393): enter gabble/tubes-DEBUG: 11/29/2011 15:07:50.422224: gabble_private_tubes_factory_add_cap (private-tubes-factory.c:602): org.freedesktop.Telepathy.Client.Vinagre: adding capability http://telepathy.freedesktop.org/xmpp/tubes/stream#rfb gabble/media-channel-DEBUG: 11/29/2011 15:07:50.422245: gabble_media_factory_add_caps (media-factory.c:1018): Client org.freedesktop.Telepathy.Client.Vinagre media capabilities: gabble/connection-DEBUG: 11/29/2011 15:07:50.422259: gabble_connection_update_capabilities (connection.c:3485): client org.freedesktop.Telepathy.Client.Vinagre has no interesting data forms gabble/media-channel-DEBUG: 11/29/2011 15:07:50.422287: gabble_media_factory_add_caps (media-factory.c:1018): Client org.freedesktop.Telepathy.Client.Empathy.Auth media capabilities: gabble/connection-DEBUG: 11/29/2011 15:07:50.422300: gabble_connection_update_capabilities (connection.c:3463): client org.freedesktop.Telepathy.Client.Empathy.Auth has no interesting capabilities gabble/connection-DEBUG: 11/29/2011 15:07:50.422310: gabble_connection_update_capabilities (connection.c:3485): client org.freedesktop.Telepathy.Client.Empathy.Auth has no interesting data forms gabble/connection-DEBUG: 11/29/2011 15:07:50.422318: gabble_connection_update_capabilities (connection.c:3411): client org.freedesktop.Telepathy.Client.Empathy.AuthEventManager can't do anything gabble/connection-DEBUG: 11/29/2011 15:07:50.422327: gabble_connection_update_capabilities (connection.c:3411): client org.freedesktop.Telepathy.Client.Logger can't do anything gabble/connection-DEBUG: 11/29/2011 15:07:50.422337: gabble_connection_update_capabilities (connection.c:3411): client org.freedesktop.Telepathy.Client.Empathy.ChatroomManager._3a1_2e181.n0 can't do anything gabble/media-channel-DEBUG: 11/29/2011 15:07:50.422395: gabble_media_factory_add_caps (media-factory.c:1018): Client org.freedesktop.Telepathy.Client.Empathy.AudioVideo media capabilities: audio video gtalk-p2p ice-udp H.264 gabble/connection-DEBUG: 11/29/2011 15:07:50.422426: gabble_connection_update_capabilities (connection.c:3485): client org.freedesktop.Telepathy.Client.Empathy.AudioVideo has no interesting data forms gabble/media-channel-DEBUG: 11/29/2011 15:07:50.422451: gabble_media_factory_add_caps (media-factory.c:1018): Client org.freedesktop.Telepathy.Client.Vino media capabilities: gabble/connection-DEBUG: 11/29/2011 15:07:50.422462: gabble_connection_update_capabilities (connection.c:3463): client org.freedesktop.Telepathy.Client.Vino has no interesting capabilities gabble/connection-DEBUG: 11/29/2011 15:07:50.422472: gabble_connection_update_capabilities (connection.c:3485): client org.freedesktop.Telepathy.Client.Vino has no interesting data forms gabble/connection-DEBUG: 11/29/2011 15:07:50.422482: gabble_connection_update_capabilities (connection.c:3411): client org.freedesktop.Telepathy.Client.Empathy.CallObserver can't do anything gabble/media-channel-DEBUG: 11/29/2011 15:07:50.422511: gabble_media_factory_add_caps (media-factory.c:1018): Client org.freedesktop.Telepathy.Client.Empathy.FileTransfer media capabilities: gabble/file-transfer-DEBUG: 11/29/2011 15:07:50.422522: gabble_ft_manager_represent_client (ft-manager.c:846): client org.freedesktop.Telepathy.Client.Empathy.FileTransfer supports file transfer gabble/connection-DEBUG: 11/29/2011 15:07:50.422535: gabble_connection_update_capabilities (connection.c:3485): client org.freedesktop.Telepathy.Client.Empathy.FileTransfer has no interesting data forms gabble/connection-DEBUG: 11/29/2011 15:07:50.422544: gabble_connection_update_capabilities (connection.c:3411): client org.freedesktop.Telepathy.Client.Empathy.EventManager can't do anything gabble/media-channel-DEBUG: 11/29/2011 15:07:50.422569: gabble_media_factory_add_caps (media-factory.c:1018): Client org.freedesktop.Telepathy.Client.GnomeShell._3a1_2e25.n0 media capabilities: gabble/connection-DEBUG: 11/29/2011 15:07:50.422580: gabble_connection_update_capabilities (connection.c:3463): client org.freedesktop.Telepathy.Client.GnomeShell._3a1_2e25.n0 has no interesting capabilities gabble/connection-DEBUG: 11/29/2011 15:07:50.422590: gabble_connection_update_capabilities (connection.c:3485): client org.freedesktop.Telepathy.Client.GnomeShell._3a1_2e25.n0 has no interesting data forms gabble/media-channel-DEBUG: 11/29/2011 15:07:50.422619: gabble_media_factory_add_caps (media-factory.c:1018): Client org.freedesktop.Telepathy.Client.Empathy.Chat media capabilities: gabble/connection-DEBUG: 11/29/2011 15:07:50.422631: gabble_connection_update_capabilities (connection.c:3463): client org.freedesktop.Telepathy.Client.Empathy.Chat has no interesting capabilities gabble/connection-DEBUG: 11/29/2011 15:07:50.422641: gabble_connection_update_capabilities (connection.c:3485): client org.freedesktop.Telepathy.Client.Empathy.Chat has no interesting data forms gabble/presence-DEBUG: 11/29/2011 15:07:50.422700: gabble_presence_set_capabilities (presence.c:336): about to add caps to resource a61fabea with serial 2 gabble/presence-DEBUG: 11/29/2011 15:07:50.422708: gabble_presence_set_capabilities (presence.c:347): found resource a61fabea gabble/presence-DEBUG: 11/29/2011 15:07:50.422857: gabble_presence_set_capabilities (presence.c:352): new serial 2, old 1, clearing caps gabble/presence-DEBUG: 11/29/2011 15:07:50.422874: gabble_presence_set_capabilities (presence.c:360): updating caps for resource a61fabea gabble/connection-DEBUG: 11/29/2011 15:07:50.422910: gabble_connection_refresh_capabilities (connection.c:2469): nothing to do gabble/connection-DEBUG: 11/29/2011 15:07:50.430214: _gabble_connection_connect (connection.c:2141): disabling SRV because "server" or "old-ssl" was specified or port was not 5222, will connect to vkmessenger.com gabble/connection-DEBUG: 11/29/2011 15:07:50.430250: _gabble_connection_connect (connection.c:2160): require-encryption is False; flipping ignore_ssl_errors to True gabble/connection-DEBUG: 11/29/2011 15:07:50.430291: _gabble_connection_connect (connection.c:2200): Start connecting gabble/im-DEBUG: 11/29/2011 15:07:50.439234: connection_status_changed_cb (im-factory.c:428): adding callbacks gabble/jid-DEBUG: 11/29/2011 15:07:50.439253: gabble_signal_connect_weak (util.c:966): connecting to 0x82100a0:item-found with context 0x8227cb0 gabble/jid-DEBUG: 11/29/2011 15:07:50.439271: gabble_signal_connect_weak (util.c:966): connecting to 0x82100a0:done with context 0x8227cd0 gabble/tls-DEBUG: 11/29/2011 15:07:50.439290: connection_status_changed_cb (server-tls-manager.c:121): Connection status changed, now 1 gabble/muc-DEBUG: 11/29/2011 15:07:50.439299: connection_status_changed_cb (muc-factory.c:945): adding callbacks wocky-DEBUG: 11/29/2011 15:07:50.527923: wocky_xmpp_writer_stream_open: wocky-xmpp-writer.c:298: Writing stream opening: wocky-DEBUG: 11/29/2011 15:07:50.602092: handle_stream_open: wocky-xmpp-reader.c:431: Received stream opening: stream, prefix: stream, uri: http://etherx.jabber.org/streams wocky-DEBUG: 11/29/2011 15:07:50.602108: handle_stream_open: wocky-xmpp-reader.c:450: Stream opening attribute: id = 'c2s_159182' (prefix: , uri: ) wocky-DEBUG: 11/29/2011 15:07:50.602119: handle_stream_open: wocky-xmpp-reader.c:450: Stream opening attribute: from = 'vk.com' (prefix: , uri: ) wocky-DEBUG: 11/29/2011 15:07:50.602128: handle_stream_open: wocky-xmpp-reader.c:450: Stream opening attribute: version = '1.0' (prefix: , uri: ) wocky-DEBUG: 11/29/2011 15:07:50.602313: _end_element_ns: Received stanza * features xmlns='http://etherx.jabber.org/streams' * starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls' * mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl' * mechanism "DIGEST-MD5" * mechanism "PLAIN" * mechanism "OAUTH" wocky-DEBUG: 11/29/2011 15:07:50.602515: _write_node_tree: Serializing tree: * starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls' wocky-DEBUG: 11/29/2011 15:07:50.676964: _end_element_ns: Received stanza * proceed xmlns='urn:ietf:params:xml:ns:xmpp-tls' gabble/tls-DEBUG: 11/29/2011 15:07:51.409959: gabble_server_tls_manager_verify_async (server-tls-manager.c:284): verify_async() called on the GabbleServerTLSManager. gabble/tls-DEBUG: 11/29/2011 15:07:51.410896: gabble_server_tls_channel_constructed (server-tls-channel.c:208): Server TLS channel constructed at /org/freedesktop/Telepathy/Connection/gabble/jabber/square_5fcircle_40vk_2ecom_2fa61fabea/ServerTLSChannel gabble/tls-DEBUG: 11/29/2011 15:07:51.688993: gabble_tls_certificate_accept (tls-certificate.c:264): Accept() called on the TLS certificate; current state 0 gabble/tls-DEBUG: 11/29/2011 15:07:51.689054: tls_certificate_accepted_cb (server-tls-manager.c:177): TLS certificate accepted wocky-DEBUG: 11/29/2011 15:07:51.689232: wocky_xmpp_writer_stream_open: wocky-xmpp-writer.c:298: Writing stream opening: wocky-DEBUG: 11/29/2011 15:07:52.769510: handle_stream_open: wocky-xmpp-reader.c:431: Received stream opening: stream, prefix: stream, uri: http://etherx.jabber.org/streams wocky-DEBUG: 11/29/2011 15:07:52.769524: handle_stream_open: wocky-xmpp-reader.c:450: Stream opening attribute: id = 'c2s_159182' (prefix: , uri: ) wocky-DEBUG: 11/29/2011 15:07:52.769532: handle_stream_open: wocky-xmpp-reader.c:450: Stream opening attribute: from = 'vk.com' (prefix: , uri: ) wocky-DEBUG: 11/29/2011 15:07:52.769542: handle_stream_open: wocky-xmpp-reader.c:450: Stream opening attribute: version = '1.0' (prefix: , uri: ) wocky-DEBUG: 11/29/2011 15:07:52.769649: _end_element_ns: Received stanza * features xmlns='http://etherx.jabber.org/streams' * mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl' * mechanism "DIGEST-MD5" * mechanism "PLAIN" * mechanism "OAUTH" gabble/authentication-DEBUG: 11/29/2011 15:07:52.770272: gabble_server_sasl_channel_start_auth_async (server-sasl-channel.c:814): Starting authentication gabble/authentication-DEBUG: 11/29/2011 15:07:53.22172: gabble_server_sasl_channel_start_mechanism_with_data (server-sasl-channel.c:520): Starting X-TELEPATHY-PASSWORD authentication with 6 bytes of initial data wocky-DEBUG: 11/29/2011 15:07:53.22604: _write_node_tree: Serializing tree: * auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' xmlns:wocky-zb='http://www.google.com/talk/protocol/auth' wocky-zb:client-uses-full-bind-result='true' mechanism='DIGEST-MD5' wocky-DEBUG: 11/29/2011 15:07:53.91876: _end_element_ns: Received stanza * challenge xmlns='urn:ietf:params:xml:ns:xmpp-sasl' "cmVhbG09InZrLmNvbSIsbm9uY2U9IkNPS0pNTFFTIixxb3A9ImF1dGgiLGNoYXJzZXQ9dXRmLTgsYWxnb3JpdGhtPW1kNS1zZXNz" wocky-DEBUG: 11/29/2011 15:07:53.91922: auth_data_to_hash: wocky-sasl-digest-md5.c:453: Got digest-md5 challenge: realm="vk.com",nonce="COKJMLQS",qop="auth",charset=utf-8,algorithm=md5-sess wocky-DEBUG: 11/29/2011 15:07:53.91937: digest_md5_challenge_to_hash: wocky-sasl-digest-md5.c:252: challenge 'realm' = 'vk.com' wocky-DEBUG: 11/29/2011 15:07:53.91945: digest_md5_challenge_to_hash: wocky-sasl-digest-md5.c:252: challenge 'nonce' = 'COKJMLQS' wocky-DEBUG: 11/29/2011 15:07:53.91953: digest_md5_challenge_to_hash: wocky-sasl-digest-md5.c:252: challenge 'qop' = 'auth' wocky-DEBUG: 11/29/2011 15:07:53.91963: digest_md5_challenge_to_hash: wocky-sasl-digest-md5.c:252: challenge 'charset' = 'utf-8' wocky-DEBUG: 11/29/2011 15:07:53.91970: digest_md5_challenge_to_hash: wocky-sasl-digest-md5.c:252: challenge 'algorithm' = 'md5-sess' wocky-DEBUG: 11/29/2011 15:07:53.91979: md5_prepare_response: wocky-sasl-digest-md5.c:319: Got username and password wocky-DEBUG: 11/29/2011 15:07:53.92174: digest_md5_make_initial_response: wocky-sasl-digest-md5.c:412: Prepared response: username="square_circle",realm="vk.com",digest-uri="xmpp/vk.com",nonce="COKJMLQS",nc=00000001,cnonce="zQjWVWI18eziLMhxmc0RooppHM8tjs2SnutfllJlZ4k=",qop=auth,charset=utf-8,response=c75040c8d40557525cd18b4103db7714 wocky-DEBUG: 11/29/2011 15:07:53.92287: _write_node_tree: Serializing tree: * response xmlns='urn:ietf:params:xml:ns:xmpp-sasl' "dXNlcm5hbWU9InNxdWFyZV9jaXJjbGUiLHJlYWxtPSJ2ay5jb20iLGRpZ2VzdC11cmk9InhtcHAvdmsuY29tIixub25jZT0iQ09LSk1MUVMiLG5jPTAwMDAwMDAxLGNub25jZT0ielFqV1ZXSTE4ZXppTE1oeG1jMFJvb3BwSE04dGpzMlNudXRmbGxKbFo0az0iLHFvcD1hdXRoLGNoYXJzZXQ9dXRmLTgscmVzcG9uc2U9Yzc1MDQwYzhkNDA1NTc1MjVjZDE4YjQxMDNkYjc3MTQ=" wocky-DEBUG: 11/29/2011 15:07:53.245508: _end_element_ns: Received stanza * challenge xmlns='urn:ietf:params:xml:ns:xmpp-sasl' "cnNwYXV0aD0xNDEyMzM5MzU0OTZiMzMxNGQ5N2ViZTJkM2ZlMDcxYw==" wocky-DEBUG: 11/29/2011 15:07:53.245543: auth_data_to_hash: wocky-sasl-digest-md5.c:453: Got digest-md5 challenge: rspauth=141233935496b3314d97ebe2d3fe071c wocky-DEBUG: 11/29/2011 15:07:53.245557: digest_md5_challenge_to_hash: wocky-sasl-digest-md5.c:252: challenge 'rspauth' = '141233935496b3314d97ebe2d3fe071c' wocky-DEBUG: 11/29/2011 15:07:53.245637: _write_node_tree: Serializing tree: * response xmlns='urn:ietf:params:xml:ns:xmpp-sasl' wocky-DEBUG: 11/29/2011 15:07:53.313555: _end_element_ns: Received stanza * success xmlns='urn:ietf:params:xml:ns:xmpp-sasl' gabble/authentication-DEBUG: 11/29/2011 15:07:53.313585: gabble_server_sasl_channel_success_async (server-sasl-channel.c:886): gabble/authentication-DEBUG: 11/29/2011 15:07:53.314865: gabble_server_sasl_channel_accept_sasl (server-sasl-channel.c:656): client has accepted server's success wocky-DEBUG: 11/29/2011 15:07:53.315018: auth_succeeded: wocky-sasl-auth.c:246: Authentication succeeded wocky-DEBUG: 11/29/2011 15:07:53.315033: wocky_xmpp_reader_reset: wocky-xmpp-reader.c:775: Resetting the xmpp reader wocky-DEBUG: 11/29/2011 15:07:53.315099: wocky_xmpp_writer_stream_open: wocky-xmpp-writer.c:298: Writing stream opening: gabble/authentication-DEBUG: 11/29/2011 15:07:53.316365: gabble_server_sasl_channel_close (server-sasl-channel.c:965): called on 0x81f5d60 wocky-DEBUG: 11/29/2011 15:07:53.387758: handle_stream_open: wocky-xmpp-reader.c:431: Received stream opening: stream, prefix: stream, uri: http://etherx.jabber.org/streams wocky-DEBUG: 11/29/2011 15:07:53.387770: handle_stream_open: wocky-xmpp-reader.c:450: Stream opening attribute: id = 'c2s_159182' (prefix: , uri: ) wocky-DEBUG: 11/29/2011 15:07:53.387780: handle_stream_open: wocky-xmpp-reader.c:450: Stream opening attribute: from = 'vk.com' (prefix: , uri: ) wocky-DEBUG: 11/29/2011 15:07:53.387790: handle_stream_open: wocky-xmpp-reader.c:450: Stream opening attribute: version = '1.0' (prefix: , uri: ) wocky-DEBUG: 11/29/2011 15:07:53.387885: _end_element_ns: Received stanza * features xmlns='http://etherx.jabber.org/streams' * bind xmlns='urn:ietf:params:xml:ns:xmpp-bind' * session xmlns='urn:ietf:params:xml:ns:xmpp-session' wocky-DEBUG: 11/29/2011 15:07:53.388055: _write_node_tree: Serializing tree: * iq xmlns='jabber:client' type='set' id='314113387973' * bind xmlns='urn:ietf:params:xml:ns:xmpp-bind' * resource "a61fabea" wocky-DEBUG: 11/29/2011 15:07:53.460783: _end_element_ns: Received stanza * iq xmlns='jabber:client' type='result' id='314113387973' * bind xmlns='urn:ietf:params:xml:ns:xmpp-bind' * jid "square_circle@vk.com/a61fabea" wocky-DEBUG: 11/29/2011 15:07:53.460866: _write_node_tree: Serializing tree: * iq xmlns='jabber:client' type='set' id='264194460805' * session xmlns='urn:ietf:params:xml:ns:xmpp-session' wocky-DEBUG: 11/29/2011 15:07:53.529980: _end_element_ns: Received stanza * iq xmlns='jabber:client' from='vk.com' type='result' id='264194460805' gabble/connection-DEBUG: 11/29/2011 15:07:53.530010: connector_connected (connection.c:1860): connected (jid: square_circle@vk.com/a61fabea) gabble/roster-DEBUG: 11/29/2011 15:07:53.530684: gabble_roster_porter_available_cb (roster.c:1748): adding callbacks gabble/connection-DEBUG: 11/29/2011 15:07:53.531138: connector_connected (connection.c:1926): Created self handle 1, our JID is square_circle@vk.com/a61fabea gabble/presence-DEBUG: 11/29/2011 15:07:53.531219: gabble_presence_set_capabilities (presence.c:336): about to add caps to resource a61fabea with serial 3 gabble/presence-DEBUG: 11/29/2011 15:07:53.531227: gabble_presence_set_capabilities (presence.c:347): found resource a61fabea gabble/presence-DEBUG: 11/29/2011 15:07:53.531234: gabble_presence_set_capabilities (presence.c:352): new serial 3, old 2, clearing caps gabble/presence-DEBUG: 11/29/2011 15:07:53.531250: gabble_presence_set_capabilities (presence.c:360): updating caps for resource a61fabea gabble/connection-DEBUG: 11/29/2011 15:07:53.531286: gabble_connection_refresh_capabilities (connection.c:2469): nothing to do gabble/disco-DEBUG: 11/29/2011 15:07:53.531299: gabble_disco_request_with_timeout (disco.c:509): Creating disco request 0x8219e20 for vk.com wocky-DEBUG: 11/29/2011 15:07:53.531407: _write_node_tree: Serializing tree: * iq xmlns='jabber:client' type='get' to='vk.com' id='2826531338' * query xmlns='http://jabber.org/protocol/disco#info' gabble/disco-DEBUG: 11/29/2011 15:07:53.531582: gabble_disco_request_with_timeout (disco.c:509): Creating disco request 0x8219da0 for square_circle@vk.com wocky-DEBUG: 11/29/2011 15:07:53.531869: _write_node_tree: Serializing tree: * iq xmlns='jabber:client' type='get' to='square_circle@vk.com' id='2052531623' * query xmlns='http://jabber.org/protocol/disco#info' wocky-DEBUG: 11/29/2011 15:07:53.603677: _end_element_ns: Received stanza * iq xmlns='jabber:client' type='result' from='vk.com' to='square_circle@vk.com/a61fabea' id='2826531338' * query xmlns='http://jabber.org/protocol/disco#info' * identity category='server' type='im' name='VK XMPP' * feature var='http://jabber.org/protocol/disco#info' * feature var='http://jabber.org/protocol/disco#items' * feature var='http://jabber.org/protocol/disco#items' * feature var='http://jabber.org/protocol/commands' * feature var='jabber:iq:version' * feature var='http://jabber.org/protocol/chatstates' gabble/connection-DEBUG: 11/29/2011 15:07:53.603833: connection_disco_cb: got: * query xmlns='http://jabber.org/protocol/disco#info' * identity category='server' type='im' name='VK XMPP' * feature var='http://jabber.org/protocol/disco#info' * feature var='http://jabber.org/protocol/disco#items' * feature var='http://jabber.org/protocol/disco#items' * feature var='http://jabber.org/protocol/commands' * feature var='jabber:iq:version' * feature var='http://jabber.org/protocol/chatstates' gabble/connection-DEBUG: 11/29/2011 15:07:53.603852: connection_disco_cb (connection.c:2910): set features flags to 0 wocky-DEBUG: 11/29/2011 15:07:53.603935: _write_node_tree: Serializing tree: * iq xmlns='jabber:client' type='get' id='532603888' * query xmlns='jabber:iq:privacy' wocky-DEBUG: 11/29/2011 15:07:53.682589: _end_element_ns: Received stanza * iq xmlns='jabber:client' type='result' from='vk.com' to='square_circle@vk.com/a61fabea' id='2052531623' * query xmlns='http://jabber.org/protocol/disco#info' * identity category='server' type='im' name='VK XMPP' * feature var='http://jabber.org/protocol/disco#info' * feature var='http://jabber.org/protocol/disco#items' * feature var='http://jabber.org/protocol/disco#items' * feature var='http://jabber.org/protocol/commands' * feature var='jabber:iq:version' * feature var='http://jabber.org/protocol/chatstates' wocky-DEBUG: 11/29/2011 15:07:53.682641: check_spoofing: wocky-c2s-porter.c:867: 'vk.com' (normal: 'vk.com') attempts to spoof an IQ reply from 'square_circle@vk.com' wocky-DEBUG: 11/29/2011 15:07:53.682650: check_spoofing: wocky-c2s-porter.c:869: Our full JID is 'square_circle@vk.com/a61fabea' and our bare JID is 'square_circle@vk.com' wocky-DEBUG: 11/29/2011 15:07:53.682660: handle_stanza: wocky-c2s-porter.c:1011: Stanza not handled wocky-DEBUG: 11/29/2011 15:07:53.753103: _end_element_ns: Received stanza * iq xmlns='jabber:client' type='error' id='532603888' to='square_circle@vk.com/a61fabea' * query xmlns='jabber:iq:privacy' * error code='503' type='cancel' * service-unavailable xmlns='urn:ietf:params:xml:ns:xmpp-stanzas' gabble/connection-DEBUG: 11/29/2011 15:07:53.753175: get_existing_privacy_lists_cb (conn-presence.c:1116): Error getting privacy lists: the server or recipient does not currently provide the requested service gabble/presence-DEBUG: 11/29/2011 15:07:53.753453: gabble_presence_cache_add_own_caps (presence-cache.c:2164): caching our own caps (http://telepathy.freedesktop.org/caps#Nn5EUY18xWMLfOO2msyT/m0k1HU=) wocky-DEBUG: 11/29/2011 15:07:53.753551: _write_node_tree: Serializing tree: * presence xmlns='jabber:client' * x xmlns='vcard-temp:x:update' * c xmlns='http://jabber.org/protocol/caps' hash='sha-1' node='http://telepathy.freedesktop.org/caps' ver='Nn5EUY18xWMLfOO2msyT/m0k1HU=' ext='pmuc-v1 share-v1 voice-v1 video-v1 camera-v1' gabble/connection-DEBUG: 11/29/2011 15:07:59.3184: bare_jid_disco_cb (connection.c:1720): Got disco error on bare jid: Request for info on square_circle@vk.com timed out gabble/roster-DEBUG: 11/29/2011 15:07:59.3226: connection_status_changed_cb (roster.c:1781): requesting roster wocky-DEBUG: 11/29/2011 15:07:59.3343: _write_node_tree: Serializing tree: * iq xmlns='jabber:client' type='get' id='20743285' * query xmlns='jabber:iq:roster' gabble/tls-DEBUG: 11/29/2011 15:07:59.3523: connection_status_changed_cb (server-tls-manager.c:121): Connection status changed, now 0 gabble/media-channel-DEBUG: 11/29/2011 15:07:59.3552: take_stun_server (jingle-factory.c:244): Resolving fallback STUN server stun.telepathy.im:3478 gabble/disco-DEBUG: 11/29/2011 15:07:59.3659: gabble_disco_conn_status_changed_cb (disco.c:921): connected, initiating service discovery on vk.com gabble/disco-DEBUG: 11/29/2011 15:07:59.3674: gabble_disco_request_with_timeout (disco.c:509): Creating disco request 0x8219d40 for vk.com gabble/vcard-DEBUG: 11/29/2011 15:07:59.3829: gabble_vcard_manager_request (vcard-manager.c:1559): Created request 0x8219e40 to retrieve <1>'s vCard gabble/vcard-DEBUG: 11/29/2011 15:07:59.3845: request_send (vcard-manager.c:1493): Cache entry 0x82153c0 is my own, not setting @to gabble/pipeline-DEBUG: 11/29/2011 15:07:59.3880: gabble_request_pipeline_enqueue (request-pipeline.c:433): enqueued new request as item 0x821a0c0 gabble/pipeline-DEBUG: 11/29/2011 15:07:59.3890: gabble_request_pipeline_enqueue (request-pipeline.c:434): number of items in flight: 0 gabble/vcard-DEBUG: 11/29/2011 15:07:59.3907: request_send (vcard-manager.c:1514): adding request to cache entry 0x82153c0 and queueing the gabble/connection-DEBUG: 11/29/2011 15:07:59.4403: sidecars_conn_status_changed_cb (conn-sidecars.c:322): connected; requesting sidecars from plugins wocky-DEBUG: 11/29/2011 15:07:59.4659: _write_node_tree: Serializing tree: * iq xmlns='jabber:client' type='get' to='vk.com' id='25873733' * query xmlns='http://jabber.org/protocol/disco#items' gabble/media-channel-DEBUG: 11/29/2011 15:07:59.4863: stun_server_resolved_cb (jingle-factory.c:199): Resolved STUN server stun.telepathy.im:3478 to 93.93.128.223:3478 wocky-DEBUG: 11/29/2011 15:07:59.4988: _write_node_tree: Serializing tree: * iq xmlns='jabber:client' type='set' id='314312' * pubsub xmlns='http://jabber.org/protocol/pubsub' * publish node='http://laptop.org/xmpp/activities' * item * activities xmlns='http://laptop.org/xmpp/activities' wocky-DEBUG: 11/29/2011 15:07:59.5249: _write_node_tree: Serializing tree: * iq xmlns='jabber:client' type='set' id='2864374' * pubsub xmlns='http://jabber.org/protocol/pubsub' * publish node='http://laptop.org/xmpp/activity-properties' * item * activities xmlns='http://laptop.org/xmpp/activity-properties' gabble/pipeline-DEBUG: 11/29/2011 15:07:59.5460: gabble_request_pipeline_go (request-pipeline.c:390): called; 1 pending items, 0 items in flight gabble/pipeline-DEBUG: 11/29/2011 15:07:59.5470: send_next_request (request-pipeline.c:361): processing request 0x821a0c0 wocky-DEBUG: 11/29/2011 15:07:59.5532: _write_node_tree: Serializing tree: * iq xmlns='jabber:client' type='get' id='2795486' * vCard xmlns='vcard-temp' gabble/vcard-DEBUG: 11/29/2011 15:07:59.20495: gabble_vcard_manager_request (vcard-manager.c:1559): Created request 0x8244dc0 to retrieve <1>'s vCard gabble/vcard-DEBUG: 11/29/2011 15:07:59.20509: request_send (vcard-manager.c:1477): adding to cache entry 0x82153c0 with already pending gabble/vcard-DEBUG: 11/29/2011 15:07:59.41523: gabble_vcard_manager_request (vcard-manager.c:1559): Created request 0x8443580 to retrieve <1>'s vCard gabble/vcard-DEBUG: 11/29/2011 15:07:59.41536: request_send (vcard-manager.c:1477): adding to cache entry 0x82153c0 with already pending gabble/vcard-DEBUG: 11/29/2011 15:07:59.48976: gabble_vcard_manager_request (vcard-manager.c:1559): Created request 0x84435c0 to retrieve <1>'s vCard gabble/vcard-DEBUG: 11/29/2011 15:07:59.48990: request_send (vcard-manager.c:1477): adding to cache entry 0x82153c0 with already pending gabble/vcard-DEBUG: 11/29/2011 15:07:59.49545: gabble_vcard_manager_edit (vcard-manager.c:1602): called; invalidating cache gabble/vcard-DEBUG: 11/29/2011 15:07:59.49561: cache_entry_attempt_to_free (vcard-manager.c:462): Not freeing vCard cache entry 0x82153c0: it has a pipeline_item 0x821a0c0 gabble/vcard-DEBUG: 11/29/2011 15:07:59.49567: gabble_vcard_manager_edit (vcard-manager.c:1604): checking if we have pending requests already wocky-DEBUG: 11/29/2011 15:07:59.155836: _end_element_ns: Received stanza * iq xmlns='jabber:client' type='result' from='vk.com' to='square_circle@vk.com/a61fabea' id='25873733' * query xmlns='http://jabber.org/protocol/disco#items' gabble/search-DEBUG: 11/29/2011 15:07:59.155915: disco_done_cb (search-manager.c:137): Disco is done; complete pending requests wocky-DEBUG: 11/29/2011 15:07:59.813360: _end_element_ns: Received stanza * iq xmlns='jabber:client' to='square_circle@vk.com/a61fabea' type='result' id='20743285' " " * query xmlns='jabber:iq:roster' * item jid='frosty@vk.com' name='Андрей Медведев' subscription='both' * item jid='areku@vk.com' name='Алексей Макаренко' subscription='both' * item jid='id1482422@vk.com' name='Наталия Фролова' subscription='both' * item jid='kpu3uc@vk.com' name='Глеб Кондратюк' subscription='both' * item jid='radaunia@vk.com' name='Змитер Радовня' subscription='both' * item jid='id1815986@vk.com' name='Мария Щур' subscription='both' * item jid='id2782134@vk.com' name='Георгий Далин' subscription='both' * item jid='id2924724@vk.com' name='Павел Липай' subscription='both' * item jid='id3130389@vk.com' name='Виктор Кадол' subscription='both' * item jid='nutipa@vk.com' name='Алексей Ивлев' subscription='both' * item jid='id3549349@vk.com' name='Артем Озарин' subscription='both' * item jid='vitsinkevich@vk.com' name='Вит Синкевич' subscription='both' * group "Buddies" * item jid='milk.n.milk@vk.com' name='Малако Смалаком' subscription='both' * item jid='id4550173@vk.com' name='Ирина Езерская' subscription='both' * item jid='lapateen@vk.com' name='Alexandr Lapateen' subscription='both' * item jid='sunkey_mathafunkey@vk.com' name='Sunkey Mathafunkey' subscription='both' * item jid='id6763643@vk.com' name='Светлана Алексеевна' subscription='both' * item jid='id7377118@vk.com' name='Лёша Семашко' subscription='both' * item jid='id7505637@vk.com' name='Екатерина Воронцова' subscription='both' * item jid='id7553455@vk.com' name='Вячеслав Неборак' subscription='both' * item jid='id7689494@vk.com' name='Елена Пильченко' subscription='both' * item jid='id7760132@vk.com' name='Артем Кривонос' subscription='both' * item jid='id7760816@vk.com' name='Татьяна Стешина' subscription='both' * item jid='id8243586@vk.com' name='Urteco Illustrator' subscription='both' * item jid='snakesoul@vk.com' name='Ярослав Глухоторенко' subscription='both' * item jid='id8544800@vk.com' name='Иван Качур' subscription='both' * item jid='izumilkai@vk.com' name='Александра Журомски' subscription='both' * item jid='id10237395@vk.com' name='Lena Smith' subscription='both' * item jid='id11581959@vk.com' name='Максим Сырец' subscription='both' * item jid='id12315119@vk.com' name='Екатерина Селивончик' subscription='both' * item jid='id12410724@vk.com' name='Катя Биденко' subscription='both' * item jid='id12586089@vk.com' name='Макс Клименко' subscription='both' * item jid='id12744756@vk.com' name='Андрей Цыбулькин' subscription='both' * item jid='adrigo@vk.com' name='Алексей Дриго' subscription='both' * item jid='gostuchin@vk.com' name='Евгений Гостюхин' subscription='both' * item jid='id14037902@vk.com' name='Vitalia Rusetskaya' subscription='both' * item jid='alkxa@vk.com' name='Иван Ольха' subscription='both' * group "Buddies" * item jid='id15852351@vk.com' name='Таня Конышева' subscription='both' * item jid='id16502907@vk.com' name='Серёжик Зайцев' subscription='both' * item jid='id17275340@vk.com' name='Дарья Ладутько' subscription='both' * item jid='amelkacaramelka@vk.com' name='Амелька Эм' subscription='both' * item jid='id19882427@vk.com' name='Инга Ворон' subscription='both' * item jid='alllasin@vk.com' name='Allla Sinkevich' subscription='both' * item jid='id20776330@vk.com' name='Снежана Антипова' subscription='both' * item jid='adel_aida@vk.com' name='Аделаида Вернулась' subscription='both' * item jid='saint_vegan@vk.com' name='Наташка Легенькова' subscription='both' * item jid='id30274074@vk.com' name='Алексей Великанович' subscription='both' * item jid='id31595981@vk.com' name='Маша Шешко' subscription='both' * item jid='id32756627@vk.com' name='Яна Гусейнова' subscription='both' * item jid='id34052558@vk.com' name='Елена Мельченко' subscription='both' * item jid='arexche@vk.com' name='Arex Человеков' subscription='both' * item jid='londonband@vk.com' name='Дарья Филиппова' subscription='both' * item jid='id55760857@vk.com' name='Алексей Карпов' subscription='both' * item jid='sensemiliyanestle@vk.com' name='Надежда Рафальская' subscription='both' * item jid='id68212691@vk.com' name='Ксюня Лобанова' subscription='both' * item jid='chickendance@vk.com' name='Анастасия Леонова' subscription='both' * item jid='id73261001@vk.com' name='Кирилл Мухин' subscription='both' * item jid='id83273127@vk.com' name='Пограновская Ольга' subscription='both' * item jid='id87820734@vk.com' name='Кот Обормот' subscription='both' * item jid='id89844757@vk.com' name='Stas Unitsky' subscription='both' * item jid='id91620136@vk.com' name='Елена Маломальская' subscription='both' * item jid='weakfreak@vk.com' name='Егор Ващекин' subscription='both' * item jid='id99906044@vk.com' name='Анька Мазейко' subscription='both' * item jid='id102137349@vk.com' name='Сергей Селивончик' subscription='both' * item jid='id116499827@vk.com' name='Wera♥♥♥ Melnik' subscription='both' * item jid='underdrugs@vk.com' name='Riko Grin' subscription='both' * item jid='id119262622@vk.com' name='Дарья Улитковна' subscription='both' * item jid='id125453027@vk.com' name='Debra Winger' subscription='both' * item jid='id133498579@vk.com' name='Зарина Шевко' subscription='both' * item jid='id137367759@vk.com' name='Екатерина Филипчик' subscription='both' * item jid='id138784194@vk.com' name='Диана Заикина' subscription='both' * item jid='id138947571@vk.com' name='Климов Никита' subscription='both' * item jid='id149093782@vk.com' name='Ксения Субботина' subscription='both' wocky-DEBUG: 11/29/2011 15:07:59.814132: handle_iq_reply: wocky-c2s-porter.c:900: Ignored IQ reply gabble/roster-DEBUG: 11/29/2011 15:07:59.814568: _gabble_roster_item_maybe_remove (roster.c:497): contact#2 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.814982: _gabble_roster_item_maybe_remove (roster.c:497): contact#3 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.815368: _gabble_roster_item_maybe_remove (roster.c:497): contact#4 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.815768: _gabble_roster_item_maybe_remove (roster.c:497): contact#5 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.816152: _gabble_roster_item_maybe_remove (roster.c:497): contact#6 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.816526: _gabble_roster_item_maybe_remove (roster.c:497): contact#7 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.816927: _gabble_roster_item_maybe_remove (roster.c:497): contact#8 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.817459: _gabble_roster_item_maybe_remove (roster.c:497): contact#9 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.817881: _gabble_roster_item_maybe_remove (roster.c:497): contact#10 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.818259: _gabble_roster_item_maybe_remove (roster.c:497): contact#11 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.818631: _gabble_roster_item_maybe_remove (roster.c:497): contact#12 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.819044: _gabble_roster_item_maybe_remove (roster.c:497): contact#13 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.819423: _gabble_roster_item_maybe_remove (roster.c:497): contact#14 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.819813: _gabble_roster_item_maybe_remove (roster.c:497): contact#15 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.820197: _gabble_roster_item_maybe_remove (roster.c:497): contact#16 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.820570: _gabble_roster_item_maybe_remove (roster.c:497): contact#17 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.820954: _gabble_roster_item_maybe_remove (roster.c:497): contact#18 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.821322: _gabble_roster_item_maybe_remove (roster.c:497): contact#19 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.821685: _gabble_roster_item_maybe_remove (roster.c:497): contact#20 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.822072: _gabble_roster_item_maybe_remove (roster.c:497): contact#21 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.822449: _gabble_roster_item_maybe_remove (roster.c:497): contact#22 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.822844: _gabble_roster_item_maybe_remove (roster.c:497): contact#23 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.823225: _gabble_roster_item_maybe_remove (roster.c:497): contact#24 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.823591: _gabble_roster_item_maybe_remove (roster.c:497): contact#25 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.823992: _gabble_roster_item_maybe_remove (roster.c:497): contact#26 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.824368: _gabble_roster_item_maybe_remove (roster.c:497): contact#27 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.824753: _gabble_roster_item_maybe_remove (roster.c:497): contact#28 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.825134: _gabble_roster_item_maybe_remove (roster.c:497): contact#29 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.825510: _gabble_roster_item_maybe_remove (roster.c:497): contact#30 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.825901: _gabble_roster_item_maybe_remove (roster.c:497): contact#31 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.826284: _gabble_roster_item_maybe_remove (roster.c:497): contact#32 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.826663: _gabble_roster_item_maybe_remove (roster.c:497): contact#33 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.827054: _gabble_roster_item_maybe_remove (roster.c:497): contact#34 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.827430: _gabble_roster_item_maybe_remove (roster.c:497): contact#35 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.827832: _gabble_roster_item_maybe_remove (roster.c:497): contact#36 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.828198: _gabble_roster_item_maybe_remove (roster.c:497): contact#37 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.828593: _gabble_roster_item_maybe_remove (roster.c:497): contact#38 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.828989: _gabble_roster_item_maybe_remove (roster.c:497): contact#39 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.829355: _gabble_roster_item_maybe_remove (roster.c:497): contact#40 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.829760: _gabble_roster_item_maybe_remove (roster.c:497): contact#41 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.830148: _gabble_roster_item_maybe_remove (roster.c:497): contact#42 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.830521: _gabble_roster_item_maybe_remove (roster.c:497): contact#43 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.830921: _gabble_roster_item_maybe_remove (roster.c:497): contact#44 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.831300: _gabble_roster_item_maybe_remove (roster.c:497): contact#45 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.831674: _gabble_roster_item_maybe_remove (roster.c:497): contact#46 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.832058: _gabble_roster_item_maybe_remove (roster.c:497): contact#47 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.832432: _gabble_roster_item_maybe_remove (roster.c:497): contact#48 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.832835: _gabble_roster_item_maybe_remove (roster.c:497): contact#49 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.833203: _gabble_roster_item_maybe_remove (roster.c:497): contact#50 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.833558: _gabble_roster_item_maybe_remove (roster.c:497): contact#51 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.833928: _gabble_roster_item_maybe_remove (roster.c:497): contact#52 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.834435: _gabble_roster_item_maybe_remove (roster.c:497): contact#53 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.834825: _gabble_roster_item_maybe_remove (roster.c:497): contact#54 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.835199: _gabble_roster_item_maybe_remove (roster.c:497): contact#55 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.835561: _gabble_roster_item_maybe_remove (roster.c:497): contact#56 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.835958: _gabble_roster_item_maybe_remove (roster.c:497): contact#57 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.836327: _gabble_roster_item_maybe_remove (roster.c:497): contact#58 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.836692: _gabble_roster_item_maybe_remove (roster.c:497): contact#59 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.837989: _gabble_roster_item_maybe_remove (roster.c:497): contact#60 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.838376: _gabble_roster_item_maybe_remove (roster.c:497): contact#61 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.838782: _gabble_roster_item_maybe_remove (roster.c:497): contact#62 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.839149: _gabble_roster_item_maybe_remove (roster.c:497): contact#63 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.839523: _gabble_roster_item_maybe_remove (roster.c:497): contact#64 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.839915: _gabble_roster_item_maybe_remove (roster.c:497): contact#65 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.840280: _gabble_roster_item_maybe_remove (roster.c:497): contact#66 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.840658: _gabble_roster_item_maybe_remove (roster.c:497): contact#67 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.841053: _gabble_roster_item_maybe_remove (roster.c:497): contact#68 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.841422: _gabble_roster_item_maybe_remove (roster.c:497): contact#69 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.841814: _gabble_roster_item_maybe_remove (roster.c:497): contact#70 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.842190: _gabble_roster_item_maybe_remove (roster.c:497): contact#71 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.842569: _gabble_roster_item_maybe_remove (roster.c:497): contact#72 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.842952: _gabble_roster_item_maybe_remove (roster.c:497): contact#73 is still on the roster gabble/roster-DEBUG: 11/29/2011 15:07:59.843312: _gabble_roster_item_maybe_remove (roster.c:497): contact#74 is still on the roster tp-glib-CRITICAL: 11/29/2011 15:07:59.849581: tp_base_contact_list_set_list_received: assertion `self->priv->state != TP_CONTACT_LIST_STATE_SUCCESS' failed