tp-glib/proxy-DEBUG: tp_proxy_dispose: 0x1c3c800 tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0x1c3c800: Proxy unreferenced tp-glib/proxy-DEBUG: tp_proxy_finalize: 0x1c3c800 gabble-DEBUG: 2012-02-21T12:52:59.798728Z.798728: gabble_plugin_loader_probe (plugin-loader.c:134): probing /home/cassidy/dev/telepathy-gabble/plugins/.libs ** DEBUG: 2012-02-21T12:52:59.799007Z.799007: gabble_plugin_create: loaded gabble-DEBUG: 2012-02-21T12:52:59.799103Z.799103: plugin_loader_try_to_load (plugin-loader.c:99): loaded 'Gateway registration plugin' version 0.15.3.1 (/home/cassidy/dev/telepathy-gabble/plugins/.libs/libgateways.so), implementing these sidecars: org.freedesktop.Telepathy.Gabble.Plugin.Gateways ** DEBUG: 2012-02-21T12:52:59.799278Z.799278: gabble_plugin_create: loaded gabble-DEBUG: 2012-02-21T12:52:59.799338Z.799338: plugin_loader_try_to_load (plugin-loader.c:99): loaded 'XMPP console' version 0.15.3.1 (/home/cassidy/dev/telepathy-gabble/plugins/.libs/libconsole.so), implementing these sidecars: org.freedesktop.Telepathy.Gabble.Plugin.Console gabble-DEBUG: 2012-02-21T12:52:59.799454Z.799454: plugin_loader_try_to_load (plugin-loader.c:61): /home/cassidy/dev/telepathy-gabble/plugins/.libs/console.so: undefined symbol: gabble_plugin_create_sidecar gabble-DEBUG: 2012-02-21T12:52:59.799558Z.799558: plugin_loader_try_to_load (plugin-loader.c:61): /home/cassidy/dev/telepathy-gabble/plugins/.libs/test.so: undefined symbol: gabble_plugin_create_sidecar gabble-DEBUG: 2012-02-21T12:52:59.799663Z.799663: plugin_loader_try_to_load (plugin-loader.c:61): /home/cassidy/dev/telepathy-gabble/plugins/.libs/gateways.so: undefined symbol: gabble_plugin_create_sidecar ** DEBUG: 2012-02-21T12:52:59.799812Z.799812: test_plugin_init: 0x1c32900 gabble-DEBUG: 2012-02-21T12:52:59.799854Z.799854: plugin_loader_try_to_load (plugin-loader.c:99): loaded 'Sidecar test plugin' version 0.15.3.1 (/home/cassidy/dev/telepathy-gabble/plugins/.libs/libtest.so), implementing these sidecars: org.freedesktop.Telepathy.Gabble.Plugin.Test, org.freedesktop.Telepathy.Gabble.Plugin.Test.Props, org.freedesktop.Telepathy.Gabble.Plugin.Test.Buggy, org.freedesktop.Telepathy.Gabble.Plugin.Test.IQ tp-glib-DEBUG: 2012-02-21T12:52:59.801944Z.801944: started version 0.15.3.1 (telepathy-glib version 0.17.5.1) tp-glib/params-DEBUG: 2012-02-21T12:52:59.802835Z.802835: tp_base_protocol_sanitize_parameters: using specified value for account: "test1@localhost/Resource" tp-glib/params-DEBUG: 2012-02-21T12:52:59.802880Z.802880: tp_base_protocol_sanitize_parameters: using specified value for password: tp-glib/params-DEBUG: 2012-02-21T12:52:59.802912Z.802912: tp_base_protocol_sanitize_parameters: using specified value for server: "localhost" tp-glib/params-DEBUG: 2012-02-21T12:52:59.802942Z.802942: tp_base_protocol_sanitize_parameters: using specified value for resource: "Resource" tp-glib/params-DEBUG: 2012-02-21T12:52:59.802982Z.802982: tp_base_protocol_sanitize_parameters: using specified value for port: 4242 tp-glib/params-DEBUG: 2012-02-21T12:52:59.803016Z.803016: tp_base_protocol_sanitize_parameters: using specified value for require-encryption: FALSE tp-glib/params-DEBUG: 2012-02-21T12:52:59.803078Z.803078: tp_base_protocol_sanitize_parameters: using specified value for fallback-socks5-proxies: [] gabble-DEBUG: 2012-02-21T12:52:59.803775Z.803775: gabble_connection_class_init (connection.c:965): Initializing (GabbleConnectionClass *)0x1c37170 tp-glib/presence-DEBUG: 2012-02-21T12:52:59.804212Z.804212: tp_presence_mixin_class_init: called. gabble-DEBUG: 2012-02-21T12:52:59.804484Z.804484: gabble_connection_init (connection.c:555): Initializing (GabbleConnection *)0x1c70040 gabble-DEBUG: 2012-02-21T12:52:59.804566Z.804566: gabble_capabilities_init (capabilities.c:236): 0x1c70040 gabble-DEBUG: 2012-02-21T12:52:59.805262Z.805262: gabble_roomlist_manager_constructed (roomlist-manager.c:209): 0x1c35ac0 gabble-DEBUG: 2012-02-21T12:52:59.805355Z.805355: gabble_signal_connect_weak (util.c:676): connecting to 0x1c70040:status-changed with context 0x1c6f3a0 gabble-DEBUG: 2012-02-21T12:52:59.805476Z.805476: gabble_signal_connect_weak (util.c:676): connecting to 0x1c70040:status-changed with context 0x1c6f4a0 gabble-DEBUG: 2012-02-21T12:52:59.805628Z.805628: gabble_server_tls_manager_constructed (server-tls-manager.c:420): Server TLS Manager constructed gabble-DEBUG: 2012-02-21T12:52:59.805672Z.805672: gabble_signal_connect_weak (util.c:676): connecting to 0x1c70040:status-changed with context 0x1c6f520 gabble-DEBUG: 2012-02-21T12:52:59.805853Z.805853: gabble_signal_connect_weak (util.c:676): connecting to 0x1c70040:status-changed with context 0x1c6f240 gabble-DEBUG: 2012-02-21T12:52:59.805910Z.805910: gabble_signal_connect_weak (util.c:676): connecting to 0x1c70040:porter-available with context 0x1c6f160 ** DEBUG: 2012-02-21T12:52:59.806084Z.806084: test_plugin_create_channel_managers: plugin 0x1c32900 on connection 0x1c70040 gabble-DEBUG: 2012-02-21T12:52:59.806360Z.806360: gabble_connection_constructor (connection.c:425): Post-construction: (GabbleConnection *)0x1c70040 tp-glib/presence-DEBUG: 2012-02-21T12:52:59.807074Z.807074: tp_presence_mixin_init: called. gabble-DEBUG: 2012-02-21T12:52:59.807339Z.807339: gabble_signal_connect_weak (util.c:676): connecting to 0x1c35b80:item-found with context 0x1c8a480 gabble-DEBUG: 2012-02-21T12:52:59.807389Z.807389: gabble_signal_connect_weak (util.c:676): connecting to 0x1c70040:status-changed with context 0x1c8a520 gabble-DEBUG: 2012-02-21T12:52:59.807535Z.807535: gabble_connection_constructed (connection.c:538): defaulted resource to cb11d944 tp-glib/connection-DEBUG: 2012-02-21T12:52:59.808120Z.808120: tp_base_connection_register: 0x1c70040: bus name org.freedesktop.Telepathy.Connection.gabble.jabber.test1_40localhost_2fResource; object path /org/freedesktop/Telepathy/Connection/gabble/jabber/test1_40localhost_2fResource tp-glib/params-DEBUG: 2012-02-21T12:52:59.811393Z.811393: tp_base_protocol_sanitize_parameters: using specified value for account: "test2@localhost/Resource" tp-glib/params-DEBUG: 2012-02-21T12:52:59.811439Z.811439: tp_base_protocol_sanitize_parameters: using specified value for password: tp-glib/params-DEBUG: 2012-02-21T12:52:59.811474Z.811474: tp_base_protocol_sanitize_parameters: using specified value for server: "localhost" tp-glib/params-DEBUG: 2012-02-21T12:52:59.811509Z.811509: tp_base_protocol_sanitize_parameters: using specified value for resource: "Resource" tp-glib/params-DEBUG: 2012-02-21T12:52:59.811547Z.811547: tp_base_protocol_sanitize_parameters: using specified value for port: 4343 tp-glib/params-DEBUG: 2012-02-21T12:52:59.811584Z.811584: tp_base_protocol_sanitize_parameters: using specified value for require-encryption: FALSE tp-glib/params-DEBUG: 2012-02-21T12:52:59.811635Z.811635: tp_base_protocol_sanitize_parameters: using specified value for fallback-socks5-proxies: [] gabble-DEBUG: 2012-02-21T12:52:59.811700Z.811700: gabble_connection_init (connection.c:555): Initializing (GabbleConnection *)0x1c703a0 gabble-DEBUG: 2012-02-21T12:52:59.811747Z.811747: gabble_capabilities_init (capabilities.c:236): 0x1c703a0 gabble-DEBUG: 2012-02-21T12:52:59.811988Z.811988: gabble_roomlist_manager_constructed (roomlist-manager.c:209): 0x1c35d00 gabble-DEBUG: 2012-02-21T12:52:59.812073Z.812073: gabble_signal_connect_weak (util.c:676): connecting to 0x1c703a0:status-changed with context 0x1c8a660 gabble-DEBUG: 2012-02-21T12:52:59.812144Z.812144: gabble_signal_connect_weak (util.c:676): connecting to 0x1c703a0:status-changed with context 0x1c8a2e0 gabble-DEBUG: 2012-02-21T12:52:59.812217Z.812217: gabble_server_tls_manager_constructed (server-tls-manager.c:420): Server TLS Manager constructed gabble-DEBUG: 2012-02-21T12:52:59.812255Z.812255: gabble_signal_connect_weak (util.c:676): connecting to 0x1c703a0:status-changed with context 0x1c93040 gabble-DEBUG: 2012-02-21T12:52:59.812391Z.812391: gabble_signal_connect_weak (util.c:676): connecting to 0x1c703a0:status-changed with context 0x1c93180 gabble-DEBUG: 2012-02-21T12:52:59.812442Z.812442: gabble_signal_connect_weak (util.c:676): connecting to 0x1c703a0:porter-available with context 0x1c93260 ** DEBUG: 2012-02-21T12:52:59.812558Z.812558: test_plugin_create_channel_managers: plugin 0x1c32900 on connection 0x1c703a0 gabble-DEBUG: 2012-02-21T12:52:59.812799Z.812799: gabble_connection_constructor (connection.c:425): Post-construction: (GabbleConnection *)0x1c703a0 tp-glib/presence-DEBUG: 2012-02-21T12:52:59.813249Z.813249: tp_presence_mixin_init: called. gabble-DEBUG: 2012-02-21T12:52:59.813477Z.813477: gabble_signal_connect_weak (util.c:676): connecting to 0x1c35d40:item-found with context 0x1c93600 gabble-DEBUG: 2012-02-21T12:52:59.813531Z.813531: gabble_signal_connect_weak (util.c:676): connecting to 0x1c703a0:status-changed with context 0x1c93680 gabble-DEBUG: 2012-02-21T12:52:59.813626Z.813626: gabble_connection_constructed (connection.c:538): defaulted resource to cb11d944 tp-glib/connection-DEBUG: 2012-02-21T12:52:59.814554Z.814554: tp_base_connection_register: 0x1c703a0: bus name org.freedesktop.Telepathy.Connection.gabble.jabber.test2_40localhost_2fResource; object path /org/freedesktop/Telepathy/Connection/gabble/jabber/test2_40localhost_2fResource gabble-DEBUG: 2012-02-21T12:52:59.820512Z.820512: _gabble_connection_connect (connection.c:2158): disabling SRV because "server" or "old-ssl" was specified or port was not 5222, will connect to localhost gabble-DEBUG: 2012-02-21T12:52:59.820570Z.820570: _gabble_connection_connect (connection.c:2177): require-encryption is False; flipping ignore_ssl_errors to True gabble-DEBUG: 2012-02-21T12:52:59.820630Z.820630: _gabble_connection_connect (connection.c:2217): Start connecting wocky-DEBUG: 2012-02-21T12:52:59.820947Z.820947: connector_connect_async: wocky-connector.c:2314: host: localhost; port: 4242 tp-glib/connection-DEBUG: 2012-02-21T12:52:59.821247Z.821247: tp_base_connection_change_status: was 4294967295, now 1, for reason 1 tp-glib/connection-DEBUG: 2012-02-21T12:52:59.821291Z.821291: tp_base_connection_change_status: emitting status-changed to 1, for reason 1 gabble-DEBUG: 2012-02-21T12:52:59.821335Z.821335: gabble_signal_connect_weak (util.c:676): connecting to 0x1c35b80:item-found with context 0x1c8a580 gabble-DEBUG: 2012-02-21T12:52:59.821373Z.821373: gabble_signal_connect_weak (util.c:676): connecting to 0x1c35b80:done with context 0x1c8a860 gabble-DEBUG: 2012-02-21T12:52:59.821410Z.821410: connection_status_changed_cb (server-tls-manager.c:123): Connection status changed, now 1 wocky-DEBUG: 2012-02-21T12:52:59.822711Z.822711: tcp_host_connected: wocky-connector.c:937: HOST connection succeeded wocky-DEBUG: 2012-02-21T12:52:59.823190Z.823190: xmpp_init: wocky-connector.c:1056: sending XMPP stream open to server wocky-DEBUG: 2012-02-21T12:52:59.823267Z.823267: wocky_xmpp_writer_stream_open: wocky-xmpp-writer.c:298: Writing stream opening: wocky-DEBUG: 2012-02-21T12:52:59.823490Z.823490: xmpp_init_sent_cb: wocky-connector.c:1077: waiting for stream open from server gabble-DEBUG: 2012-02-21T12:52:59.829719Z.829719: _gabble_connection_connect (connection.c:2158): disabling SRV because "server" or "old-ssl" was specified or port was not 5222, will connect to localhost gabble-DEBUG: 2012-02-21T12:52:59.829781Z.829781: _gabble_connection_connect (connection.c:2177): require-encryption is False; flipping ignore_ssl_errors to True gabble-DEBUG: 2012-02-21T12:52:59.829841Z.829841: _gabble_connection_connect (connection.c:2217): Start connecting wocky-DEBUG: 2012-02-21T12:52:59.829919Z.829919: connector_connect_async: wocky-connector.c:2314: host: localhost; port: 4343 tp-glib/connection-DEBUG: 2012-02-21T12:52:59.830039Z.830039: tp_base_connection_change_status: was 4294967295, now 1, for reason 1 tp-glib/connection-DEBUG: 2012-02-21T12:52:59.830085Z.830085: tp_base_connection_change_status: emitting status-changed to 1, for reason 1 gabble-DEBUG: 2012-02-21T12:52:59.830126Z.830126: gabble_signal_connect_weak (util.c:676): connecting to 0x1c35d40:item-found with context 0x1cba480 gabble-DEBUG: 2012-02-21T12:52:59.830164Z.830164: gabble_signal_connect_weak (util.c:676): connecting to 0x1c35d40:done with context 0x1cba400 gabble-DEBUG: 2012-02-21T12:52:59.830217Z.830217: connection_status_changed_cb (server-tls-manager.c:123): Connection status changed, now 1 wocky-DEBUG: 2012-02-21T12:52:59.830558Z.830558: tcp_host_connected: wocky-connector.c:937: HOST connection succeeded wocky-DEBUG: 2012-02-21T12:52:59.830658Z.830658: xmpp_init: wocky-connector.c:1056: sending XMPP stream open to server wocky-DEBUG: 2012-02-21T12:52:59.830707Z.830707: wocky_xmpp_writer_stream_open: wocky-xmpp-writer.c:298: Writing stream opening: wocky-DEBUG: 2012-02-21T12:52:59.830849Z.830849: xmpp_init_sent_cb: wocky-connector.c:1077: waiting for stream open from server wocky-DEBUG: 2012-02-21T12:52:59.831343Z.831343: Parsing chunk: DIGEST-MD5 wocky-DEBUG: 2012-02-21T12:52:59.831448Z.831448: handle_stream_open: wocky-xmpp-reader.c:431: Received stream opening: stream, prefix: stream, uri: http://etherx.jabber.org/streams wocky-DEBUG: 2012-02-21T12:52:59.831487Z.831487: handle_stream_open: wocky-xmpp-reader.c:450: Stream opening attribute: version = '1.0' (prefix: , uri: ) wocky-DEBUG: 2012-02-21T12:52:59.831617Z.831617: _end_element_ns: Received stanza * features xmlns='http://etherx.jabber.org/streams' * mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl' * mechanism "DIGEST-MD5" * starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls' * required wocky-DEBUG: 2012-02-21T12:52:59.831681Z.831681: xmpp_init_recv_cb: wocky-connector.c:1111: TCP Connection Established: : received XMPP version=1.0 stream open from server wocky-DEBUG: 2012-02-21T12:52:59.831731Z.831731: xmpp_init_recv_cb: wocky-connector.c:1126: waiting for feature stanza from server wocky-DEBUG: 2012-02-21T12:52:59.831805Z.831805: xmpp_features_cb: wocky-connector.c:1182: received feature stanza from server wocky-DEBUG: 2012-02-21T12:52:59.831908Z.831908: do_starttls: wocky-tls-connector.c:443: Sending STARTTLS stanza wocky-DEBUG: 2012-02-21T12:52:59.831961Z.831961: _write_node_tree: Serializing tree: * starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls' wocky-DEBUG: 2012-02-21T12:52:59.832014Z.832014: Writing xml: wocky-DEBUG: 2012-02-21T12:52:59.832113Z.832113: starttls_sent_cb: wocky-tls-connector.c:430: Sent STARTTLS stanza wocky-DEBUG: 2012-02-21T12:52:59.834552Z.834552: Parsing chunk: PLAIN wocky-DEBUG: 2012-02-21T12:52:59.834624Z.834624: handle_stream_open: wocky-xmpp-reader.c:431: Received stream opening: stream, prefix: stream, uri: http://etherx.jabber.org/streams wocky-DEBUG: 2012-02-21T12:52:59.834662Z.834662: handle_stream_open: wocky-xmpp-reader.c:450: Stream opening attribute: version = '1.0' (prefix: , uri: ) wocky-DEBUG: 2012-02-21T12:52:59.834698Z.834698: handle_stream_open: wocky-xmpp-reader.c:450: Stream opening attribute: id = '66d938797716a5c1' (prefix: , uri: ) wocky-DEBUG: 2012-02-21T12:52:59.834791Z.834791: _end_element_ns: Received stanza * features xmlns='http://etherx.jabber.org/streams' * mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl' * mechanism "PLAIN" wocky-DEBUG: 2012-02-21T12:52:59.834851Z.834851: xmpp_init_recv_cb: wocky-connector.c:1111: TCP Connection Established: : received XMPP version=1.0 stream open from server wocky-DEBUG: 2012-02-21T12:52:59.834904Z.834904: xmpp_init_recv_cb: wocky-connector.c:1126: waiting for feature stanza from server wocky-DEBUG: 2012-02-21T12:52:59.834971Z.834971: Parsing chunk: wocky-DEBUG: 2012-02-21T12:52:59.835031Z.835031: _end_element_ns: Received stanza * proceed xmlns='urn:ietf:params:xml:ns:xmpp-tls' wocky-DEBUG: 2012-02-21T12:52:59.835089Z.835089: starttls_recv_cb: wocky-tls-connector.c:372: Received STARTTLS response wocky-DEBUG: 2012-02-21T12:52:59.837120Z.837120: wocky_tls_session_constructed: wocky-tls.c:1561: priority set to: 'NORMAL:-COMP-NULL:+COMP-DEFLATE:+COMP-NULL' wocky-DEBUG: 2012-02-21T12:52:59.837195Z.837195: wocky_tls_session_add_ca: wocky-tls.c:584: adding CA CERT path '/home/cassidy/.config/telepathy/certs' wocky-DEBUG: 2012-02-21T12:52:59.837226Z.837226: add_certfiles: wocky-tls.c:543: checking /home/cassidy/.config/telepathy/certs wocky-DEBUG: 2012-02-21T12:52:59.837259Z.837259: add_certfiles: wocky-tls.c:547: ca/crl file '/home/cassidy/.config/telepathy/certs': stat failed) wocky-DEBUG: 2012-02-21T12:52:59.837287Z.837287: wocky_tls_session_add_ca: wocky-tls.c:584: adding CA CERT path '/etc/pki/tls/certs/ca-bundle.crt' wocky-DEBUG: 2012-02-21T12:52:59.837315Z.837315: add_certfiles: wocky-tls.c:543: checking /etc/pki/tls/certs/ca-bundle.crt wocky-DEBUG: 2012-02-21T12:52:59.909448Z.909448: add_certfiles: wocky-tls.c:576: + /etc/pki/tls/certs/ca-bundle.crt: 136 certs from file wocky-DEBUG: 2012-02-21T12:52:59.909533Z.909533: starttls_recv_cb: wocky-tls-connector.c:401: Starting client TLS handshake 0x1c51180 wocky-DEBUG: 2012-02-21T12:52:59.909568Z.909568: wocky_tls_session_try_operation: wocky-tls.c:401: session 0x1c51180: async job handshake wocky-DEBUG: 2012-02-21T12:52:59.909898Z.909898: xmpp_features_cb: wocky-connector.c:1182: received feature stanza from server wocky-DEBUG: 2012-02-21T12:52:59.910016Z.910016: sasl_request_auth: wocky-connector.c:1310: handing over control to SASL module wocky-DEBUG: 2012-02-21T12:52:59.910069Z.910069: wocky_auth_registry_select_handler: wocky-auth-registry.c:287: Choosing PLAIN as auth mechanism wocky-DEBUG: 2012-02-21T12:52:59.910179Z.910179: plain_initial_response: wocky-sasl-plain.c:165: Got username and password wocky-DEBUG: 2012-02-21T12:52:59.910260Z.910260: wocky_tls_session_try_operation: wocky-tls.c:401: session 0x1c51180: async job handshake wocky-DEBUG: 2012-02-21T12:52:59.910409Z.910409: _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' "AHRlc3QyAHBhc3M=" wocky-DEBUG: 2012-02-21T12:52:59.910478Z.910478: Writing xml: AHRlc3QyAHBhc3M= wocky-DEBUG: 2012-02-21T12:52:59.911542Z.911542: wocky_tls_session_try_operation: wocky-tls.c:401: session 0x1c51180: async job handshake GLib-WARNING **: 2012-02-21T12:52:59.911605Z.911605: (gerror.c:390):g_error_new_valist: runtime check failed: (domain != 0)