empathyLocation-DEBUG: 10/09/2013 12:54:55.478972: resource_cb: resource-network changed empathyLocation-DEBUG: 10/09/2013 12:54:55.479028: resource_cb: resource-cell changed empathyLocation-DEBUG: 10/09/2013 12:54:55.479038: resource_cb: resource-gps changed empathyLocation-DEBUG: 10/09/2013 12:54:55.479055: accuracy_cb: reduce-accuracy changed empathyLocation-DEBUG: 10/09/2013 12:54:55.479063: publish_cb: Publish Conf changed empathyOther-DEBUG: 10/09/2013 12:54:55.514162: builder_get_file_valist: Loading file /usr/share/empathy/empathy-roster-window.ui empathyOther-DEBUG: 10/09/2013 12:54:55.527791: builder_get_file_valist: Loading file /usr/share/empathy/empathy-roster-window-menubar.ui empathyOther-DEBUG: 10/09/2013 12:54:55.605124: empathy_notify_manager_init: add capability: actions empathyOther-DEBUG: 10/09/2013 12:54:55.605133: empathy_notify_manager_init: add capability: action-icons empathyOther-DEBUG: 10/09/2013 12:54:55.605138: empathy_notify_manager_init: add capability: body empathyOther-DEBUG: 10/09/2013 12:54:55.605144: empathy_notify_manager_init: add capability: body-markup empathyOther-DEBUG: 10/09/2013 12:54:55.605149: empathy_notify_manager_init: add capability: icon-static empathyOther-DEBUG: 10/09/2013 12:54:55.605154: empathy_notify_manager_init: add capability: persistence empathyOther-DEBUG: 10/09/2013 12:54:55.605159: empathy_notify_manager_init: add capability: sound Gtk/-DEBUG: 10/09/2013 12:54:55.480103: Connecting to session manager folks/-DEBUG: 10/09/2013 12:54:55.600231: individual-aggregator.vala:387: Setting primary store IDs to defaults. folks/-DEBUG: 10/09/2013 12:54:55.600332: individual-aggregator.vala:408: Primary store IDs are 'eds' and 'system-address-book'. empathyOther-DEBUG: 10/09/2013 12:54:55.678148: list_names_cb: GNOME Shell is running, don't create status icon folks/-WARNING: 10/09/2013 12:54:55.787842: backend-store.vala:409: Error preparing Backend 'ofono': GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files empathyOther-DEBUG: 10/09/2013 12:54:55.797557: most_available_presence_changed: Presence changed to 'offline' (1) "" empathyOther-DEBUG: 10/09/2013 12:54:55.797590: empathy_presence_manager_set_presence: Changing presence to (null) (2) empathyOther-DEBUG: 10/09/2013 12:54:55.800980: empathy_presence_manager_set_presence: Changing presence to (null) (2) empathyLocation-DEBUG: 10/09/2013 12:54:55.806262: new_connection_cb: New connection (nil) empathyLocation-DEBUG: 10/09/2013 12:54:55.806649: new_connection_cb: New connection (nil) empathyLocation-DEBUG: 10/09/2013 12:54:56.209501: new_connection_cb: New connection (nil) empathyLocation-DEBUG: 10/09/2013 12:54:56.209696: new_connection_cb: New connection (nil) empathyLocation-DEBUG: 10/09/2013 12:55:00.247173: new_connection_cb: New connection 0x222e530 empathyOther-DEBUG: 10/09/2013 12:55:00.259416: most_available_presence_changed: Presence changed to 'available' (2) "" empathyLocation-DEBUG: 10/09/2013 12:55:00.794390: new_connection_cb: New connection 0x222e6e0 empathyOther-DEBUG: 10/09/2013 12:55:01.243390: builder_get_file_valist: Loading file /usr/share/empathy/empathy-individual-widget.ui empathyAccount-DEBUG: 10/09/2013 12:55:10.173691: launch_empathy_accounts: Launching empathy-accounts (if_needed: 0, hidden: 0, account: ) empathyLocation-DEBUG: 10/09/2013 12:55:13.743230: new_connection_cb: New connection (nil) empathyLocation-DEBUG: 10/09/2013 12:55:27.128401: new_connection_cb: New connection (nil) empathyOther-DEBUG: 10/09/2013 12:55:27.128606: most_available_presence_changed: Presence changed to 'offline' (1) "" empathyAccount-DEBUG: 10/09/2013 12:55:31.714292: launch_empathy_accounts: Launching empathy-accounts (if_needed: 0, hidden: 0, account: ) empathyLocation-DEBUG: 10/09/2013 12:55:34.383989: new_connection_cb: New connection (nil) empathyLocation-DEBUG: 10/09/2013 12:55:34.417982: new_connection_cb: New connection (nil) empathyLocation-DEBUG: 10/09/2013 12:55:37.348423: new_connection_cb: New connection 0x222ebf0 empathyOther-DEBUG: 10/09/2013 12:55:37.366502: most_available_presence_changed: Presence changed to 'available' (2) "" empathyLocation-DEBUG: 10/09/2013 12:55:45.668581: new_connection_cb: New connection (nil) empathyOther-DEBUG: 10/09/2013 12:55:45.668735: most_available_presence_changed: Presence changed to 'offline' (1) "" tp-glibproxy-DEBUG: 10/09/2013 12:53:57.813190: tp_proxy_constructor: 0x25808c0: request 0x1618760 represents core features on TpAccountManager tp-glibproxy-DEBUG: 10/09/2013 12:53:57.850785: tp_proxy_get_interface_by_id: 0x25808c0: org.freedesktop.Telepathy.AccountManager DBusGProxy is 0x254a920 tp-glibproxy-DEBUG: 10/09/2013 12:53:57.850871: tp_proxy_get_interface_by_id: 0x25808c0: org.freedesktop.DBus.Properties DBusGProxy is 0x254aae0 tp-glibproxy-DEBUG: 10/09/2013 12:53:57.851888: request_is_complete: 0x25808c0: calling callback for tp-account-manager-feature-core tp-glibproxy-DEBUG: 10/09/2013 12:53:57.851893: tp_proxy_poll_features: 0x25808c0: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:53:58.135767: tp_proxy_poll_features: 0x25808c0: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:53:58.135773: tp_proxy_poll_features: 0x25808c0: core features not ready yet, nothing prepared tp-glibclient-DEBUG: 10/09/2013 12:53:58.312041: tp_base_client_register: request name org.freedesktop.Telepathy.Client.GnomeShell._3a1_2e19.n0 tp-glibproxy-DEBUG: 10/09/2013 12:53:58.312683: tp_proxy_poll_features: 0x25808c0: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:53:58.312688: tp_proxy_poll_features: 0x25808c0: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:53:58.312690: tp_proxy_poll_features: 0x25808c0: core features not ready yet, nothing prepared GnomeDesktop/-DEBUG: 10/09/2013 12:53:59.720689: Updating clock because schema changed tp-glibproxy-DEBUG: 10/09/2013 12:54:00.433928: noc_idle_context_invoke: NameOwnerChanged(org.freedesktop.Telepathy.AccountManager, -> :1.26) tp-glibproxy-DEBUG: 10/09/2013 12:54:00.433936: noc_idle_context_invoke: NameOwnerChanged(org.freedesktop.Telepathy.AccountManager, -> :1.26) tp-glibaccounts-DEBUG: 10/09/2013 12:54:00.435324: _tp_account_manager_check_core_ready: manager has 0 accounts left to prepare tp-glibproxy-DEBUG: 10/09/2013 12:54:00.435336: tp_proxy_poll_features: 0x25808c0: request 0x1618760 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:00.435340: tp_proxy_prepare_request_finish: 0x1618760 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.435345: tp_proxy_poll_features: 0x25808c0: request 0x1618d40 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:00.435348: tp_proxy_prepare_request_finish: 0x1618d40 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.435360: tp_proxy_poll_features: 0x25808c0: request 0x161d7a0 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:00.435367: tp_proxy_prepare_request_finish: 0x161d7a0 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.435372: tp_proxy_poll_features: 0x25808c0: request 0x1629a40 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:00.435375: tp_proxy_prepare_request_finish: 0x1629a40 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.440834: tp_proxy_constructor: 0x2ab1020: request 0x1627900 represents core features on TpAccount tp-glibproxy-DEBUG: 10/09/2013 12:54:00.441040: tp_proxy_get_interface_by_id: 0x2ab1020: org.freedesktop.Telepathy.Account DBusGProxy is 0x2bb0d70 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.441184: tp_proxy_get_interface_by_id: 0x2ab1020: org.freedesktop.DBus.Properties DBusGProxy is 0x21f25d0 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.441757: request_is_complete: 0x2ab1020: calling callback for tp-account-feature-core tp-glibproxy-DEBUG: 10/09/2013 12:54:00.441762: tp_proxy_poll_features: 0x2ab1020: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:00.442707: tp_proxy_constructor: 0x2ab11b0: request 0x1629780 represents core features on TpAccount tp-glibproxy-DEBUG: 10/09/2013 12:54:00.442801: tp_proxy_get_interface_by_id: 0x2ab11b0: org.freedesktop.Telepathy.Account DBusGProxy is 0x21f2560 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.442919: tp_proxy_get_interface_by_id: 0x2ab11b0: org.freedesktop.DBus.Properties DBusGProxy is 0x2bb0ec0 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.443372: request_is_complete: 0x2ab11b0: calling callback for tp-account-feature-core tp-glibproxy-DEBUG: 10/09/2013 12:54:00.443376: tp_proxy_poll_features: 0x2ab11b0: core features not ready yet, nothing prepared tp-glibaccounts-DEBUG: 10/09/2013 12:54:00.445883: _tp_account_got_all_cb: Got whole set of properties for /org/freedesktop/Telepathy/Account/gabble/jabber/goa_google_account_1380290654 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.445949: tp_proxy_poll_features: 0x2ab1020: request 0x1627900 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:00.445954: tp_proxy_prepare_request_finish: 0x1627900 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.445960: request_is_complete: 0x2ab1020: calling callback for tp-account-feature-connection tp-glibproxy-DEBUG: 10/09/2013 12:54:00.446254: tp_proxy_poll_features: 0x2ab1020: request 0x162ba60 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:00.446259: tp_proxy_prepare_request_finish: 0x162ba60 tp-glibaccounts-DEBUG: 10/09/2013 12:54:00.446270: _tp_account_got_all_cb: Got whole set of properties for /org/freedesktop/Telepathy/Account/gabble/jabber/goa_google_account_1380290716 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.446305: tp_proxy_poll_features: 0x2ab11b0: request 0x1629780 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:00.446310: tp_proxy_prepare_request_finish: 0x1629780 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.446316: request_is_complete: 0x2ab11b0: calling callback for tp-account-feature-connection tp-glibproxy-DEBUG: 10/09/2013 12:54:00.447007: tp_proxy_poll_features: 0x2ab11b0: request 0x1627240 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:00.447012: tp_proxy_prepare_request_finish: 0x1627240 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.196876: tp_proxy_constructor: 0x1979220: request 0x5a5b6e0 represents core features on TpConnection tp-glibconnection-DEBUG: 10/09/2013 12:54:56.196887: tp_connection_constructed: /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5 (0x1979220) constructed tp-glibproxy-DEBUG: 10/09/2013 12:54:56.196954: tp_proxy_get_interface_by_id: 0x1979220: org.freedesktop.Telepathy.Connection DBusGProxy is 0x7ffbc4007820 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.197038: tp_proxy_get_interface_by_id: 0x1979220: org.freedesktop.DBus.Properties DBusGProxy is 0x7ffbc4007970 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.197134: request_is_complete: 0x1979220: calling callback for tp-connection-feature-core tp-glibproxy-DEBUG: 10/09/2013 12:54:56.197139: tp_proxy_poll_features: 0x1979220: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.197144: tp_proxy_poll_features: 0x1979220: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.197148: tp_proxy_poll_features: 0x1979220: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.200963: tp_proxy_constructor: 0x19793d0: request 0x5be3000 represents core features on TpConnection tp-glibconnection-DEBUG: 10/09/2013 12:54:56.200973: tp_connection_constructed: /org/freedesktop/Telepathy/Connection/gabble/jabber/corentinlecouvey_40gmail_2ecom_2f53151ac5 (0x19793d0) constructed tp-glibproxy-DEBUG: 10/09/2013 12:54:56.201014: tp_proxy_get_interface_by_id: 0x19793d0: org.freedesktop.Telepathy.Connection DBusGProxy is 0x328b4f0 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.201090: tp_proxy_get_interface_by_id: 0x19793d0: org.freedesktop.DBus.Properties DBusGProxy is 0x7ffbc40074f0 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.201154: request_is_complete: 0x19793d0: calling callback for tp-connection-feature-core tp-glibproxy-DEBUG: 10/09/2013 12:54:56.201159: tp_proxy_poll_features: 0x19793d0: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.201164: tp_proxy_poll_features: 0x19793d0: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.201168: tp_proxy_poll_features: 0x19793d0: core features not ready yet, nothing prepared tp-glibconnection-DEBUG: 10/09/2013 12:54:56.201570: tp_connection_status_changed: 0x1979220: -1 -> 1 because 0 tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253115: tp_connection_status_changed: 0x19793d0: -1 -> 1 because 0 tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253607: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactList tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253613: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Capabilities tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253618: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactBlocking tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253623: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Location tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253627: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253632: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Aliasing tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253635: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.SimplePresence tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253640: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ClientTypes tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253644: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Avatars tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253648: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Addressing1 tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253652: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactGroups tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253655: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactInfo tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253659: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactCapabilities tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253664: tp_connection_continue_introspection: CORE ready, but not CONNECTED tp-glibproxy-DEBUG: 10/09/2013 12:54:56.253670: tp_proxy_poll_features: 0x1979220: request 0x5a5b6e0 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.253675: tp_proxy_prepare_request_finish: 0x5a5b6e0 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.253681: request_is_complete: 0x1979220: calling callback for tp-connection-feature-contact-list-properties tp-glibproxy-DEBUG: 10/09/2013 12:54:56.253746: tp_proxy_get_interface_by_id: 0x1979220: org.freedesktop.Telepathy.Connection.Interface.ContactList DBusGProxy is 0x39a8320 tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253958: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactList tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253969: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Capabilities tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253974: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactBlocking tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253978: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Location tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253983: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253987: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Aliasing tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253990: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.SimplePresence tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253998: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ClientTypes tp-glibconnection-DEBUG: 10/09/2013 12:54:56.254002: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Avatars tp-glibconnection-DEBUG: 10/09/2013 12:54:56.254005: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Addressing1 tp-glibconnection-DEBUG: 10/09/2013 12:54:56.254009: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactGroups tp-glibconnection-DEBUG: 10/09/2013 12:54:56.254014: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactInfo tp-glibconnection-DEBUG: 10/09/2013 12:54:56.254018: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactCapabilities tp-glibconnection-DEBUG: 10/09/2013 12:54:56.254021: tp_connection_continue_introspection: CORE ready, but not CONNECTED tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254026: tp_proxy_poll_features: 0x19793d0: request 0x5be3000 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254030: tp_proxy_prepare_request_finish: 0x5be3000 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254034: request_is_complete: 0x19793d0: calling callback for tp-connection-feature-contact-list-properties tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254070: tp_proxy_get_interface_by_id: 0x19793d0: org.freedesktop.Telepathy.Connection.Interface.ContactList DBusGProxy is 0x39a8910 tp-glibconnection-DEBUG: 10/09/2013 12:54:56.254570: prepare_contact_list_props_cb: Got contact list properties; state=0 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254627: tp_proxy_poll_features: 0x1979220: request 0x5be2ca0 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254632: tp_proxy_prepare_request_finish: 0x5be2ca0 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254638: request_is_complete: 0x1979220: calling callback for tp-connection-feature-contact-list tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254687: tp_proxy_poll_features: 0x1979220: request 0x7ffbc001f6a0 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254692: tp_proxy_prepare_request_finish: 0x7ffbc001f6a0 tp-glibconnection-DEBUG: 10/09/2013 12:54:56.254704: prepare_contact_list_props_cb: Got contact list properties; state=0 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254976: tp_proxy_poll_features: 0x19793d0: request 0x5be3040 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254981: tp_proxy_prepare_request_finish: 0x5be3040 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254988: request_is_complete: 0x19793d0: calling callback for tp-connection-feature-contact-list tp-glibproxy-DEBUG: 10/09/2013 12:54:56.255021: tp_proxy_poll_features: 0x19793d0: request 0x5bdd880 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.255024: tp_proxy_prepare_request_finish: 0x5bdd880 tp-glibconnection-DEBUG: 10/09/2013 12:54:59.475635: on_self_handle_changed: Ignoring early self-handle change to 1, we'll pick it up later tp-glibconnection-DEBUG: 10/09/2013 12:55:00.162930: on_self_handle_changed: Ignoring early self-handle change to 1, we'll pick it up later tp-glibconnection-DEBUG: 10/09/2013 12:55:00.198086: tp_connection_status_changed: 0x1979220: 1 -> 0 because 1 tp-glibconnection-DEBUG: 10/09/2013 12:55:00.776693: tp_connection_status_changed: 0x19793d0: 1 -> 0 because 1 tp-glibproxy-DEBUG: 10/09/2013 12:55:00.902712: tp_proxy_get_interface_by_id: 0x1979220: org.freedesktop.Telepathy.Connection.Interface.Aliasing DBusGProxy is 0x40218b0 tp-glibproxy-DEBUG: 10/09/2013 12:55:00.902787: tp_proxy_get_interface_by_id: 0x1979220: org.freedesktop.Telepathy.Connection.Interface.SimplePresence DBusGProxy is 0x4021a00 tp-glibproxy-DEBUG: 10/09/2013 12:55:00.902838: tp_proxy_get_interface_by_id: 0x1979220: org.freedesktop.Telepathy.Connection.Interface.Avatars DBusGProxy is 0x4021990 tp-glibproxy-DEBUG: 10/09/2013 12:55:00.902913: tp_proxy_get_interface_by_id: 0x1979220: org.freedesktop.Telepathy.Connection.Interface.Contacts DBusGProxy is 0x39a8ad0 tp-glibproxy-DEBUG: 10/09/2013 12:55:00.903054: tp_proxy_get_interface_by_id: 0x19793d0: org.freedesktop.Telepathy.Connection.Interface.Aliasing DBusGProxy is 0x39a89f0 tp-glibproxy-DEBUG: 10/09/2013 12:55:00.903101: tp_proxy_get_interface_by_id: 0x19793d0: org.freedesktop.Telepathy.Connection.Interface.SimplePresence DBusGProxy is 0x39a8a60 tp-glibproxy-DEBUG: 10/09/2013 12:55:00.903156: tp_proxy_get_interface_by_id: 0x19793d0: org.freedesktop.Telepathy.Connection.Interface.Avatars DBusGProxy is 0x7ffbc4007410 tp-glibproxy-DEBUG: 10/09/2013 12:55:00.903234: tp_proxy_get_interface_by_id: 0x19793d0: org.freedesktop.Telepathy.Connection.Interface.Contacts DBusGProxy is 0x5d9ac80 tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.903935: contact_set_avatar_token: contact#1 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.903955: contact_update_avatar_data: contact#1 has no avatar tp-glibconnection-DEBUG: 10/09/2013 12:55:00.904025: signal_connected: /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5 (0x1979220): CORE and CONNECTED ready tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.904108: contact_set_avatar_token: contact#1 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.904114: contact_update_avatar_data: contact#1 has no avatar tp-glibconnection-DEBUG: 10/09/2013 12:55:00.904155: signal_connected: /org/freedesktop/Telepathy/Connection/gabble/jabber/corentinlecouvey_40gmail_2ecom_2f53151ac5 (0x19793d0): CORE and CONNECTED ready tp-glibconnection-DEBUG: 10/09/2013 12:55:00.968518: contact_list_state_changed_cb: contact list state changed: 3 tp-glibconnection-DEBUG: 10/09/2013 12:55:00.968525: prepare_roster: CM has the roster for connection /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5, fetch it now. tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.969703: contacts_aliases_changed: Contact "clecou35@gmail.com" alias changed from "clecou35@gmail.com" to "corentin lecouvey" tp-glibconnection-DEBUG: 10/09/2013 12:55:00.974517: got_contact_list_attributes_cb: roster fetched with 9 contacts tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974538: contact_set_avatar_token: contact#31 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974556: contact_set_subscription_states: contact#31 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974572: contact_set_avatar_token: contact#3 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974584: contact_set_subscription_states: contact#3 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974597: contact_set_avatar_token: contact#6 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974608: contact_set_subscription_states: contact#6 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974622: contact_set_avatar_token: contact#9 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974633: contact_set_subscription_states: contact#9 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974647: contact_set_avatar_token: contact#17 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974658: contact_set_subscription_states: contact#17 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974673: contact_set_avatar_token: contact#18 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974683: contact_set_subscription_states: contact#18 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974697: contact_set_avatar_token: contact#19 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974706: contact_set_subscription_states: contact#19 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974720: contact_set_avatar_token: contact#20 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974730: contact_set_subscription_states: contact#20 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974744: contact_set_avatar_token: contact#21 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974765: contact_set_subscription_states: contact#21 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.24228: contact_set_avatar_token: contact#21 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.24235: contact_update_avatar_data: contact#21 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.24367: contact_set_avatar_token: contact#6 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.24373: contact_update_avatar_data: contact#6 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.25681: contact_set_avatar_token: contact#19 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.25687: contact_update_avatar_data: contact#19 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.28004: contact_set_avatar_token: contact#20 token is 0a4e48ad363ea4d638e7361c0c8f96e2e99d2657 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.28065: connection_avatar_request_idle_cb: Request 1 avatars tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.31011: contact_set_avatar_token: contact#9 token is f565f4bf29f5081a66b8b21f4421368bc2decc4c tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.31137: connection_avatar_request_idle_cb: Request 1 avatars tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.31521: contact_set_avatar_token: contact#31 token is 0336ff725ca00fcce5df7d68484c944fc8b6ec58 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.31594: connection_avatar_request_idle_cb: Request 1 avatars tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.32546: contact_set_avatar_token: contact#17 token is 2089ca33b5dc79a0e7c223caece81579d3a95cae tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.32597: connection_avatar_request_idle_cb: Request 1 avatars tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.34884: contact_set_avatar_token: contact#3 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.34890: contact_update_avatar_data: contact#3 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.65756: contacts_aliases_changed: Contact "corentinlecouvey@gmail.com" alias changed from "corentinlecouvey@gmail.com" to "corentin lecouvey" tp-glibconnection-DEBUG: 10/09/2013 12:55:01.341097: contact_list_state_changed_cb: contact list state changed: 3 tp-glibconnection-DEBUG: 10/09/2013 12:55:01.341104: prepare_roster: CM has the roster for connection /org/freedesktop/Telepathy/Connection/gabble/jabber/corentinlecouvey_40gmail_2ecom_2f53151ac5, fetch it now. tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.354928: contact_avatar_retrieved: Contact#20 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_30a4e48ad363ea4d638e7361c0c8f96e2e99d2657, image/jpeg tp-glibconnection-DEBUG: 10/09/2013 12:55:01.355994: got_contact_list_attributes_cb: roster fetched with 34 contacts tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356029: contact_set_avatar_token: contact#244 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356044: contact_set_subscription_states: contact#244 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356056: contact_set_avatar_token: contact#2 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356065: contact_set_subscription_states: contact#2 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356074: contact_set_avatar_token: contact#4 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356081: contact_set_subscription_states: contact#4 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356090: contact_set_avatar_token: contact#5 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356096: contact_set_subscription_states: contact#5 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356106: contact_set_avatar_token: contact#250 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356112: contact_set_subscription_states: contact#250 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356122: contact_set_avatar_token: contact#7 token is 0a4e48ad363ea4d638e7361c0c8f96e2e99d2657 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356163: contact_update_avatar_data: contact#7 avatar found in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_30a4e48ad363ea4d638e7361c0c8f96e2e99d2657, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356174: contact_set_subscription_states: contact#7 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356188: contact_set_avatar_token: contact#313 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356194: contact_set_subscription_states: contact#313 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356204: contact_set_avatar_token: contact#6 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356209: contact_update_avatar_data: contact#6 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356215: contact_set_subscription_states: contact#6 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356225: contact_set_avatar_token: contact#317 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356232: contact_set_subscription_states: contact#317 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356244: contact_set_avatar_token: contact#17 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356251: contact_set_subscription_states: contact#17 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356268: contact_set_avatar_token: contact#263 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356276: contact_set_subscription_states: contact#263 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356286: contact_set_avatar_token: contact#265 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356292: contact_set_subscription_states: contact#265 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356301: contact_set_avatar_token: contact#21 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356307: contact_set_subscription_states: contact#21 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356317: contact_set_avatar_token: contact#27 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356323: contact_set_subscription_states: contact#27 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356333: contact_set_avatar_token: contact#28 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356339: contact_set_subscription_states: contact#28 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356348: contact_set_avatar_token: contact#29 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356355: contact_set_subscription_states: contact#29 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356364: contact_set_avatar_token: contact#30 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356370: contact_set_subscription_states: contact#30 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356388: contact_set_avatar_token: contact#275 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356395: contact_set_subscription_states: contact#275 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356405: contact_set_avatar_token: contact#278 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356411: contact_set_subscription_states: contact#278 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356420: contact_set_avatar_token: contact#279 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356427: contact_set_subscription_states: contact#279 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356522: contact_set_avatar_token: contact#220 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356535: contact_set_subscription_states: contact#220 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356554: contact_set_avatar_token: contact#222 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356568: contact_set_subscription_states: contact#222 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356583: contact_set_avatar_token: contact#223 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356600: contact_set_subscription_states: contact#223 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356610: contact_set_avatar_token: contact#284 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356616: contact_set_subscription_states: contact#284 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356626: contact_set_avatar_token: contact#225 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356642: contact_set_subscription_states: contact#225 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356653: contact_set_avatar_token: contact#288 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356662: contact_set_subscription_states: contact#288 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356671: contact_set_avatar_token: contact#228 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356678: contact_set_subscription_states: contact#228 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356687: contact_set_avatar_token: contact#230 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356694: contact_set_subscription_states: contact#230 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356703: contact_set_avatar_token: contact#233 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356710: contact_set_subscription_states: contact#233 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356720: contact_set_avatar_token: contact#296 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356728: contact_set_subscription_states: contact#296 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356736: contact_set_avatar_token: contact#237 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356744: contact_set_subscription_states: contact#237 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356761: contact_set_avatar_token: contact#298 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356770: contact_set_subscription_states: contact#298 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356777: contact_set_avatar_token: contact#239 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356786: contact_set_subscription_states: contact#239 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356796: contact_set_avatar_token: contact#243 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356803: contact_set_subscription_states: contact#243 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.357599: contact_set_avatar_token: contact#284 token is 4943b4be7751f77373157b762c7685181af6c2e3 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.357748: contact_set_avatar_token: contact#30 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.357753: contact_update_avatar_data: contact#30 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.357883: contact_set_avatar_token: contact#220 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.357888: contact_update_avatar_data: contact#220 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.357997: contact_set_avatar_token: contact#263 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.358004: contact_update_avatar_data: contact#263 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.358100: contact_set_avatar_token: contact#223 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.358107: contact_update_avatar_data: contact#223 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.358243: contact_set_avatar_token: contact#317 token is 96719cef8ac4bc274a6c12a87eb3246605682eb0 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.358288: connection_avatar_request_idle_cb: Request 2 avatars tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.366075: contact_set_avatar_token: contact#275 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.366080: contact_update_avatar_data: contact#275 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.366198: contact_set_avatar_token: contact#243 token is 2089ca33b5dc79a0e7c223caece81579d3a95cae tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.366329: contact_set_avatar_token: contact#4 token is d3ba9c18866b794ed17c4764f11d640c96957259 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.368886: contact_set_avatar_token: contact#222 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.368894: contact_update_avatar_data: contact#222 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369012: contact_set_avatar_token: contact#278 token is cc7e541694e20177c112d59ac6392d91f41c399e tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369137: contact_set_avatar_token: contact#21 token is 910070b49d203ebfbf234732fabe5b703bd23b38 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369278: contact_set_avatar_token: contact#228 token is 7f8008bc24f4731e6ecd1b3dcd6030fab6cd7d48 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369493: contact_set_avatar_token: contact#17 token is 0336ff725ca00fcce5df7d68484c944fc8b6ec58 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369622: contact_set_avatar_token: contact#230 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369628: contact_update_avatar_data: contact#230 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369740: contact_set_avatar_token: contact#237 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369745: contact_update_avatar_data: contact#237 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369858: contact_set_avatar_token: contact#5 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369864: contact_update_avatar_data: contact#5 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369975: contact_set_avatar_token: contact#225 token is 0427637457edcee800613e72685898b64e09e9a1 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.370107: contact_set_avatar_token: contact#233 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.370114: contact_update_avatar_data: contact#233 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.370220: contact_set_avatar_token: contact#265 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.370225: contact_update_avatar_data: contact#265 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.370332: contact_set_avatar_token: contact#250 token is adab1a9085cb62ab9f49d53e4a0e3d6f3aefe6bb tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.370381: connection_avatar_request_idle_cb: Request 8 avatars tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.379798: contact_avatar_retrieved: Contact#9 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/f565f4bf29f5081a66b8b21f4421368bc2decc4c, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.534101: contact_avatar_retrieved: Contact#31 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_30336ff725ca00fcce5df7d68484c944fc8b6ec58, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.547823: contact_avatar_retrieved: Contact#17 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_32089ca33b5dc79a0e7c223caece81579d3a95cae, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.740995: contact_avatar_retrieved: Contact#284 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_34943b4be7751f77373157b762c7685181af6c2e3, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.823762: contact_avatar_retrieved: Contact#317 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_396719cef8ac4bc274a6c12a87eb3246605682eb0, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.839045: contact_avatar_retrieved: Contact#243 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_32089ca33b5dc79a0e7c223caece81579d3a95cae, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.851994: contact_avatar_retrieved: Contact#4 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/d3ba9c18866b794ed17c4764f11d640c96957259, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.867378: contact_avatar_retrieved: Contact#278 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/cc7e541694e20177c112d59ac6392d91f41c399e, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.884376: contact_avatar_retrieved: Contact#228 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_37f8008bc24f4731e6ecd1b3dcd6030fab6cd7d48, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.895845: contact_avatar_retrieved: Contact#17 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_30336ff725ca00fcce5df7d68484c944fc8b6ec58, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.914066: contact_avatar_retrieved: Contact#21 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_3910070b49d203ebfbf234732fabe5b703bd23b38, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.928661: contact_avatar_retrieved: Contact#225 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_30427637457edcee800613e72685898b64e09e9a1, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.947709: contact_avatar_retrieved: Contact#250 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/adab1a9085cb62ab9f49d53e4a0e3d6f3aefe6bb, image/jpeg tp-glibconnection-DEBUG: 10/09/2013 12:55:13.729934: tp_connection_status_changed: 0x19793d0: 0 -> 2 because 1 tp-glibproxy-DEBUG: 10/09/2013 12:55:13.729964: tp_proxy_invalidate: 0x19793d0: User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.729974: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc4007890: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.729985: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc4007720: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.729995: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc40076b0: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.730123: tp_proxy_signal_connection_proxy_invalidated: 0x39a8980: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.730149: tp_proxy_signal_connection_proxy_invalidated: 0x328b6b0: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.730159: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc4007a50: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.730170: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc40079e0: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.730180: tp_proxy_signal_connection_proxy_invalidated: 0x4021b50: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.730189: tp_proxy_signal_connection_proxy_invalidated: 0x5d9ac10: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.730206: tp_proxy_signal_connection_proxy_invalidated: 0x5d9b090: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:19.68732: tp_proxy_invalidate: 0x2ab11b0: Account removed tp-glibproxy-DEBUG: 10/09/2013 12:55:19.68747: tp_proxy_signal_connection_proxy_invalidated: 0x270d6e0: TpProxy 0x2ab11b0 invalidated (I have 0x2ab11b0): Account removed tp-glibproxy-DEBUG: 10/09/2013 12:55:19.68775: tp_proxy_signal_connection_proxy_invalidated: 0x2606e50: TpProxy 0x2ab11b0 invalidated (I have 0x2ab11b0): Account removed tp-glibconnection-DEBUG: 10/09/2013 12:55:27.158818: tp_connection_status_changed: 0x1979220: 0 -> 2 because 1 tp-glibproxy-DEBUG: 10/09/2013 12:55:27.158829: tp_proxy_invalidate: 0x1979220: User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.158838: tp_proxy_signal_connection_proxy_invalidated: 0x2929f20: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.158853: tp_proxy_signal_connection_proxy_invalidated: 0x292d750: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.158874: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc4007900: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.158941: tp_proxy_signal_connection_proxy_invalidated: 0x39a8570: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.158957: tp_proxy_signal_connection_proxy_invalidated: 0x4021840: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.158967: tp_proxy_signal_connection_proxy_invalidated: 0x4021920: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.158977: tp_proxy_signal_connection_proxy_invalidated: 0x4021ae0: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.158988: tp_proxy_signal_connection_proxy_invalidated: 0x39a8b40: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.159009: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc4007480: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.159019: tp_proxy_signal_connection_proxy_invalidated: 0x5d9b170: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:34.445923: tp_proxy_constructor: 0x1979580: request 0x4b316e0 represents core features on TpConnection tp-glibconnection-DEBUG: 10/09/2013 12:55:34.445935: tp_connection_constructed: /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5 (0x1979580) constructed tp-glibproxy-DEBUG: 10/09/2013 12:55:34.446008: tp_proxy_get_interface_by_id: 0x1979580: org.freedesktop.Telepathy.Connection DBusGProxy is 0x39a8320 tp-glibproxy-DEBUG: 10/09/2013 12:55:34.446105: tp_proxy_get_interface_by_id: 0x1979580: org.freedesktop.DBus.Properties DBusGProxy is 0x39a8b40 tp-glibproxy-DEBUG: 10/09/2013 12:55:34.446173: request_is_complete: 0x1979580: calling callback for tp-connection-feature-core tp-glibproxy-DEBUG: 10/09/2013 12:55:34.446177: tp_proxy_poll_features: 0x1979580: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:55:34.446183: tp_proxy_poll_features: 0x1979580: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:55:34.446187: tp_proxy_poll_features: 0x1979580: core features not ready yet, nothing prepared tp-glibconnection-DEBUG: 10/09/2013 12:55:34.446883: tp_connection_status_changed: 0x1979580: -1 -> 1 because 0 tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485486: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactList tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485496: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Capabilities tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485502: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactBlocking tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485507: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Location tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485512: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485517: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Aliasing tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485522: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.SimplePresence tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485527: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ClientTypes tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485532: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Avatars tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485537: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Addressing1 tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485541: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactGroups tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485544: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactInfo tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485549: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactCapabilities tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485554: tp_connection_continue_introspection: CORE ready, but not CONNECTED tp-glibproxy-DEBUG: 10/09/2013 12:55:34.485563: tp_proxy_poll_features: 0x1979580: request 0x4b316e0 prepared tp-glibproxy-DEBUG: 10/09/2013 12:55:34.485569: tp_proxy_prepare_request_finish: 0x4b316e0 tp-glibproxy-DEBUG: 10/09/2013 12:55:34.485577: request_is_complete: 0x1979580: calling callback for tp-connection-feature-contact-list-properties tp-glibproxy-DEBUG: 10/09/2013 12:55:34.485661: tp_proxy_get_interface_by_id: 0x1979580: org.freedesktop.Telepathy.Connection.Interface.ContactList DBusGProxy is 0x39a8570 tp-glibconnection-DEBUG: 10/09/2013 12:55:34.486140: prepare_contact_list_props_cb: Got contact list properties; state=0 tp-glibproxy-DEBUG: 10/09/2013 12:55:34.486175: tp_proxy_poll_features: 0x1979580: request 0x660e460 prepared tp-glibproxy-DEBUG: 10/09/2013 12:55:34.486180: tp_proxy_prepare_request_finish: 0x660e460 tp-glibproxy-DEBUG: 10/09/2013 12:55:34.486195: request_is_complete: 0x1979580: calling callback for tp-connection-feature-contact-list tp-glibproxy-DEBUG: 10/09/2013 12:55:34.486224: tp_proxy_poll_features: 0x1979580: request 0x5c3f520 prepared tp-glibproxy-DEBUG: 10/09/2013 12:55:34.486228: tp_proxy_prepare_request_finish: 0x5c3f520 tp-glibconnection-DEBUG: 10/09/2013 12:55:36.637183: on_self_handle_changed: Ignoring early self-handle change to 1, we'll pick it up later tp-glibconnection-DEBUG: 10/09/2013 12:55:37.347315: tp_connection_status_changed: 0x1979580: 1 -> 0 because 1 tp-glibconnection-DEBUG: 10/09/2013 12:55:37.742935: contact_list_state_changed_cb: contact list state changed: 3 tp-glibconnection-DEBUG: 10/09/2013 12:55:37.742944: prepare_roster: CM has the roster for connection /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5, fetch it now. tp-glibproxy-DEBUG: 10/09/2013 12:55:37.743166: tp_proxy_get_interface_by_id: 0x1979580: org.freedesktop.Telepathy.Connection.Interface.Aliasing DBusGProxy is 0x40218b0 tp-glibproxy-DEBUG: 10/09/2013 12:55:37.743295: tp_proxy_get_interface_by_id: 0x1979580: org.freedesktop.Telepathy.Connection.Interface.SimplePresence DBusGProxy is 0x5d9b1e0 tp-glibproxy-DEBUG: 10/09/2013 12:55:37.743393: tp_proxy_get_interface_by_id: 0x1979580: org.freedesktop.Telepathy.Connection.Interface.Avatars DBusGProxy is 0x4021920 tp-glibconnection-DEBUG: 10/09/2013 12:55:37.744901: got_contact_list_attributes_cb: roster fetched with 9 contacts tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.744920: contact_set_avatar_token: contact#31 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.744932: contact_set_subscription_states: contact#31 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.744946: contact_set_avatar_token: contact#3 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.744952: contact_set_subscription_states: contact#3 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.744962: contact_set_avatar_token: contact#6 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.744970: contact_set_subscription_states: contact#6 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.744981: contact_set_avatar_token: contact#9 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.744988: contact_set_subscription_states: contact#9 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.744997: contact_set_avatar_token: contact#17 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.745004: contact_set_subscription_states: contact#17 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.745014: contact_set_avatar_token: contact#18 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.745022: contact_set_subscription_states: contact#18 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.745031: contact_set_avatar_token: contact#19 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.745038: contact_set_subscription_states: contact#19 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.745049: contact_set_avatar_token: contact#20 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.745058: contact_set_subscription_states: contact#20 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.745066: contact_set_avatar_token: contact#21 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.745074: contact_set_subscription_states: contact#21 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.120733: contact_set_avatar_token: contact#9 token is f565f4bf29f5081a66b8b21f4421368bc2decc4c tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.120841: contact_update_avatar_data: contact#9 avatar found in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/f565f4bf29f5081a66b8b21f4421368bc2decc4c, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.121102: contact_set_avatar_token: contact#6 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.121112: contact_update_avatar_data: contact#6 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.121345: contact_set_avatar_token: contact#3 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.121361: contact_update_avatar_data: contact#3 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.121669: contact_set_avatar_token: contact#20 token is 0a4e48ad363ea4d638e7361c0c8f96e2e99d2657 tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.121710: contact_update_avatar_data: contact#20 avatar found in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_30a4e48ad363ea4d638e7361c0c8f96e2e99d2657, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.121946: contact_set_avatar_token: contact#19 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.121995: contact_update_avatar_data: contact#19 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.122147: contact_set_avatar_token: contact#31 token is 0336ff725ca00fcce5df7d68484c944fc8b6ec58 tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.122174: contact_update_avatar_data: contact#31 avatar found in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_30336ff725ca00fcce5df7d68484c944fc8b6ec58, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.122298: contact_set_avatar_token: contact#17 token is 2089ca33b5dc79a0e7c223caece81579d3a95cae tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.122323: contact_update_avatar_data: contact#17 avatar found in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_32089ca33b5dc79a0e7c223caece81579d3a95cae, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.122446: contact_set_avatar_token: contact#21 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.122478: contact_update_avatar_data: contact#21 has no avatar tp-glibproxy-DEBUG: 10/09/2013 12:55:38.195677: tp_proxy_get_interface_by_id: 0x1979580: org.freedesktop.Telepathy.Connection.Interface.Contacts DBusGProxy is 0x7ffbc40079e0 tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.196340: contact_set_avatar_token: contact#1 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.196356: contact_update_avatar_data: contact#1 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.196369: contact_set_subscription_states: contact#1 state changed: subscribe=N publish=N '' tp-glibconnection-DEBUG: 10/09/2013 12:55:38.196433: signal_connected: /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5 (0x1979580): CORE and CONNECTED ready tp-glibconnection-DEBUG: 10/09/2013 12:55:45.568151: tp_connection_status_changed: 0x1979580: 0 -> 2 because 1 tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568175: tp_proxy_invalidate: 0x1979580: User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568193: tp_proxy_signal_connection_proxy_invalidated: 0x2929f20: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568219: tp_proxy_signal_connection_proxy_invalidated: 0x5d9b170: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568278: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc4007480: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568564: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc4007900: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568612: tp_proxy_signal_connection_proxy_invalidated: 0x4b330a0: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568634: tp_proxy_signal_connection_proxy_invalidated: 0x328b6b0: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568656: tp_proxy_signal_connection_proxy_invalidated: 0x4021a00: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568679: tp_proxy_signal_connection_proxy_invalidated: 0x39a8ad0: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568700: tp_proxy_signal_connection_proxy_invalidated: 0x4021840: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568720: tp_proxy_signal_connection_proxy_invalidated: 0x4021ae0: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:48.962052: tp_proxy_invalidate: 0x2ab1020: Account removed tp-glibproxy-DEBUG: 10/09/2013 12:55:48.962070: tp_proxy_signal_connection_proxy_invalidated: 0x2280570: TpProxy 0x2ab1020 invalidated (I have 0x2ab1020): Account removed tp-glibproxy-DEBUG: 10/09/2013 12:55:48.962083: tp_proxy_signal_connection_proxy_invalidated: 0x2280500: TpProxy 0x2ab1020 invalidated (I have 0x2ab1020): Account removed tp-glibproxy-DEBUG: 10/09/2013 12:56:43.304066: tp_proxy_constructor: 0x2ab1340: request 0x565e380 represents core features on TpAccount tp-glibproxy-DEBUG: 10/09/2013 12:56:43.307651: tp_proxy_get_interface_by_id: 0x2ab1340: org.freedesktop.Telepathy.Account DBusGProxy is 0x328b6b0 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.307898: tp_proxy_get_interface_by_id: 0x2ab1340: org.freedesktop.DBus.Properties DBusGProxy is 0x2bb0d70 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.308161: request_is_complete: 0x2ab1340: calling callback for tp-account-feature-core tp-glibproxy-DEBUG: 10/09/2013 12:56:43.308242: tp_proxy_poll_features: 0x2ab1340: core features not ready yet, nothing prepared tp-glibaccounts-DEBUG: 10/09/2013 12:56:43.424180: _tp_account_got_all_cb: Got whole set of properties for /org/freedesktop/Telepathy/Account/gabble/jabber/clecou35_40gmail_2ecom0 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.424277: tp_proxy_poll_features: 0x2ab1340: request 0x565e380 prepared tp-glibproxy-DEBUG: 10/09/2013 12:56:43.424326: tp_proxy_prepare_request_finish: 0x565e380 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.424354: request_is_complete: 0x2ab1340: calling callback for tp-account-feature-connection tp-glibproxy-DEBUG: 10/09/2013 12:56:43.424496: tp_proxy_poll_features: 0x2ab1340: request 0x66d2080 prepared tp-glibproxy-DEBUG: 10/09/2013 12:56:43.424544: tp_proxy_prepare_request_finish: 0x66d2080 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.424762: tp_proxy_constructor: 0x19798e0: request 0x599df40 represents core features on TpConnection tp-glibconnection-DEBUG: 10/09/2013 12:56:43.424830: tp_connection_constructed: /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5 (0x19798e0) constructed tp-glibproxy-DEBUG: 10/09/2013 12:56:43.424916: tp_proxy_get_interface_by_id: 0x19798e0: org.freedesktop.Telepathy.Connection DBusGProxy is 0x39a8ad0 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.425040: tp_proxy_get_interface_by_id: 0x19798e0: org.freedesktop.DBus.Properties DBusGProxy is 0x5d9ac10 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.425147: request_is_complete: 0x19798e0: calling callback for tp-connection-feature-core tp-glibproxy-DEBUG: 10/09/2013 12:56:43.425184: tp_proxy_poll_features: 0x19798e0: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:56:43.425210: tp_proxy_poll_features: 0x19798e0: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:56:43.425234: tp_proxy_poll_features: 0x19798e0: core features not ready yet, nothing prepared tp-glibconnection-DEBUG: 10/09/2013 12:56:43.429291: tp_connection_status_changed: 0x19798e0: -1 -> 1 because 0 tp-glibconnection-DEBUG: 10/09/2013 12:56:43.430639: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactList tp-glibconnection-DEBUG: 10/09/2013 12:56:43.430834: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Capabilities tp-glibconnection-DEBUG: 10/09/2013 12:56:43.430903: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactBlocking tp-glibconnection-DEBUG: 10/09/2013 12:56:43.430979: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Location tp-glibconnection-DEBUG: 10/09/2013 12:56:43.431103: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection tp-glibconnection-DEBUG: 10/09/2013 12:56:43.431818: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Aliasing tp-glibconnection-DEBUG: 10/09/2013 12:56:43.431843: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.SimplePresence tp-glibconnection-DEBUG: 10/09/2013 12:56:43.431868: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ClientTypes tp-glibconnection-DEBUG: 10/09/2013 12:56:43.431894: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Avatars tp-glibconnection-DEBUG: 10/09/2013 12:56:43.431917: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Addressing1 tp-glibconnection-DEBUG: 10/09/2013 12:56:43.431941: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactGroups tp-glibconnection-DEBUG: 10/09/2013 12:56:43.431965: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactInfo tp-glibconnection-DEBUG: 10/09/2013 12:56:43.431988: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactCapabilities tp-glibconnection-DEBUG: 10/09/2013 12:56:43.432020: tp_connection_continue_introspection: CORE ready, but not CONNECTED tp-glibproxy-DEBUG: 10/09/2013 12:56:43.432046: tp_proxy_poll_features: 0x19798e0: request 0x599df40 prepared tp-glibproxy-DEBUG: 10/09/2013 12:56:43.432070: tp_proxy_prepare_request_finish: 0x599df40 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.432104: request_is_complete: 0x19798e0: calling callback for tp-connection-feature-contact-list-properties tp-glibproxy-DEBUG: 10/09/2013 12:56:43.432161: tp_proxy_get_interface_by_id: 0x19798e0: org.freedesktop.Telepathy.Connection.Interface.ContactList DBusGProxy is 0x5d9b170 tp-glibconnection-DEBUG: 10/09/2013 12:56:43.533051: prepare_contact_list_props_cb: Got contact list properties; state=0 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.534415: tp_proxy_poll_features: 0x19798e0: request 0x4a2d320 prepared tp-glibproxy-DEBUG: 10/09/2013 12:56:43.534512: tp_proxy_prepare_request_finish: 0x4a2d320 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.534593: request_is_complete: 0x19798e0: calling callback for tp-connection-feature-contact-list tp-glibproxy-DEBUG: 10/09/2013 12:56:43.534699: tp_proxy_poll_features: 0x19798e0: request 0x66d3a80 prepared tp-glibproxy-DEBUG: 10/09/2013 12:56:43.534775: tp_proxy_prepare_request_finish: 0x66d3a80 tp-glibconnection-DEBUG: 10/09/2013 12:56:44.902955: tp_connection_status_changed: 0x19798e0: 1 -> 2 because 3 tp-glibproxy-DEBUG: 10/09/2013 12:56:44.903225: tp_proxy_invalidate: 0x19798e0: WOCKY_AUTH_ERROR_FAILURE (#6): Authentication failed: not-authorized tp-glibproxy-DEBUG: 10/09/2013 12:56:44.903327: tp_proxy_signal_connection_proxy_invalidated: 0x4021a00: TpProxy 0x19798e0 invalidated (I have 0x19798e0): WOCKY_AUTH_ERROR_FAILURE (#6): Authentication failed: not-authorized tp-glibproxy-DEBUG: 10/09/2013 12:56:44.903420: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc4007890: TpProxy 0x19798e0 invalidated (I have 0x19798e0): WOCKY_AUTH_ERROR_FAILURE (#6): Authentication failed: not-authorized tp-glibproxy-DEBUG: 10/09/2013 12:56:44.903549: tp_proxy_signal_connection_proxy_invalidated: 0x5d9b090: TpProxy 0x19798e0 invalidated (I have 0x19798e0): WOCKY_AUTH_ERROR_FAILURE (#6): Authentication failed: not-authorized tp-glibproxy-DEBUG: 10/09/2013 12:56:44.903762: tp_proxy_signal_connection_proxy_invalidated: 0x39a8570: TpProxy 0x19798e0 invalidated (I have 0x19798e0): WOCKY_AUTH_ERROR_FAILURE (#6): Authentication failed: not-authorized gabbleplugins-DEBUG: 10/09/2013 12:56:43.322309: gabble_plugin_loader_probe (plugin-loader.c:135): probing /usr/lib64/telepathy/gabble-0/plugins gabbleplugins-DEBUG: 10/09/2013 12:56:43.324954: plugin_loader_try_to_load (plugin-loader.c:100): loaded 'XMPP console' version 0.18.1 (/usr/lib64/telepathy/gabble-0/plugins/libconsole.so), implementing these sidecars: org.freedesktop.Telepathy.Gabble.Plugin.Console gabbleplugins-DEBUG: 10/09/2013 12:56:43.325268: plugin_loader_try_to_load (plugin-loader.c:100): loaded 'Gateway registration plugin' version 0.18.1 (/usr/lib64/telepathy/gabble-0/plugins/libgateways.so), implementing these sidecars: org.freedesktop.Telepathy.Gabble.Plugin.Gateways tp-glib/-DEBUG: 10/09/2013 12:56:43.342977: started version 0.18.1 (telepathy-glib version 0.20.4) gabbleconnection-DEBUG: 10/09/2013 12:56:43.343369: gabble_connection_class_init (connection.c:1001): Initializing (GabbleConnectionClass *)0x11f7ae0 gabbleconnection-DEBUG: 10/09/2013 12:56:43.343564: gabble_connection_init (connection.c:557): Initializing (GabbleConnection *)0x1202010 gabblepresence-DEBUG: 10/09/2013 12:56:43.343585: gabble_capabilities_init (capabilities.c:249): 0x1202010 gabblemuc-DEBUG: 10/09/2013 12:56:43.343822: gabble_roomlist_manager_constructed (roomlist-manager.c:208): 0x11ca2c0 gabblejid-DEBUG: 10/09/2013 12:56:43.343836: gabble_signal_connect_weak (util.c:666): connecting to 0x1202010:status-changed with context 0x11f66a0 gabblejid-DEBUG: 10/09/2013 12:56:43.343875: gabble_signal_connect_weak (util.c:666): connecting to 0x1202010:status-changed with context 0x11f6620 gabbletls-DEBUG: 10/09/2013 12:56:43.349265: gabble_server_tls_manager_constructed (server-tls-manager.c:454): Server TLS Manager constructed gabblejid-DEBUG: 10/09/2013 12:56:43.349986: gabble_signal_connect_weak (util.c:666): connecting to 0x1202010:status-changed with context 0x1226e00 gabbleconnection-DEBUG: 10/09/2013 12:56:43.350099: gabble_connection_constructor (connection.c:425): Post-construction: (GabbleConnection *)0x1202010 gabblejid-DEBUG: 10/09/2013 12:56:43.350363: gabble_signal_connect_weak (util.c:666): connecting to 0x11ca580:item-found with context 0x1230820 gabblejid-DEBUG: 10/09/2013 12:56:43.350370: gabble_signal_connect_weak (util.c:666): connecting to 0x1202010:status-changed with context 0x1230880 gabbleconnection-DEBUG: 10/09/2013 12:56:43.350425: gabble_connection_constructed (connection.c:540): defaulted resource to 53151ac5 wocky/-DEBUG: 10/09/2013 12:56:43.476326: wocky_xmpp_writer_stream_open: wocky-xmpp-writer.c:302: Writing stream opening: wocky/-DEBUG: 10/09/2013 12:56:43.561335: handle_stream_open: wocky-xmpp-reader.c:481: Received stream opening: stream, prefix: stream, uri: http://etherx.jabber.org/streams wocky/-DEBUG: 10/09/2013 12:56:43.561522: handle_stream_open: wocky-xmpp-reader.c:500: Stream opening attribute: from = 'gmail.com' (prefix: , uri: ) wocky/-DEBUG: 10/09/2013 12:56:43.561548: handle_stream_open: wocky-xmpp-reader.c:500: Stream opening attribute: id = 'E32B7BD30E83DAA7' (prefix: , uri: ) wocky/-DEBUG: 10/09/2013 12:56:43.561584: handle_stream_open: wocky-xmpp-reader.c:500: Stream opening attribute: version = '1.0' (prefix: , uri: ) wocky/-DEBUG: 10/09/2013 12:56:43.561728: _end_element_ns: Received stanza * features xmlns='http://etherx.jabber.org/streams' * starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls' * required * mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl' * mechanism "X-OAUTH2" * mechanism "X-GOOGLE-TOKEN" wocky/-DEBUG: 10/09/2013 12:56:43.561795: _write_node_tree: Serializing tree: * starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls' wocky/-DEBUG: 10/09/2013 12:56:43.641169: _end_element_ns: Received stanza * proceed xmlns='urn:ietf:params:xml:ns:xmpp-tls' gabbletls-DEBUG: 10/09/2013 12:56:43.975796: gabble_server_tls_manager_verify_async (server-tls-manager.c:341): verify_async() called on the GabbleServerTLSManager. gabbletls-DEBUG: 10/09/2013 12:56:43.976249: gabble_server_tls_channel_constructed (server-tls-channel.c:202): Server TLS channel constructed at /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5/ServerTLSChannel1 gabbletls-DEBUG: 10/09/2013 12:56:44.347712: gabble_tls_certificate_accept (tls-certificate.c:264): Accept() called on the TLS certificate; current state 0 gabbletls-DEBUG: 10/09/2013 12:56:44.347764: tls_certificate_accepted_cb (server-tls-manager.c:243): TLS certificate accepted wocky/-DEBUG: 10/09/2013 12:56:44.347848: wocky_xmpp_writer_stream_open: wocky-xmpp-writer.c:302: Writing stream opening: wocky/-DEBUG: 10/09/2013 12:56:44.454238: handle_stream_open: wocky-xmpp-reader.c:481: Received stream opening: stream, prefix: stream, uri: http://etherx.jabber.org/streams wocky/-DEBUG: 10/09/2013 12:56:44.454298: handle_stream_open: wocky-xmpp-reader.c:500: Stream opening attribute: from = 'gmail.com' (prefix: , uri: ) wocky/-DEBUG: 10/09/2013 12:56:44.454334: handle_stream_open: wocky-xmpp-reader.c:500: Stream opening attribute: id = '73C50EF275260068' (prefix: , uri: ) wocky/-DEBUG: 10/09/2013 12:56:44.454358: handle_stream_open: wocky-xmpp-reader.c:500: Stream opening attribute: version = '1.0' (prefix: , uri: ) wocky/-DEBUG: 10/09/2013 12:56:44.454526: _end_element_ns: Received stanza * features xmlns='http://etherx.jabber.org/streams' * mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl' * mechanism "X-OAUTH2" * mechanism "X-GOOGLE-TOKEN" * mechanism "PLAIN" gabbleauthentication-DEBUG: 10/09/2013 12:56:44.454771: gabble_server_sasl_channel_start_auth_async (server-sasl-channel.c:836): Starting authentication gabbleauthentication-DEBUG: 10/09/2013 12:56:44.483216: gabble_server_sasl_channel_start_mechanism_with_data (server-sasl-channel.c:548): Starting X-TELEPATHY-PASSWORD authentication with 10 bytes of initial data wocky/-DEBUG: 10/09/2013 12:56:44.483293: wocky_auth_registry_select_handler: wocky-auth-registry.c:290: Choosing PLAIN as auth mechanism wocky/-DEBUG: 10/09/2013 12:56:44.483371: plain_initial_response: wocky-sasl-plain.c:168: Got username and password wocky/-DEBUG: 10/09/2013 12:56:44.483433: _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='PLAIN' "AGNsZWNvdTM1AENsZWNvdTc0NTQ=" wocky/-DEBUG: 10/09/2013 12:56:44.838169: _end_element_ns: Received stanza * failure xmlns='urn:ietf:params:xml:ns:xmpp-sasl' * not-authorized wocky/-DEBUG: 10/09/2013 12:56:44.838238: auth_failed: wocky-sasl-auth.c:274: Authentication failed!: Authentication failed: not-authorized gabbleauthentication-DEBUG: 10/09/2013 12:56:44.838294: gabble_server_sasl_channel_fail (server-sasl-channel.c:959): auth failed: WOCKY_AUTH_ERROR_FAILURE (#6): Authentication failed: not-authorized gabbleconnection-DEBUG: 10/09/2013 12:56:44.838371: connector_error_disconnect (connection.c:1764): Interactive authentication error, reason 3, dbus error org.freedesktop.Telepathy.Error.AuthenticationFailed gabbleroster-DEBUG: 10/09/2013 12:56:44.838433: gabble_roster_close_all (roster.c:1672): closing channels gabblemuc-DEBUG: 10/09/2013 12:56:44.838506: connection_status_changed_cb (roomlist-manager.c:191): disconnected, closing room lists gabblemuc-DEBUG: 10/09/2013 12:56:44.838540: gabble_roomlist_manager_close_all (roomlist-manager.c:85): 0x11ca2c0 gabblesearch-DEBUG: 10/09/2013 12:56:44.838566: gabble_search_manager_close_all (search-manager.c:99): closing channels gabbleauthentication-DEBUG: 10/09/2013 12:56:44.838592: gabble_auth_manager_close_all (auth-manager.c:92): called gabbleauthentication-DEBUG: 10/09/2013 12:56:44.838617: gabble_server_sasl_channel_close (server-sasl-channel.c:998): called on 0x11d6dc0 gabbletls-DEBUG: 10/09/2013 12:56:44.838685: connection_status_changed_cb (server-tls-manager.c:147): Connection status changed, now 2 gabbletls-DEBUG: 10/09/2013 12:56:44.838712: gabble_server_tls_channel_close (server-tls-channel.c:300): Close() called on the TLS channel 0x11d2360 gabbletls-DEBUG: 10/09/2013 12:56:44.838742: server_tls_channel_closed_cb (server-tls-manager.c:197): Server TLS channel closed. gabbletls-DEBUG: 10/09/2013 12:56:44.838783: gabble_server_tls_channel_dispose (server-tls-channel.c:140): Dispose TLS channel gabbletls-DEBUG: 10/09/2013 12:56:44.838818: gabble_server_tls_channel_finalize (server-tls-channel.c:123): Finalize TLS channel gabblemuc-DEBUG: 10/09/2013 12:56:44.838864: gabble_muc_factory_close_all (muc-factory.c:892): closing channels gabbletubes-DEBUG: 10/09/2013 12:56:44.838891: gabble_private_tubes_factory_close_all (private-tubes-factory.c:374): closing 1-1 tubes channels gabblemedia-channel-DEBUG: 10/09/2013 12:56:44.838918: gabble_media_factory_close_all (media-factory.c:423): closing channels gabbleconnection-DEBUG: 10/09/2013 12:56:44.838963: connection_shut_down (connection.c:2367): neither porter nor connector is alive: clean up the base connection gabbleconnection-DEBUG: 10/09/2013 12:56:44.845227: gabble_connection_dispose (connection.c:1269): called gabblebytestream-DEBUG: 10/09/2013 12:56:44.845263: gabble_bytestream_factory_dispose (bytestream-factory.c:683): dispose called gabblejid-DEBUG: 10/09/2013 12:56:44.845294: user_data_destroyed_cb (util.c:635): user_data for 0x1230820 destroyed; disconnecting gabblejid-DEBUG: 10/09/2013 12:56:44.845319: user_data_destroyed_cb (util.c:635): user_data for 0x1230880 destroyed; disconnecting gabbledisco-DEBUG: 10/09/2013 12:56:44.845345: gabble_disco_dispose (disco.c:227): dispose called gabblejid-DEBUG: 10/09/2013 12:56:44.845369: instance_destroyed_cb (util.c:622): instance for 0x1241c20 destroyed; cleaning up gabblejid-DEBUG: 10/09/2013 12:56:44.845392: instance_destroyed_cb (util.c:622): instance for 0x1241840 destroyed; cleaning up gabbledisco-DEBUG: 10/09/2013 12:56:44.845416: gabble_disco_finalize (disco.c:254): called with 0x11ca580 gabblepipeline-DEBUG: 10/09/2013 12:56:44.845499: gabble_request_pipeline_dispose (request-pipeline.c:275): disposing request-pipeline gabblevcard-DEBUG: 10/09/2013 12:56:44.845647: gabble_vcard_manager_dispose (vcard-manager.c:548): 0x7fc20c003630 gabblevcard-DEBUG: 10/09/2013 12:56:44.845678: gabble_vcard_manager_finalize (vcard-manager.c:579): 0x7fc20c003630 gabblepresence-DEBUG: 10/09/2013 12:56:44.845714: gabble_presence_cache_dispose (presence-cache.c:548): dispose called gabblepresence-DEBUG: 10/09/2013 12:56:44.845765: gabble_presence_cache_finalize (presence-cache.c:579): called with 0x122d030 gabbleroster-DEBUG: 10/09/2013 12:56:44.845824: gabble_roster_dispose (roster.c:200): dispose called gabbleroster-DEBUG: 10/09/2013 12:56:44.845849: gabble_roster_close_all (roster.c:1672): closing channels gabbleroster-DEBUG: 10/09/2013 12:56:44.845875: gabble_roster_finalize (roster.c:221): called with 0x1207040 gabbleim-DEBUG: 10/09/2013 12:56:44.845901: gabble_im_factory_dispose (im-factory.c:120): dispose called gabblemuc-DEBUG: 10/09/2013 12:56:44.845940: gabble_roomlist_manager_dispose (roomlist-manager.c:127): running gabblemuc-DEBUG: 10/09/2013 12:56:44.845963: gabble_roomlist_manager_close_all (roomlist-manager.c:85): 0x11ca2c0 gabblesearch-DEBUG: 10/09/2013 12:56:44.845988: gabble_search_manager_dispose (search-manager.c:213): dispose called gabblesearch-DEBUG: 10/09/2013 12:56:44.846010: gabble_search_manager_close_all (search-manager.c:99): closing channels gabblejid-DEBUG: 10/09/2013 12:56:44.846045: user_data_destroyed_cb (util.c:635): user_data for 0x11f66a0 destroyed; disconnecting gabblemuc-DEBUG: 10/09/2013 12:56:44.846072: gabble_muc_factory_dispose (muc-factory.c:148): dispose called gabblemuc-DEBUG: 10/09/2013 12:56:44.846096: gabble_muc_factory_close_all (muc-factory.c:892): closing channels gabbletubes-DEBUG: 10/09/2013 12:56:44.846124: gabble_private_tubes_factory_dispose (private-tubes-factory.c:288): dispose called gabbletubes-DEBUG: 10/09/2013 12:56:44.846148: gabble_private_tubes_factory_close_all (private-tubes-factory.c:374): closing 1-1 tubes channels gabblemedia-channel-DEBUG: 10/09/2013 12:56:44.846174: gabble_media_factory_dispose (media-factory.c:158): dispose called gabblemedia-channel-DEBUG: 10/09/2013 12:56:44.846199: gabble_media_factory_close_all (media-factory.c:423): closing channels gabblejid-DEBUG: 10/09/2013 12:56:44.846246: instance_destroyed_cb (util.c:622): instance for 0x1226e00 destroyed; cleaning up gabblejid-DEBUG: 10/09/2013 12:56:44.846271: instance_destroyed_cb (util.c:622): instance for 0x11f6620 destroyed; cleaning up gabbleconnection-DEBUG: 10/09/2013 12:56:44.846295: gabble_connection_finalize (connection.c:1348): called with 0x1202010 gabblepresence-DEBUG: 10/09/2013 12:56:44.846348: gabble_capabilities_finalize (capabilities.c:337): 0x1202010 gabbletls-DEBUG: 10/09/2013 12:56:44.846399: gabble_server_tls_manager_dispose (server-tls-manager.c:416): 0x11d6a00 gabbletls-DEBUG: 10/09/2013 12:56:44.846426: gabble_server_tls_manager_finalize (server-tls-manager.c:434): 0x11d6a00 gabbleauthentication-DEBUG: 10/09/2013 12:56:44.846487: gabble_auth_manager_dispose (auth-manager.c:161): dispose called gabbleauthentication-DEBUG: 10/09/2013 12:56:44.846518: gabble_auth_manager_close_all (auth-manager.c:92): called tp-glib/-DEBUG: 10/09/2013 12:56:49.843542: no connections, and timed out tp-glib/-MESSAGE: 10/09/2013 12:56:49.843769: Exiting empathyTls-DEBUG: 10/09/2013 12:56:44.49231: main: Empathy auth client started. empathyTls-DEBUG: 10/09/2013 12:56:44.52829: start_timer: Start timer empathyTls-DEBUG: 10/09/2013 12:56:44.61500: handle_channels: Handle TLS or SASL carrier channels. empathyTls-DEBUG: 10/09/2013 12:56:44.61629: tls_handler_init_async: Received hostname: gmail.com empathyTls-DEBUG: 10/09/2013 12:56:44.61640: tls_handler_init_async: Received reference identities: gmail.com, talk.google.com, talk.google.com empathyTls-DEBUG: 10/09/2013 12:56:44.61650: tls_handler_init_async: Creating an TpTLSCertificate for path /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5/ServerTLSChannel1/TLSCertificateObject, bus name :1.70 empathyTls-DEBUG: 10/09/2013 12:56:44.63486: auth_factory_new_tls_handler_cb: New TLS server handler received from the factory empathyTls-DEBUG: 10/09/2013 12:56:44.63522: empathy_tls_verifier_verify_async: Starting verification empathyTls-DEBUG: 10/09/2013 12:56:44.64285: empathy_server_tls_handler_finalize: 0x7f99600025e0 /-DEBUG: 10/09/2013 12:56:44.317684: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.317894: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.318213: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.318317: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.318620: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.318722: generalTime generalTime: decoded tag did not match expected empathyTls-DEBUG: 10/09/2013 12:56:44.338084: perform_verification: Performing verification empathyTls-DEBUG: 10/09/2013 12:56:44.338149: debug_certificate_chain: Certificate chain: length 4 status anchored empathyTls-DEBUG: 10/09/2013 12:56:44.338587: debug_certificate: Certificate: C=US, ST=California, L=Mountain View, O=Google Inc, CN=gmail.com empathyTls-DEBUG: 10/09/2013 12:56:44.338790: debug_certificate: Certificate: C=US, O=Google Inc, CN=Google Internet Authority G2 empathyTls-DEBUG: 10/09/2013 12:56:44.338975: debug_certificate: Certificate: C=US, O=GeoTrust Inc., CN=GeoTrust Global CA empathyTls-DEBUG: 10/09/2013 12:56:44.339361: debug_certificate: Certificate: C=US, O=Equifax, OU=Equifax Secure Certificate Authority empathyTls-DEBUG: 10/09/2013 12:56:44.343544: perform_verification: Certificate verification gave result 1 with reason 0 empathyTls-DEBUG: 10/09/2013 12:56:44.343674: perform_verification: Hostname matched empathyTls-DEBUG: 10/09/2013 12:56:44.343744: complete_verification: Verification successful, completing... /-DEBUG: 10/09/2013 12:56:44.338294: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338300: printableString printableString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338304: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338309: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338313: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338363: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338367: printableString printableString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338371: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338376: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338380: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338423: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338427: printableString printableString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338430: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338442: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338445: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338556: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338567: printableString printableString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338572: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338576: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338578: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338691: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338696: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338700: utf8String utf8String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338704: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338707: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338768: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338773: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338777: utf8String utf8String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338781: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338783: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338892: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338897: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338901: utf8String utf8String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338905: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338908: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338954: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338958: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338963: utf8String utf8String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338965: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338969: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339145: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339152: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339276: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339279: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339283: utf8String utf8String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339287: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339291: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339339: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339344: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339346: utf8String utf8String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339350: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339354: ia5String ia5String: decoded tag did not match expected empathyTls-DEBUG: 10/09/2013 12:56:44.348227: empathy_tls_verifier_finalize: 0xe398a0 empathyTls-DEBUG: 10/09/2013 12:56:44.459284: observe_channels: New auth channel to observe empathyOther-DEBUG: 10/09/2013 12:56:44.459496: empathy_keyring_get_account_password_async: Trying to get password for: gabble/jabber/clecou35_40gmail_2ecom0 empathyTls-DEBUG: 10/09/2013 12:56:44.479058: get_password_cb: We have a password for account /org/freedesktop/Telepathy/Account/gabble/jabber/clecou35_40gmail_2ecom0, calling Claim empathyTls-DEBUG: 10/09/2013 12:56:44.479675: password_claim_cb: Claim called successfully empathyOther-DEBUG: 10/09/2013 12:56:44.479795: empathy_keyring_get_account_password_async: Trying to get password for: gabble/jabber/clecou35_40gmail_2ecom0 empathyTls-DEBUG: 10/09/2013 12:56:44.482691: auth_factory_new_sasl_handler_cb: New SASL server handler received from the factory empathySasl-DEBUG: 10/09/2013 12:56:44.483000: empathy_sasl_auth_password_async: Start X-TELEPATHY-PASSWORD mechanism empathySasl-DEBUG: 10/09/2013 12:56:44.483788: empathy_server_sasl_handler_provide_password: not remembering the password empathySasl-DEBUG: 10/09/2013 12:56:44.483956: channel_has_may_save_response: MaySaveResponse unknown, assuming TRUE empathySasl-DEBUG: 10/09/2013 12:56:44.851447: sasl_status_changed_cb: SASL failed: WOCKY_AUTH_ERROR_FAILURE (#6): Authentication failed: not-authorized empathyTls-DEBUG: 10/09/2013 12:56:44.851553: auth_factory_auth_passsword_failed: Authentication on gabble/jabber/clecou35_40gmail_2ecom0 failed, popup password dialog empathySasl-DEBUG: 10/09/2013 12:56:45.95393: base_password_dialog_grab_keyboard: Could not get the event device! empathySasl-DEBUG: 10/09/2013 12:56:45.95755: base_password_dialog_grab_keyboard: Could not get the event device! empathyTls-DEBUG: 10/09/2013 12:56:45.98056: sasl_handler_invalidated_cb: SASL handler for channel /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5/ServerSASLChannel is invalidated, unref it empathySasl-DEBUG: 10/09/2013 12:56:45.98146: empathy_server_sasl_handler_dispose: 0x7f9968003e30 empathySasl-DEBUG: 10/09/2013 12:56:45.98232: empathy_server_sasl_handler_finalize: 0x7f9968003e30 empathySasl-DEBUG: 10/09/2013 12:56:45.151520: base_password_dialog_grab_keyboard: Could not get the event device! empathySasl-DEBUG: 10/09/2013 12:56:45.155124: base_password_dialog_grab_keyboard: Could not get the event device! empathySasl-DEBUG: 10/09/2013 12:56:45.213064: base_password_dialog_grab_keyboard: Could not get the event device! empathySasl-DEBUG: 10/09/2013 12:56:49.730067: base_password_dialog_grab_keyboard: Could not get the event device! empathySasl-DEBUG: 10/09/2013 12:56:52.660825: base_password_dialog_grab_keyboard: Could not get the event device! ############################################################################################################## LOG AFTER ENTERING THE CORRECT PASSWORD ... ############################################################################################################## empathyLocation-DEBUG: 10/09/2013 12:54:55.478972: resource_cb: resource-network changed empathyLocation-DEBUG: 10/09/2013 12:54:55.479028: resource_cb: resource-cell changed empathyLocation-DEBUG: 10/09/2013 12:54:55.479038: resource_cb: resource-gps changed empathyLocation-DEBUG: 10/09/2013 12:54:55.479055: accuracy_cb: reduce-accuracy changed empathyLocation-DEBUG: 10/09/2013 12:54:55.479063: publish_cb: Publish Conf changed empathyOther-DEBUG: 10/09/2013 12:54:55.514162: builder_get_file_valist: Loading file /usr/share/empathy/empathy-roster-window.ui empathyOther-DEBUG: 10/09/2013 12:54:55.527791: builder_get_file_valist: Loading file /usr/share/empathy/empathy-roster-window-menubar.ui empathyOther-DEBUG: 10/09/2013 12:54:55.605124: empathy_notify_manager_init: add capability: actions empathyOther-DEBUG: 10/09/2013 12:54:55.605133: empathy_notify_manager_init: add capability: action-icons empathyOther-DEBUG: 10/09/2013 12:54:55.605138: empathy_notify_manager_init: add capability: body empathyOther-DEBUG: 10/09/2013 12:54:55.605144: empathy_notify_manager_init: add capability: body-markup empathyOther-DEBUG: 10/09/2013 12:54:55.605149: empathy_notify_manager_init: add capability: icon-static empathyOther-DEBUG: 10/09/2013 12:54:55.605154: empathy_notify_manager_init: add capability: persistence empathyOther-DEBUG: 10/09/2013 12:54:55.605159: empathy_notify_manager_init: add capability: sound Gtk/-DEBUG: 10/09/2013 12:54:55.480103: Connecting to session manager folks/-DEBUG: 10/09/2013 12:54:55.600231: individual-aggregator.vala:387: Setting primary store IDs to defaults. folks/-DEBUG: 10/09/2013 12:54:55.600332: individual-aggregator.vala:408: Primary store IDs are 'eds' and 'system-address-book'. empathyOther-DEBUG: 10/09/2013 12:54:55.678148: list_names_cb: GNOME Shell is running, don't create status icon folks/-WARNING: 10/09/2013 12:54:55.787842: backend-store.vala:409: Error preparing Backend 'ofono': GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files empathyOther-DEBUG: 10/09/2013 12:54:55.797557: most_available_presence_changed: Presence changed to 'offline' (1) "" empathyOther-DEBUG: 10/09/2013 12:54:55.797590: empathy_presence_manager_set_presence: Changing presence to (null) (2) empathyOther-DEBUG: 10/09/2013 12:54:55.800980: empathy_presence_manager_set_presence: Changing presence to (null) (2) empathyLocation-DEBUG: 10/09/2013 12:54:55.806262: new_connection_cb: New connection (nil) empathyLocation-DEBUG: 10/09/2013 12:54:55.806649: new_connection_cb: New connection (nil) empathyLocation-DEBUG: 10/09/2013 12:54:56.209501: new_connection_cb: New connection (nil) empathyLocation-DEBUG: 10/09/2013 12:54:56.209696: new_connection_cb: New connection (nil) empathyLocation-DEBUG: 10/09/2013 12:55:00.247173: new_connection_cb: New connection 0x222e530 empathyOther-DEBUG: 10/09/2013 12:55:00.259416: most_available_presence_changed: Presence changed to 'available' (2) "" empathyLocation-DEBUG: 10/09/2013 12:55:00.794390: new_connection_cb: New connection 0x222e6e0 empathyOther-DEBUG: 10/09/2013 12:55:01.243390: builder_get_file_valist: Loading file /usr/share/empathy/empathy-individual-widget.ui empathyAccount-DEBUG: 10/09/2013 12:55:10.173691: launch_empathy_accounts: Launching empathy-accounts (if_needed: 0, hidden: 0, account: ) empathyLocation-DEBUG: 10/09/2013 12:55:13.743230: new_connection_cb: New connection (nil) empathyLocation-DEBUG: 10/09/2013 12:55:27.128401: new_connection_cb: New connection (nil) empathyOther-DEBUG: 10/09/2013 12:55:27.128606: most_available_presence_changed: Presence changed to 'offline' (1) "" empathyAccount-DEBUG: 10/09/2013 12:55:31.714292: launch_empathy_accounts: Launching empathy-accounts (if_needed: 0, hidden: 0, account: ) empathyLocation-DEBUG: 10/09/2013 12:55:34.383989: new_connection_cb: New connection (nil) empathyLocation-DEBUG: 10/09/2013 12:55:34.417982: new_connection_cb: New connection (nil) empathyLocation-DEBUG: 10/09/2013 12:55:37.348423: new_connection_cb: New connection 0x222ebf0 empathyOther-DEBUG: 10/09/2013 12:55:37.366502: most_available_presence_changed: Presence changed to 'available' (2) "" empathyLocation-DEBUG: 10/09/2013 12:55:45.668581: new_connection_cb: New connection (nil) empathyOther-DEBUG: 10/09/2013 12:55:45.668735: most_available_presence_changed: Presence changed to 'offline' (1) "" tp-glibproxy-DEBUG: 10/09/2013 12:53:57.813190: tp_proxy_constructor: 0x25808c0: request 0x1618760 represents core features on TpAccountManager tp-glibproxy-DEBUG: 10/09/2013 12:53:57.850785: tp_proxy_get_interface_by_id: 0x25808c0: org.freedesktop.Telepathy.AccountManager DBusGProxy is 0x254a920 tp-glibproxy-DEBUG: 10/09/2013 12:53:57.850871: tp_proxy_get_interface_by_id: 0x25808c0: org.freedesktop.DBus.Properties DBusGProxy is 0x254aae0 tp-glibproxy-DEBUG: 10/09/2013 12:53:57.851888: request_is_complete: 0x25808c0: calling callback for tp-account-manager-feature-core tp-glibproxy-DEBUG: 10/09/2013 12:53:57.851893: tp_proxy_poll_features: 0x25808c0: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:53:58.135767: tp_proxy_poll_features: 0x25808c0: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:53:58.135773: tp_proxy_poll_features: 0x25808c0: core features not ready yet, nothing prepared tp-glibclient-DEBUG: 10/09/2013 12:53:58.312041: tp_base_client_register: request name org.freedesktop.Telepathy.Client.GnomeShell._3a1_2e19.n0 tp-glibproxy-DEBUG: 10/09/2013 12:53:58.312683: tp_proxy_poll_features: 0x25808c0: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:53:58.312688: tp_proxy_poll_features: 0x25808c0: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:53:58.312690: tp_proxy_poll_features: 0x25808c0: core features not ready yet, nothing prepared GnomeDesktop/-DEBUG: 10/09/2013 12:53:59.720689: Updating clock because schema changed tp-glibproxy-DEBUG: 10/09/2013 12:54:00.433928: noc_idle_context_invoke: NameOwnerChanged(org.freedesktop.Telepathy.AccountManager, -> :1.26) tp-glibproxy-DEBUG: 10/09/2013 12:54:00.433936: noc_idle_context_invoke: NameOwnerChanged(org.freedesktop.Telepathy.AccountManager, -> :1.26) tp-glibaccounts-DEBUG: 10/09/2013 12:54:00.435324: _tp_account_manager_check_core_ready: manager has 0 accounts left to prepare tp-glibproxy-DEBUG: 10/09/2013 12:54:00.435336: tp_proxy_poll_features: 0x25808c0: request 0x1618760 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:00.435340: tp_proxy_prepare_request_finish: 0x1618760 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.435345: tp_proxy_poll_features: 0x25808c0: request 0x1618d40 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:00.435348: tp_proxy_prepare_request_finish: 0x1618d40 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.435360: tp_proxy_poll_features: 0x25808c0: request 0x161d7a0 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:00.435367: tp_proxy_prepare_request_finish: 0x161d7a0 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.435372: tp_proxy_poll_features: 0x25808c0: request 0x1629a40 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:00.435375: tp_proxy_prepare_request_finish: 0x1629a40 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.440834: tp_proxy_constructor: 0x2ab1020: request 0x1627900 represents core features on TpAccount tp-glibproxy-DEBUG: 10/09/2013 12:54:00.441040: tp_proxy_get_interface_by_id: 0x2ab1020: org.freedesktop.Telepathy.Account DBusGProxy is 0x2bb0d70 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.441184: tp_proxy_get_interface_by_id: 0x2ab1020: org.freedesktop.DBus.Properties DBusGProxy is 0x21f25d0 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.441757: request_is_complete: 0x2ab1020: calling callback for tp-account-feature-core tp-glibproxy-DEBUG: 10/09/2013 12:54:00.441762: tp_proxy_poll_features: 0x2ab1020: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:00.442707: tp_proxy_constructor: 0x2ab11b0: request 0x1629780 represents core features on TpAccount tp-glibproxy-DEBUG: 10/09/2013 12:54:00.442801: tp_proxy_get_interface_by_id: 0x2ab11b0: org.freedesktop.Telepathy.Account DBusGProxy is 0x21f2560 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.442919: tp_proxy_get_interface_by_id: 0x2ab11b0: org.freedesktop.DBus.Properties DBusGProxy is 0x2bb0ec0 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.443372: request_is_complete: 0x2ab11b0: calling callback for tp-account-feature-core tp-glibproxy-DEBUG: 10/09/2013 12:54:00.443376: tp_proxy_poll_features: 0x2ab11b0: core features not ready yet, nothing prepared tp-glibaccounts-DEBUG: 10/09/2013 12:54:00.445883: _tp_account_got_all_cb: Got whole set of properties for /org/freedesktop/Telepathy/Account/gabble/jabber/goa_google_account_1380290654 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.445949: tp_proxy_poll_features: 0x2ab1020: request 0x1627900 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:00.445954: tp_proxy_prepare_request_finish: 0x1627900 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.445960: request_is_complete: 0x2ab1020: calling callback for tp-account-feature-connection tp-glibproxy-DEBUG: 10/09/2013 12:54:00.446254: tp_proxy_poll_features: 0x2ab1020: request 0x162ba60 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:00.446259: tp_proxy_prepare_request_finish: 0x162ba60 tp-glibaccounts-DEBUG: 10/09/2013 12:54:00.446270: _tp_account_got_all_cb: Got whole set of properties for /org/freedesktop/Telepathy/Account/gabble/jabber/goa_google_account_1380290716 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.446305: tp_proxy_poll_features: 0x2ab11b0: request 0x1629780 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:00.446310: tp_proxy_prepare_request_finish: 0x1629780 tp-glibproxy-DEBUG: 10/09/2013 12:54:00.446316: request_is_complete: 0x2ab11b0: calling callback for tp-account-feature-connection tp-glibproxy-DEBUG: 10/09/2013 12:54:00.447007: tp_proxy_poll_features: 0x2ab11b0: request 0x1627240 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:00.447012: tp_proxy_prepare_request_finish: 0x1627240 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.196876: tp_proxy_constructor: 0x1979220: request 0x5a5b6e0 represents core features on TpConnection tp-glibconnection-DEBUG: 10/09/2013 12:54:56.196887: tp_connection_constructed: /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5 (0x1979220) constructed tp-glibproxy-DEBUG: 10/09/2013 12:54:56.196954: tp_proxy_get_interface_by_id: 0x1979220: org.freedesktop.Telepathy.Connection DBusGProxy is 0x7ffbc4007820 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.197038: tp_proxy_get_interface_by_id: 0x1979220: org.freedesktop.DBus.Properties DBusGProxy is 0x7ffbc4007970 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.197134: request_is_complete: 0x1979220: calling callback for tp-connection-feature-core tp-glibproxy-DEBUG: 10/09/2013 12:54:56.197139: tp_proxy_poll_features: 0x1979220: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.197144: tp_proxy_poll_features: 0x1979220: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.197148: tp_proxy_poll_features: 0x1979220: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.200963: tp_proxy_constructor: 0x19793d0: request 0x5be3000 represents core features on TpConnection tp-glibconnection-DEBUG: 10/09/2013 12:54:56.200973: tp_connection_constructed: /org/freedesktop/Telepathy/Connection/gabble/jabber/corentinlecouvey_40gmail_2ecom_2f53151ac5 (0x19793d0) constructed tp-glibproxy-DEBUG: 10/09/2013 12:54:56.201014: tp_proxy_get_interface_by_id: 0x19793d0: org.freedesktop.Telepathy.Connection DBusGProxy is 0x328b4f0 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.201090: tp_proxy_get_interface_by_id: 0x19793d0: org.freedesktop.DBus.Properties DBusGProxy is 0x7ffbc40074f0 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.201154: request_is_complete: 0x19793d0: calling callback for tp-connection-feature-core tp-glibproxy-DEBUG: 10/09/2013 12:54:56.201159: tp_proxy_poll_features: 0x19793d0: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.201164: tp_proxy_poll_features: 0x19793d0: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.201168: tp_proxy_poll_features: 0x19793d0: core features not ready yet, nothing prepared tp-glibconnection-DEBUG: 10/09/2013 12:54:56.201570: tp_connection_status_changed: 0x1979220: -1 -> 1 because 0 tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253115: tp_connection_status_changed: 0x19793d0: -1 -> 1 because 0 tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253607: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactList tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253613: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Capabilities tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253618: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactBlocking tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253623: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Location tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253627: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253632: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Aliasing tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253635: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.SimplePresence tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253640: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ClientTypes tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253644: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Avatars tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253648: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Addressing1 tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253652: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactGroups tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253655: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactInfo tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253659: got_contact_attribute_interfaces: 0x1979220: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactCapabilities tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253664: tp_connection_continue_introspection: CORE ready, but not CONNECTED tp-glibproxy-DEBUG: 10/09/2013 12:54:56.253670: tp_proxy_poll_features: 0x1979220: request 0x5a5b6e0 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.253675: tp_proxy_prepare_request_finish: 0x5a5b6e0 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.253681: request_is_complete: 0x1979220: calling callback for tp-connection-feature-contact-list-properties tp-glibproxy-DEBUG: 10/09/2013 12:54:56.253746: tp_proxy_get_interface_by_id: 0x1979220: org.freedesktop.Telepathy.Connection.Interface.ContactList DBusGProxy is 0x39a8320 tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253958: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactList tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253969: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Capabilities tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253974: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactBlocking tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253978: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Location tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253983: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253987: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Aliasing tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253990: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.SimplePresence tp-glibconnection-DEBUG: 10/09/2013 12:54:56.253998: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ClientTypes tp-glibconnection-DEBUG: 10/09/2013 12:54:56.254002: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Avatars tp-glibconnection-DEBUG: 10/09/2013 12:54:56.254005: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Addressing1 tp-glibconnection-DEBUG: 10/09/2013 12:54:56.254009: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactGroups tp-glibconnection-DEBUG: 10/09/2013 12:54:56.254014: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactInfo tp-glibconnection-DEBUG: 10/09/2013 12:54:56.254018: got_contact_attribute_interfaces: 0x19793d0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactCapabilities tp-glibconnection-DEBUG: 10/09/2013 12:54:56.254021: tp_connection_continue_introspection: CORE ready, but not CONNECTED tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254026: tp_proxy_poll_features: 0x19793d0: request 0x5be3000 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254030: tp_proxy_prepare_request_finish: 0x5be3000 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254034: request_is_complete: 0x19793d0: calling callback for tp-connection-feature-contact-list-properties tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254070: tp_proxy_get_interface_by_id: 0x19793d0: org.freedesktop.Telepathy.Connection.Interface.ContactList DBusGProxy is 0x39a8910 tp-glibconnection-DEBUG: 10/09/2013 12:54:56.254570: prepare_contact_list_props_cb: Got contact list properties; state=0 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254627: tp_proxy_poll_features: 0x1979220: request 0x5be2ca0 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254632: tp_proxy_prepare_request_finish: 0x5be2ca0 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254638: request_is_complete: 0x1979220: calling callback for tp-connection-feature-contact-list tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254687: tp_proxy_poll_features: 0x1979220: request 0x7ffbc001f6a0 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254692: tp_proxy_prepare_request_finish: 0x7ffbc001f6a0 tp-glibconnection-DEBUG: 10/09/2013 12:54:56.254704: prepare_contact_list_props_cb: Got contact list properties; state=0 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254976: tp_proxy_poll_features: 0x19793d0: request 0x5be3040 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254981: tp_proxy_prepare_request_finish: 0x5be3040 tp-glibproxy-DEBUG: 10/09/2013 12:54:56.254988: request_is_complete: 0x19793d0: calling callback for tp-connection-feature-contact-list tp-glibproxy-DEBUG: 10/09/2013 12:54:56.255021: tp_proxy_poll_features: 0x19793d0: request 0x5bdd880 prepared tp-glibproxy-DEBUG: 10/09/2013 12:54:56.255024: tp_proxy_prepare_request_finish: 0x5bdd880 tp-glibconnection-DEBUG: 10/09/2013 12:54:59.475635: on_self_handle_changed: Ignoring early self-handle change to 1, we'll pick it up later tp-glibconnection-DEBUG: 10/09/2013 12:55:00.162930: on_self_handle_changed: Ignoring early self-handle change to 1, we'll pick it up later tp-glibconnection-DEBUG: 10/09/2013 12:55:00.198086: tp_connection_status_changed: 0x1979220: 1 -> 0 because 1 tp-glibconnection-DEBUG: 10/09/2013 12:55:00.776693: tp_connection_status_changed: 0x19793d0: 1 -> 0 because 1 tp-glibproxy-DEBUG: 10/09/2013 12:55:00.902712: tp_proxy_get_interface_by_id: 0x1979220: org.freedesktop.Telepathy.Connection.Interface.Aliasing DBusGProxy is 0x40218b0 tp-glibproxy-DEBUG: 10/09/2013 12:55:00.902787: tp_proxy_get_interface_by_id: 0x1979220: org.freedesktop.Telepathy.Connection.Interface.SimplePresence DBusGProxy is 0x4021a00 tp-glibproxy-DEBUG: 10/09/2013 12:55:00.902838: tp_proxy_get_interface_by_id: 0x1979220: org.freedesktop.Telepathy.Connection.Interface.Avatars DBusGProxy is 0x4021990 tp-glibproxy-DEBUG: 10/09/2013 12:55:00.902913: tp_proxy_get_interface_by_id: 0x1979220: org.freedesktop.Telepathy.Connection.Interface.Contacts DBusGProxy is 0x39a8ad0 tp-glibproxy-DEBUG: 10/09/2013 12:55:00.903054: tp_proxy_get_interface_by_id: 0x19793d0: org.freedesktop.Telepathy.Connection.Interface.Aliasing DBusGProxy is 0x39a89f0 tp-glibproxy-DEBUG: 10/09/2013 12:55:00.903101: tp_proxy_get_interface_by_id: 0x19793d0: org.freedesktop.Telepathy.Connection.Interface.SimplePresence DBusGProxy is 0x39a8a60 tp-glibproxy-DEBUG: 10/09/2013 12:55:00.903156: tp_proxy_get_interface_by_id: 0x19793d0: org.freedesktop.Telepathy.Connection.Interface.Avatars DBusGProxy is 0x7ffbc4007410 tp-glibproxy-DEBUG: 10/09/2013 12:55:00.903234: tp_proxy_get_interface_by_id: 0x19793d0: org.freedesktop.Telepathy.Connection.Interface.Contacts DBusGProxy is 0x5d9ac80 tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.903935: contact_set_avatar_token: contact#1 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.903955: contact_update_avatar_data: contact#1 has no avatar tp-glibconnection-DEBUG: 10/09/2013 12:55:00.904025: signal_connected: /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5 (0x1979220): CORE and CONNECTED ready tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.904108: contact_set_avatar_token: contact#1 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.904114: contact_update_avatar_data: contact#1 has no avatar tp-glibconnection-DEBUG: 10/09/2013 12:55:00.904155: signal_connected: /org/freedesktop/Telepathy/Connection/gabble/jabber/corentinlecouvey_40gmail_2ecom_2f53151ac5 (0x19793d0): CORE and CONNECTED ready tp-glibconnection-DEBUG: 10/09/2013 12:55:00.968518: contact_list_state_changed_cb: contact list state changed: 3 tp-glibconnection-DEBUG: 10/09/2013 12:55:00.968525: prepare_roster: CM has the roster for connection /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5, fetch it now. tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.969703: contacts_aliases_changed: Contact "clecou35@gmail.com" alias changed from "clecou35@gmail.com" to "corentin lecouvey" tp-glibconnection-DEBUG: 10/09/2013 12:55:00.974517: got_contact_list_attributes_cb: roster fetched with 9 contacts tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974538: contact_set_avatar_token: contact#31 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974556: contact_set_subscription_states: contact#31 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974572: contact_set_avatar_token: contact#3 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974584: contact_set_subscription_states: contact#3 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974597: contact_set_avatar_token: contact#6 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974608: contact_set_subscription_states: contact#6 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974622: contact_set_avatar_token: contact#9 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974633: contact_set_subscription_states: contact#9 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974647: contact_set_avatar_token: contact#17 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974658: contact_set_subscription_states: contact#17 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974673: contact_set_avatar_token: contact#18 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974683: contact_set_subscription_states: contact#18 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974697: contact_set_avatar_token: contact#19 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974706: contact_set_subscription_states: contact#19 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974720: contact_set_avatar_token: contact#20 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974730: contact_set_subscription_states: contact#20 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974744: contact_set_avatar_token: contact#21 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:00.974765: contact_set_subscription_states: contact#21 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.24228: contact_set_avatar_token: contact#21 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.24235: contact_update_avatar_data: contact#21 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.24367: contact_set_avatar_token: contact#6 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.24373: contact_update_avatar_data: contact#6 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.25681: contact_set_avatar_token: contact#19 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.25687: contact_update_avatar_data: contact#19 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.28004: contact_set_avatar_token: contact#20 token is 0a4e48ad363ea4d638e7361c0c8f96e2e99d2657 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.28065: connection_avatar_request_idle_cb: Request 1 avatars tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.31011: contact_set_avatar_token: contact#9 token is f565f4bf29f5081a66b8b21f4421368bc2decc4c tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.31137: connection_avatar_request_idle_cb: Request 1 avatars tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.31521: contact_set_avatar_token: contact#31 token is 0336ff725ca00fcce5df7d68484c944fc8b6ec58 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.31594: connection_avatar_request_idle_cb: Request 1 avatars tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.32546: contact_set_avatar_token: contact#17 token is 2089ca33b5dc79a0e7c223caece81579d3a95cae tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.32597: connection_avatar_request_idle_cb: Request 1 avatars tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.34884: contact_set_avatar_token: contact#3 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.34890: contact_update_avatar_data: contact#3 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.65756: contacts_aliases_changed: Contact "corentinlecouvey@gmail.com" alias changed from "corentinlecouvey@gmail.com" to "corentin lecouvey" tp-glibconnection-DEBUG: 10/09/2013 12:55:01.341097: contact_list_state_changed_cb: contact list state changed: 3 tp-glibconnection-DEBUG: 10/09/2013 12:55:01.341104: prepare_roster: CM has the roster for connection /org/freedesktop/Telepathy/Connection/gabble/jabber/corentinlecouvey_40gmail_2ecom_2f53151ac5, fetch it now. tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.354928: contact_avatar_retrieved: Contact#20 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_30a4e48ad363ea4d638e7361c0c8f96e2e99d2657, image/jpeg tp-glibconnection-DEBUG: 10/09/2013 12:55:01.355994: got_contact_list_attributes_cb: roster fetched with 34 contacts tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356029: contact_set_avatar_token: contact#244 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356044: contact_set_subscription_states: contact#244 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356056: contact_set_avatar_token: contact#2 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356065: contact_set_subscription_states: contact#2 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356074: contact_set_avatar_token: contact#4 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356081: contact_set_subscription_states: contact#4 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356090: contact_set_avatar_token: contact#5 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356096: contact_set_subscription_states: contact#5 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356106: contact_set_avatar_token: contact#250 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356112: contact_set_subscription_states: contact#250 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356122: contact_set_avatar_token: contact#7 token is 0a4e48ad363ea4d638e7361c0c8f96e2e99d2657 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356163: contact_update_avatar_data: contact#7 avatar found in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_30a4e48ad363ea4d638e7361c0c8f96e2e99d2657, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356174: contact_set_subscription_states: contact#7 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356188: contact_set_avatar_token: contact#313 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356194: contact_set_subscription_states: contact#313 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356204: contact_set_avatar_token: contact#6 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356209: contact_update_avatar_data: contact#6 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356215: contact_set_subscription_states: contact#6 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356225: contact_set_avatar_token: contact#317 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356232: contact_set_subscription_states: contact#317 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356244: contact_set_avatar_token: contact#17 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356251: contact_set_subscription_states: contact#17 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356268: contact_set_avatar_token: contact#263 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356276: contact_set_subscription_states: contact#263 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356286: contact_set_avatar_token: contact#265 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356292: contact_set_subscription_states: contact#265 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356301: contact_set_avatar_token: contact#21 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356307: contact_set_subscription_states: contact#21 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356317: contact_set_avatar_token: contact#27 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356323: contact_set_subscription_states: contact#27 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356333: contact_set_avatar_token: contact#28 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356339: contact_set_subscription_states: contact#28 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356348: contact_set_avatar_token: contact#29 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356355: contact_set_subscription_states: contact#29 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356364: contact_set_avatar_token: contact#30 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356370: contact_set_subscription_states: contact#30 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356388: contact_set_avatar_token: contact#275 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356395: contact_set_subscription_states: contact#275 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356405: contact_set_avatar_token: contact#278 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356411: contact_set_subscription_states: contact#278 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356420: contact_set_avatar_token: contact#279 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356427: contact_set_subscription_states: contact#279 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356522: contact_set_avatar_token: contact#220 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356535: contact_set_subscription_states: contact#220 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356554: contact_set_avatar_token: contact#222 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356568: contact_set_subscription_states: contact#222 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356583: contact_set_avatar_token: contact#223 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356600: contact_set_subscription_states: contact#223 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356610: contact_set_avatar_token: contact#284 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356616: contact_set_subscription_states: contact#284 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356626: contact_set_avatar_token: contact#225 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356642: contact_set_subscription_states: contact#225 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356653: contact_set_avatar_token: contact#288 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356662: contact_set_subscription_states: contact#288 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356671: contact_set_avatar_token: contact#228 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356678: contact_set_subscription_states: contact#228 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356687: contact_set_avatar_token: contact#230 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356694: contact_set_subscription_states: contact#230 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356703: contact_set_avatar_token: contact#233 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356710: contact_set_subscription_states: contact#233 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356720: contact_set_avatar_token: contact#296 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356728: contact_set_subscription_states: contact#296 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356736: contact_set_avatar_token: contact#237 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356744: contact_set_subscription_states: contact#237 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356761: contact_set_avatar_token: contact#298 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356770: contact_set_subscription_states: contact#298 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356777: contact_set_avatar_token: contact#239 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356786: contact_set_subscription_states: contact#239 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356796: contact_set_avatar_token: contact#243 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.356803: contact_set_subscription_states: contact#243 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.357599: contact_set_avatar_token: contact#284 token is 4943b4be7751f77373157b762c7685181af6c2e3 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.357748: contact_set_avatar_token: contact#30 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.357753: contact_update_avatar_data: contact#30 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.357883: contact_set_avatar_token: contact#220 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.357888: contact_update_avatar_data: contact#220 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.357997: contact_set_avatar_token: contact#263 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.358004: contact_update_avatar_data: contact#263 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.358100: contact_set_avatar_token: contact#223 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.358107: contact_update_avatar_data: contact#223 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.358243: contact_set_avatar_token: contact#317 token is 96719cef8ac4bc274a6c12a87eb3246605682eb0 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.358288: connection_avatar_request_idle_cb: Request 2 avatars tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.366075: contact_set_avatar_token: contact#275 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.366080: contact_update_avatar_data: contact#275 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.366198: contact_set_avatar_token: contact#243 token is 2089ca33b5dc79a0e7c223caece81579d3a95cae tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.366329: contact_set_avatar_token: contact#4 token is d3ba9c18866b794ed17c4764f11d640c96957259 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.368886: contact_set_avatar_token: contact#222 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.368894: contact_update_avatar_data: contact#222 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369012: contact_set_avatar_token: contact#278 token is cc7e541694e20177c112d59ac6392d91f41c399e tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369137: contact_set_avatar_token: contact#21 token is 910070b49d203ebfbf234732fabe5b703bd23b38 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369278: contact_set_avatar_token: contact#228 token is 7f8008bc24f4731e6ecd1b3dcd6030fab6cd7d48 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369493: contact_set_avatar_token: contact#17 token is 0336ff725ca00fcce5df7d68484c944fc8b6ec58 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369622: contact_set_avatar_token: contact#230 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369628: contact_update_avatar_data: contact#230 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369740: contact_set_avatar_token: contact#237 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369745: contact_update_avatar_data: contact#237 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369858: contact_set_avatar_token: contact#5 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369864: contact_update_avatar_data: contact#5 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.369975: contact_set_avatar_token: contact#225 token is 0427637457edcee800613e72685898b64e09e9a1 tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.370107: contact_set_avatar_token: contact#233 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.370114: contact_update_avatar_data: contact#233 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.370220: contact_set_avatar_token: contact#265 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.370225: contact_update_avatar_data: contact#265 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.370332: contact_set_avatar_token: contact#250 token is adab1a9085cb62ab9f49d53e4a0e3d6f3aefe6bb tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.370381: connection_avatar_request_idle_cb: Request 8 avatars tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.379798: contact_avatar_retrieved: Contact#9 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/f565f4bf29f5081a66b8b21f4421368bc2decc4c, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.534101: contact_avatar_retrieved: Contact#31 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_30336ff725ca00fcce5df7d68484c944fc8b6ec58, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.547823: contact_avatar_retrieved: Contact#17 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_32089ca33b5dc79a0e7c223caece81579d3a95cae, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.740995: contact_avatar_retrieved: Contact#284 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_34943b4be7751f77373157b762c7685181af6c2e3, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.823762: contact_avatar_retrieved: Contact#317 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_396719cef8ac4bc274a6c12a87eb3246605682eb0, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.839045: contact_avatar_retrieved: Contact#243 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_32089ca33b5dc79a0e7c223caece81579d3a95cae, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.851994: contact_avatar_retrieved: Contact#4 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/d3ba9c18866b794ed17c4764f11d640c96957259, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.867378: contact_avatar_retrieved: Contact#278 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/cc7e541694e20177c112d59ac6392d91f41c399e, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.884376: contact_avatar_retrieved: Contact#228 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_37f8008bc24f4731e6ecd1b3dcd6030fab6cd7d48, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.895845: contact_avatar_retrieved: Contact#17 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_30336ff725ca00fcce5df7d68484c944fc8b6ec58, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.914066: contact_avatar_retrieved: Contact#21 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_3910070b49d203ebfbf234732fabe5b703bd23b38, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.928661: contact_avatar_retrieved: Contact#225 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_30427637457edcee800613e72685898b64e09e9a1, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:01.947709: contact_avatar_retrieved: Contact#250 avatar stored in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/adab1a9085cb62ab9f49d53e4a0e3d6f3aefe6bb, image/jpeg tp-glibconnection-DEBUG: 10/09/2013 12:55:13.729934: tp_connection_status_changed: 0x19793d0: 0 -> 2 because 1 tp-glibproxy-DEBUG: 10/09/2013 12:55:13.729964: tp_proxy_invalidate: 0x19793d0: User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.729974: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc4007890: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.729985: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc4007720: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.729995: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc40076b0: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.730123: tp_proxy_signal_connection_proxy_invalidated: 0x39a8980: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.730149: tp_proxy_signal_connection_proxy_invalidated: 0x328b6b0: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.730159: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc4007a50: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.730170: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc40079e0: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.730180: tp_proxy_signal_connection_proxy_invalidated: 0x4021b50: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.730189: tp_proxy_signal_connection_proxy_invalidated: 0x5d9ac10: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:13.730206: tp_proxy_signal_connection_proxy_invalidated: 0x5d9b090: TpProxy 0x19793d0 invalidated (I have 0x19793d0): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:19.68732: tp_proxy_invalidate: 0x2ab11b0: Account removed tp-glibproxy-DEBUG: 10/09/2013 12:55:19.68747: tp_proxy_signal_connection_proxy_invalidated: 0x270d6e0: TpProxy 0x2ab11b0 invalidated (I have 0x2ab11b0): Account removed tp-glibproxy-DEBUG: 10/09/2013 12:55:19.68775: tp_proxy_signal_connection_proxy_invalidated: 0x2606e50: TpProxy 0x2ab11b0 invalidated (I have 0x2ab11b0): Account removed tp-glibconnection-DEBUG: 10/09/2013 12:55:27.158818: tp_connection_status_changed: 0x1979220: 0 -> 2 because 1 tp-glibproxy-DEBUG: 10/09/2013 12:55:27.158829: tp_proxy_invalidate: 0x1979220: User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.158838: tp_proxy_signal_connection_proxy_invalidated: 0x2929f20: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.158853: tp_proxy_signal_connection_proxy_invalidated: 0x292d750: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.158874: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc4007900: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.158941: tp_proxy_signal_connection_proxy_invalidated: 0x39a8570: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.158957: tp_proxy_signal_connection_proxy_invalidated: 0x4021840: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.158967: tp_proxy_signal_connection_proxy_invalidated: 0x4021920: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.158977: tp_proxy_signal_connection_proxy_invalidated: 0x4021ae0: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.158988: tp_proxy_signal_connection_proxy_invalidated: 0x39a8b40: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.159009: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc4007480: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:27.159019: tp_proxy_signal_connection_proxy_invalidated: 0x5d9b170: TpProxy 0x1979220 invalidated (I have 0x1979220): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:34.445923: tp_proxy_constructor: 0x1979580: request 0x4b316e0 represents core features on TpConnection tp-glibconnection-DEBUG: 10/09/2013 12:55:34.445935: tp_connection_constructed: /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5 (0x1979580) constructed tp-glibproxy-DEBUG: 10/09/2013 12:55:34.446008: tp_proxy_get_interface_by_id: 0x1979580: org.freedesktop.Telepathy.Connection DBusGProxy is 0x39a8320 tp-glibproxy-DEBUG: 10/09/2013 12:55:34.446105: tp_proxy_get_interface_by_id: 0x1979580: org.freedesktop.DBus.Properties DBusGProxy is 0x39a8b40 tp-glibproxy-DEBUG: 10/09/2013 12:55:34.446173: request_is_complete: 0x1979580: calling callback for tp-connection-feature-core tp-glibproxy-DEBUG: 10/09/2013 12:55:34.446177: tp_proxy_poll_features: 0x1979580: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:55:34.446183: tp_proxy_poll_features: 0x1979580: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:55:34.446187: tp_proxy_poll_features: 0x1979580: core features not ready yet, nothing prepared tp-glibconnection-DEBUG: 10/09/2013 12:55:34.446883: tp_connection_status_changed: 0x1979580: -1 -> 1 because 0 tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485486: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactList tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485496: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Capabilities tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485502: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactBlocking tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485507: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Location tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485512: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485517: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Aliasing tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485522: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.SimplePresence tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485527: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ClientTypes tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485532: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Avatars tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485537: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Addressing1 tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485541: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactGroups tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485544: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactInfo tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485549: got_contact_attribute_interfaces: 0x1979580: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactCapabilities tp-glibconnection-DEBUG: 10/09/2013 12:55:34.485554: tp_connection_continue_introspection: CORE ready, but not CONNECTED tp-glibproxy-DEBUG: 10/09/2013 12:55:34.485563: tp_proxy_poll_features: 0x1979580: request 0x4b316e0 prepared tp-glibproxy-DEBUG: 10/09/2013 12:55:34.485569: tp_proxy_prepare_request_finish: 0x4b316e0 tp-glibproxy-DEBUG: 10/09/2013 12:55:34.485577: request_is_complete: 0x1979580: calling callback for tp-connection-feature-contact-list-properties tp-glibproxy-DEBUG: 10/09/2013 12:55:34.485661: tp_proxy_get_interface_by_id: 0x1979580: org.freedesktop.Telepathy.Connection.Interface.ContactList DBusGProxy is 0x39a8570 tp-glibconnection-DEBUG: 10/09/2013 12:55:34.486140: prepare_contact_list_props_cb: Got contact list properties; state=0 tp-glibproxy-DEBUG: 10/09/2013 12:55:34.486175: tp_proxy_poll_features: 0x1979580: request 0x660e460 prepared tp-glibproxy-DEBUG: 10/09/2013 12:55:34.486180: tp_proxy_prepare_request_finish: 0x660e460 tp-glibproxy-DEBUG: 10/09/2013 12:55:34.486195: request_is_complete: 0x1979580: calling callback for tp-connection-feature-contact-list tp-glibproxy-DEBUG: 10/09/2013 12:55:34.486224: tp_proxy_poll_features: 0x1979580: request 0x5c3f520 prepared tp-glibproxy-DEBUG: 10/09/2013 12:55:34.486228: tp_proxy_prepare_request_finish: 0x5c3f520 tp-glibconnection-DEBUG: 10/09/2013 12:55:36.637183: on_self_handle_changed: Ignoring early self-handle change to 1, we'll pick it up later tp-glibconnection-DEBUG: 10/09/2013 12:55:37.347315: tp_connection_status_changed: 0x1979580: 1 -> 0 because 1 tp-glibconnection-DEBUG: 10/09/2013 12:55:37.742935: contact_list_state_changed_cb: contact list state changed: 3 tp-glibconnection-DEBUG: 10/09/2013 12:55:37.742944: prepare_roster: CM has the roster for connection /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5, fetch it now. tp-glibproxy-DEBUG: 10/09/2013 12:55:37.743166: tp_proxy_get_interface_by_id: 0x1979580: org.freedesktop.Telepathy.Connection.Interface.Aliasing DBusGProxy is 0x40218b0 tp-glibproxy-DEBUG: 10/09/2013 12:55:37.743295: tp_proxy_get_interface_by_id: 0x1979580: org.freedesktop.Telepathy.Connection.Interface.SimplePresence DBusGProxy is 0x5d9b1e0 tp-glibproxy-DEBUG: 10/09/2013 12:55:37.743393: tp_proxy_get_interface_by_id: 0x1979580: org.freedesktop.Telepathy.Connection.Interface.Avatars DBusGProxy is 0x4021920 tp-glibconnection-DEBUG: 10/09/2013 12:55:37.744901: got_contact_list_attributes_cb: roster fetched with 9 contacts tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.744920: contact_set_avatar_token: contact#31 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.744932: contact_set_subscription_states: contact#31 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.744946: contact_set_avatar_token: contact#3 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.744952: contact_set_subscription_states: contact#3 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.744962: contact_set_avatar_token: contact#6 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.744970: contact_set_subscription_states: contact#6 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.744981: contact_set_avatar_token: contact#9 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.744988: contact_set_subscription_states: contact#9 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.744997: contact_set_avatar_token: contact#17 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.745004: contact_set_subscription_states: contact#17 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.745014: contact_set_avatar_token: contact#18 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.745022: contact_set_subscription_states: contact#18 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.745031: contact_set_avatar_token: contact#19 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.745038: contact_set_subscription_states: contact#19 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.745049: contact_set_avatar_token: contact#20 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.745058: contact_set_subscription_states: contact#20 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.745066: contact_set_avatar_token: contact#21 token is (null) tp-glibcontacts-DEBUG: 10/09/2013 12:55:37.745074: contact_set_subscription_states: contact#21 state changed: subscribe=Y publish=Y '' tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.120733: contact_set_avatar_token: contact#9 token is f565f4bf29f5081a66b8b21f4421368bc2decc4c tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.120841: contact_update_avatar_data: contact#9 avatar found in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/f565f4bf29f5081a66b8b21f4421368bc2decc4c, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.121102: contact_set_avatar_token: contact#6 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.121112: contact_update_avatar_data: contact#6 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.121345: contact_set_avatar_token: contact#3 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.121361: contact_update_avatar_data: contact#3 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.121669: contact_set_avatar_token: contact#20 token is 0a4e48ad363ea4d638e7361c0c8f96e2e99d2657 tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.121710: contact_update_avatar_data: contact#20 avatar found in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_30a4e48ad363ea4d638e7361c0c8f96e2e99d2657, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.121946: contact_set_avatar_token: contact#19 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.121995: contact_update_avatar_data: contact#19 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.122147: contact_set_avatar_token: contact#31 token is 0336ff725ca00fcce5df7d68484c944fc8b6ec58 tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.122174: contact_update_avatar_data: contact#31 avatar found in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_30336ff725ca00fcce5df7d68484c944fc8b6ec58, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.122298: contact_set_avatar_token: contact#17 token is 2089ca33b5dc79a0e7c223caece81579d3a95cae tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.122323: contact_update_avatar_data: contact#17 avatar found in cache: /home/user/.cache/telepathy/avatars/gabble/jabber/_32089ca33b5dc79a0e7c223caece81579d3a95cae, image/jpeg tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.122446: contact_set_avatar_token: contact#21 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.122478: contact_update_avatar_data: contact#21 has no avatar tp-glibproxy-DEBUG: 10/09/2013 12:55:38.195677: tp_proxy_get_interface_by_id: 0x1979580: org.freedesktop.Telepathy.Connection.Interface.Contacts DBusGProxy is 0x7ffbc40079e0 tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.196340: contact_set_avatar_token: contact#1 token is tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.196356: contact_update_avatar_data: contact#1 has no avatar tp-glibcontacts-DEBUG: 10/09/2013 12:55:38.196369: contact_set_subscription_states: contact#1 state changed: subscribe=N publish=N '' tp-glibconnection-DEBUG: 10/09/2013 12:55:38.196433: signal_connected: /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5 (0x1979580): CORE and CONNECTED ready tp-glibconnection-DEBUG: 10/09/2013 12:55:45.568151: tp_connection_status_changed: 0x1979580: 0 -> 2 because 1 tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568175: tp_proxy_invalidate: 0x1979580: User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568193: tp_proxy_signal_connection_proxy_invalidated: 0x2929f20: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568219: tp_proxy_signal_connection_proxy_invalidated: 0x5d9b170: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568278: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc4007480: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568564: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc4007900: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568612: tp_proxy_signal_connection_proxy_invalidated: 0x4b330a0: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568634: tp_proxy_signal_connection_proxy_invalidated: 0x328b6b0: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568656: tp_proxy_signal_connection_proxy_invalidated: 0x4021a00: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568679: tp_proxy_signal_connection_proxy_invalidated: 0x39a8ad0: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568700: tp_proxy_signal_connection_proxy_invalidated: 0x4021840: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:45.568720: tp_proxy_signal_connection_proxy_invalidated: 0x4021ae0: TpProxy 0x1979580 invalidated (I have 0x1979580): User requested disconnection tp-glibproxy-DEBUG: 10/09/2013 12:55:48.962052: tp_proxy_invalidate: 0x2ab1020: Account removed tp-glibproxy-DEBUG: 10/09/2013 12:55:48.962070: tp_proxy_signal_connection_proxy_invalidated: 0x2280570: TpProxy 0x2ab1020 invalidated (I have 0x2ab1020): Account removed tp-glibproxy-DEBUG: 10/09/2013 12:55:48.962083: tp_proxy_signal_connection_proxy_invalidated: 0x2280500: TpProxy 0x2ab1020 invalidated (I have 0x2ab1020): Account removed tp-glibproxy-DEBUG: 10/09/2013 12:56:43.304066: tp_proxy_constructor: 0x2ab1340: request 0x565e380 represents core features on TpAccount tp-glibproxy-DEBUG: 10/09/2013 12:56:43.307651: tp_proxy_get_interface_by_id: 0x2ab1340: org.freedesktop.Telepathy.Account DBusGProxy is 0x328b6b0 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.307898: tp_proxy_get_interface_by_id: 0x2ab1340: org.freedesktop.DBus.Properties DBusGProxy is 0x2bb0d70 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.308161: request_is_complete: 0x2ab1340: calling callback for tp-account-feature-core tp-glibproxy-DEBUG: 10/09/2013 12:56:43.308242: tp_proxy_poll_features: 0x2ab1340: core features not ready yet, nothing prepared tp-glibaccounts-DEBUG: 10/09/2013 12:56:43.424180: _tp_account_got_all_cb: Got whole set of properties for /org/freedesktop/Telepathy/Account/gabble/jabber/clecou35_40gmail_2ecom0 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.424277: tp_proxy_poll_features: 0x2ab1340: request 0x565e380 prepared tp-glibproxy-DEBUG: 10/09/2013 12:56:43.424326: tp_proxy_prepare_request_finish: 0x565e380 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.424354: request_is_complete: 0x2ab1340: calling callback for tp-account-feature-connection tp-glibproxy-DEBUG: 10/09/2013 12:56:43.424496: tp_proxy_poll_features: 0x2ab1340: request 0x66d2080 prepared tp-glibproxy-DEBUG: 10/09/2013 12:56:43.424544: tp_proxy_prepare_request_finish: 0x66d2080 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.424762: tp_proxy_constructor: 0x19798e0: request 0x599df40 represents core features on TpConnection tp-glibconnection-DEBUG: 10/09/2013 12:56:43.424830: tp_connection_constructed: /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5 (0x19798e0) constructed tp-glibproxy-DEBUG: 10/09/2013 12:56:43.424916: tp_proxy_get_interface_by_id: 0x19798e0: org.freedesktop.Telepathy.Connection DBusGProxy is 0x39a8ad0 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.425040: tp_proxy_get_interface_by_id: 0x19798e0: org.freedesktop.DBus.Properties DBusGProxy is 0x5d9ac10 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.425147: request_is_complete: 0x19798e0: calling callback for tp-connection-feature-core tp-glibproxy-DEBUG: 10/09/2013 12:56:43.425184: tp_proxy_poll_features: 0x19798e0: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:56:43.425210: tp_proxy_poll_features: 0x19798e0: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:56:43.425234: tp_proxy_poll_features: 0x19798e0: core features not ready yet, nothing prepared tp-glibconnection-DEBUG: 10/09/2013 12:56:43.429291: tp_connection_status_changed: 0x19798e0: -1 -> 1 because 0 tp-glibconnection-DEBUG: 10/09/2013 12:56:43.430639: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactList tp-glibconnection-DEBUG: 10/09/2013 12:56:43.430834: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Capabilities tp-glibconnection-DEBUG: 10/09/2013 12:56:43.430903: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactBlocking tp-glibconnection-DEBUG: 10/09/2013 12:56:43.430979: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Location tp-glibconnection-DEBUG: 10/09/2013 12:56:43.431103: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection tp-glibconnection-DEBUG: 10/09/2013 12:56:43.431818: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Aliasing tp-glibconnection-DEBUG: 10/09/2013 12:56:43.431843: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.SimplePresence tp-glibconnection-DEBUG: 10/09/2013 12:56:43.431868: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ClientTypes tp-glibconnection-DEBUG: 10/09/2013 12:56:43.431894: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Avatars tp-glibconnection-DEBUG: 10/09/2013 12:56:43.431917: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Addressing1 tp-glibconnection-DEBUG: 10/09/2013 12:56:43.431941: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactGroups tp-glibconnection-DEBUG: 10/09/2013 12:56:43.431965: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactInfo tp-glibconnection-DEBUG: 10/09/2013 12:56:43.431988: got_contact_attribute_interfaces: 0x19798e0: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactCapabilities tp-glibconnection-DEBUG: 10/09/2013 12:56:43.432020: tp_connection_continue_introspection: CORE ready, but not CONNECTED tp-glibproxy-DEBUG: 10/09/2013 12:56:43.432046: tp_proxy_poll_features: 0x19798e0: request 0x599df40 prepared tp-glibproxy-DEBUG: 10/09/2013 12:56:43.432070: tp_proxy_prepare_request_finish: 0x599df40 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.432104: request_is_complete: 0x19798e0: calling callback for tp-connection-feature-contact-list-properties tp-glibproxy-DEBUG: 10/09/2013 12:56:43.432161: tp_proxy_get_interface_by_id: 0x19798e0: org.freedesktop.Telepathy.Connection.Interface.ContactList DBusGProxy is 0x5d9b170 tp-glibconnection-DEBUG: 10/09/2013 12:56:43.533051: prepare_contact_list_props_cb: Got contact list properties; state=0 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.534415: tp_proxy_poll_features: 0x19798e0: request 0x4a2d320 prepared tp-glibproxy-DEBUG: 10/09/2013 12:56:43.534512: tp_proxy_prepare_request_finish: 0x4a2d320 tp-glibproxy-DEBUG: 10/09/2013 12:56:43.534593: request_is_complete: 0x19798e0: calling callback for tp-connection-feature-contact-list tp-glibproxy-DEBUG: 10/09/2013 12:56:43.534699: tp_proxy_poll_features: 0x19798e0: request 0x66d3a80 prepared tp-glibproxy-DEBUG: 10/09/2013 12:56:43.534775: tp_proxy_prepare_request_finish: 0x66d3a80 tp-glibconnection-DEBUG: 10/09/2013 12:56:44.902955: tp_connection_status_changed: 0x19798e0: 1 -> 2 because 3 tp-glibproxy-DEBUG: 10/09/2013 12:56:44.903225: tp_proxy_invalidate: 0x19798e0: WOCKY_AUTH_ERROR_FAILURE (#6): Authentication failed: not-authorized tp-glibproxy-DEBUG: 10/09/2013 12:56:44.903327: tp_proxy_signal_connection_proxy_invalidated: 0x4021a00: TpProxy 0x19798e0 invalidated (I have 0x19798e0): WOCKY_AUTH_ERROR_FAILURE (#6): Authentication failed: not-authorized tp-glibproxy-DEBUG: 10/09/2013 12:56:44.903420: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc4007890: TpProxy 0x19798e0 invalidated (I have 0x19798e0): WOCKY_AUTH_ERROR_FAILURE (#6): Authentication failed: not-authorized tp-glibproxy-DEBUG: 10/09/2013 12:56:44.903549: tp_proxy_signal_connection_proxy_invalidated: 0x5d9b090: TpProxy 0x19798e0 invalidated (I have 0x19798e0): WOCKY_AUTH_ERROR_FAILURE (#6): Authentication failed: not-authorized tp-glibproxy-DEBUG: 10/09/2013 12:56:44.903762: tp_proxy_signal_connection_proxy_invalidated: 0x39a8570: TpProxy 0x19798e0 invalidated (I have 0x19798e0): WOCKY_AUTH_ERROR_FAILURE (#6): Authentication failed: not-authorized tp-glibproxy-DEBUG: 10/09/2013 12:57:02.343131: tp_proxy_constructor: 0x1979a90: request 0x7366f80 represents core features on TpConnection tp-glibconnection-DEBUG: 10/09/2013 12:57:02.343246: tp_connection_constructed: /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5 (0x1979a90) constructed tp-glibproxy-DEBUG: 10/09/2013 12:57:02.343518: tp_proxy_get_interface_by_id: 0x1979a90: org.freedesktop.Telepathy.Connection DBusGProxy is 0x39a8570 tp-glibproxy-DEBUG: 10/09/2013 12:57:02.343708: tp_proxy_get_interface_by_id: 0x1979a90: org.freedesktop.DBus.Properties DBusGProxy is 0x40218b0 tp-glibproxy-DEBUG: 10/09/2013 12:57:02.343923: request_is_complete: 0x1979a90: calling callback for tp-connection-feature-core tp-glibproxy-DEBUG: 10/09/2013 12:57:02.344002: tp_proxy_poll_features: 0x1979a90: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:57:02.344079: tp_proxy_poll_features: 0x1979a90: core features not ready yet, nothing prepared tp-glibproxy-DEBUG: 10/09/2013 12:57:02.344153: tp_proxy_poll_features: 0x1979a90: core features not ready yet, nothing prepared tp-glibconnection-DEBUG: 10/09/2013 12:57:02.456166: tp_connection_status_changed: 0x1979a90: -1 -> 1 because 0 tp-glibconnection-DEBUG: 10/09/2013 12:57:02.548831: got_contact_attribute_interfaces: 0x1979a90: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactList tp-glibconnection-DEBUG: 10/09/2013 12:57:02.548898: got_contact_attribute_interfaces: 0x1979a90: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Capabilities tp-glibconnection-DEBUG: 10/09/2013 12:57:02.548927: got_contact_attribute_interfaces: 0x1979a90: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactBlocking tp-glibconnection-DEBUG: 10/09/2013 12:57:02.548950: got_contact_attribute_interfaces: 0x1979a90: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Location tp-glibconnection-DEBUG: 10/09/2013 12:57:02.548974: got_contact_attribute_interfaces: 0x1979a90: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection tp-glibconnection-DEBUG: 10/09/2013 12:57:02.548999: got_contact_attribute_interfaces: 0x1979a90: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Aliasing tp-glibconnection-DEBUG: 10/09/2013 12:57:02.549021: got_contact_attribute_interfaces: 0x1979a90: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.SimplePresence tp-glibconnection-DEBUG: 10/09/2013 12:57:02.549046: got_contact_attribute_interfaces: 0x1979a90: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ClientTypes tp-glibconnection-DEBUG: 10/09/2013 12:57:02.549081: got_contact_attribute_interfaces: 0x1979a90: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Avatars tp-glibconnection-DEBUG: 10/09/2013 12:57:02.549118: got_contact_attribute_interfaces: 0x1979a90: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.Addressing1 tp-glibconnection-DEBUG: 10/09/2013 12:57:02.549144: got_contact_attribute_interfaces: 0x1979a90: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactGroups tp-glibconnection-DEBUG: 10/09/2013 12:57:02.549166: got_contact_attribute_interfaces: 0x1979a90: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactInfo tp-glibconnection-DEBUG: 10/09/2013 12:57:02.549190: got_contact_attribute_interfaces: 0x1979a90: ContactAttributeInterfaces has org.freedesktop.Telepathy.Connection.Interface.ContactCapabilities tp-glibconnection-DEBUG: 10/09/2013 12:57:02.549227: tp_connection_continue_introspection: CORE ready, but not CONNECTED tp-glibproxy-DEBUG: 10/09/2013 12:57:02.549266: tp_proxy_poll_features: 0x1979a90: request 0x7366f80 prepared tp-glibproxy-DEBUG: 10/09/2013 12:57:02.549290: tp_proxy_prepare_request_finish: 0x7366f80 tp-glibproxy-DEBUG: 10/09/2013 12:57:02.549315: request_is_complete: 0x1979a90: calling callback for tp-connection-feature-contact-list-properties tp-glibproxy-DEBUG: 10/09/2013 12:57:02.549391: tp_proxy_get_interface_by_id: 0x1979a90: org.freedesktop.Telepathy.Connection.Interface.ContactList DBusGProxy is 0x4b330a0 tp-glibconnection-DEBUG: 10/09/2013 12:57:02.638233: prepare_contact_list_props_cb: Got contact list properties; state=0 tp-glibproxy-DEBUG: 10/09/2013 12:57:02.638624: tp_proxy_poll_features: 0x1979a90: request 0x73bb4c0 prepared tp-glibproxy-DEBUG: 10/09/2013 12:57:02.638701: tp_proxy_prepare_request_finish: 0x73bb4c0 tp-glibproxy-DEBUG: 10/09/2013 12:57:02.638786: request_is_complete: 0x1979a90: calling callback for tp-connection-feature-contact-list tp-glibproxy-DEBUG: 10/09/2013 12:57:02.638894: tp_proxy_poll_features: 0x1979a90: request 0x5b7ce00 prepared tp-glibproxy-DEBUG: 10/09/2013 12:57:02.638959: tp_proxy_prepare_request_finish: 0x5b7ce00 tp-glibconnection-DEBUG: 10/09/2013 12:57:03.65650: tp_connection_status_changed: 0x1979a90: 1 -> 2 because 3 tp-glibproxy-DEBUG: 10/09/2013 12:57:03.65741: tp_proxy_invalidate: 0x1979a90: Close() called tp-glibproxy-DEBUG: 10/09/2013 12:57:03.65784: tp_proxy_signal_connection_proxy_invalidated: 0x4021b50: TpProxy 0x1979a90 invalidated (I have 0x1979a90): Close() called tp-glibproxy-DEBUG: 10/09/2013 12:57:03.65821: tp_proxy_signal_connection_proxy_invalidated: 0x5d9b1e0: TpProxy 0x1979a90 invalidated (I have 0x1979a90): Close() called tp-glibproxy-DEBUG: 10/09/2013 12:57:03.65862: tp_proxy_signal_connection_proxy_invalidated: 0x4021840: TpProxy 0x1979a90 invalidated (I have 0x1979a90): Close() called tp-glibproxy-DEBUG: 10/09/2013 12:57:03.66036: tp_proxy_signal_connection_proxy_invalidated: 0x7ffbc4007890: TpProxy 0x1979a90 invalidated (I have 0x1979a90): Close() called gabbleplugins-DEBUG: 10/09/2013 12:57:02.253335: gabble_plugin_loader_probe (plugin-loader.c:135): probing /usr/lib64/telepathy/gabble-0/plugins gabbleplugins-DEBUG: 10/09/2013 12:57:02.253909: plugin_loader_try_to_load (plugin-loader.c:100): loaded 'XMPP console' version 0.18.1 (/usr/lib64/telepathy/gabble-0/plugins/libconsole.so), implementing these sidecars: org.freedesktop.Telepathy.Gabble.Plugin.Console gabbleplugins-DEBUG: 10/09/2013 12:57:02.254125: plugin_loader_try_to_load (plugin-loader.c:100): loaded 'Gateway registration plugin' version 0.18.1 (/usr/lib64/telepathy/gabble-0/plugins/libgateways.so), implementing these sidecars: org.freedesktop.Telepathy.Gabble.Plugin.Gateways tp-glib/-DEBUG: 10/09/2013 12:57:02.263766: started version 0.18.1 (telepathy-glib version 0.20.4) gabbleconnection-DEBUG: 10/09/2013 12:57:02.264200: gabble_connection_class_init (connection.c:1001): Initializing (GabbleConnectionClass *)0x2888ae0 gabbleconnection-DEBUG: 10/09/2013 12:57:02.264394: gabble_connection_init (connection.c:557): Initializing (GabbleConnection *)0x2892010 gabblepresence-DEBUG: 10/09/2013 12:57:02.264415: gabble_capabilities_init (capabilities.c:249): 0x2892010 gabblemuc-DEBUG: 10/09/2013 12:57:02.264719: gabble_roomlist_manager_constructed (roomlist-manager.c:208): 0x285b2c0 gabblejid-DEBUG: 10/09/2013 12:57:02.264733: gabble_signal_connect_weak (util.c:666): connecting to 0x2892010:status-changed with context 0x28876a0 gabblejid-DEBUG: 10/09/2013 12:57:02.264774: gabble_signal_connect_weak (util.c:666): connecting to 0x2892010:status-changed with context 0x2887620 gabbletls-DEBUG: 10/09/2013 12:57:02.285291: gabble_server_tls_manager_constructed (server-tls-manager.c:454): Server TLS Manager constructed gabblejid-DEBUG: 10/09/2013 12:57:02.285300: gabble_signal_connect_weak (util.c:666): connecting to 0x2892010:status-changed with context 0x7fc0880017a0 gabbleconnection-DEBUG: 10/09/2013 12:57:02.285615: gabble_connection_constructor (connection.c:425): Post-construction: (GabbleConnection *)0x2892010 gabblejid-DEBUG: 10/09/2013 12:57:02.285893: gabble_signal_connect_weak (util.c:666): connecting to 0x285b580:item-found with context 0x28b90a0 gabblejid-DEBUG: 10/09/2013 12:57:02.285902: gabble_signal_connect_weak (util.c:666): connecting to 0x2892010:status-changed with context 0x28b9000 gabbleconnection-DEBUG: 10/09/2013 12:57:02.285960: gabble_connection_constructed (connection.c:540): defaulted resource to 53151ac5 wocky/-DEBUG: 10/09/2013 12:57:02.398910: wocky_xmpp_writer_stream_open: wocky-xmpp-writer.c:302: Writing stream opening: wocky/-DEBUG: 10/09/2013 12:57:02.465190: handle_stream_open: wocky-xmpp-reader.c:481: Received stream opening: stream, prefix: stream, uri: http://etherx.jabber.org/streams wocky/-DEBUG: 10/09/2013 12:57:02.465321: handle_stream_open: wocky-xmpp-reader.c:500: Stream opening attribute: from = 'gmail.com' (prefix: , uri: ) wocky/-DEBUG: 10/09/2013 12:57:02.465359: handle_stream_open: wocky-xmpp-reader.c:500: Stream opening attribute: id = '509728E1112058AE' (prefix: , uri: ) wocky/-DEBUG: 10/09/2013 12:57:02.465396: handle_stream_open: wocky-xmpp-reader.c:500: Stream opening attribute: version = '1.0' (prefix: , uri: ) wocky/-DEBUG: 10/09/2013 12:57:02.465548: _end_element_ns: Received stanza * features xmlns='http://etherx.jabber.org/streams' * starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls' * required * mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl' * mechanism "X-OAUTH2" * mechanism "X-GOOGLE-TOKEN" wocky/-DEBUG: 10/09/2013 12:57:02.465694: _write_node_tree: Serializing tree: * starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls' wocky/-DEBUG: 10/09/2013 12:57:02.530550: _end_element_ns: Received stanza * proceed xmlns='urn:ietf:params:xml:ns:xmpp-tls' gabbletls-DEBUG: 10/09/2013 12:57:02.801238: gabble_server_tls_manager_verify_async (server-tls-manager.c:341): verify_async() called on the GabbleServerTLSManager. gabbletls-DEBUG: 10/09/2013 12:57:02.801727: gabble_server_tls_channel_constructed (server-tls-channel.c:202): Server TLS channel constructed at /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5/ServerTLSChannel1 gabbletls-DEBUG: 10/09/2013 12:57:02.832500: gabble_tls_certificate_accept (tls-certificate.c:264): Accept() called on the TLS certificate; current state 0 gabbletls-DEBUG: 10/09/2013 12:57:02.832629: tls_certificate_accepted_cb (server-tls-manager.c:243): TLS certificate accepted wocky/-DEBUG: 10/09/2013 12:57:02.832886: wocky_xmpp_writer_stream_open: wocky-xmpp-writer.c:302: Writing stream opening: wocky/-DEBUG: 10/09/2013 12:57:02.899274: handle_stream_open: wocky-xmpp-reader.c:481: Received stream opening: stream, prefix: stream, uri: http://etherx.jabber.org/streams wocky/-DEBUG: 10/09/2013 12:57:02.899441: handle_stream_open: wocky-xmpp-reader.c:500: Stream opening attribute: from = 'gmail.com' (prefix: , uri: ) wocky/-DEBUG: 10/09/2013 12:57:02.899563: handle_stream_open: wocky-xmpp-reader.c:500: Stream opening attribute: id = '4A34F8690EFCD89C' (prefix: , uri: ) wocky/-DEBUG: 10/09/2013 12:57:02.899640: handle_stream_open: wocky-xmpp-reader.c:500: Stream opening attribute: version = '1.0' (prefix: , uri: ) wocky/-DEBUG: 10/09/2013 12:57:02.899890: _end_element_ns: Received stanza * features xmlns='http://etherx.jabber.org/streams' * mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl' * mechanism "X-OAUTH2" * mechanism "X-GOOGLE-TOKEN" * mechanism "PLAIN" gabbleauthentication-DEBUG: 10/09/2013 12:57:02.900202: gabble_server_sasl_channel_start_auth_async (server-sasl-channel.c:836): Starting authentication gabbleauthentication-DEBUG: 10/09/2013 12:57:02.914196: gabble_server_sasl_channel_start_mechanism_with_data (server-sasl-channel.c:548): Starting X-TELEPATHY-PASSWORD authentication with 9 bytes of initial data wocky/-DEBUG: 10/09/2013 12:57:02.914412: wocky_auth_registry_select_handler: wocky-auth-registry.c:290: Choosing PLAIN as auth mechanism wocky/-DEBUG: 10/09/2013 12:57:02.914583: plain_initial_response: wocky-sasl-plain.c:168: Got username and password wocky/-DEBUG: 10/09/2013 12:57:02.914716: _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='PLAIN' "AGNsZWNvdTM1AENsZWNvdTc1NQ==" gabbleauthentication-DEBUG: 10/09/2013 12:57:02.915911: gabble_server_sasl_channel_start_mechanism_with_data (server-sasl-channel.c:530): cannot start: state 1 != Not_Started gabbleauthentication-DEBUG: 10/09/2013 12:57:02.916402: gabble_server_sasl_channel_close (server-sasl-channel.c:998): called on 0x2867dc0 wocky/-DEBUG: 10/09/2013 12:57:03.16797: _end_element_ns: Received stanza * success xmlns='urn:ietf:params:xml:ns:xmpp-sasl' wocky/-DEBUG: 10/09/2013 12:57:03.17000: auth_failed: wocky-sasl-auth.c:274: Authentication failed!: Client aborted authentication. gabbleconnection-DEBUG: 10/09/2013 12:57:03.17112: connector_error_disconnect (connection.c:1764): Interactive authentication error, reason 3, dbus error org.freedesktop.Telepathy.Error.AuthenticationFailed gabbleroster-DEBUG: 10/09/2013 12:57:03.17647: gabble_roster_close_all (roster.c:1672): closing channels gabblemuc-DEBUG: 10/09/2013 12:57:03.17733: connection_status_changed_cb (roomlist-manager.c:191): disconnected, closing room lists gabblemuc-DEBUG: 10/09/2013 12:57:03.17807: gabble_roomlist_manager_close_all (roomlist-manager.c:85): 0x285b2c0 gabblesearch-DEBUG: 10/09/2013 12:57:03.17890: gabble_search_manager_close_all (search-manager.c:99): closing channels gabbleauthentication-DEBUG: 10/09/2013 12:57:03.17966: gabble_auth_manager_close_all (auth-manager.c:92): called gabbletls-DEBUG: 10/09/2013 12:57:03.18043: connection_status_changed_cb (server-tls-manager.c:147): Connection status changed, now 2 gabbletls-DEBUG: 10/09/2013 12:57:03.18129: gabble_server_tls_channel_close (server-tls-channel.c:300): Close() called on the TLS channel 0x2863360 gabbletls-DEBUG: 10/09/2013 12:57:03.18537: server_tls_channel_closed_cb (server-tls-manager.c:197): Server TLS channel closed. gabbletls-DEBUG: 10/09/2013 12:57:03.23694: gabble_server_tls_channel_dispose (server-tls-channel.c:140): Dispose TLS channel gabbletls-DEBUG: 10/09/2013 12:57:03.23806: gabble_server_tls_channel_finalize (server-tls-channel.c:123): Finalize TLS channel gabblemuc-DEBUG: 10/09/2013 12:57:03.23933: gabble_muc_factory_close_all (muc-factory.c:892): closing channels gabbletubes-DEBUG: 10/09/2013 12:57:03.24032: gabble_private_tubes_factory_close_all (private-tubes-factory.c:374): closing 1-1 tubes channels gabblemedia-channel-DEBUG: 10/09/2013 12:57:03.24525: gabble_media_factory_close_all (media-factory.c:423): closing channels gabbleconnection-DEBUG: 10/09/2013 12:57:03.25998: connection_shut_down (connection.c:2367): neither porter nor connector is alive: clean up the base connection gabbleconnection-DEBUG: 10/09/2013 12:57:03.31631: gabble_connection_dispose (connection.c:1269): called gabblebytestream-DEBUG: 10/09/2013 12:57:03.31969: gabble_bytestream_factory_dispose (bytestream-factory.c:683): dispose called gabblejid-DEBUG: 10/09/2013 12:57:03.32052: user_data_destroyed_cb (util.c:635): user_data for 0x28b90a0 destroyed; disconnecting gabblejid-DEBUG: 10/09/2013 12:57:03.32151: user_data_destroyed_cb (util.c:635): user_data for 0x28b9000 destroyed; disconnecting gabbledisco-DEBUG: 10/09/2013 12:57:03.32227: gabble_disco_dispose (disco.c:227): dispose called gabblejid-DEBUG: 10/09/2013 12:57:03.32548: instance_destroyed_cb (util.c:622): instance for 0x28cbb40 destroyed; cleaning up gabblejid-DEBUG: 10/09/2013 12:57:03.32624: instance_destroyed_cb (util.c:622): instance for 0x28d1680 destroyed; cleaning up gabbledisco-DEBUG: 10/09/2013 12:57:03.32699: gabble_disco_finalize (disco.c:254): called with 0x285b580 gabblepipeline-DEBUG: 10/09/2013 12:57:03.32773: gabble_request_pipeline_dispose (request-pipeline.c:275): disposing request-pipeline gabblevcard-DEBUG: 10/09/2013 12:57:03.32852: gabble_vcard_manager_dispose (vcard-manager.c:548): 0x7fc0880035c0 gabblevcard-DEBUG: 10/09/2013 12:57:03.32938: gabble_vcard_manager_finalize (vcard-manager.c:579): 0x7fc0880035c0 gabblepresence-DEBUG: 10/09/2013 12:57:03.33145: gabble_presence_cache_dispose (presence-cache.c:548): dispose called gabblepresence-DEBUG: 10/09/2013 12:57:03.33412: gabble_presence_cache_finalize (presence-cache.c:579): called with 0x28bd820 gabbleroster-DEBUG: 10/09/2013 12:57:03.33545: gabble_roster_dispose (roster.c:200): dispose called gabbleroster-DEBUG: 10/09/2013 12:57:03.33620: gabble_roster_close_all (roster.c:1672): closing channels gabbleroster-DEBUG: 10/09/2013 12:57:03.33705: gabble_roster_finalize (roster.c:221): called with 0x2898040 gabbleim-DEBUG: 10/09/2013 12:57:03.33771: gabble_im_factory_dispose (im-factory.c:120): dispose called gabblemuc-DEBUG: 10/09/2013 12:57:03.33849: gabble_roomlist_manager_dispose (roomlist-manager.c:127): running gabblemuc-DEBUG: 10/09/2013 12:57:03.33920: gabble_roomlist_manager_close_all (roomlist-manager.c:85): 0x285b2c0 gabblesearch-DEBUG: 10/09/2013 12:57:03.33994: gabble_search_manager_dispose (search-manager.c:213): dispose called gabblesearch-DEBUG: 10/09/2013 12:57:03.34094: gabble_search_manager_close_all (search-manager.c:99): closing channels gabblejid-DEBUG: 10/09/2013 12:57:03.34169: user_data_destroyed_cb (util.c:635): user_data for 0x28876a0 destroyed; disconnecting gabblemuc-DEBUG: 10/09/2013 12:57:03.34254: gabble_muc_factory_dispose (muc-factory.c:148): dispose called gabblemuc-DEBUG: 10/09/2013 12:57:03.34337: gabble_muc_factory_close_all (muc-factory.c:892): closing channels gabbletubes-DEBUG: 10/09/2013 12:57:03.34416: gabble_private_tubes_factory_dispose (private-tubes-factory.c:288): dispose called gabbletubes-DEBUG: 10/09/2013 12:57:03.34519: gabble_private_tubes_factory_close_all (private-tubes-factory.c:374): closing 1-1 tubes channels gabblemedia-channel-DEBUG: 10/09/2013 12:57:03.34600: gabble_media_factory_dispose (media-factory.c:158): dispose called gabblemedia-channel-DEBUG: 10/09/2013 12:57:03.34678: gabble_media_factory_close_all (media-factory.c:423): closing channels gabblejid-DEBUG: 10/09/2013 12:57:03.34789: instance_destroyed_cb (util.c:622): instance for 0x7fc0880017a0 destroyed; cleaning up gabblejid-DEBUG: 10/09/2013 12:57:03.34862: instance_destroyed_cb (util.c:622): instance for 0x2887620 destroyed; cleaning up gabbleconnection-DEBUG: 10/09/2013 12:57:03.34934: gabble_connection_finalize (connection.c:1348): called with 0x2892010 gabblepresence-DEBUG: 10/09/2013 12:57:03.35021: gabble_capabilities_finalize (capabilities.c:337): 0x2892010 gabbletls-DEBUG: 10/09/2013 12:57:03.37691: gabble_server_tls_manager_dispose (server-tls-manager.c:416): 0x2867a00 gabbletls-DEBUG: 10/09/2013 12:57:03.38100: gabble_server_tls_manager_finalize (server-tls-manager.c:434): 0x2867a00 gabbleauthentication-DEBUG: 10/09/2013 12:57:03.38201: gabble_auth_manager_dispose (auth-manager.c:161): dispose called gabbleauthentication-DEBUG: 10/09/2013 12:57:03.38276: gabble_auth_manager_close_all (auth-manager.c:92): called tp-glib/-DEBUG: 10/09/2013 12:57:08.31584: no connections, and timed out tp-glib/-MESSAGE: 10/09/2013 12:57:08.31959: Exiting empathyTls-DEBUG: 10/09/2013 12:56:44.49231: main: Empathy auth client started. empathyTls-DEBUG: 10/09/2013 12:56:44.52829: start_timer: Start timer empathyTls-DEBUG: 10/09/2013 12:56:44.61500: handle_channels: Handle TLS or SASL carrier channels. empathyTls-DEBUG: 10/09/2013 12:56:44.61629: tls_handler_init_async: Received hostname: gmail.com empathyTls-DEBUG: 10/09/2013 12:56:44.61640: tls_handler_init_async: Received reference identities: gmail.com, talk.google.com, talk.google.com empathyTls-DEBUG: 10/09/2013 12:56:44.61650: tls_handler_init_async: Creating an TpTLSCertificate for path /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5/ServerTLSChannel1/TLSCertificateObject, bus name :1.70 empathyTls-DEBUG: 10/09/2013 12:56:44.63486: auth_factory_new_tls_handler_cb: New TLS server handler received from the factory empathyTls-DEBUG: 10/09/2013 12:56:44.63522: empathy_tls_verifier_verify_async: Starting verification empathyTls-DEBUG: 10/09/2013 12:56:44.64285: empathy_server_tls_handler_finalize: 0x7f99600025e0 /-DEBUG: 10/09/2013 12:56:44.317684: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.317894: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.318213: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.318317: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.318620: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.318722: generalTime generalTime: decoded tag did not match expected empathyTls-DEBUG: 10/09/2013 12:56:44.338084: perform_verification: Performing verification empathyTls-DEBUG: 10/09/2013 12:56:44.338149: debug_certificate_chain: Certificate chain: length 4 status anchored empathyTls-DEBUG: 10/09/2013 12:56:44.338587: debug_certificate: Certificate: C=US, ST=California, L=Mountain View, O=Google Inc, CN=gmail.com empathyTls-DEBUG: 10/09/2013 12:56:44.338790: debug_certificate: Certificate: C=US, O=Google Inc, CN=Google Internet Authority G2 empathyTls-DEBUG: 10/09/2013 12:56:44.338975: debug_certificate: Certificate: C=US, O=GeoTrust Inc., CN=GeoTrust Global CA empathyTls-DEBUG: 10/09/2013 12:56:44.339361: debug_certificate: Certificate: C=US, O=Equifax, OU=Equifax Secure Certificate Authority empathyTls-DEBUG: 10/09/2013 12:56:44.343544: perform_verification: Certificate verification gave result 1 with reason 0 empathyTls-DEBUG: 10/09/2013 12:56:44.343674: perform_verification: Hostname matched empathyTls-DEBUG: 10/09/2013 12:56:44.343744: complete_verification: Verification successful, completing... /-DEBUG: 10/09/2013 12:56:44.338294: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338300: printableString printableString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338304: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338309: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338313: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338363: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338367: printableString printableString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338371: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338376: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338380: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338423: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338427: printableString printableString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338430: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338442: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338445: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338556: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338567: printableString printableString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338572: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338576: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338578: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338691: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338696: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338700: utf8String utf8String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338704: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338707: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338768: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338773: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338777: utf8String utf8String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338781: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338783: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338892: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338897: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338901: utf8String utf8String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338905: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338908: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338954: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338958: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338963: utf8String utf8String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338965: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.338969: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339145: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339152: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339276: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339279: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339283: utf8String utf8String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339287: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339291: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339339: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339344: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339346: utf8String utf8String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339350: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:56:44.339354: ia5String ia5String: decoded tag did not match expected empathyTls-DEBUG: 10/09/2013 12:56:44.348227: empathy_tls_verifier_finalize: 0xe398a0 empathyTls-DEBUG: 10/09/2013 12:56:44.459284: observe_channels: New auth channel to observe empathyOther-DEBUG: 10/09/2013 12:56:44.459496: empathy_keyring_get_account_password_async: Trying to get password for: gabble/jabber/clecou35_40gmail_2ecom0 empathyTls-DEBUG: 10/09/2013 12:56:44.479058: get_password_cb: We have a password for account /org/freedesktop/Telepathy/Account/gabble/jabber/clecou35_40gmail_2ecom0, calling Claim empathyTls-DEBUG: 10/09/2013 12:56:44.479675: password_claim_cb: Claim called successfully empathyOther-DEBUG: 10/09/2013 12:56:44.479795: empathy_keyring_get_account_password_async: Trying to get password for: gabble/jabber/clecou35_40gmail_2ecom0 empathyTls-DEBUG: 10/09/2013 12:56:44.482691: auth_factory_new_sasl_handler_cb: New SASL server handler received from the factory empathySasl-DEBUG: 10/09/2013 12:56:44.483000: empathy_sasl_auth_password_async: Start X-TELEPATHY-PASSWORD mechanism empathySasl-DEBUG: 10/09/2013 12:56:44.483788: empathy_server_sasl_handler_provide_password: not remembering the password empathySasl-DEBUG: 10/09/2013 12:56:44.483956: channel_has_may_save_response: MaySaveResponse unknown, assuming TRUE empathySasl-DEBUG: 10/09/2013 12:56:44.851447: sasl_status_changed_cb: SASL failed: WOCKY_AUTH_ERROR_FAILURE (#6): Authentication failed: not-authorized empathyTls-DEBUG: 10/09/2013 12:56:44.851553: auth_factory_auth_passsword_failed: Authentication on gabble/jabber/clecou35_40gmail_2ecom0 failed, popup password dialog empathySasl-DEBUG: 10/09/2013 12:56:45.95393: base_password_dialog_grab_keyboard: Could not get the event device! empathySasl-DEBUG: 10/09/2013 12:56:45.95755: base_password_dialog_grab_keyboard: Could not get the event device! empathyTls-DEBUG: 10/09/2013 12:56:45.98056: sasl_handler_invalidated_cb: SASL handler for channel /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5/ServerSASLChannel is invalidated, unref it empathySasl-DEBUG: 10/09/2013 12:56:45.98146: empathy_server_sasl_handler_dispose: 0x7f9968003e30 empathySasl-DEBUG: 10/09/2013 12:56:45.98232: empathy_server_sasl_handler_finalize: 0x7f9968003e30 empathySasl-DEBUG: 10/09/2013 12:56:45.151520: base_password_dialog_grab_keyboard: Could not get the event device! empathySasl-DEBUG: 10/09/2013 12:56:45.155124: base_password_dialog_grab_keyboard: Could not get the event device! empathySasl-DEBUG: 10/09/2013 12:56:45.213064: base_password_dialog_grab_keyboard: Could not get the event device! empathySasl-DEBUG: 10/09/2013 12:56:49.730067: base_password_dialog_grab_keyboard: Could not get the event device! empathySasl-DEBUG: 10/09/2013 12:56:52.660825: base_password_dialog_grab_keyboard: Could not get the event device! empathySasl-DEBUG: 10/09/2013 12:56:55.110507: base_password_dialog_grab_keyboard: Could not get the event device! empathySasl-DEBUG: 10/09/2013 12:56:57.881014: base_password_dialog_grab_keyboard: Could not get the event device! empathyTls-DEBUG: 10/09/2013 12:57:02.234687: retry_account_cb: Try reconnecting to gabble/jabber/clecou35_40gmail_2ecom0 empathyTls-DEBUG: 10/09/2013 12:57:02.804307: handle_channels: Handle TLS or SASL carrier channels. empathyTls-DEBUG: 10/09/2013 12:57:02.804436: tls_handler_init_async: Received hostname: gmail.com empathyTls-DEBUG: 10/09/2013 12:57:02.804537: tls_handler_init_async: Received reference identities: gmail.com, talk.google.com, talk.google.com empathyTls-DEBUG: 10/09/2013 12:57:02.804582: tls_handler_init_async: Creating an TpTLSCertificate for path /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5/ServerTLSChannel1/TLSCertificateObject, bus name :1.75 empathyTls-DEBUG: 10/09/2013 12:57:02.805391: auth_factory_new_tls_handler_cb: New TLS server handler received from the factory empathyTls-DEBUG: 10/09/2013 12:57:02.805435: empathy_tls_verifier_verify_async: Starting verification empathyTls-DEBUG: 10/09/2013 12:57:02.805545: empathy_server_tls_handler_finalize: 0xed9230 /-DEBUG: 10/09/2013 12:57:02.807970: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.807980: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.808191: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.808198: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.808378: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.808387: generalTime generalTime: decoded tag did not match expected empathyTls-DEBUG: 10/09/2013 12:57:02.818301: perform_verification: Performing verification empathyTls-DEBUG: 10/09/2013 12:57:02.818341: debug_certificate_chain: Certificate chain: length 4 status anchored empathyTls-DEBUG: 10/09/2013 12:57:02.818722: debug_certificate: Certificate: C=US, ST=California, L=Mountain View, O=Google Inc, CN=gmail.com empathyTls-DEBUG: 10/09/2013 12:57:02.818916: debug_certificate: Certificate: C=US, O=Google Inc, CN=Google Internet Authority G2 empathyTls-DEBUG: 10/09/2013 12:57:02.819130: debug_certificate: Certificate: C=US, O=GeoTrust Inc., CN=GeoTrust Global CA empathyTls-DEBUG: 10/09/2013 12:57:02.819611: debug_certificate: Certificate: C=US, O=Equifax, OU=Equifax Secure Certificate Authority empathyTls-DEBUG: 10/09/2013 12:57:02.820861: perform_verification: Certificate verification gave result 1 with reason 0 empathyTls-DEBUG: 10/09/2013 12:57:02.820975: perform_verification: Hostname matched empathyTls-DEBUG: 10/09/2013 12:57:02.821060: complete_verification: Verification successful, completing... /-DEBUG: 10/09/2013 12:57:02.818489: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818497: printableString printableString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818501: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818514: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818517: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818563: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818568: printableString printableString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818572: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818577: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818581: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818633: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818636: printableString printableString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818640: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818645: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818649: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818698: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818702: printableString printableString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818706: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818711: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818716: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818829: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818834: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818837: utf8String utf8String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818841: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818845: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818892: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818897: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818901: utf8String utf8String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818905: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.818908: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819034: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819039: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819042: utf8String utf8String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819046: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819050: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819089: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819094: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819098: utf8String utf8String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819102: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819106: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819358: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819364: generalTime generalTime: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819518: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819523: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819526: utf8String utf8String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819539: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819542: ia5String ia5String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819586: teletexString teletexString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819590: universalString universalString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819593: utf8String utf8String: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819597: bmpString bmpString: decoded tag did not match expected /-DEBUG: 10/09/2013 12:57:02.819602: ia5String ia5String: decoded tag did not match expected empathyTls-DEBUG: 10/09/2013 12:57:02.832770: empathy_tls_verifier_finalize: 0x7f9968003ad0 empathyTls-DEBUG: 10/09/2013 12:57:02.903991: observe_channels: New auth channel to observe empathyTls-DEBUG: 10/09/2013 12:57:02.904196: observe_channels: We have a retry password for account gabble/jabber/clecou35_40gmail_2ecom0, calling Claim empathyTls-DEBUG: 10/09/2013 12:57:02.904817: password_claim_cb: Claim called successfully empathyOther-DEBUG: 10/09/2013 12:57:02.904931: empathy_keyring_get_account_password_async: Trying to get password for: gabble/jabber/clecou35_40gmail_2ecom0 empathyTls-DEBUG: 10/09/2013 12:57:02.913687: server_sasl_handler_ready_cb: Use retry password empathySasl-DEBUG: 10/09/2013 12:57:02.913974: empathy_sasl_auth_password_async: Start X-TELEPATHY-PASSWORD mechanism empathySasl-DEBUG: 10/09/2013 12:57:02.915031: empathy_server_sasl_handler_provide_password: remembering the password empathySasl-DEBUG: 10/09/2013 12:57:02.915204: channel_has_may_save_response: MaySaveResponse unknown, assuming TRUE empathyTls-DEBUG: 10/09/2013 12:57:02.915282: auth_factory_new_sasl_handler_cb: New SASL server handler received from the factory empathySasl-DEBUG: 10/09/2013 12:57:02.915642: empathy_sasl_auth_password_async: Start X-TELEPATHY-PASSWORD mechanism empathySasl-DEBUG: 10/09/2013 12:57:02.916001: empathy_server_sasl_handler_provide_password: not remembering the password empathySasl-DEBUG: 10/09/2013 12:57:02.916169: channel_has_may_save_response: MaySaveResponse unknown, assuming TRUE empathyTls-DEBUG: 10/09/2013 12:57:02.917062: sasl_handler_invalidated_cb: SASL handler for channel /org/freedesktop/Telepathy/Connection/gabble/jabber/clecou35_40gmail_2ecom_2f53151ac5/ServerSASLChannel is invalidated, unref it