tp-glib-DEBUG: tp_proxy_borrow_interface_by_id: 0x24ba0f0: org.freedesktop.DBus DBusGProxy is 0x24ba2b0
tp-glib-DEBUG: started version 0.7.28.1 (telepathy-glib version 0.7.32)
tp-glib-DEBUG: tp_cm_param_setter_offset: account = "test@localhost/Resource"
tp-glib-DEBUG: tp_cm_param_setter_offset: password = <hidden>
tp-glib-DEBUG: tp_cm_param_setter_offset: server = "localhost"
tp-glib-DEBUG: tp_cm_param_setter_offset: resource = "Resource"
tp-glib-DEBUG: tp_cm_param_setter_offset: priority = 0 = 0x0
tp-glib-DEBUG: tp_cm_param_setter_offset: port = 4242 = 0x1092
tp-glib-DEBUG: tp_cm_param_setter_offset: old-ssl = FALSE
tp-glib-DEBUG: tp_cm_param_setter_offset: require-encryption = FALSE
tp-glib-DEBUG: tp_cm_param_setter_offset: register = FALSE
tp-glib-DEBUG: tp_cm_param_setter_offset: low-bandwidth = FALSE
tp-glib-DEBUG: parse_parameters: https-proxy-server not given, using default behaviour
tp-glib-DEBUG: tp_cm_param_setter_offset: https-proxy-port = 443 = 0x1bb
tp-glib-DEBUG: parse_parameters: fallback-conference-server not given, using default behaviour
tp-glib-DEBUG: parse_parameters: stun-server not given, using default behaviour
tp-glib-DEBUG: tp_cm_param_setter_offset: stun-port = 3478 = 0xd96
tp-glib-DEBUG: tp_cm_param_setter_offset: fallback-stun-server = "stun.collabora.co.uk"
tp-glib-DEBUG: tp_cm_param_setter_offset: fallback-stun-port = 3478 = 0xd96
tp-glib-DEBUG: tp_cm_param_setter_offset: ignore-ssl-errors = FALSE
tp-glib-DEBUG: parse_parameters: alias not given, using default behaviour
tp-glib-DEBUG: tp_cm_param_setter_offset: fallback-socks5-proxies = []
tp-glib-DEBUG: tp_cm_param_setter_offset: keepalive-interval = 30 = 0x1e
tp-glib-DEBUG: tp_base_connection_class_init: Initializing (TpBaseConnectionClass *)0x24b9a80
gabble-DEBUG: gabble_connection_class_init: Initializing (GabbleConnectionClass *)0x24c6120
tp-glib-DEBUG: tp_presence_mixin_class_init: called.
tp-glib-DEBUG: tp_base_connection_init: Initializing (TpBaseConnection *)0x24d0080
gabble-DEBUG: gabble_connection_init: Initializing (GabbleConnection *)0x24d0080
tp-glib-DEBUG: tp_base_connection_constructor: Post-construction: (TpBaseConnection *)0x24d0080
tp-glib-DEBUG: tp_base_connection_constructor: Handle repo for type #0 at (nil)
tp-glib-DEBUG: tp_base_connection_constructor: Handle repo for type #1 at 0x24c5aa0
tp-glib-DEBUG: tp_base_connection_constructor: Handle repo for type #2 at 0x24c5b00
tp-glib-DEBUG: tp_base_connection_constructor: Handle repo for type #3 at 0x24bc860
tp-glib-DEBUG: tp_base_connection_constructor: Handle repo for type #4 at 0x24c5b60
gabble-DEBUG: gabble_roomlist_manager_constructed: 0x24cf400
tp-glib-DEBUG: tp_base_connection_constructor: Channel manager #0 at 0x24cab40
tp-glib-DEBUG: tp_base_connection_constructor: Channel manager #1 at 0x24ca6d0
tp-glib-DEBUG: tp_base_connection_constructor: Channel manager #2 at 0x24cf400
tp-glib-DEBUG: tp_base_connection_constructor: Channel manager #3 at 0x24d3800
tp-glib-DEBUG: tp_base_connection_constructor: Channel manager #4 at 0x24cf6d0
tp-glib-DEBUG: tp_base_connection_constructor: Channel manager #5 at 0x24d59e0
tp-glib-DEBUG: tp_base_connection_constructor: Channel manager #6 at 0x24bf080
tp-glib-DEBUG: tp_base_connection_constructor: Channel manager #7 at 0x24bf0c0
gabble-DEBUG: gabble_connection_constructor: Post-construction: (GabbleConnection *)0x24d0080
tp-glib-DEBUG: tp_presence_mixin_init: called.
gabble-DEBUG: gabble_signal_connect_weak: connecting to 0x24bf140:item-found with context 0x24c1140
gabble-DEBUG: gabble_signal_connect_weak: connecting to 0x24d0080:status-changed with context 0x24c1000
gabble-DEBUG: gabble_connection_constructed: defaulted resource to Telepathy.d0733b7b
tp-glib-DEBUG: tp_base_connection_register: bus name org.freedesktop.Telepathy.Connection.gabble.jabber.test_40localhost_2fResource
tp-glib-DEBUG: tp_base_connection_register: object path /org/freedesktop/Telepathy/Connection/gabble/jabber/test_40localhost_2fResource
gabble-DEBUG: _gabble_connection_connect: disabling SRV because "server" or "port" parameter specified, will connect to localhost
gabble-DEBUG: do_connect: calling lm_connection_open
Going to connect to localhost:4242
tp-glib-DEBUG: tp_base_connection_change_status: was 4294967295, now 1, for reason 1
tp-glib-DEBUG: tp_base_connection_change_status: emitting status-changed to 1, for reason 1
gabble-DEBUG: connection_status_changed_cb: adding callbacks
gabble-DEBUG: connection_status_changed_cb: adding callbacks
gabble-DEBUG: connection_status_changed_cb: adding callbacks
Trying ::1 port 4242...
Connection failed.
Connection failed: Connection refused (error 111)
Freeing up IOChannel and file descriptor
Trying 127.0.0.1 port 4242...
Connection success (1).

SEND:
-----------------------------------
<?xml version='1.0' encoding='UTF-8'?>
-----------------------------------

SEND:
-----------------------------------
<stream:stream version="1.0" xmlns="jabber:client" xmlns:stream="http://etherx.jabber.org/streams" to="localhost" id="659720667978">
-----------------------------------

RECV [107]:
-----------------------------------
'<stream:stream xmlns:stream='http://etherx.jabber.org/streams' xmlns='jabber:client' id='6457cfd859d4aa4e'>'
-----------------------------------
gabble-DEBUG: do_auth: authenticating with username: test, password: <hidden>, resource: Resource

SEND:
-----------------------------------
<iq type="get" id="661761670018"> <query xmlns="jabber:iq:auth"> <username>test</username>
</query>
</iq>

-----------------------------------

RECV [135]:
-----------------------------------
'<iq type='result' id='661761670018'><query xmlns='jabber:iq:auth'><username>test</username><password/><digest/><resource/></query></iq>'
-----------------------------------

SEND:
-----------------------------------
<iq type="set" id="671786671785"> <query xmlns="jabber:iq:auth"> <username>test</username>
 <digest>bcb1386b602765867ee5b459f32c66c9fa931b19</digest>
 <resource>Resource</resource>
</query>
</iq>

-----------------------------------

RECV [37]:
-----------------------------------
'<iq type='result' id='671786671785'/>'
-----------------------------------
gabble-DEBUG: connection_auth_cb: Created self handle 1, our JID is test@localhost/Resource
gabble-DEBUG: gabble_presence_set_capabilities: about to add caps 140261 to resource Resource with serial 1
gabble-DEBUG: gabble_presence_set_capabilities: found resource Resource
gabble-DEBUG: gabble_presence_set_capabilities: new serial 1, old 0, clearing caps
gabble-DEBUG: gabble_presence_set_capabilities: adding caps 140261 to resource Resource
gabble-DEBUG: gabble_presence_set_capabilities: resource Resource caps now 140261
gabble-DEBUG: gabble_presence_set_capabilities: total caps now 140261
gabble-DEBUG: gabble_disco_request_with_timeout: Creating disco request 0x24bf1c0 for localhost

SEND:
-----------------------------------
<iq type="get" to="localhost" id="673963674472"> <query xmlns="http://jabber.org/protocol/disco#info"></query>
</iq>

-----------------------------------

RECV [159]:
-----------------------------------
'<iq to='localhost' type='result' id='673963674472'> <query xmlns='http://jabber.org/protocol/disco#info'><identity category='pubsub' type='pep'/></query>
</iq>'
-----------------------------------
gabble-DEBUG: connection_disco_cb: got:
<query xmlns="http://jabber.org/protocol/disco#info"> <identity type="pep" category="pubsub"></identity>
</query>

gabble-DEBUG: connection_disco_cb: set features flags to 16

SEND:
-----------------------------------
<presence id="675228675739"> <x xmlns="vcard-temp:x:update"></x>
 <c ver="L/lOGHTCbxvV8FduYaTnQ4d+J6U=" node="http://telepathy.freedesktop.org/caps" hash="sha-1" xmlns="http://jabber.org/protocol/caps"></c>
</presence>

-----------------------------------
tp-glib-DEBUG: tp_base_connection_change_status: was 1, now 0, for reason 1
tp-glib-DEBUG: tp_base_connection_change_status: emitting status-changed to 0, for reason 1
gabble-DEBUG: connection_status_changed_cb: requesting roster

SEND:
-----------------------------------
<iq type="get" id="675981676556"> <query xmlns="jabber:iq:roster"></query>
</iq>

-----------------------------------
gabble-DEBUG: gabble_disco_conn_status_changed_cb: connected, initiating service discovery on localhost
gabble-DEBUG: gabble_disco_request_with_timeout: Creating disco request 0x24bf200 for localhost

SEND:
-----------------------------------
<iq type="get" to="localhost" id="676102676674"> <query xmlns="http://jabber.org/protocol/disco#items"></query>
</iq>

-----------------------------------
gabble-DEBUG: gabble_vcard_manager_request: Created request 0x24dbed0 to retrieve <1>'s vCard
gabble-DEBUG: cache_entry_ensure_queued: Cache entry 0x24dbea0 is my own, not setting @to
gabble-DEBUG: gabble_request_pipeline_enqueue: enqueued new request as item 0x24dbf00
gabble-DEBUG: cache_entry_ensure_queued: adding request to cache entry 0x24dbea0 and queueing the <iq>
tp-glib-DEBUG: tp_presence_mixin_emit_presence_update: called.
tp-glib-DEBUG: construct_presence_hash: called.
tp-glib-DEBUG: construct_simple_presence_hash: called.

SEND:
-----------------------------------
<iq type="set" id="678200678263"> <pubsub xmlns="http://jabber.org/protocol/pubsub"> <publish node="http://laptop.org/xmpp/activities"> <item> <activities xmlns="http://laptop.org/xmpp/activities"></activities>
</item>
</publish>
</pubsub>
</iq>

-----------------------------------

SEND:
-----------------------------------
<iq type="set" id="678289678348"> <pubsub xmlns="http://jabber.org/protocol/pubsub"> <publish node="http://laptop.org/xmpp/activity-properties"> <item> <activities xmlns="http://laptop.org/xmpp/activity-properties"></activities>
</item>
</publish>
</pubsub>
</iq>

-----------------------------------
gabble-DEBUG: query_socks5_proxies: Need 5 more proxies
gabble-DEBUG: delayed_run_pipeline: calling gabble_request_pipeline_go
gabble-DEBUG: gabble_request_pipeline_go: called
gabble-DEBUG: send_next_request: processing request 0x24dbf00

SEND:
-----------------------------------
<iq type="get" id="676247676817"> <vCard xmlns="vcard-temp"></vCard>
</iq>

-----------------------------------
gabble-DEBUG: gabble_connection_set_aliases: called

SEND:
-----------------------------------
<iq type="set" id="679362720894"> <pubsub xmlns="http://jabber.org/protocol/pubsub"> <publish node="http://jabber.org/protocol/nick"> <item> <nick xmlns="http://jabber.org/protocol/nick">Some Guy</nick>
</item>
</publish>
</pubsub>
</iq>

-----------------------------------
gabble-DEBUG: gabble_vcard_manager_edit: called; invalidating cache
gabble-DEBUG: cache_entry_attempt_to_free: Not freeing vCard cache entry 0x24dbea0: it has a pipeline_item 0x24dbf00
gabble-DEBUG: gabble_vcard_manager_edit: checking if we have pending requests already
gabble-DEBUG: gabble_vcard_manager_edit: NICKNAME => value of length 8 starting Some Guy

RECV [68]:
-----------------------------------
'<iq type='result' id='676247676817'><vCard xmlns='vcard-temp'/></iq>'
-----------------------------------
gabble-DEBUG: response_cb: got reply for request 0x24dbf00
gabble-DEBUG: pipeline_reply_cb: called for entry 0x24dbea0
gabble-DEBUG: pipeline_reply_cb: will patch vcard
gabble-DEBUG: gabble_request_pipeline_enqueue: enqueued new request as item 0x24e1cd0
gabble-DEBUG: observe_vcard: got no vCard alias for handle 1

SEND:
-----------------------------------
<presence id="658700724224"> <x xmlns="vcard-temp:x:update"> <photo></photo>
</x>
 <c ver="L/lOGHTCbxvV8FduYaTnQ4d+J6U=" node="http://telepathy.freedesktop.org/caps" hash="sha-1" xmlns="http://jabber.org/protocol/caps"></c>
</presence>

-----------------------------------
gabble-DEBUG: delete_request: Discarding request 0x24dbed0
gabble-DEBUG: cache_entry_attempt_to_free: Not freeing vCard cache entry 0x24dbea0: it has a cached vCard 0x24e08f0
gabble-DEBUG: delete_item: deleting item 0x24dbf00
gabble-DEBUG: response_cb: calling gabble_request_pipeline_go
gabble-DEBUG: gabble_request_pipeline_go: called
gabble-DEBUG: send_next_request: processing request 0x24e1cd0

SEND:
-----------------------------------
<iq type="set" id="657459723566"> <vCard xmlns="vcard-temp"> <NICKNAME>Some Guy</NICKNAME>
</vCard>
</iq>

-----------------------------------
gabble-DEBUG: delayed_run_pipeline: calling gabble_request_pipeline_go
gabble-DEBUG: gabble_request_pipeline_go: called
gabble-DEBUG: gabble_connection_set_avatar: called
gabble-DEBUG: gabble_vcard_manager_edit: called; invalidating cache
gabble-DEBUG: gabble_vcard_manager_edit: checking if we have pending requests already
gabble-DEBUG: gabble_vcard_manager_edit: we don't, create one
gabble-DEBUG: gabble_vcard_manager_request: Created request 0x24e1d90 to retrieve <1>'s vCard
gabble-DEBUG: cache_entry_ensure_queued: Cache entry 0x24e1d60 is my own, not setting @to
gabble-DEBUG: gabble_request_pipeline_enqueue: enqueued new request as item 0x24e2800
gabble-DEBUG: cache_entry_ensure_queued: adding request to cache entry 0x24e1d60 and queueing the <iq>
gabble-DEBUG: gabble_vcard_manager_edit: PHOTO => value of length 18 starting image/png aGVsbG8=
tp-glib-DEBUG: tp_base_connection_change_status: was 0, now 2, for reason 1
tp-glib-DEBUG: tp_base_connection_change_status: emitting status-changed to 2, for reason 1
gabble-DEBUG: gabble_roster_close_all: closing channels
gabble-DEBUG: gabble_roster_close_all: removing callbacks
gabble-DEBUG: gabble_im_factory_close_all: closing channels
gabble-DEBUG: gabble_im_factory_close_all: removing callbacks
gabble-DEBUG: connection_status_changed_cb: disconnected, closing room lists
gabble-DEBUG: gabble_roomlist_manager_close_all: 0x24cf400
gabble-DEBUG: gabble_muc_factory_close_all: closing channels
gabble-DEBUG: gabble_muc_factory_close_all: removing callbacks
gabble-DEBUG: gabble_private_tubes_factory_close_all: closing 1-1 tubes channels
gabble-DEBUG: gabble_media_factory_close_all: closing channels
gabble-DEBUG: connection_shut_down: still open; calling lm_connection_close

SEND:
-----------------------------------
</stream:stream>
-----------------------------------
Freeing up IOChannel and file descriptor
gabble-DEBUG: connection_disconnected_cb: called with reason 0
gabble-DEBUG: connection_disconnected_cb: expected; emitting DISCONNECTED
tp-glib-DEBUG: connection_shutdown_finished_cb: dereferenced connection
gabble-DEBUG: gabble_connection_dispose: called
gabble-DEBUG: gabble_bytestream_factory_dispose: dispose called
gabble-DEBUG: user_data_destroyed_cb: user_data for 0x24c1140 destroyed; disconnecting
gabble-DEBUG: user_data_destroyed_cb: user_data for 0x24c1000 destroyed; disconnecting
gabble-DEBUG: gabble_disco_dispose: dispose called
gabble-DEBUG: disco_items_cb: Got error on items request: Request for items on localhost cancelled
gabble-DEBUG: gabble_disco_finalize: called with 0x24bf140
gabble-DEBUG: gabble_request_pipeline_dispose: disposing request-pipeline
gabble-DEBUG: replace_reply_cb: called: some error
gabble-DEBUG: gabble_vcard_manager_remove_edit_request: request == 0x24e1ca0
gabble-DEBUG: delete_item: deleting item 0x24e1cd0
gabble-DEBUG: pipeline_reply_cb: called for entry 0x24e1d60
gabble-DEBUG: delete_request: Discarding request 0x24e1d90
gabble-DEBUG: delete_item: deleting item 0x24e2800
gabble-DEBUG: gabble_vcard_manager_dispose: 0x24cac40
gabble-DEBUG: gabble_vcard_manager_remove_edit_request: request == 0x24e2830
gabble-DEBUG: gabble_vcard_manager_finalize: 0x24cac40
gabble-DEBUG: gabble_jingle_factory_dispose: dispose called
gabble-DEBUG: gabble_presence_cache_dispose: dispose called
gabble-DEBUG: gabble_presence_cache_finalize: called with 0x24d3880
gabble-DEBUG: gabble_roster_dispose: dispose called
gabble-DEBUG: gabble_roster_close_all: closing channels
gabble-DEBUG: gabble_roster_finalize: called with 0x24cab40
gabble-DEBUG: gabble_im_factory_dispose: dispose called
gabble-DEBUG: gabble_roomlist_manager_dispose: running
gabble-DEBUG: gabble_roomlist_manager_close_all: 0x24cf400
gabble-DEBUG: gabble_muc_factory_dispose: dispose called
gabble-DEBUG: gabble_muc_factory_close_all: closing channels
gabble-DEBUG: gabble_private_tubes_factory_dispose: dispose called
gabble-DEBUG: gabble_private_tubes_factory_close_all: closing 1-1 tubes channels
gabble-DEBUG: gabble_media_factory_dispose: dispose called
gabble-DEBUG: gabble_media_factory_close_all: closing channels
gabble-DEBUG: gabble_olpc_gadget_manager_dispose: running
gabble-DEBUG: gabble_olpc_gadget_manager_close_all: 0x24bf080
tp-glib-DEBUG: tp_proxy_signal_connection_lost_weak_ref: 0x24ca9f0: lost weak ref to 0x24c5aa0
tp-glib-DEBUG: tp_proxy_signal_connection_lost_weak_ref: 0x24caa60: lost weak ref to 0x24c5b00
tp-glib-DEBUG: tp_proxy_signal_connection_lost_weak_ref: 0x24caad0: lost weak ref to 0x24c5b60
gabble-DEBUG: gabble_connection_finalize: called with 0x24d0080
tp-glib-DEBUG: tp_contacts_mixin_finalize: 0x24d0080
tp-glib-DEBUG: tp_presence_mixin_finalize: 0x24d0080