$ make -C tests/twisted check-twisted TWISTED_TESTS=account-storage/default-keyring-storage.py Testing account-storage/default-keyring-storage.py ... FAIL: account-storage/default-keyring-storage.py (1) ===== log file: missioncontrol-24416.log ===== (process:24416): mcd-DEBUG: telepathy-mission-control version 5.99.6+ (process:24416): mc-plugins-DEBUG: loader.c:166 mcp-account-diversion.lai is not a loadable module (process:24416): mc-plugins-DEBUG: loader.c:160 mc-debug-server isn't a plugin (doesn't start with mcp-) (process:24416): mc-plugins-DEBUG: loader.c:160 fake-network-monitor.o isn't a plugin (doesn't start with mcp-) (process:24416): mc-plugins-DEBUG: loader.c:160 dbus-account-plugin.o isn't a plugin (doesn't start with mcp-) (process:24416): mc-plugins-DEBUG: loader.c:174 g_module_open (/home/cassidy/dev/telepathy-mission-control/tests/twisted/.libs/mcp-account-diversion.so, ...) = 0x1980450 ** (process:24416): DEBUG: Initializing mcp-account-diversion-plugin (n=0) ** (process:24416): DEBUG: account_diversion_plugin_class_init ** (process:24416): DEBUG: account_diversion_plugin_init ** (process:24416): DEBUG: Initializing mcp-account-diversion-plugin (n=1) (process:24416): mc-plugins-DEBUG: loader.c:202 1 plugin object(s) found in mcp-account-diversion.so (process:24416): mc-plugins-DEBUG: loader.c:166 mcp-plugin.o is not a loadable module (process:24416): mc-plugins-DEBUG: loader.c:160 libgiofakenetworkmonitor.lai isn't a plugin (doesn't start with mcp-) (process:24416): mc-plugins-DEBUG: loader.c:166 mcp-account-diversion.la is not a loadable module (process:24416): mc-plugins-DEBUG: loader.c:160 libgiofakenetworkmonitor.so isn't a plugin (doesn't start with mcp-) (process:24416): mc-plugins-DEBUG: loader.c:160 libgiofakenetworkmonitor.la isn't a plugin (doesn't start with mcp-) (process:24416): mc-plugins-DEBUG: loader.c:166 mcp-account-diversion.o is not a loadable module (process:24416): mc-plugins-DEBUG: loader.c:166 mcp-plugin.la is not a loadable module (process:24416): mc-plugins-DEBUG: loader.c:160 lt-mc-debug-server isn't a plugin (doesn't start with mcp-) (process:24416): mc-plugins-DEBUG: loader.c:166 mcp-plugin.lai is not a loadable module (process:24416): mc-plugins-DEBUG: loader.c:174 g_module_open (/home/cassidy/dev/telepathy-mission-control/tests/twisted/.libs/mcp-plugin.so, ...) = 0x1980eb0 ** (process:24416): DEBUG: Initializing mcp-plugin (n=0) ** (process:24416): DEBUG: Initializing mcp-plugin (n=1) ** (process:24416): DEBUG: Initializing mcp-plugin (n=2) ** (process:24416): DEBUG: Initializing mcp-plugin (n=3) ** (process:24416): DEBUG: Initializing mcp-plugin (n=4) ** (process:24416): DEBUG: test_dbus_account_plugin_class_init: called ** (process:24416): DEBUG: test_dbus_account_plugin_init: called ** (process:24416): DEBUG: Initializing mcp-plugin (n=5) (process:24416): mc-plugins-DEBUG: loader.c:202 5 plugin object(s) found in mcp-plugin.so (process:24416): mcd-DEBUG: mcd_service_init: called (process:24416): mcd-DEBUG: _mcd_account_manager_constructed: ** (process:24416): DEBUG: g_io_module_load: FakeNetworkMonitor plugin ** (process:24416): DEBUG: g_io_module_load: FakeNetworkMonitor plugin ** (process:24416): DEBUG: fake_network_monitor_class_init: enter ** (process:24416): DEBUG: fake_network_monitor_init: enter (process:24416): mcd-DEBUG: connectivity_monitor_network_changed: GNetworkMonitor (FakeNetworkMonitor) says we are offline (process:24416): mcd-DEBUG: connectivity_monitor_change_states: awake: 1 -> 1; up: 2 -> 0; stable: 4 -> 4; running: 8 -> 8 (process:24416): mcd-DEBUG: connectivity_monitor_change_states: disconnected (process:24416): mcd-DEBUG: connectivity_monitor_nm_state_change_cb: New NetworkManager network state 70 (stable state) (process:24416): mcd-DEBUG: plugin_iface_init: (process:24416): mcd-DEBUG: _mcd_account_manager_constructed: loading plugins (process:24416): mcd-DEBUG: mcd_account_manager_default_class_init: mcd_account_manager_default_class_init (process:24416): mcd-DEBUG: mcd_account_manager_default_init: mcd_account_manager_default_init (process:24416): mcd-DEBUG: sort_and_cache_plugins: found plugin TestDBusAccount [TestDBusAccountPlugin; priority 200] Regression test plugin (process:24416): mcd-DEBUG: sort_and_cache_plugins: found plugin diverted-keyfile [AccountDiversionPlugin; priority 100] Test plugin that grabs all accounts it receives (except 'fakecm/fakeprotocol/dontdivert*') and diverts them to 'mcp-test-diverted-account-plugin.conf' in g_get_user_cache_dir () instead of the usual location. (process:24416): mcd-DEBUG: sort_and_cache_plugins: found plugin default [McdAccountManagerDefault; priority 0] Default account storage backend (process:24416): mc-plugins-DEBUG: account-storage.c:823: default: (process:24416): mcd-DEBUG: am_default_load_directory: Error opening directory '/home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control': No such file or directory (process:24416): mcd-DEBUG: am_default_load_directory: Error opening directory '/home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/share/telepathy-1/mission-control': No such file or directory (process:24416): mcd-DEBUG: am_default_load_directory: Error opening directory '/home/cassidy/dev/telepathy-mission-control/tests/twisted/telepathy-1/mission-control': No such file or directory (process:24416): mcd-DEBUG: _list: Creating initial account data (process:24416): mcd-DEBUG: _list: Saving initial or migrated account data (process:24416): mcd-DEBUG: _commit: Saving accounts to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24416): mcd-DEBUG: mcd_ensure_directory: /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24416): mcd-DEBUG: mcd_storage_load: listing from plugin default [prio: 0] (process:24416): mc-plugins-DEBUG: account-storage.c:823: diverted-keyfile: ** (process:24416): DEBUG: checking for /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/cache/mcp-test-diverted-account-plugin.conf ** (process:24416): DEBUG: created /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/cache/mcp-test-diverted-account-plugin.conf (process:24416): mcd-DEBUG: mcd_storage_load: listing from plugin diverted-keyfile [prio: 100] (process:24416): mc-plugins-DEBUG: account-storage.c:823: TestDBusAccount: ** (process:24416): DEBUG: test_dbus_account_plugin_list: called (process:24416): mcd-DEBUG: mcd_storage_load: listing from plugin TestDBusAccount [prio: 200] (process:24416): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal created (process:24416): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal created (process:24416): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal created (process:24416): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal toggled (process:24416): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal toggled (process:24416): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal toggled (process:24416): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal deleted (process:24416): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal deleted (process:24416): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal deleted (process:24416): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal altered-one (process:24416): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal altered-one (process:24416): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal altered-one (process:24416): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal reconnect (process:24416): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal reconnect (process:24416): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal reconnect (process:24416): mcd-DEBUG: mcd_client_registry_constructed: Starting to look for clients (process:24416): tp-glib/proxy-DEBUG: _tp_proxy_get_interface_by_id: 0x19649f0: org.freedesktop.DBus DBusGProxy is 0x19ab570 (process:24416): mcd-DEBUG: mcd_account_manager_write_conf_async: updating all 0 accounts (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin TestDBusAccount to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:723: TestDBusAccount: committing all accounts ** (process:24416): DEBUG: test_dbus_account_plugin_commit: called (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin diverted-keyfile to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:723: diverted-keyfile: committing all accounts (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin default to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:723: default: committing all accounts (process:24416): mcd-DEBUG: mcd_storage_ready: Unblocking async account ops by TestDBusAccount ** (process:24416): DEBUG: test_dbus_account_plugin_ready: called (process:24416): mcd-DEBUG: mcd_storage_ready: Unblocking async account ops by diverted-keyfile (process:24416): mcd-DEBUG: mcd_storage_ready: Unblocking async account ops by default (process:24416): mcd-DEBUG: release_load_accounts_lock: called, count is now 0 (process:24416): mcd-DEBUG: mcd_service_constructed: called (process:24416): mcd-DEBUG: mcd_service_obtain_bus_name: Requesting MC dbus service (process:24416): mcd-DEBUG: Object Hierarchy of object 0x1960c20 (process:24416): mcd-DEBUG: [ (process:24416): mcd-DEBUG: McdService (0x1960c20): 2 (process:24416): mcd-DEBUG: ] ** (process:24416): DEBUG: fake_network_monitor_get_properties_cb: Initial state: online ** (process:24416): DEBUG: fake_network_monitor_set_state: New fake ConnMan network state online (an available state) ** (process:24416): DEBUG: fake_network_monitor_set_state: notify::network-available ** (process:24416): DEBUG: fake_network_monitor_emit_network_changed: available=1 (process:24416): mcd-DEBUG: connectivity_monitor_network_changed: GNetworkMonitor (FakeNetworkMonitor) says we are at least partially online (process:24416): mcd-DEBUG: connectivity_monitor_change_states: awake: 1 -> 1; up: 0 -> 2; stable: 4 -> 4; running: 8 -> 8 (process:24416): mcd-DEBUG: connectivity_monitor_change_states: connected (process:24416): mcd-DEBUG: mcd_client_registry_list_names_cb: ListNames returned (process:24416): tp-glib/proxy-DEBUG: noc_idle_context_invoke: NameOwnerChanged(im.telepathy.v1.ChannelDispatcher, -> :1.2) (process:24416): mcd-DEBUG: list_connection_names_cb: 0 connections (process:24416): tp-glib/proxy-DEBUG: noc_idle_context_invoke: NameOwnerChanged(im.telepathy.v1.AccountManager, -> :1.2) (process:24416): tp-glib/proxy-DEBUG: noc_idle_context_invoke: NameOwnerChanged(im.telepathy.v1.MissionControl6, -> :1.2) (process:24416): mcd-DEBUG: connectivity_monitor_nm_state_change_cb: New NetworkManager network state 70 (stable state) (process:24416): mcd-DEBUG: login1_inhibit_cb: unable to delay sleep and shutdown: g-dbus-error-quark #2: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.login1 was not provided by any .service files (process:24416): mcd-DEBUG: mcd_client_registry_list_activatable_names_cb: ListActivatableNames returned (process:24416): mcd-DEBUG: _mcd_client_registry_found_name: Registering client im.telepathy.v1.Client.AbiWord (process:24416): mcd-DEBUG: mcd_client_proxy_constructed: im.telepathy.v1.Client.AbiWord (process:24416): tp-glib/proxy-DEBUG: tp_dbus_daemon_watch_name_owner: Adding match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.Client.AbiWord' (process:24416): mcd-DEBUG: _mcd_client_registry_inc_startup_lock: 1 -> 2 (process:24416): mcd-DEBUG: _mcd_client_registry_found_name: Registering client im.telepathy.v1.Client.Logger (process:24416): mcd-DEBUG: mcd_client_proxy_constructed: im.telepathy.v1.Client.Logger (process:24416): tp-glib/proxy-DEBUG: tp_dbus_daemon_watch_name_owner: Adding match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.Client.Logger' (process:24416): mcd-DEBUG: _mcd_client_registry_inc_startup_lock: 2 -> 3 (process:24416): mcd-DEBUG: _mcd_client_registry_dec_startup_lock: 3 -> 2 (process:24416): tp-glib/proxy-DEBUG: _tp_dbus_daemon_get_name_owner_idle: GetNameOwner(im.telepathy.v1.Client.AbiWord) raised org.freedesktop.DBus.Error.NameHasNoOwner: Could not get owner of name 'im.telepathy.v1.Client.AbiWord': no such name (process:24416): mcd-DEBUG: _mcd_client_proxy_parse_client_file: File found for im.telepathy.v1.Client.AbiWord: /home/cassidy/dev/telepathy-mission-control/tests/twisted/telepathy-1/clients/AbiWord.client (process:24416): mcd-DEBUG: _mcd_client_proxy_add_interfaces: im.telepathy.v1.Client.AbiWord: im.telepathy.v1.Client.Handler (process:24416): mcd-DEBUG: mcd_client_proxy_introspect: im.telepathy.v1.Client.AbiWord is a Handler but not active (process:24416): mcd-DEBUG: mcd_client_registry_ready_cb: im.telepathy.v1.Client.AbiWord (process:24416): mcd-DEBUG: _mcd_client_registry_dec_startup_lock: 2 -> 1 (process:24416): tp-glib/proxy-DEBUG: _tp_dbus_daemon_get_name_owner_idle: GetNameOwner(im.telepathy.v1.Client.Logger) raised org.freedesktop.DBus.Error.NameHasNoOwner: Could not get owner of name 'im.telepathy.v1.Client.Logger': no such name (process:24416): mcd-DEBUG: _mcd_client_proxy_parse_client_file: File found for im.telepathy.v1.Client.Logger: /home/cassidy/dev/telepathy-mission-control/tests/twisted/telepathy-1/clients/Logger.client (process:24416): mcd-DEBUG: _mcd_client_proxy_add_interfaces: im.telepathy.v1.Client.Logger: im.telepathy.v1.Client.Observer (process:24416): mcd-DEBUG: mcd_client_registry_ready_cb: im.telepathy.v1.Client.Logger (process:24416): mcd-DEBUG: _mcd_client_registry_dec_startup_lock: 1 -> 0 (process:24416): mcd-DEBUG: mcd_dispatcher_client_registry_ready_cb: All initial clients have been inspected (process:24416): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: im.telepathy.v1.Client.AbiWord: (process:24416): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: - 2 channel filters (process:24416): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: - 2 capability tokens: (process:24416): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: com.example.Foo (process:24416): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: com.example.Bar (process:24416): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: -end- (process:24416): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: im.telepathy.v1.Client.Logger: (process:24416): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: - 0 channel filters (process:24416): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: - 0 capability tokens: (process:24416): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: -end- (process:24416): mcd-DEBUG: dbusprop_get_all: im.telepathy.v1.AccountManager (process:24416): mcd-DEBUG: get_usable_accounts: called (process:24416): mcd-DEBUG: get_unusable_accounts: called (process:24416): mcd-DEBUG: mcd_dbus_get_interfaces: called (process:24416): tp-glib/proxy-DEBUG: noc_idle_context_invoke: NameOwnerChanged(im.telepathy.v1.ConnectionManager.fakecm, -> :1.0) (process:24416): mcd-DEBUG: _mcd_account_manager_create_account: called (process:24416): tp-glib/proxy-DEBUG: tp_proxy_constructor: 0x1962300: request 0x1988980 represents core features on TpProtocol (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: fakecm/fakeprotocol: new Protocol (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: immutable properties not supplied (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Protocol name: fakeprotocol (process:24416): tp-glib/proxy-DEBUG: request_is_complete: 0x1962300: calling callback for tp-protocol-feature-core (process:24416): tp-glib/proxy-DEBUG: tp_proxy_poll_features: 0x1962300: request 0x1988980 prepared (process:24416): tp-glib/proxy-DEBUG: tp_proxy_prepare_request_finish: 0x1988980 (process:24416): tp-glib/proxy-DEBUG: _tp_proxy_get_interface_by_id: 0x1962300: im.telepathy.v1.Protocol DBusGProxy is 0x19ab6c0 (process:24416): mc-plugins-DEBUG: account-storage.c:517: TestDBusAccount: ** (process:24416): DEBUG: test_dbus_account_plugin_set_attribute: manager of fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:475: TestDBusAccount: (process:24416): mcd-DEBUG: update_storage: MCP:TestDBusAccount -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.manager (process:24416): mc-plugins-DEBUG: account-storage.c:517: diverted-keyfile: (process:24416): mc-plugins-DEBUG: account-storage.c:475: diverted-keyfile: (process:24416): mcd-DEBUG: update_storage: MCP:diverted-keyfile -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.manager (process:24416): mc-plugins-DEBUG: account-storage.c:517: default: (process:24416): mcd-DEBUG: update_storage: MCP:default -> store attribute fakecm/fakeprotocol/dontdivert_40example_2ecom0.manager (process:24416): mc-plugins-DEBUG: account-storage.c:517: TestDBusAccount: ** (process:24416): DEBUG: test_dbus_account_plugin_set_attribute: protocol of fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:475: TestDBusAccount: (process:24416): mcd-DEBUG: update_storage: MCP:TestDBusAccount -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.protocol (process:24416): mc-plugins-DEBUG: account-storage.c:517: diverted-keyfile: (process:24416): mc-plugins-DEBUG: account-storage.c:475: diverted-keyfile: (process:24416): mcd-DEBUG: update_storage: MCP:diverted-keyfile -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.protocol (process:24416): mc-plugins-DEBUG: account-storage.c:517: default: (process:24416): mcd-DEBUG: update_storage: MCP:default -> store attribute fakecm/fakeprotocol/dontdivert_40example_2ecom0.protocol (process:24416): mc-plugins-DEBUG: account-storage.c:517: TestDBusAccount: ** (process:24416): DEBUG: test_dbus_account_plugin_set_attribute: DisplayName of fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:475: TestDBusAccount: (process:24416): mcd-DEBUG: update_storage: MCP:TestDBusAccount -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.DisplayName (process:24416): mc-plugins-DEBUG: account-storage.c:517: diverted-keyfile: (process:24416): mc-plugins-DEBUG: account-storage.c:475: diverted-keyfile: (process:24416): mcd-DEBUG: update_storage: MCP:diverted-keyfile -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.DisplayName (process:24416): mc-plugins-DEBUG: account-storage.c:517: default: (process:24416): mcd-DEBUG: update_storage: MCP:default -> store attribute fakecm/fakeprotocol/dontdivert_40example_2ecom0.DisplayName (process:24416): mcd-DEBUG: _mcd_account_constructed: 0x19683d0 (fakecm/fakeprotocol/dontdivert_40example_2ecom0) (process:24416): mc-plugins-DEBUG: account-storage.c:517: TestDBusAccount: ** (process:24416): DEBUG: test_dbus_account_plugin_set_attribute: AutomaticPresence of fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:475: TestDBusAccount: (process:24416): mcd-DEBUG: update_storage: MCP:TestDBusAccount -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.AutomaticPresence (process:24416): mc-plugins-DEBUG: account-storage.c:517: diverted-keyfile: (process:24416): mc-plugins-DEBUG: account-storage.c:475: diverted-keyfile: (process:24416): mcd-DEBUG: update_storage: MCP:diverted-keyfile -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.AutomaticPresence (process:24416): mc-plugins-DEBUG: account-storage.c:517: default: (process:24416): mcd-DEBUG: update_storage: MCP:default -> store attribute fakecm/fakeprotocol/dontdivert_40example_2ecom0.AutomaticPresence (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin TestDBusAccount fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: TestDBusAccount: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 ** (process:24416): DEBUG: test_dbus_account_plugin_commit_one: fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin diverted-keyfile fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: diverted-keyfile: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:723: diverted-keyfile: committing all accounts (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin default fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: default: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: _commit: Saving accounts to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24416): mcd-DEBUG: mcd_ensure_directory: /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24416): mcd-DEBUG: am_default_commit_one: Saving account fakecm/fakeprotocol/dontdivert_40example_2ecom0 to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0.account (process:24416): mcd-DEBUG: am_default_commit_one: {'manager': <'fakecm'>, 'AutomaticPresence': <(uint32 0, '', '')>, 'DisplayName': <'fakeaccount'>, 'protocol': <'fakeprotocol'>, 'Parameters': <@a{sv} {}>, 'KeyFileParameters': <@a{ss} {}>} (process:24416): tp-glib/proxy-DEBUG: tp_proxy_constructor: 0x1962400: request 0x7f4b7c0018c0 represents core features on TpConnectionManager (process:24416): tp-glib/proxy-DEBUG: tp_dbus_daemon_watch_name_owner: Adding match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.ConnectionManager.fakecm' (process:24416): tp-glib/manager-DEBUG: tp_connection_manager_find_manager_file: in XDG_DATA_HOME: trying /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/managers/fakecm.manager (process:24416): tp-glib/manager-DEBUG: tp_connection_manager_find_manager_file: in XDG_DATA_DIRS: trying /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/share/telepathy-1/managers/fakecm.manager (process:24416): tp-glib/manager-DEBUG: tp_connection_manager_find_manager_file: in XDG_DATA_DIRS: trying /home/cassidy/dev/telepathy-mission-control/tests/twisted/telepathy-1/managers/fakecm.manager (process:24416): tp-glib/proxy-DEBUG: request_is_complete: 0x1962400: calling callback for tp-connection-manager-feature-core (process:24416): tp-glib/proxy-DEBUG: tp_proxy_poll_features: 0x1962400: core features not ready yet, nothing prepared (process:24416): mcd-DEBUG: mcd_manager_setup: Manager fakecm created (process:24416): mcd-DEBUG: _mcd_mission_set_parent: child = 0x1966e60, parent = 0x1960c20 (process:24416): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0x1962300 (process:24416): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0x1962300: Proxy unreferenced (process:24416): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0x1962300 (process:24416): tp-glib/proxy-DEBUG: _tp_dbus_daemon_get_name_owner_idle: GetNameOwner(im.telepathy.v1.ConnectionManager.fakecm) -> :1.0 (process:24416): tp-glib/manager-DEBUG: tp_connection_manager_name_owner_changed_cb: fakecm: is now running (process:24416): tp-glib/manager-DEBUG: tp_connection_manager_name_owner_changed_cb: fakecm: starting introspection now we know the name owner (process:24416): tp-glib/manager-DEBUG: tp_connection_manager_continue_introspection: fakecm (process:24416): tp-glib/manager-DEBUG: tp_connection_manager_continue_introspection: fakecm: calling GetAll on CM (process:24416): tp-glib/proxy-DEBUG: _tp_proxy_get_interface_by_id: 0x1962400: org.freedesktop.DBus.Properties DBusGProxy is 0x19699a0 (process:24416): tp-glib/manager-DEBUG: tp_connection_manager_idle_read_manager_file: fakecm: reading /home/cassidy/dev/telepathy-mission-control/tests/twisted/telepathy-1/managers/fakecm.manager (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: fakecm: reading protocol 'fakeprotocol' from manager file (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: account (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x3 (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: s (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: NULL of type gchararray (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: password (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0xb (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: s (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: NULL of type gchararray (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: nickname (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x2 (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: s (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: NULL of type gchararray (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: register (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x4 (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: b (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: FALSE of type gboolean (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: com.example.Badgerable.Badgered (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x14 (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: b (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: FALSE of type gboolean (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: secret-mushroom (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x0 (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: s (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: NULL of type gchararray (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: snakes (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x0 (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: u (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: 0 of type guint (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: contrived-example (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x4 (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: u (process:24416): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: 5 of type guint (process:24416): tp-glib/proxy-DEBUG: tp_proxy_constructor: 0x1962500: request 0x19c1820 represents core features on TpProtocol (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: fakecm/fakeprotocol: new Protocol (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: immutable properties already supplied (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.RecommendedAvatarHeight = 0 (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.ConnectionInterfaces = NULL (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.MaximumAvatarHeight = 0 (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Icon = "" (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Parameters = ((GPtrArray_GValueArray_gchararray+guint+gchararray+GValue__*) 0x1954500) (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.VCardField = "" (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.RequestableChannelClasses = ((GPtrArray_GValueArray_GHashTable_gchararray+GValue_+GStrv__*) 0x19c1800) (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interfaces = NULL (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.RecommendedAvatarWidth = 0 (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.MaximumAvatarBytes = 0 (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.EnglishName = "" (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.MinimumAvatarHeight = 0 (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.MinimumAvatarWidth = 0 (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.SupportedAvatarMIMETypes = NULL (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.AuthenticationTypes = NULL (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.MaximumAvatarWidth = 0 (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Protocol name: fakeprotocol (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: account (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x3 (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: NULL of type gchararray (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: password (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0xb (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: NULL of type gchararray (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: nickname (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x2 (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: NULL of type gchararray (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: register (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x4 (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: b (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: FALSE of type gboolean (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: com.example.Badgerable.Badgered (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x14 (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: b (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: FALSE of type gboolean (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: secret-mushroom (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x0 (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: NULL of type gchararray (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: snakes (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x0 (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: u (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: 0 of type guint (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: contrived-example (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x4 (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: u (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: 5 of type guint (process:24416): tp-glib/proxy-DEBUG: request_is_complete: 0x1962500: calling callback for tp-protocol-feature-core (process:24416): tp-glib/params-DEBUG: tp_protocol_check_for_core: Core feature ready (process:24416): tp-glib/proxy-DEBUG: tp_proxy_poll_features: 0x1962500: request 0x19c1820 prepared (process:24416): tp-glib/proxy-DEBUG: tp_proxy_prepare_request_finish: 0x19c1820 (process:24416): tp-glib/manager-DEBUG: tp_connection_manager_idle_read_manager_file: fakecm: got info from file (process:24416): tp-glib/proxy-DEBUG: tp_proxy_poll_features: 0x1962400: request 0x7f4b7c0018c0 prepared (process:24416): tp-glib/proxy-DEBUG: tp_proxy_prepare_request_finish: 0x7f4b7c0018c0 (process:24416): tp-glib/proxy-DEBUG: tp_proxy_poll_features: 0x1962400: request 0x1988700 prepared (process:24416): tp-glib/proxy-DEBUG: tp_proxy_prepare_request_finish: 0x1988700 (process:24416): mcd-DEBUG: on_manager_ready: manager fakecm is ready (process:24416): mcd-DEBUG: mcd_account_check_parameters: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: mcd_account_check_parameters: missing required parameter 'account' (process:24416): mcd-DEBUG: _mcd_account_set_parameters: called (process:24416): mc-plugins-DEBUG: account-storage.c:562: TestDBusAccount: ** (process:24416): DEBUG: test_dbus_account_plugin_set_parameter: snakes of fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:475: TestDBusAccount: (process:24416): mcd-DEBUG: update_storage: MCP:TestDBusAccount -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.param-snakes (process:24416): mc-plugins-DEBUG: account-storage.c:562: diverted-keyfile: (process:24416): mc-plugins-DEBUG: account-storage.c:475: diverted-keyfile: (process:24416): mcd-DEBUG: update_storage: MCP:diverted-keyfile -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.param-snakes (process:24416): mc-plugins-DEBUG: account-storage.c:562: default: (process:24416): mcd-DEBUG: update_storage: MCP:default -> store parameter fakecm/fakeprotocol/dontdivert_40example_2ecom0.param-snakes (process:24416): mcd-DEBUG: mcd_storage_make_secret: flagging fakecm/fakeprotocol/dontdivert_40example_2ecom0 parameter password as secret (process:24416): mc-plugins-DEBUG: account-storage.c:562: TestDBusAccount: ** (process:24416): DEBUG: test_dbus_account_plugin_set_parameter: password of fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:475: TestDBusAccount: (process:24416): mcd-DEBUG: update_storage: MCP:TestDBusAccount -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.param-password (process:24416): mc-plugins-DEBUG: account-storage.c:562: diverted-keyfile: (process:24416): mc-plugins-DEBUG: account-storage.c:475: diverted-keyfile: (process:24416): mcd-DEBUG: update_storage: MCP:diverted-keyfile -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.param-password (process:24416): mc-plugins-DEBUG: account-storage.c:562: default: (process:24416): mcd-DEBUG: update_storage: MCP:default -> store parameter fakecm/fakeprotocol/dontdivert_40example_2ecom0.param-password (process:24416): mc-plugins-DEBUG: account-storage.c:562: TestDBusAccount: ** (process:24416): DEBUG: test_dbus_account_plugin_set_parameter: account of fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:475: TestDBusAccount: (process:24416): mcd-DEBUG: update_storage: MCP:TestDBusAccount -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.param-account (process:24416): mc-plugins-DEBUG: account-storage.c:562: diverted-keyfile: (process:24416): mc-plugins-DEBUG: account-storage.c:475: diverted-keyfile: (process:24416): mcd-DEBUG: update_storage: MCP:diverted-keyfile -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.param-account (process:24416): mc-plugins-DEBUG: account-storage.c:562: default: (process:24416): mcd-DEBUG: update_storage: MCP:default -> store parameter fakecm/fakeprotocol/dontdivert_40example_2ecom0.param-account (process:24416): mcd-DEBUG: mcd_account_check_parameters: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: check_usability_check_parameters_cb: Account usability changed (old: 0, new: 1) (process:24416): mcd-DEBUG: mcd_account_changed_property: called: Usable (process:24416): mcd-DEBUG: mcd_account_changed_property: First changed property (process:24416): mcd-DEBUG: mcd_account_request_presence_int: Requested presence: 1 offline (process:24416): mcd-DEBUG: mcd_account_would_like_to_connect: fakecm/fakeprotocol/dontdivert_40example_2ecom0 not Enabled (process:24416): mcd-DEBUG: add_account: adding account fakecm/fakeprotocol/dontdivert_40example_2ecom0 (0x19683d0) from complete_account_creation_set_cb (process:24416): mcd-DEBUG: add_account: account fakecm/fakeprotocol/dontdivert_40example_2ecom0 usability: 1 (process:24416): mcd-DEBUG: mcd_account_check_parameters: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: mcd_account_manager_write_conf_async: updating fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin TestDBusAccount fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: TestDBusAccount: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 ** (process:24416): DEBUG: test_dbus_account_plugin_commit_one: fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin diverted-keyfile fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: diverted-keyfile: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:723: diverted-keyfile: committing all accounts (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin default fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: default: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: _commit: Saving accounts to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24416): mcd-DEBUG: mcd_ensure_directory: /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24416): mcd-DEBUG: am_default_commit_one: Saving account fakecm/fakeprotocol/dontdivert_40example_2ecom0 to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0.account (process:24416): mcd-DEBUG: am_default_commit_one: {'manager': <'fakecm'>, 'AutomaticPresence': <(uint32 0, '', '')>, 'DisplayName': <'fakeaccount'>, 'protocol': <'fakeprotocol'>, 'Parameters': <{'snakes': , 'password': <'secrecy'>, 'account': <'dontdivert@example.com'>}>, 'KeyFileParameters': <@a{ss} {}>} (process:24416): mcd-DEBUG: mcd_account_would_like_to_connect: fakecm/fakeprotocol/dontdivert_40example_2ecom0 not Enabled (process:24416): mcd-DEBUG: proxy_new_cb: org.gnome.SessionManager service not found (process:24416): tp-glib/manager-DEBUG: tp_connection_manager_get_all_cb: fakecm: 1 Protocols from GetAll() (process:24416): tp-glib/proxy-DEBUG: tp_proxy_constructor: 0x1962600: request 0x19c1da0 represents core features on TpProtocol (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: fakecm/fakeprotocol: new Protocol (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: immutable properties already supplied (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.ConnectionInterfaces = [] (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.VCardField = "x-fakecm" (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Icon = "im-fakecm" (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Parameters = ((GPtrArray_GValueArray_gchararray+guint+gchararray+GValue__*) 0x19c1d40) (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.RequestableChannelClasses = ((GPtrArray_GValueArray_GHashTable_gchararray+GValue_+GStrv__*) 0x19c1b40) (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interfaces = [] (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.EnglishName = "fakecm" (process:24416): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.AuthenticationTypes = [] (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Protocol name: fakeprotocol (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: account (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x3 (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: "" of type gchararray (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: password (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0xb (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: "" of type gchararray (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: nickname (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x2 (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: "" of type gchararray (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: register (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x4 (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: b (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: FALSE of type gboolean (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: com.example.Badgerable.Badgered (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x14 (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: b (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: FALSE of type gboolean (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: secret-mushroom (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x8 (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: "" of type gchararray (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: snakes (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x0 (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: u (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: 0 of type guint (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: contrived-example (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x4 (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: u (process:24416): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: 5 of type guint (process:24416): tp-glib/proxy-DEBUG: request_is_complete: 0x1962600: calling callback for tp-protocol-feature-core (process:24416): tp-glib/params-DEBUG: tp_protocol_check_for_core: Core feature ready (process:24416): tp-glib/proxy-DEBUG: tp_proxy_poll_features: 0x1962600: request 0x19c1da0 prepared (process:24416): tp-glib/proxy-DEBUG: tp_proxy_prepare_request_finish: 0x19c1da0 (process:24416): tp-glib/manager-DEBUG: tp_connection_manager_continue_introspection: fakecm (process:24416): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0x1962500 (process:24416): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0x1962500: Proxy unreferenced (process:24416): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0x1962500 (process:24416): tp-glib/manager-DEBUG: tp_connection_manager_end_introspection: fakecm: end of introspection, info source live (2) (process:24416): mcd-DEBUG: emit_property_changed: called (process:24416): mcd-DEBUG: dbusprop_get_all: im.telepathy.v1.AccountManager (process:24416): mcd-DEBUG: get_usable_accounts: called (process:24416): mcd-DEBUG: get_unusable_accounts: called (process:24416): mcd-DEBUG: mcd_dbus_get_interfaces: called (process:24416): mcd-DEBUG: get_mcddbusprop: im.telepathy.v1.Account, DisplayName (process:24416): mcd-DEBUG: set_display_name: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:517: TestDBusAccount: ** (process:24416): DEBUG: test_dbus_account_plugin_set_attribute: DisplayName of fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:475: TestDBusAccount: (process:24416): mcd-DEBUG: update_storage: MCP:TestDBusAccount -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.DisplayName (process:24416): mc-plugins-DEBUG: account-storage.c:517: diverted-keyfile: (process:24416): mc-plugins-DEBUG: account-storage.c:475: diverted-keyfile: (process:24416): mcd-DEBUG: update_storage: MCP:diverted-keyfile -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.DisplayName (process:24416): mc-plugins-DEBUG: account-storage.c:517: default: (process:24416): mcd-DEBUG: update_storage: MCP:default -> store attribute fakecm/fakeprotocol/dontdivert_40example_2ecom0.DisplayName (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin TestDBusAccount fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: TestDBusAccount: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 ** (process:24416): DEBUG: test_dbus_account_plugin_commit_one: fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin diverted-keyfile fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: diverted-keyfile: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:723: diverted-keyfile: committing all accounts (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin default fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: default: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: _commit: Saving accounts to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24416): mcd-DEBUG: mcd_ensure_directory: /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24416): mcd-DEBUG: am_default_commit_one: Saving account fakecm/fakeprotocol/dontdivert_40example_2ecom0 to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0.account (process:24416): mcd-DEBUG: am_default_commit_one: {'manager': <'fakecm'>, 'AutomaticPresence': <(uint32 0, '', '')>, 'DisplayName': <'Work account'>, 'protocol': <'fakeprotocol'>, 'Parameters': <{'snakes': , 'password': <'secrecy'>, 'account': <'dontdivert@example.com'>}>, 'KeyFileParameters': <@a{ss} {}>} (process:24416): mcd-DEBUG: mcd_account_changed_property: called: DisplayName (process:24416): mcd-DEBUG: mcd_account_changed_property: First changed property (process:24416): mcd-DEBUG: get_mcddbusprop: im.telepathy.v1.Account, Icon (process:24416): mcd-DEBUG: set_icon: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:517: TestDBusAccount: ** (process:24416): DEBUG: test_dbus_account_plugin_set_attribute: Icon of fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:475: TestDBusAccount: (process:24416): mcd-DEBUG: update_storage: MCP:TestDBusAccount -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.Icon (process:24416): mc-plugins-DEBUG: account-storage.c:517: diverted-keyfile: (process:24416): mc-plugins-DEBUG: account-storage.c:475: diverted-keyfile: (process:24416): mcd-DEBUG: update_storage: MCP:diverted-keyfile -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.Icon (process:24416): mc-plugins-DEBUG: account-storage.c:517: default: (process:24416): mcd-DEBUG: update_storage: MCP:default -> store attribute fakecm/fakeprotocol/dontdivert_40example_2ecom0.Icon (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin TestDBusAccount fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: TestDBusAccount: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 ** (process:24416): DEBUG: test_dbus_account_plugin_commit_one: fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin diverted-keyfile fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: diverted-keyfile: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:723: diverted-keyfile: committing all accounts (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin default fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: default: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: _commit: Saving accounts to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24416): mcd-DEBUG: mcd_ensure_directory: /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24416): mcd-DEBUG: am_default_commit_one: Saving account fakecm/fakeprotocol/dontdivert_40example_2ecom0 to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0.account (process:24416): mcd-DEBUG: am_default_commit_one: {'Icon': <'im-jabber'>, 'manager': <'fakecm'>, 'AutomaticPresence': <(uint32 0, '', '')>, 'DisplayName': <'Work account'>, 'protocol': <'fakeprotocol'>, 'Parameters': <{'snakes': , 'password': <'secrecy'>, 'account': <'dontdivert@example.com'>}>, 'KeyFileParameters': <@a{ss} {}>} (process:24416): mcd-DEBUG: mcd_account_changed_property: called: Icon (process:24416): mcd-DEBUG: get_mcddbusprop: im.telepathy.v1.Account, Nickname (process:24416): mcd-DEBUG: set_nickname: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:517: TestDBusAccount: ** (process:24416): DEBUG: test_dbus_account_plugin_set_attribute: Nickname of fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:475: TestDBusAccount: (process:24416): mcd-DEBUG: update_storage: MCP:TestDBusAccount -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.Nickname (process:24416): mc-plugins-DEBUG: account-storage.c:517: diverted-keyfile: (process:24416): mc-plugins-DEBUG: account-storage.c:475: diverted-keyfile: (process:24416): mcd-DEBUG: update_storage: MCP:diverted-keyfile -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.Nickname (process:24416): mc-plugins-DEBUG: account-storage.c:517: default: (process:24416): mcd-DEBUG: update_storage: MCP:default -> store attribute fakecm/fakeprotocol/dontdivert_40example_2ecom0.Nickname (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin TestDBusAccount fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: TestDBusAccount: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 ** (process:24416): DEBUG: test_dbus_account_plugin_commit_one: fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin diverted-keyfile fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: diverted-keyfile: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:723: diverted-keyfile: committing all accounts (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin default fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: default: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: _commit: Saving accounts to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24416): mcd-DEBUG: mcd_ensure_directory: /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24416): mcd-DEBUG: am_default_commit_one: Saving account fakecm/fakeprotocol/dontdivert_40example_2ecom0 to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0.account (process:24416): mcd-DEBUG: am_default_commit_one: {'Icon': <'im-jabber'>, 'manager': <'fakecm'>, 'AutomaticPresence': <(uint32 0, '', '')>, 'DisplayName': <'Work account'>, 'protocol': <'fakeprotocol'>, 'Nickname': <'Joe Bloggs'>, 'Parameters': <{'snakes': , 'password': <'secrecy'>, 'account': <'dontdivert@example.com'>}>, 'KeyFileParameters': <@a{ss} {}>} (process:24416): mcd-DEBUG: mcd_account_changed_property: called: Nickname (process:24416): mcd-DEBUG: get_mcddbusprop: im.telepathy.v1.Account, ConnectAutomatically (process:24416): mcd-DEBUG: set_connect_automatically: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 ** (process:24416): DEBUG: test_dbus_account_plugin_owns: fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:421: diverted-keyfile: (process:24416): mc-plugins-DEBUG: account-storage.c:421: default: (process:24416): mc-plugins-DEBUG: account-storage.c:517: TestDBusAccount: ** (process:24416): DEBUG: test_dbus_account_plugin_set_attribute: ConnectAutomatically of fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:475: TestDBusAccount: (process:24416): mcd-DEBUG: update_storage: MCP:TestDBusAccount -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.ConnectAutomatically (process:24416): mc-plugins-DEBUG: account-storage.c:517: diverted-keyfile: (process:24416): mc-plugins-DEBUG: account-storage.c:475: diverted-keyfile: (process:24416): mcd-DEBUG: update_storage: MCP:diverted-keyfile -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.ConnectAutomatically (process:24416): mc-plugins-DEBUG: account-storage.c:517: default: (process:24416): mcd-DEBUG: update_storage: MCP:default -> store attribute fakecm/fakeprotocol/dontdivert_40example_2ecom0.ConnectAutomatically (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin TestDBusAccount fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: TestDBusAccount: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 ** (process:24416): DEBUG: test_dbus_account_plugin_commit_one: fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin diverted-keyfile fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: diverted-keyfile: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:723: diverted-keyfile: committing all accounts (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin default fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: default: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: _commit: Saving accounts to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24416): mcd-DEBUG: mcd_ensure_directory: /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24416): mcd-DEBUG: am_default_commit_one: Saving account fakecm/fakeprotocol/dontdivert_40example_2ecom0 to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0.account (process:24416): mcd-DEBUG: am_default_commit_one: {'Icon': <'im-jabber'>, 'manager': <'fakecm'>, 'AutomaticPresence': <(uint32 0, '', '')>, 'DisplayName': <'Work account'>, 'protocol': <'fakeprotocol'>, 'Nickname': <'Joe Bloggs'>, 'ConnectAutomatically': , 'Parameters': <{'snakes': , 'password': <'secrecy'>, 'account': <'dontdivert@example.com'>}>, 'KeyFileParameters': <@a{ss} {}>} (process:24416): mcd-DEBUG: mcd_account_changed_property: called: ConnectAutomatically (process:24416): mcd-DEBUG: mcd_account_would_like_to_connect: fakecm/fakeprotocol/dontdivert_40example_2ecom0 not Enabled (process:24416): mcd-DEBUG: get_mcddbusprop: im.telepathy.v1.Account, AutomaticPresence (process:24416): mcd-DEBUG: set_automatic_presence: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: set_automatic_presence: setting automatic presence: 4, xa, never online (process:24416): mc-plugins-DEBUG: account-storage.c:517: TestDBusAccount: ** (process:24416): DEBUG: test_dbus_account_plugin_set_attribute: AutomaticPresence of fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:475: TestDBusAccount: (process:24416): mcd-DEBUG: update_storage: MCP:TestDBusAccount -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.AutomaticPresence (process:24416): mc-plugins-DEBUG: account-storage.c:517: diverted-keyfile: (process:24416): mc-plugins-DEBUG: account-storage.c:475: diverted-keyfile: (process:24416): mcd-DEBUG: update_storage: MCP:diverted-keyfile -> ignore fakecm/fakeprotocol/dontdivert_40example_2ecom0.AutomaticPresence (process:24416): mc-plugins-DEBUG: account-storage.c:517: default: (process:24416): mcd-DEBUG: update_storage: MCP:default -> store attribute fakecm/fakeprotocol/dontdivert_40example_2ecom0.AutomaticPresence (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin TestDBusAccount fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: TestDBusAccount: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 ** (process:24416): DEBUG: test_dbus_account_plugin_commit_one: fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin diverted-keyfile fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: diverted-keyfile: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mc-plugins-DEBUG: account-storage.c:723: diverted-keyfile: committing all accounts (process:24416): mcd-DEBUG: mcd_storage_commit: flushing plugin default fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24416): mc-plugins-DEBUG: account-storage.c:781: default: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: _commit: Saving accounts to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24416): mcd-DEBUG: mcd_ensure_directory: /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24416): mcd-DEBUG: am_default_commit_one: Saving account fakecm/fakeprotocol/dontdivert_40example_2ecom0 to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0.account (process:24416): mcd-DEBUG: am_default_commit_one: {'Icon': <'im-jabber'>, 'manager': <'fakecm'>, 'AutomaticPresence': <(uint32 4, 'xa', 'never online')>, 'DisplayName': <'Work account'>, 'protocol': <'fakeprotocol'>, 'Nickname': <'Joe Bloggs'>, 'ConnectAutomatically': , 'Parameters': <{'snakes': , 'password': <'secrecy'>, 'account': <'dontdivert@example.com'>}>, 'KeyFileParameters': <@a{ss} {}>} (process:24416): mcd-DEBUG: mcd_account_changed_property: called: AutomaticPresence ** Message: Aborting by popular request (process:24416): mcd-DEBUG: _mcd_operation_abort: Operation abort received, aborting all children (process:24416): mcd-DEBUG: _mcd_operation_abort: Operation abort received, aborting all children (process:24416): mcd-DEBUG: on_parent_abort: called (process:24416): mcd-DEBUG: _mcd_mission_set_parent: child = 0x1966e60, parent = (nil) ** (process:24416): DEBUG: McdService aborted, unreffing it (process:24416): mcd-DEBUG: Object Hierarchy of object 0x1960c20 (process:24416): mcd-DEBUG: [ (process:24416): mcd-DEBUG: McdService (0x1960c20): 2 (process:24416): mcd-DEBUG: McdManager (0x1966e60): 2 (process:24416): mcd-DEBUG: ] (process:24416): mcd-DEBUG: unref_account: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24416): mcd-DEBUG: _mcd_operation_dispose: operation disposed (process:24416): mcd-DEBUG: _mcd_mission_dispose: mission disposed 0x1960c20 (process:24416): mcd-DEBUG: _mcd_mission_finalize: mission finalized 0x1960c20 (process:24416): mcd-DEBUG: emit_property_changed: called (process:24416): mcd-DEBUG: _mcd_account_dispose: 0x19683d0 (fakecm/fakeprotocol/dontdivert_40example_2ecom0) (process:24416): tp-glib/proxy-DEBUG: _tp_dbus_daemon_stop_watching: Removing match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.Client.AbiWord' (process:24416): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0x19b18d0 (process:24416): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0x19b18d0: Proxy unreferenced (process:24416): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0x19b18d0 (process:24416): tp-glib/proxy-DEBUG: _tp_dbus_daemon_stop_watching: Removing match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.Client.Logger' (process:24416): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0x19b19c0 (process:24416): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0x19b19c0: Proxy unreferenced (process:24416): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0x19b19c0 (process:24416): tp-glib/proxy-DEBUG: _tp_dbus_daemon_stop_watching: Removing match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.ConnectionManager.fakecm' (process:24416): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0x1962600 (process:24416): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0x1962600: Proxy unreferenced (process:24416): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0x1962600 (process:24416): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0x1962400 (process:24416): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0x1962400: Proxy unreferenced (process:24416): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0x1962400 (process:24416): mcd-DEBUG: _mcd_operation_dispose: operation disposed (process:24416): mcd-DEBUG: _mcd_mission_dispose: mission disposed 0x1966e60 (process:24416): mcd-DEBUG: _mcd_mission_finalize: mission finalized 0x1966e60 (process:24416): mcd-DEBUG: mcd_inhibit_release: 0x197fa00 (fd -1): 1 -> 0 (process:24416): mcd-DEBUG: _mcd_account_finalize: 0x19683d0 (fakecm/fakeprotocol/dontdivert_40example_2ecom0) (process:24416): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0x19649f0 (process:24416): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0x19649f0: Proxy unreferenced (process:24416): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0x19649f0 ** Message: Exiting with 0 ===== log file: missioncontrol-24448.log ===== (process:24448): mcd-DEBUG: telepathy-mission-control version 5.99.6+ (process:24448): mc-plugins-DEBUG: loader.c:166 mcp-account-diversion.lai is not a loadable module (process:24448): mc-plugins-DEBUG: loader.c:160 mc-debug-server isn't a plugin (doesn't start with mcp-) (process:24448): mc-plugins-DEBUG: loader.c:160 fake-network-monitor.o isn't a plugin (doesn't start with mcp-) (process:24448): mc-plugins-DEBUG: loader.c:160 dbus-account-plugin.o isn't a plugin (doesn't start with mcp-) (process:24448): mc-plugins-DEBUG: loader.c:174 g_module_open (/home/cassidy/dev/telepathy-mission-control/tests/twisted/.libs/mcp-account-diversion.so, ...) = 0x1669480 ** (process:24448): DEBUG: Initializing mcp-account-diversion-plugin (n=0) ** (process:24448): DEBUG: account_diversion_plugin_class_init ** (process:24448): DEBUG: account_diversion_plugin_init ** (process:24448): DEBUG: Initializing mcp-account-diversion-plugin (n=1) (process:24448): mc-plugins-DEBUG: loader.c:202 1 plugin object(s) found in mcp-account-diversion.so (process:24448): mc-plugins-DEBUG: loader.c:166 mcp-plugin.o is not a loadable module (process:24448): mc-plugins-DEBUG: loader.c:160 libgiofakenetworkmonitor.lai isn't a plugin (doesn't start with mcp-) (process:24448): mc-plugins-DEBUG: loader.c:166 mcp-account-diversion.la is not a loadable module (process:24448): mc-plugins-DEBUG: loader.c:160 libgiofakenetworkmonitor.so isn't a plugin (doesn't start with mcp-) (process:24448): mc-plugins-DEBUG: loader.c:160 libgiofakenetworkmonitor.la isn't a plugin (doesn't start with mcp-) (process:24448): mc-plugins-DEBUG: loader.c:166 mcp-account-diversion.o is not a loadable module (process:24448): mc-plugins-DEBUG: loader.c:166 mcp-plugin.la is not a loadable module (process:24448): mc-plugins-DEBUG: loader.c:160 lt-mc-debug-server isn't a plugin (doesn't start with mcp-) (process:24448): mc-plugins-DEBUG: loader.c:166 mcp-plugin.lai is not a loadable module (process:24448): mc-plugins-DEBUG: loader.c:174 g_module_open (/home/cassidy/dev/telepathy-mission-control/tests/twisted/.libs/mcp-plugin.so, ...) = 0x1669ee0 ** (process:24448): DEBUG: Initializing mcp-plugin (n=0) ** (process:24448): DEBUG: Initializing mcp-plugin (n=1) ** (process:24448): DEBUG: Initializing mcp-plugin (n=2) ** (process:24448): DEBUG: Initializing mcp-plugin (n=3) ** (process:24448): DEBUG: Initializing mcp-plugin (n=4) ** (process:24448): DEBUG: test_dbus_account_plugin_class_init: called ** (process:24448): DEBUG: test_dbus_account_plugin_init: called ** (process:24448): DEBUG: Initializing mcp-plugin (n=5) (process:24448): mc-plugins-DEBUG: loader.c:202 5 plugin object(s) found in mcp-plugin.so (process:24448): mcd-DEBUG: mcd_service_init: called (process:24448): mcd-DEBUG: _mcd_account_manager_constructed: ** (process:24448): DEBUG: g_io_module_load: FakeNetworkMonitor plugin ** (process:24448): DEBUG: g_io_module_load: FakeNetworkMonitor plugin ** (process:24448): DEBUG: fake_network_monitor_class_init: enter ** (process:24448): DEBUG: fake_network_monitor_init: enter (process:24448): mcd-DEBUG: connectivity_monitor_network_changed: GNetworkMonitor (FakeNetworkMonitor) says we are offline (process:24448): mcd-DEBUG: connectivity_monitor_change_states: awake: 1 -> 1; up: 2 -> 0; stable: 4 -> 4; running: 8 -> 8 (process:24448): mcd-DEBUG: connectivity_monitor_change_states: disconnected (process:24448): mcd-DEBUG: connectivity_monitor_nm_state_change_cb: New NetworkManager network state 70 (stable state) (process:24448): mcd-DEBUG: plugin_iface_init: (process:24448): mcd-DEBUG: _mcd_account_manager_constructed: loading plugins (process:24448): mcd-DEBUG: mcd_account_manager_default_class_init: mcd_account_manager_default_class_init (process:24448): mcd-DEBUG: mcd_account_manager_default_init: mcd_account_manager_default_init (process:24448): mcd-DEBUG: sort_and_cache_plugins: found plugin TestDBusAccount [TestDBusAccountPlugin; priority 200] Regression test plugin (process:24448): mcd-DEBUG: sort_and_cache_plugins: found plugin diverted-keyfile [AccountDiversionPlugin; priority 100] Test plugin that grabs all accounts it receives (except 'fakecm/fakeprotocol/dontdivert*') and diverts them to 'mcp-test-diverted-account-plugin.conf' in g_get_user_cache_dir () instead of the usual location. (process:24448): mcd-DEBUG: sort_and_cache_plugins: found plugin default [McdAccountManagerDefault; priority 0] Default account storage backend (process:24448): mc-plugins-DEBUG: account-storage.c:823: default: (process:24448): mcd-DEBUG: am_default_load_directory: Looking for accounts in /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24448): mcd-DEBUG: am_default_load_variant_file: fakecm/fakeprotocol/dontdivert_40example_2ecom0 from /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0.account (process:24448): mcd-DEBUG: am_default_load_directory: Error opening directory '/home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/share/telepathy-1/mission-control': No such file or directory (process:24448): mcd-DEBUG: am_default_load_directory: Error opening directory '/home/cassidy/dev/telepathy-mission-control/tests/twisted/telepathy-1/mission-control': No such file or directory (process:24448): mcd-DEBUG: mcd_storage_load: listing from plugin default [prio: 0] (process:24448): mcd-DEBUG: mcd_storage_load: fetching fakecm/fakeprotocol/dontdivert_40example_2ecom0 from plugin default [prio: 0] (process:24448): mc-plugins-DEBUG: account-storage.c:421: default: (process:24448): mc-plugins-DEBUG: account-storage.c:823: diverted-keyfile: ** (process:24448): DEBUG: checking for /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/cache/mcp-test-diverted-account-plugin.conf (process:24448): mcd-DEBUG: mcd_storage_load: listing from plugin diverted-keyfile [prio: 100] (process:24448): mc-plugins-DEBUG: account-storage.c:823: TestDBusAccount: ** (process:24448): DEBUG: test_dbus_account_plugin_list: called (process:24448): mcd-DEBUG: mcd_storage_load: listing from plugin TestDBusAccount [prio: 200] (process:24448): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal created (process:24448): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal created (process:24448): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal created (process:24448): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal toggled (process:24448): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal toggled (process:24448): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal toggled (process:24448): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal deleted (process:24448): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal deleted (process:24448): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal deleted (process:24448): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal altered-one (process:24448): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal altered-one (process:24448): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal altered-one (process:24448): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal reconnect (process:24448): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal reconnect (process:24448): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal reconnect (process:24448): mcd-DEBUG: mcd_client_registry_constructed: Starting to look for clients (process:24448): tp-glib/proxy-DEBUG: _tp_proxy_get_interface_by_id: 0x164d9f0: org.freedesktop.DBus DBusGProxy is 0x169d550 (process:24448): mcd-DEBUG: _mcd_account_constructed: 0x16513d0 (fakecm/fakeprotocol/dontdivert_40example_2ecom0) (process:24448): tp-glib/proxy-DEBUG: tp_proxy_constructor: 0x164b300: request 0x163d240 represents core features on TpConnectionManager (process:24448): tp-glib/proxy-DEBUG: tp_dbus_daemon_watch_name_owner: Adding match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.ConnectionManager.fakecm' (process:24448): tp-glib/manager-DEBUG: tp_connection_manager_find_manager_file: in XDG_DATA_HOME: trying /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/managers/fakecm.manager (process:24448): tp-glib/manager-DEBUG: tp_connection_manager_find_manager_file: in XDG_DATA_DIRS: trying /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/share/telepathy-1/managers/fakecm.manager (process:24448): tp-glib/manager-DEBUG: tp_connection_manager_find_manager_file: in XDG_DATA_DIRS: trying /home/cassidy/dev/telepathy-mission-control/tests/twisted/telepathy-1/managers/fakecm.manager (process:24448): tp-glib/proxy-DEBUG: request_is_complete: 0x164b300: calling callback for tp-connection-manager-feature-core (process:24448): tp-glib/proxy-DEBUG: tp_proxy_poll_features: 0x164b300: core features not ready yet, nothing prepared (process:24448): mcd-DEBUG: mcd_manager_setup: Manager fakecm created (process:24448): mcd-DEBUG: _mcd_mission_set_parent: child = 0x16731a0, parent = 0x1649c20 (process:24448): mcd-DEBUG: add_account: adding account fakecm/fakeprotocol/dontdivert_40example_2ecom0 (0x16513d0) from keyfile (process:24448): mcd-DEBUG: add_account: account fakecm/fakeprotocol/dontdivert_40example_2ecom0 usability: 0 (process:24448): mcd-DEBUG: mcd_account_manager_write_conf_async: updating all 0 accounts (process:24448): mcd-DEBUG: mcd_storage_commit: flushing plugin TestDBusAccount to long term storage (process:24448): mc-plugins-DEBUG: account-storage.c:723: TestDBusAccount: committing all accounts ** (process:24448): DEBUG: test_dbus_account_plugin_commit: called (process:24448): mcd-DEBUG: mcd_storage_commit: flushing plugin diverted-keyfile to long term storage (process:24448): mc-plugins-DEBUG: account-storage.c:723: diverted-keyfile: committing all accounts (process:24448): mcd-DEBUG: mcd_storage_commit: flushing plugin default to long term storage (process:24448): mc-plugins-DEBUG: account-storage.c:723: default: committing all accounts (process:24448): mcd-DEBUG: mcd_storage_ready: Unblocking async account ops by TestDBusAccount ** (process:24448): DEBUG: test_dbus_account_plugin_ready: called (process:24448): mcd-DEBUG: mcd_storage_ready: Unblocking async account ops by diverted-keyfile (process:24448): mcd-DEBUG: mcd_storage_ready: Unblocking async account ops by default (process:24448): mcd-DEBUG: release_load_accounts_lock: called, count is now 1 (process:24448): mcd-DEBUG: mcd_account_would_like_to_connect: fakecm/fakeprotocol/dontdivert_40example_2ecom0 not Enabled (process:24448): mcd-DEBUG: mcd_service_constructed: called (process:24448): mcd-DEBUG: mcd_service_obtain_bus_name: Requesting MC dbus service (process:24448): mcd-DEBUG: Object Hierarchy of object 0x1649c20 (process:24448): mcd-DEBUG: [ (process:24448): mcd-DEBUG: McdService (0x1649c20): 3 (process:24448): mcd-DEBUG: McdManager (0x16731a0): 2 (process:24448): mcd-DEBUG: ] ** (process:24448): DEBUG: fake_network_monitor_get_properties_cb: Initial state: online ** (process:24448): DEBUG: fake_network_monitor_set_state: New fake ConnMan network state online (an available state) ** (process:24448): DEBUG: fake_network_monitor_set_state: notify::network-available ** (process:24448): DEBUG: fake_network_monitor_emit_network_changed: available=1 (process:24448): mcd-DEBUG: connectivity_monitor_network_changed: GNetworkMonitor (FakeNetworkMonitor) says we are at least partially online (process:24448): mcd-DEBUG: connectivity_monitor_change_states: awake: 1 -> 1; up: 0 -> 2; stable: 4 -> 4; running: 8 -> 8 (process:24448): mcd-DEBUG: connectivity_monitor_change_states: connected (process:24448): mcd-DEBUG: mcd_account_would_like_to_connect: fakecm/fakeprotocol/dontdivert_40example_2ecom0 not Enabled (process:24448): mcd-DEBUG: mcd_client_registry_list_names_cb: ListNames returned (process:24448): mcd-DEBUG: login1_inhibit_cb: unable to delay sleep and shutdown: g-dbus-error-quark #2: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.login1 was not provided by any .service files (process:24448): tp-glib/proxy-DEBUG: noc_idle_context_invoke: NameOwnerChanged(im.telepathy.v1.ChannelDispatcher, -> :1.5) (process:24448): mcd-DEBUG: list_connection_names_cb: 0 connections (process:24448): mcd-DEBUG: proxy_new_cb: org.gnome.SessionManager service not found (process:24448): tp-glib/proxy-DEBUG: _tp_dbus_daemon_get_name_owner_idle: GetNameOwner(im.telepathy.v1.ConnectionManager.fakecm) -> :1.0 (process:24448): tp-glib/manager-DEBUG: tp_connection_manager_name_owner_changed_cb: fakecm: is now running (process:24448): tp-glib/manager-DEBUG: tp_connection_manager_name_owner_changed_cb: fakecm: starting introspection now we know the name owner (process:24448): tp-glib/proxy-DEBUG: noc_idle_context_invoke: NameOwnerChanged(im.telepathy.v1.MissionControl6, -> :1.5) (process:24448): mcd-DEBUG: mcd_client_registry_list_activatable_names_cb: ListActivatableNames returned (process:24448): mcd-DEBUG: _mcd_client_registry_found_name: Registering client im.telepathy.v1.Client.AbiWord (process:24448): mcd-DEBUG: mcd_client_proxy_constructed: im.telepathy.v1.Client.AbiWord (process:24448): tp-glib/proxy-DEBUG: tp_dbus_daemon_watch_name_owner: Adding match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.Client.AbiWord' (process:24448): mcd-DEBUG: _mcd_client_registry_inc_startup_lock: 1 -> 2 (process:24448): mcd-DEBUG: _mcd_client_registry_found_name: Registering client im.telepathy.v1.Client.Logger (process:24448): mcd-DEBUG: mcd_client_proxy_constructed: im.telepathy.v1.Client.Logger (process:24448): tp-glib/proxy-DEBUG: tp_dbus_daemon_watch_name_owner: Adding match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.Client.Logger' (process:24448): mcd-DEBUG: _mcd_client_registry_inc_startup_lock: 2 -> 3 (process:24448): mcd-DEBUG: _mcd_client_registry_dec_startup_lock: 3 -> 2 (process:24448): tp-glib/proxy-DEBUG: _tp_dbus_daemon_get_name_owner_idle: GetNameOwner(im.telepathy.v1.Client.AbiWord) raised org.freedesktop.DBus.Error.NameHasNoOwner: Could not get owner of name 'im.telepathy.v1.Client.AbiWord': no such name (process:24448): mcd-DEBUG: _mcd_client_proxy_parse_client_file: File found for im.telepathy.v1.Client.AbiWord: /home/cassidy/dev/telepathy-mission-control/tests/twisted/telepathy-1/clients/AbiWord.client (process:24448): mcd-DEBUG: _mcd_client_proxy_add_interfaces: im.telepathy.v1.Client.AbiWord: im.telepathy.v1.Client.Handler (process:24448): mcd-DEBUG: mcd_client_proxy_introspect: im.telepathy.v1.Client.AbiWord is a Handler but not active (process:24448): mcd-DEBUG: mcd_client_registry_ready_cb: im.telepathy.v1.Client.AbiWord (process:24448): mcd-DEBUG: _mcd_client_registry_dec_startup_lock: 2 -> 1 (process:24448): tp-glib/proxy-DEBUG: _tp_dbus_daemon_get_name_owner_idle: GetNameOwner(im.telepathy.v1.Client.Logger) raised org.freedesktop.DBus.Error.NameHasNoOwner: Could not get owner of name 'im.telepathy.v1.Client.Logger': no such name (process:24448): mcd-DEBUG: _mcd_client_proxy_parse_client_file: File found for im.telepathy.v1.Client.Logger: /home/cassidy/dev/telepathy-mission-control/tests/twisted/telepathy-1/clients/Logger.client (process:24448): mcd-DEBUG: _mcd_client_proxy_add_interfaces: im.telepathy.v1.Client.Logger: im.telepathy.v1.Client.Observer (process:24448): mcd-DEBUG: mcd_client_registry_ready_cb: im.telepathy.v1.Client.Logger (process:24448): mcd-DEBUG: _mcd_client_registry_dec_startup_lock: 1 -> 0 (process:24448): mcd-DEBUG: mcd_dispatcher_client_registry_ready_cb: All initial clients have been inspected (process:24448): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: im.telepathy.v1.Client.AbiWord: (process:24448): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: - 2 channel filters (process:24448): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: - 2 capability tokens: (process:24448): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: com.example.Foo (process:24448): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: com.example.Bar (process:24448): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: -end- (process:24448): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: im.telepathy.v1.Client.Logger: (process:24448): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: - 0 channel filters (process:24448): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: - 0 capability tokens: (process:24448): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: -end- (process:24448): tp-glib/manager-DEBUG: tp_connection_manager_continue_introspection: fakecm (process:24448): tp-glib/manager-DEBUG: tp_connection_manager_continue_introspection: fakecm: calling GetAll on CM (process:24448): tp-glib/proxy-DEBUG: _tp_proxy_get_interface_by_id: 0x164b300: org.freedesktop.DBus.Properties DBusGProxy is 0x1673930 (process:24448): tp-glib/manager-DEBUG: tp_connection_manager_idle_read_manager_file: fakecm: reading /home/cassidy/dev/telepathy-mission-control/tests/twisted/telepathy-1/managers/fakecm.manager (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: fakecm: reading protocol 'fakeprotocol' from manager file (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: account (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x3 (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: s (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: NULL of type gchararray (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: password (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0xb (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: s (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: NULL of type gchararray (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: nickname (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x2 (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: s (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: NULL of type gchararray (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: register (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x4 (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: b (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: FALSE of type gboolean (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: com.example.Badgerable.Badgered (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x14 (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: b (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: FALSE of type gboolean (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: secret-mushroom (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x0 (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: s (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: NULL of type gchararray (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: snakes (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x0 (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: u (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: 0 of type guint (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: contrived-example (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x4 (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: u (process:24448): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: 5 of type guint (process:24448): tp-glib/proxy-DEBUG: tp_proxy_constructor: 0x164b400: request 0x16b18c0 represents core features on TpProtocol (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: fakecm/fakeprotocol: new Protocol (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: immutable properties already supplied (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.RecommendedAvatarHeight = 0 (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.ConnectionInterfaces = NULL (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.MaximumAvatarHeight = 0 (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Icon = "" (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Parameters = ((GPtrArray_GValueArray_gchararray+guint+gchararray+GValue__*) 0x16ac940) (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.VCardField = "" (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.RequestableChannelClasses = ((GPtrArray_GValueArray_GHashTable_gchararray+GValue_+GStrv__*) 0x16b18a0) (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interfaces = NULL (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.RecommendedAvatarWidth = 0 (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.MaximumAvatarBytes = 0 (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.EnglishName = "" (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.MinimumAvatarHeight = 0 (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.MinimumAvatarWidth = 0 (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.SupportedAvatarMIMETypes = NULL (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.AuthenticationTypes = NULL (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.MaximumAvatarWidth = 0 (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Protocol name: fakeprotocol (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: account (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x3 (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: NULL of type gchararray (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: password (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0xb (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: NULL of type gchararray (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: nickname (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x2 (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: NULL of type gchararray (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: register (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x4 (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: b (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: FALSE of type gboolean (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: com.example.Badgerable.Badgered (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x14 (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: b (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: FALSE of type gboolean (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: secret-mushroom (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x0 (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: NULL of type gchararray (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: snakes (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x0 (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: u (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: 0 of type guint (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: contrived-example (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x4 (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: u (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: 5 of type guint (process:24448): tp-glib/proxy-DEBUG: request_is_complete: 0x164b400: calling callback for tp-protocol-feature-core (process:24448): tp-glib/params-DEBUG: tp_protocol_check_for_core: Core feature ready (process:24448): tp-glib/proxy-DEBUG: tp_proxy_poll_features: 0x164b400: request 0x16b18c0 prepared (process:24448): tp-glib/proxy-DEBUG: tp_proxy_prepare_request_finish: 0x16b18c0 (process:24448): tp-glib/manager-DEBUG: tp_connection_manager_idle_read_manager_file: fakecm: got info from file (process:24448): tp-glib/proxy-DEBUG: tp_proxy_poll_features: 0x164b300: request 0x163d240 prepared (process:24448): tp-glib/proxy-DEBUG: tp_proxy_prepare_request_finish: 0x163d240 (process:24448): tp-glib/proxy-DEBUG: tp_proxy_poll_features: 0x164b300: request 0x1671b20 prepared (process:24448): tp-glib/proxy-DEBUG: tp_proxy_prepare_request_finish: 0x1671b20 (process:24448): mcd-DEBUG: on_manager_ready: manager fakecm is ready (process:24448): mcd-DEBUG: mcd_account_check_parameters: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24448): mcd-DEBUG: release_load_accounts_lock: called, count is now 0 (process:24448): mcd-DEBUG: mcd_account_would_like_to_connect: fakecm/fakeprotocol/dontdivert_40example_2ecom0 not Enabled (process:24448): tp-glib/manager-DEBUG: tp_connection_manager_get_all_cb: fakecm: 1 Protocols from GetAll() (process:24448): tp-glib/proxy-DEBUG: tp_proxy_constructor: 0x164b500: request 0x16a7000 represents core features on TpProtocol (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: fakecm/fakeprotocol: new Protocol (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: immutable properties already supplied (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.ConnectionInterfaces = [] (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.VCardField = "x-fakecm" (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Icon = "im-fakecm" (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Parameters = ((GPtrArray_GValueArray_gchararray+guint+gchararray+GValue__*) 0x16a8b80) (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.RequestableChannelClasses = ((GPtrArray_GValueArray_GHashTable_gchararray+GValue_+GStrv__*) 0x16b1d00) (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interfaces = [] (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.EnglishName = "fakecm" (process:24448): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.AuthenticationTypes = [] (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Protocol name: fakeprotocol (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: account (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x3 (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: "" of type gchararray (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: password (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0xb (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: "" of type gchararray (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: nickname (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x2 (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: "" of type gchararray (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: register (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x4 (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: b (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: FALSE of type gboolean (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: com.example.Badgerable.Badgered (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x14 (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: b (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: FALSE of type gboolean (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: secret-mushroom (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x8 (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: "" of type gchararray (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: snakes (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x0 (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: u (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: 0 of type guint (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: contrived-example (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x4 (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: u (process:24448): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: 5 of type guint (process:24448): tp-glib/proxy-DEBUG: request_is_complete: 0x164b500: calling callback for tp-protocol-feature-core (process:24448): tp-glib/params-DEBUG: tp_protocol_check_for_core: Core feature ready (process:24448): tp-glib/proxy-DEBUG: tp_proxy_poll_features: 0x164b500: request 0x16a7000 prepared (process:24448): tp-glib/proxy-DEBUG: tp_proxy_prepare_request_finish: 0x16a7000 (process:24448): tp-glib/manager-DEBUG: tp_connection_manager_continue_introspection: fakecm (process:24448): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0x164b400 (process:24448): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0x164b400: Proxy unreferenced (process:24448): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0x164b400 (process:24448): tp-glib/manager-DEBUG: tp_connection_manager_end_introspection: fakecm: end of introspection, info source live (2) (process:24448): tp-glib/proxy-DEBUG: noc_idle_context_invoke: NameOwnerChanged(im.telepathy.v1.AccountManager, -> :1.5) (process:24448): mcd-DEBUG: connectivity_monitor_nm_state_change_cb: New NetworkManager network state 70 (stable state) (process:24448): mcd-DEBUG: dbusprop_get_all: im.telepathy.v1.AccountManager (process:24448): mcd-DEBUG: get_usable_accounts: called (process:24448): mcd-DEBUG: get_unusable_accounts: called (process:24448): mcd-DEBUG: mcd_dbus_get_interfaces: called (process:24448): mcd-DEBUG: dbusprop_get: im.telepathy.v1.Account, Parameters (process:24448): mcd-DEBUG: get_mcddbusprop: im.telepathy.v1.Account, Parameters (process:24448): mcd-DEBUG: _mcd_account_dup_parameters: called (process:24448): mcd-DEBUG: account_remove: called ** (process:24448): DEBUG: test_dbus_account_plugin_owns: fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24448): mc-plugins-DEBUG: account-storage.c:421: diverted-keyfile: (process:24448): mc-plugins-DEBUG: account-storage.c:421: default: (process:24448): mc-plugins-DEBUG: account-storage.c:680: TestDBusAccount: ** (process:24448): DEBUG: test_dbus_account_plugin_delete: called (process:24448): mc-plugins-DEBUG: account-storage.c:680: diverted-keyfile: (process:24448): mc-plugins-DEBUG: account-storage.c:680: default: (process:24448): mcd-DEBUG: mcd_storage_commit: flushing plugin TestDBusAccount fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24448): mc-plugins-DEBUG: account-storage.c:781: TestDBusAccount: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 ** (process:24448): DEBUG: test_dbus_account_plugin_commit_one: fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24448): mcd-DEBUG: mcd_storage_commit: flushing plugin diverted-keyfile fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24448): mc-plugins-DEBUG: account-storage.c:781: diverted-keyfile: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24448): mc-plugins-DEBUG: account-storage.c:723: diverted-keyfile: committing all accounts (process:24448): mcd-DEBUG: mcd_storage_commit: flushing plugin default fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24448): mc-plugins-DEBUG: account-storage.c:781: default: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24448): mcd-DEBUG: _commit: Saving accounts to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24448): mcd-DEBUG: mcd_ensure_directory: /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24448): mcd-DEBUG: am_default_commit_one: Deleting account fakecm/fakeprotocol/dontdivert_40example_2ecom0 from /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0.account (process:24448): mcd-DEBUG: unref_account: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24448): mc-plugins-DEBUG: account-storage.c:680: TestDBusAccount: ** (process:24448): DEBUG: test_dbus_account_plugin_delete: called (process:24448): mc-plugins-DEBUG: account-storage.c:680: diverted-keyfile: (process:24448): mc-plugins-DEBUG: account-storage.c:680: default: (process:24448): mcd-DEBUG: mcd_account_manager_write_conf_async: updating fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24448): mcd-DEBUG: mcd_storage_commit: flushing plugin TestDBusAccount fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24448): mc-plugins-DEBUG: account-storage.c:781: TestDBusAccount: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 ** (process:24448): DEBUG: test_dbus_account_plugin_commit_one: fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24448): mcd-DEBUG: mcd_storage_commit: flushing plugin diverted-keyfile fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24448): mc-plugins-DEBUG: account-storage.c:781: diverted-keyfile: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24448): mc-plugins-DEBUG: account-storage.c:723: diverted-keyfile: committing all accounts (process:24448): mcd-DEBUG: mcd_storage_commit: flushing plugin default fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24448): mc-plugins-DEBUG: account-storage.c:781: default: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24448): mcd-DEBUG: _mcd_account_dispose: 0x16513d0 (fakecm/fakeprotocol/dontdivert_40example_2ecom0) (process:24448): mcd-DEBUG: _mcd_account_finalize: 0x16513d0 (fakecm/fakeprotocol/dontdivert_40example_2ecom0) ** Message: Aborting by popular request (process:24448): mcd-DEBUG: _mcd_operation_abort: Operation abort received, aborting all children (process:24448): mcd-DEBUG: _mcd_operation_abort: Operation abort received, aborting all children (process:24448): mcd-DEBUG: on_parent_abort: called (process:24448): mcd-DEBUG: _mcd_mission_set_parent: child = 0x16731a0, parent = (nil) ** (process:24448): DEBUG: McdService aborted, unreffing it (process:24448): mcd-DEBUG: Object Hierarchy of object 0x1649c20 (process:24448): mcd-DEBUG: [ (process:24448): mcd-DEBUG: McdService (0x1649c20): 2 (process:24448): mcd-DEBUG: McdManager (0x16731a0): 1 (process:24448): mcd-DEBUG: ] (process:24448): mcd-DEBUG: mcd_inhibit_release: 0x165c520 (fd -1): 1 -> 0 (process:24448): mcd-DEBUG: _mcd_operation_dispose: operation disposed (process:24448): tp-glib/proxy-DEBUG: _tp_dbus_daemon_stop_watching: Removing match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.Client.AbiWord' (process:24448): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0x16ac0c0 (process:24448): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0x16ac0c0: Proxy unreferenced (process:24448): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0x16ac0c0 (process:24448): tp-glib/proxy-DEBUG: _tp_dbus_daemon_stop_watching: Removing match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.Client.Logger' (process:24448): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0x16ac1b0 (process:24448): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0x16ac1b0: Proxy unreferenced (process:24448): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0x16ac1b0 (process:24448): tp-glib/proxy-DEBUG: _tp_dbus_daemon_stop_watching: Removing match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.ConnectionManager.fakecm' (process:24448): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0x164b500 (process:24448): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0x164b500: Proxy unreferenced (process:24448): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0x164b500 (process:24448): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0x164b300 (process:24448): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0x164b300: Proxy unreferenced (process:24448): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0x164b300 (process:24448): mcd-DEBUG: _mcd_operation_dispose: operation disposed (process:24448): mcd-DEBUG: _mcd_mission_dispose: mission disposed 0x16731a0 (process:24448): mcd-DEBUG: _mcd_mission_finalize: mission finalized 0x16731a0 (process:24448): mcd-DEBUG: _mcd_mission_dispose: mission disposed 0x1649c20 (process:24448): mcd-DEBUG: _mcd_mission_finalize: mission finalized 0x1649c20 (process:24448): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0x164d9f0 (process:24448): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0x164d9f0: Proxy unreferenced (process:24448): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0x164d9f0 ** Message: Exiting with 0 ===== log file: missioncontrol-24469.log ===== (process:24469): mcd-DEBUG: telepathy-mission-control version 5.99.6+ (process:24469): mc-plugins-DEBUG: loader.c:166 mcp-account-diversion.lai is not a loadable module (process:24469): mc-plugins-DEBUG: loader.c:160 mc-debug-server isn't a plugin (doesn't start with mcp-) (process:24469): mc-plugins-DEBUG: loader.c:160 fake-network-monitor.o isn't a plugin (doesn't start with mcp-) (process:24469): mc-plugins-DEBUG: loader.c:160 dbus-account-plugin.o isn't a plugin (doesn't start with mcp-) (process:24469): mc-plugins-DEBUG: loader.c:174 g_module_open (/home/cassidy/dev/telepathy-mission-control/tests/twisted/.libs/mcp-account-diversion.so, ...) = 0xd77450 ** (process:24469): DEBUG: Initializing mcp-account-diversion-plugin (n=0) ** (process:24469): DEBUG: account_diversion_plugin_class_init ** (process:24469): DEBUG: account_diversion_plugin_init ** (process:24469): DEBUG: Initializing mcp-account-diversion-plugin (n=1) (process:24469): mc-plugins-DEBUG: loader.c:202 1 plugin object(s) found in mcp-account-diversion.so (process:24469): mc-plugins-DEBUG: loader.c:166 mcp-plugin.o is not a loadable module (process:24469): mc-plugins-DEBUG: loader.c:160 libgiofakenetworkmonitor.lai isn't a plugin (doesn't start with mcp-) (process:24469): mc-plugins-DEBUG: loader.c:166 mcp-account-diversion.la is not a loadable module (process:24469): mc-plugins-DEBUG: loader.c:160 libgiofakenetworkmonitor.so isn't a plugin (doesn't start with mcp-) (process:24469): mc-plugins-DEBUG: loader.c:160 libgiofakenetworkmonitor.la isn't a plugin (doesn't start with mcp-) (process:24469): mc-plugins-DEBUG: loader.c:166 mcp-account-diversion.o is not a loadable module (process:24469): mc-plugins-DEBUG: loader.c:166 mcp-plugin.la is not a loadable module (process:24469): mc-plugins-DEBUG: loader.c:160 lt-mc-debug-server isn't a plugin (doesn't start with mcp-) (process:24469): mc-plugins-DEBUG: loader.c:166 mcp-plugin.lai is not a loadable module (process:24469): mc-plugins-DEBUG: loader.c:174 g_module_open (/home/cassidy/dev/telepathy-mission-control/tests/twisted/.libs/mcp-plugin.so, ...) = 0xd77eb0 ** (process:24469): DEBUG: Initializing mcp-plugin (n=0) ** (process:24469): DEBUG: Initializing mcp-plugin (n=1) ** (process:24469): DEBUG: Initializing mcp-plugin (n=2) ** (process:24469): DEBUG: Initializing mcp-plugin (n=3) ** (process:24469): DEBUG: Initializing mcp-plugin (n=4) ** (process:24469): DEBUG: test_dbus_account_plugin_class_init: called ** (process:24469): DEBUG: test_dbus_account_plugin_init: called ** (process:24469): DEBUG: Initializing mcp-plugin (n=5) (process:24469): mc-plugins-DEBUG: loader.c:202 5 plugin object(s) found in mcp-plugin.so (process:24469): mcd-DEBUG: mcd_service_init: called (process:24469): mcd-DEBUG: _mcd_account_manager_constructed: ** (process:24469): DEBUG: g_io_module_load: FakeNetworkMonitor plugin ** (process:24469): DEBUG: g_io_module_load: FakeNetworkMonitor plugin ** (process:24469): DEBUG: fake_network_monitor_class_init: enter ** (process:24469): DEBUG: fake_network_monitor_init: enter (process:24469): mcd-DEBUG: connectivity_monitor_network_changed: GNetworkMonitor (FakeNetworkMonitor) says we are offline (process:24469): mcd-DEBUG: connectivity_monitor_change_states: awake: 1 -> 1; up: 2 -> 0; stable: 4 -> 4; running: 8 -> 8 (process:24469): mcd-DEBUG: connectivity_monitor_change_states: disconnected (process:24469): mcd-DEBUG: connectivity_monitor_nm_state_change_cb: New NetworkManager network state 70 (stable state) (process:24469): mcd-DEBUG: plugin_iface_init: (process:24469): mcd-DEBUG: _mcd_account_manager_constructed: loading plugins (process:24469): mcd-DEBUG: mcd_account_manager_default_class_init: mcd_account_manager_default_class_init (process:24469): mcd-DEBUG: mcd_account_manager_default_init: mcd_account_manager_default_init (process:24469): mcd-DEBUG: sort_and_cache_plugins: found plugin TestDBusAccount [TestDBusAccountPlugin; priority 200] Regression test plugin (process:24469): mcd-DEBUG: sort_and_cache_plugins: found plugin diverted-keyfile [AccountDiversionPlugin; priority 100] Test plugin that grabs all accounts it receives (except 'fakecm/fakeprotocol/dontdivert*') and diverts them to 'mcp-test-diverted-account-plugin.conf' in g_get_user_cache_dir () instead of the usual location. (process:24469): mcd-DEBUG: sort_and_cache_plugins: found plugin default [McdAccountManagerDefault; priority 0] Default account storage backend (process:24469): mc-plugins-DEBUG: account-storage.c:823: default: (process:24469): mcd-DEBUG: am_default_load_directory: Looking for accounts in /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24469): mcd-DEBUG: am_default_load_variant_file: fakecm/fakeprotocol/dontdivert_40example_2ecom0priority from /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0priority.account (process:24469): mcd-DEBUG: am_default_load_variant_file: fakecm/fakeprotocol/dontdivert_40example_2ecom0masked from /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0masked.account (process:24469): mcd-DEBUG: am_default_load_variant_file: Empty file /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0masked.account masks account fakecm/fakeprotocol/dontdivert_40example_2ecom0masked (process:24469): mcd-DEBUG: am_default_load_directory: Looking for accounts in /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/share/telepathy-1/mission-control (process:24469): mcd-DEBUG: am_default_load_variant_file: fakecm/fakeprotocol/dontdivert_40example_2ecom0priority from /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/share/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0priority.account (process:24469): mcd-DEBUG: am_default_load_variant_file: Ignoring /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/share/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0priority.account: account fakecm/fakeprotocol/dontdivert_40example_2ecom0priority already loaded (process:24469): mcd-DEBUG: am_default_load_variant_file: fakecm/fakeprotocol/dontdivert_40example_2ecom0 from /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/share/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0.account (process:24469): mcd-DEBUG: am_default_load_variant_file: fakecm/fakeprotocol/dontdivert_40example_2ecom0masked from /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/share/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0masked.account (process:24469): mcd-DEBUG: am_default_load_variant_file: Ignoring /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/share/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0masked.account: account fakecm/fakeprotocol/dontdivert_40example_2ecom0masked already masked (process:24469): mcd-DEBUG: am_default_load_directory: Error opening directory '/home/cassidy/dev/telepathy-mission-control/tests/twisted/telepathy-1/mission-control': No such file or directory (process:24469): mcd-DEBUG: mcd_storage_load: listing from plugin default [prio: 0] (process:24469): mcd-DEBUG: mcd_storage_load: fetching fakecm/fakeprotocol/dontdivert_40example_2ecom0masked from plugin default [prio: 0] (process:24469): mc-plugins-DEBUG: account-storage.c:421: default: (process:24469): mcd-WARNING **: plugin default disowned account fakecm/fakeprotocol/dontdivert_40example_2ecom0masked (process:24469): mcd-DEBUG: mcd_storage_load: fetching fakecm/fakeprotocol/dontdivert_40example_2ecom0priority from plugin default [prio: 0] (process:24469): mc-plugins-DEBUG: account-storage.c:421: default: (process:24469): mcd-DEBUG: mcd_storage_load: fetching fakecm/fakeprotocol/dontdivert_40example_2ecom0 from plugin default [prio: 0] (process:24469): mc-plugins-DEBUG: account-storage.c:421: default: (process:24469): mc-plugins-DEBUG: account-storage.c:823: diverted-keyfile: ** (process:24469): DEBUG: checking for /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/cache/mcp-test-diverted-account-plugin.conf (process:24469): mcd-DEBUG: mcd_storage_load: listing from plugin diverted-keyfile [prio: 100] (process:24469): mc-plugins-DEBUG: account-storage.c:823: TestDBusAccount: ** (process:24469): DEBUG: test_dbus_account_plugin_list: called (process:24469): mcd-DEBUG: mcd_storage_load: listing from plugin TestDBusAccount [prio: 200] (process:24469): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal created (process:24469): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal created (process:24469): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal created (process:24469): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal toggled (process:24469): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal toggled (process:24469): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal toggled (process:24469): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal deleted (process:24469): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal deleted (process:24469): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal deleted (process:24469): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal altered-one (process:24469): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal altered-one (process:24469): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal altered-one (process:24469): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal reconnect (process:24469): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal reconnect (process:24469): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal reconnect (process:24469): mcd-DEBUG: mcd_client_registry_constructed: Starting to look for clients (process:24469): tp-glib/proxy-DEBUG: _tp_proxy_get_interface_by_id: 0xd5b9f0: org.freedesktop.DBus DBusGProxy is 0xdb3d40 (process:24469): mcd-DEBUG: _mcd_account_constructed: 0xd5f3d0 (fakecm/fakeprotocol/dontdivert_40example_2ecom0) (process:24469): tp-glib/proxy-DEBUG: tp_proxy_constructor: 0xd59300: request 0xd7fac0 represents core features on TpConnectionManager (process:24469): tp-glib/proxy-DEBUG: tp_dbus_daemon_watch_name_owner: Adding match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.ConnectionManager.fakecm' (process:24469): tp-glib/manager-DEBUG: tp_connection_manager_find_manager_file: in XDG_DATA_HOME: trying /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/managers/fakecm.manager (process:24469): tp-glib/manager-DEBUG: tp_connection_manager_find_manager_file: in XDG_DATA_DIRS: trying /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/share/telepathy-1/managers/fakecm.manager (process:24469): tp-glib/manager-DEBUG: tp_connection_manager_find_manager_file: in XDG_DATA_DIRS: trying /home/cassidy/dev/telepathy-mission-control/tests/twisted/telepathy-1/managers/fakecm.manager (process:24469): tp-glib/proxy-DEBUG: request_is_complete: 0xd59300: calling callback for tp-connection-manager-feature-core (process:24469): tp-glib/proxy-DEBUG: tp_proxy_poll_features: 0xd59300: core features not ready yet, nothing prepared (process:24469): mcd-DEBUG: mcd_manager_setup: Manager fakecm created (process:24469): mcd-DEBUG: _mcd_mission_set_parent: child = 0xd810e0, parent = 0xd57c20 (process:24469): mcd-DEBUG: add_account: adding account fakecm/fakeprotocol/dontdivert_40example_2ecom0 (0xd5f3d0) from keyfile (process:24469): mcd-DEBUG: add_account: account fakecm/fakeprotocol/dontdivert_40example_2ecom0 usability: 0 (process:24469): mcd-DEBUG: _mcd_account_constructed: 0xd5f520 (fakecm/fakeprotocol/dontdivert_40example_2ecom0priority) (process:24469): mcd-DEBUG: add_account: adding account fakecm/fakeprotocol/dontdivert_40example_2ecom0priority (0xd5f520) from keyfile (process:24469): mcd-DEBUG: add_account: account fakecm/fakeprotocol/dontdivert_40example_2ecom0priority usability: 0 (process:24469): mcd-DEBUG: mcd_account_manager_write_conf_async: updating all 0 accounts (process:24469): mcd-DEBUG: mcd_storage_commit: flushing plugin TestDBusAccount to long term storage (process:24469): mc-plugins-DEBUG: account-storage.c:723: TestDBusAccount: committing all accounts ** (process:24469): DEBUG: test_dbus_account_plugin_commit: called (process:24469): mcd-DEBUG: mcd_storage_commit: flushing plugin diverted-keyfile to long term storage (process:24469): mc-plugins-DEBUG: account-storage.c:723: diverted-keyfile: committing all accounts (process:24469): mcd-DEBUG: mcd_storage_commit: flushing plugin default to long term storage (process:24469): mc-plugins-DEBUG: account-storage.c:723: default: committing all accounts (process:24469): mcd-DEBUG: mcd_storage_ready: Unblocking async account ops by TestDBusAccount ** (process:24469): DEBUG: test_dbus_account_plugin_ready: called (process:24469): mcd-DEBUG: mcd_storage_ready: Unblocking async account ops by diverted-keyfile (process:24469): mcd-DEBUG: mcd_storage_ready: Unblocking async account ops by default (process:24469): mcd-DEBUG: release_load_accounts_lock: called, count is now 2 (process:24469): mcd-DEBUG: mcd_account_would_like_to_connect: fakecm/fakeprotocol/dontdivert_40example_2ecom0 not Enabled (process:24469): mcd-DEBUG: mcd_account_would_like_to_connect: fakecm/fakeprotocol/dontdivert_40example_2ecom0priority not Enabled (process:24469): mcd-DEBUG: mcd_service_constructed: called (process:24469): mcd-DEBUG: mcd_service_obtain_bus_name: Requesting MC dbus service (process:24469): mcd-DEBUG: Object Hierarchy of object 0xd57c20 (process:24469): mcd-DEBUG: [ (process:24469): mcd-DEBUG: McdService (0xd57c20): 3 (process:24469): mcd-DEBUG: McdManager (0xd810e0): 3 (process:24469): mcd-DEBUG: ] ** (process:24469): DEBUG: fake_network_monitor_get_properties_cb: Initial state: online ** (process:24469): DEBUG: fake_network_monitor_set_state: New fake ConnMan network state online (an available state) ** (process:24469): DEBUG: fake_network_monitor_set_state: notify::network-available ** (process:24469): DEBUG: fake_network_monitor_emit_network_changed: available=1 (process:24469): mcd-DEBUG: connectivity_monitor_network_changed: GNetworkMonitor (FakeNetworkMonitor) says we are at least partially online (process:24469): mcd-DEBUG: connectivity_monitor_change_states: awake: 1 -> 1; up: 0 -> 2; stable: 4 -> 4; running: 8 -> 8 (process:24469): mcd-DEBUG: connectivity_monitor_change_states: connected (process:24469): mcd-DEBUG: mcd_account_would_like_to_connect: fakecm/fakeprotocol/dontdivert_40example_2ecom0 not Enabled (process:24469): mcd-DEBUG: mcd_account_would_like_to_connect: fakecm/fakeprotocol/dontdivert_40example_2ecom0priority not Enabled (process:24469): mcd-DEBUG: mcd_client_registry_list_names_cb: ListNames returned (process:24469): mcd-DEBUG: login1_inhibit_cb: unable to delay sleep and shutdown: g-dbus-error-quark #2: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.login1 was not provided by any .service files (process:24469): tp-glib/proxy-DEBUG: noc_idle_context_invoke: NameOwnerChanged(im.telepathy.v1.ChannelDispatcher, -> :1.8) (process:24469): mcd-DEBUG: list_connection_names_cb: 0 connections (process:24469): mcd-DEBUG: proxy_new_cb: org.gnome.SessionManager service not found (process:24469): tp-glib/proxy-DEBUG: _tp_dbus_daemon_get_name_owner_idle: GetNameOwner(im.telepathy.v1.ConnectionManager.fakecm) -> :1.0 (process:24469): tp-glib/manager-DEBUG: tp_connection_manager_name_owner_changed_cb: fakecm: is now running (process:24469): tp-glib/manager-DEBUG: tp_connection_manager_name_owner_changed_cb: fakecm: starting introspection now we know the name owner (process:24469): tp-glib/proxy-DEBUG: noc_idle_context_invoke: NameOwnerChanged(im.telepathy.v1.MissionControl6, -> :1.8) (process:24469): mcd-DEBUG: mcd_client_registry_list_activatable_names_cb: ListActivatableNames returned (process:24469): mcd-DEBUG: _mcd_client_registry_found_name: Registering client im.telepathy.v1.Client.AbiWord (process:24469): mcd-DEBUG: mcd_client_proxy_constructed: im.telepathy.v1.Client.AbiWord (process:24469): tp-glib/proxy-DEBUG: tp_dbus_daemon_watch_name_owner: Adding match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.Client.AbiWord' (process:24469): mcd-DEBUG: _mcd_client_registry_inc_startup_lock: 1 -> 2 (process:24469): mcd-DEBUG: _mcd_client_registry_found_name: Registering client im.telepathy.v1.Client.Logger (process:24469): mcd-DEBUG: mcd_client_proxy_constructed: im.telepathy.v1.Client.Logger (process:24469): tp-glib/proxy-DEBUG: tp_dbus_daemon_watch_name_owner: Adding match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.Client.Logger' (process:24469): mcd-DEBUG: _mcd_client_registry_inc_startup_lock: 2 -> 3 (process:24469): mcd-DEBUG: _mcd_client_registry_dec_startup_lock: 3 -> 2 (process:24469): tp-glib/proxy-DEBUG: _tp_dbus_daemon_get_name_owner_idle: GetNameOwner(im.telepathy.v1.Client.AbiWord) raised org.freedesktop.DBus.Error.NameHasNoOwner: Could not get owner of name 'im.telepathy.v1.Client.AbiWord': no such name (process:24469): mcd-DEBUG: _mcd_client_proxy_parse_client_file: File found for im.telepathy.v1.Client.AbiWord: /home/cassidy/dev/telepathy-mission-control/tests/twisted/telepathy-1/clients/AbiWord.client (process:24469): mcd-DEBUG: _mcd_client_proxy_add_interfaces: im.telepathy.v1.Client.AbiWord: im.telepathy.v1.Client.Handler (process:24469): mcd-DEBUG: mcd_client_proxy_introspect: im.telepathy.v1.Client.AbiWord is a Handler but not active (process:24469): mcd-DEBUG: mcd_client_registry_ready_cb: im.telepathy.v1.Client.AbiWord (process:24469): mcd-DEBUG: _mcd_client_registry_dec_startup_lock: 2 -> 1 (process:24469): tp-glib/proxy-DEBUG: _tp_dbus_daemon_get_name_owner_idle: GetNameOwner(im.telepathy.v1.Client.Logger) raised org.freedesktop.DBus.Error.NameHasNoOwner: Could not get owner of name 'im.telepathy.v1.Client.Logger': no such name (process:24469): mcd-DEBUG: _mcd_client_proxy_parse_client_file: File found for im.telepathy.v1.Client.Logger: /home/cassidy/dev/telepathy-mission-control/tests/twisted/telepathy-1/clients/Logger.client (process:24469): mcd-DEBUG: _mcd_client_proxy_add_interfaces: im.telepathy.v1.Client.Logger: im.telepathy.v1.Client.Observer (process:24469): mcd-DEBUG: mcd_client_registry_ready_cb: im.telepathy.v1.Client.Logger (process:24469): mcd-DEBUG: _mcd_client_registry_dec_startup_lock: 1 -> 0 (process:24469): mcd-DEBUG: mcd_dispatcher_client_registry_ready_cb: All initial clients have been inspected (process:24469): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: im.telepathy.v1.Client.AbiWord: (process:24469): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: - 2 channel filters (process:24469): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: - 2 capability tokens: (process:24469): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: com.example.Foo (process:24469): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: com.example.Bar (process:24469): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: -end- (process:24469): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: im.telepathy.v1.Client.Logger: (process:24469): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: - 0 channel filters (process:24469): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: - 0 capability tokens: (process:24469): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: -end- (process:24469): tp-glib/manager-DEBUG: tp_connection_manager_continue_introspection: fakecm (process:24469): tp-glib/manager-DEBUG: tp_connection_manager_continue_introspection: fakecm: calling GetAll on CM (process:24469): tp-glib/proxy-DEBUG: _tp_proxy_get_interface_by_id: 0xd59300: org.freedesktop.DBus.Properties DBusGProxy is 0xd81a20 (process:24469): tp-glib/manager-DEBUG: tp_connection_manager_idle_read_manager_file: fakecm: reading /home/cassidy/dev/telepathy-mission-control/tests/twisted/telepathy-1/managers/fakecm.manager (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: fakecm: reading protocol 'fakeprotocol' from manager file (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: account (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x3 (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: s (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: NULL of type gchararray (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: password (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0xb (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: s (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: NULL of type gchararray (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: nickname (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x2 (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: s (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: NULL of type gchararray (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: register (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x4 (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: b (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: FALSE of type gboolean (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: com.example.Badgerable.Badgered (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x14 (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: b (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: FALSE of type gboolean (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: secret-mushroom (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x0 (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: s (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: NULL of type gchararray (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: snakes (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x0 (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: u (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: 0 of type guint (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param name: contrived-example (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param flags: 0x4 (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param sig: u (process:24469): tp-glib/params-DEBUG: _tp_protocol_parse_manager_file: Param default value: 5 of type guint (process:24469): tp-glib/proxy-DEBUG: tp_proxy_constructor: 0xd59400: request 0xdc8000 represents core features on TpProtocol (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: fakecm/fakeprotocol: new Protocol (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: immutable properties already supplied (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.RecommendedAvatarHeight = 0 (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.ConnectionInterfaces = NULL (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.MaximumAvatarHeight = 0 (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Icon = "" (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Parameters = ((GPtrArray_GValueArray_gchararray+guint+gchararray+GValue__*) 0xdc7e80) (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.VCardField = "" (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.RequestableChannelClasses = ((GPtrArray_GValueArray_GHashTable_gchararray+GValue_+GStrv__*) 0xdc7fa0) (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interfaces = NULL (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.RecommendedAvatarWidth = 0 (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.MaximumAvatarBytes = 0 (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.EnglishName = "" (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.MinimumAvatarHeight = 0 (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.MinimumAvatarWidth = 0 (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.SupportedAvatarMIMETypes = NULL (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.AuthenticationTypes = NULL (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interface.Avatars1.MaximumAvatarWidth = 0 (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Protocol name: fakeprotocol (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: account (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x3 (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: NULL of type gchararray (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: password (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0xb (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: NULL of type gchararray (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: nickname (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x2 (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: NULL of type gchararray (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: register (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x4 (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: b (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: FALSE of type gboolean (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: com.example.Badgerable.Badgered (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x14 (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: b (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: FALSE of type gboolean (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: secret-mushroom (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x0 (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: NULL of type gchararray (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: snakes (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x0 (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: u (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: 0 of type guint (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: contrived-example (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x4 (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: u (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: 5 of type guint (process:24469): tp-glib/proxy-DEBUG: request_is_complete: 0xd59400: calling callback for tp-protocol-feature-core (process:24469): tp-glib/params-DEBUG: tp_protocol_check_for_core: Core feature ready (process:24469): tp-glib/proxy-DEBUG: tp_proxy_poll_features: 0xd59400: request 0xdc8000 prepared (process:24469): tp-glib/proxy-DEBUG: tp_proxy_prepare_request_finish: 0xdc8000 (process:24469): tp-glib/manager-DEBUG: tp_connection_manager_idle_read_manager_file: fakecm: got info from file (process:24469): tp-glib/proxy-DEBUG: tp_proxy_poll_features: 0xd59300: request 0xd7fac0 prepared (process:24469): tp-glib/proxy-DEBUG: tp_proxy_prepare_request_finish: 0xd7fac0 (process:24469): tp-glib/proxy-DEBUG: tp_proxy_poll_features: 0xd59300: request 0xd7f720 prepared (process:24469): tp-glib/proxy-DEBUG: tp_proxy_prepare_request_finish: 0xd7f720 (process:24469): mcd-DEBUG: on_manager_ready: manager fakecm is ready (process:24469): mcd-DEBUG: mcd_account_check_parameters: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0priority (process:24469): mcd-DEBUG: release_load_accounts_lock: called, count is now 1 (process:24469): mcd-DEBUG: mcd_account_would_like_to_connect: fakecm/fakeprotocol/dontdivert_40example_2ecom0priority not Enabled (process:24469): mcd-DEBUG: mcd_account_check_parameters: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24469): mcd-DEBUG: release_load_accounts_lock: called, count is now 0 (process:24469): mcd-DEBUG: mcd_account_would_like_to_connect: fakecm/fakeprotocol/dontdivert_40example_2ecom0 not Enabled (process:24469): tp-glib/manager-DEBUG: tp_connection_manager_get_all_cb: fakecm: 1 Protocols from GetAll() (process:24469): tp-glib/proxy-DEBUG: tp_proxy_constructor: 0xd59500: request 0xdbd880 represents core features on TpProtocol (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: fakecm/fakeprotocol: new Protocol (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: immutable properties already supplied (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.ConnectionInterfaces = [] (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.VCardField = "x-fakecm" (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Icon = "im-fakecm" (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Parameters = ((GPtrArray_GValueArray_gchararray+guint+gchararray+GValue__*) 0xdc80a0) (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.RequestableChannelClasses = ((GPtrArray_GValueArray_GHashTable_gchararray+GValue_+GStrv__*) 0xdbef20) (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.Interfaces = [] (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.EnglishName = "fakecm" (process:24469): tp-glib/params-DEBUG: tp_protocol_constructed: im.telepathy.v1.Protocol.AuthenticationTypes = [] (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Protocol name: fakeprotocol (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: account (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x3 (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: "" of type gchararray (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: password (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0xb (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: "" of type gchararray (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: nickname (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x2 (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: "" of type gchararray (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: register (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x4 (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: b (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: FALSE of type gboolean (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: com.example.Badgerable.Badgered (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x14 (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: b (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: FALSE of type gboolean (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: secret-mushroom (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x8 (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: s (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: "" of type gchararray (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: snakes (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x0 (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: u (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: 0 of type guint (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param name: contrived-example (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param flags: 0x4 (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param sig: u (process:24469): tp-glib/params-DEBUG: tp_protocol_params_from_param_specs: Param default value: 5 of type guint (process:24469): tp-glib/proxy-DEBUG: request_is_complete: 0xd59500: calling callback for tp-protocol-feature-core (process:24469): tp-glib/params-DEBUG: tp_protocol_check_for_core: Core feature ready (process:24469): tp-glib/proxy-DEBUG: tp_proxy_poll_features: 0xd59500: request 0xdbd880 prepared (process:24469): tp-glib/proxy-DEBUG: tp_proxy_prepare_request_finish: 0xdbd880 (process:24469): tp-glib/manager-DEBUG: tp_connection_manager_continue_introspection: fakecm (process:24469): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0xd59400 (process:24469): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0xd59400: Proxy unreferenced (process:24469): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0xd59400 (process:24469): tp-glib/manager-DEBUG: tp_connection_manager_end_introspection: fakecm: end of introspection, info source live (2) (process:24469): tp-glib/proxy-DEBUG: noc_idle_context_invoke: NameOwnerChanged(im.telepathy.v1.AccountManager, -> :1.8) (process:24469): mcd-DEBUG: connectivity_monitor_nm_state_change_cb: New NetworkManager network state 70 (stable state) (process:24469): mcd-DEBUG: dbusprop_get_all: im.telepathy.v1.AccountManager (process:24469): mcd-DEBUG: get_usable_accounts: called (process:24469): mcd-DEBUG: get_unusable_accounts: called (process:24469): mcd-DEBUG: mcd_dbus_get_interfaces: called (process:24469): mcd-DEBUG: dbusprop_get: im.telepathy.v1.Account, Parameters (process:24469): mcd-DEBUG: get_mcddbusprop: im.telepathy.v1.Account, Parameters (process:24469): mcd-DEBUG: _mcd_account_dup_parameters: called (process:24469): mcd-DEBUG: dbusprop_get: im.telepathy.v1.Account, Parameters (process:24469): mcd-DEBUG: get_mcddbusprop: im.telepathy.v1.Account, Parameters (process:24469): mcd-DEBUG: _mcd_account_dup_parameters: called (process:24469): mcd-DEBUG: account_update_parameters: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24469): mcd-DEBUG: _mcd_account_set_parameters: called (process:24469): mcd-DEBUG: mcd_storage_make_secret: flagging fakecm/fakeprotocol/dontdivert_40example_2ecom0 parameter password as secret (process:24469): mcd-DEBUG: update_storage: MCP:TestDBusAccount -> delete fakecm/fakeprotocol/dontdivert_40example_2ecom0.param-password (process:24469): mc-plugins-DEBUG: account-storage.c:680: TestDBusAccount: ** (process:24469): DEBUG: test_dbus_account_plugin_delete: called (process:24469): mcd-DEBUG: update_storage: MCP:diverted-keyfile -> delete fakecm/fakeprotocol/dontdivert_40example_2ecom0.param-password (process:24469): mc-plugins-DEBUG: account-storage.c:680: diverted-keyfile: (process:24469): mcd-DEBUG: update_storage: MCP:default -> delete fakecm/fakeprotocol/dontdivert_40example_2ecom0.param-password (process:24469): mc-plugins-DEBUG: account-storage.c:680: default: (process:24469): mcd-DEBUG: mcd_account_check_parameters: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24469): mcd-DEBUG: mcd_account_check_parameters: missing required parameter 'password' (process:24469): mcd-DEBUG: check_usability_check_parameters_cb: Account usability changed (old: 1, new: 0) (process:24469): mcd-DEBUG: mcd_account_changed_property: called: Usable (process:24469): mcd-DEBUG: mcd_account_changed_property: First changed property (process:24469): mcd-DEBUG: mcd_account_would_like_to_connect: fakecm/fakeprotocol/dontdivert_40example_2ecom0 not Enabled (process:24469): mcd-DEBUG: _mcd_account_dup_parameters: called (process:24469): mcd-DEBUG: mcd_account_changed_property: called: Parameters (process:24469): mcd-DEBUG: mcd_storage_commit: flushing plugin TestDBusAccount fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24469): mc-plugins-DEBUG: account-storage.c:781: TestDBusAccount: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 ** (process:24469): DEBUG: test_dbus_account_plugin_commit_one: fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24469): mcd-DEBUG: mcd_storage_commit: flushing plugin diverted-keyfile fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24469): mc-plugins-DEBUG: account-storage.c:781: diverted-keyfile: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24469): mc-plugins-DEBUG: account-storage.c:723: diverted-keyfile: committing all accounts (process:24469): mcd-DEBUG: mcd_storage_commit: flushing plugin default fakecm/fakeprotocol/dontdivert_40example_2ecom0 to long term storage (process:24469): mc-plugins-DEBUG: account-storage.c:781: default: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24469): mcd-DEBUG: _commit: Saving accounts to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24469): mcd-DEBUG: mcd_ensure_directory: /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24469): mcd-DEBUG: am_default_commit_one: Saving account fakecm/fakeprotocol/dontdivert_40example_2ecom0 to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0.account (process:24469): mcd-DEBUG: am_default_commit_one: {'manager': <'fakecm'>, 'AutomaticPresence': <(uint32 2, 'available', '')>, 'DisplayName': <'New and improved account'>, 'protocol': <'fakeprotocol'>, 'Parameters': <@a{sv} {}>, 'KeyFileParameters': <{'snakes': '42', 'account': 'dontdivert@example.com'}>} (process:24469): mcd-DEBUG: emit_property_changed: called (process:24469): mcd-DEBUG: dbusprop_get: im.telepathy.v1.Account, Nickname (process:24469): mcd-DEBUG: get_mcddbusprop: im.telepathy.v1.Account, Nickname (process:24469): mcd-DEBUG: dbusprop_get: im.telepathy.v1.Account, DisplayName (process:24469): mcd-DEBUG: get_mcddbusprop: im.telepathy.v1.Account, DisplayName (process:24469): mcd-DEBUG: account_remove: called ** (process:24469): DEBUG: test_dbus_account_plugin_owns: fakecm/fakeprotocol/dontdivert_40example_2ecom0priority (process:24469): mc-plugins-DEBUG: account-storage.c:421: diverted-keyfile: (process:24469): mc-plugins-DEBUG: account-storage.c:421: default: (process:24469): mc-plugins-DEBUG: account-storage.c:680: TestDBusAccount: ** (process:24469): DEBUG: test_dbus_account_plugin_delete: called (process:24469): mc-plugins-DEBUG: account-storage.c:680: diverted-keyfile: (process:24469): mc-plugins-DEBUG: account-storage.c:680: default: (process:24469): mcd-DEBUG: mcd_storage_commit: flushing plugin TestDBusAccount fakecm/fakeprotocol/dontdivert_40example_2ecom0priority to long term storage (process:24469): mc-plugins-DEBUG: account-storage.c:781: TestDBusAccount: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0priority ** (process:24469): DEBUG: test_dbus_account_plugin_commit_one: fakecm/fakeprotocol/dontdivert_40example_2ecom0priority (process:24469): mcd-DEBUG: mcd_storage_commit: flushing plugin diverted-keyfile fakecm/fakeprotocol/dontdivert_40example_2ecom0priority to long term storage (process:24469): mc-plugins-DEBUG: account-storage.c:781: diverted-keyfile: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0priority (process:24469): mc-plugins-DEBUG: account-storage.c:723: diverted-keyfile: committing all accounts (process:24469): mcd-DEBUG: mcd_storage_commit: flushing plugin default fakecm/fakeprotocol/dontdivert_40example_2ecom0priority to long term storage (process:24469): mc-plugins-DEBUG: account-storage.c:781: default: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0priority (process:24469): mcd-DEBUG: _commit: Saving accounts to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24469): mcd-DEBUG: mcd_ensure_directory: /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24469): mcd-DEBUG: am_default_commit_one: Deleting account fakecm/fakeprotocol/dontdivert_40example_2ecom0priority from /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control/fakecm-fakeprotocol-dontdivert_40example_2ecom0priority.account (process:24469): mcd-DEBUG: unref_account: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0priority (process:24469): mc-plugins-DEBUG: account-storage.c:680: TestDBusAccount: ** (process:24469): DEBUG: test_dbus_account_plugin_delete: called (process:24469): mc-plugins-DEBUG: account-storage.c:680: diverted-keyfile: (process:24469): mc-plugins-DEBUG: account-storage.c:680: default: (process:24469): mcd-DEBUG: mcd_account_manager_write_conf_async: updating fakecm/fakeprotocol/dontdivert_40example_2ecom0priority (process:24469): mcd-DEBUG: mcd_storage_commit: flushing plugin TestDBusAccount fakecm/fakeprotocol/dontdivert_40example_2ecom0priority to long term storage (process:24469): mc-plugins-DEBUG: account-storage.c:781: TestDBusAccount: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0priority ** (process:24469): DEBUG: test_dbus_account_plugin_commit_one: fakecm/fakeprotocol/dontdivert_40example_2ecom0priority (process:24469): mcd-DEBUG: mcd_storage_commit: flushing plugin diverted-keyfile fakecm/fakeprotocol/dontdivert_40example_2ecom0priority to long term storage (process:24469): mc-plugins-DEBUG: account-storage.c:781: diverted-keyfile: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0priority (process:24469): mc-plugins-DEBUG: account-storage.c:723: diverted-keyfile: committing all accounts (process:24469): mcd-DEBUG: mcd_storage_commit: flushing plugin default fakecm/fakeprotocol/dontdivert_40example_2ecom0priority to long term storage (process:24469): mc-plugins-DEBUG: account-storage.c:781: default: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0priority (process:24469): mcd-DEBUG: _mcd_account_dispose: 0xd5f520 (fakecm/fakeprotocol/dontdivert_40example_2ecom0priority) (process:24469): mcd-DEBUG: _mcd_account_finalize: 0xd5f520 (fakecm/fakeprotocol/dontdivert_40example_2ecom0priority) ** Message: Aborting by popular request (process:24469): mcd-DEBUG: _mcd_operation_abort: Operation abort received, aborting all children (process:24469): mcd-DEBUG: _mcd_operation_abort: Operation abort received, aborting all children (process:24469): mcd-DEBUG: on_parent_abort: called (process:24469): mcd-DEBUG: _mcd_mission_set_parent: child = 0xd810e0, parent = (nil) ** (process:24469): DEBUG: McdService aborted, unreffing it (process:24469): mcd-DEBUG: Object Hierarchy of object 0xd57c20 (process:24469): mcd-DEBUG: [ (process:24469): mcd-DEBUG: McdService (0xd57c20): 2 (process:24469): mcd-DEBUG: McdManager (0xd810e0): 2 (process:24469): mcd-DEBUG: ] (process:24469): mcd-DEBUG: unref_account: called for fakecm/fakeprotocol/dontdivert_40example_2ecom0 (process:24469): mcd-DEBUG: _mcd_account_dispose: 0xd5f3d0 (fakecm/fakeprotocol/dontdivert_40example_2ecom0) (process:24469): mcd-DEBUG: mcd_inhibit_release: 0xd53c10 (fd -1): 1 -> 0 (process:24469): mcd-DEBUG: _mcd_account_finalize: 0xd5f3d0 (fakecm/fakeprotocol/dontdivert_40example_2ecom0) (process:24469): mcd-DEBUG: _mcd_operation_dispose: operation disposed (process:24469): tp-glib/proxy-DEBUG: _tp_dbus_daemon_stop_watching: Removing match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.Client.AbiWord' (process:24469): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0xdc28c0 (process:24469): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0xdc28c0: Proxy unreferenced (process:24469): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0xdc28c0 (process:24469): tp-glib/proxy-DEBUG: _tp_dbus_daemon_stop_watching: Removing match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.Client.Logger' (process:24469): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0xdc29b0 (process:24469): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0xdc29b0: Proxy unreferenced (process:24469): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0xdc29b0 (process:24469): tp-glib/proxy-DEBUG: _tp_dbus_daemon_stop_watching: Removing match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.ConnectionManager.fakecm' (process:24469): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0xd59500 (process:24469): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0xd59500: Proxy unreferenced (process:24469): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0xd59500 (process:24469): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0xd59300 (process:24469): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0xd59300: Proxy unreferenced (process:24469): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0xd59300 (process:24469): mcd-DEBUG: _mcd_operation_dispose: operation disposed (process:24469): mcd-DEBUG: _mcd_mission_dispose: mission disposed 0xd810e0 (process:24469): mcd-DEBUG: _mcd_mission_finalize: mission finalized 0xd810e0 (process:24469): mcd-DEBUG: _mcd_mission_dispose: mission disposed 0xd57c20 (process:24469): mcd-DEBUG: _mcd_mission_finalize: mission finalized 0xd57c20 (process:24469): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0xd5b9f0 (process:24469): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0xd5b9f0: Proxy unreferenced (process:24469): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0xd5b9f0 ** Message: Exiting with 0 ===== log file: missioncontrol-24491.log ===== (process:24491): mcd-DEBUG: telepathy-mission-control version 5.99.6+ (process:24491): mc-plugins-DEBUG: loader.c:166 mcp-account-diversion.lai is not a loadable module (process:24491): mc-plugins-DEBUG: loader.c:160 mc-debug-server isn't a plugin (doesn't start with mcp-) (process:24491): mc-plugins-DEBUG: loader.c:160 fake-network-monitor.o isn't a plugin (doesn't start with mcp-) (process:24491): mc-plugins-DEBUG: loader.c:160 dbus-account-plugin.o isn't a plugin (doesn't start with mcp-) (process:24491): mc-plugins-DEBUG: loader.c:174 g_module_open (/home/cassidy/dev/telepathy-mission-control/tests/twisted/.libs/mcp-account-diversion.so, ...) = 0xc71450 ** (process:24491): DEBUG: Initializing mcp-account-diversion-plugin (n=0) ** (process:24491): DEBUG: account_diversion_plugin_class_init ** (process:24491): DEBUG: account_diversion_plugin_init ** (process:24491): DEBUG: Initializing mcp-account-diversion-plugin (n=1) (process:24491): mc-plugins-DEBUG: loader.c:202 1 plugin object(s) found in mcp-account-diversion.so (process:24491): mc-plugins-DEBUG: loader.c:166 mcp-plugin.o is not a loadable module (process:24491): mc-plugins-DEBUG: loader.c:160 libgiofakenetworkmonitor.lai isn't a plugin (doesn't start with mcp-) (process:24491): mc-plugins-DEBUG: loader.c:166 mcp-account-diversion.la is not a loadable module (process:24491): mc-plugins-DEBUG: loader.c:160 libgiofakenetworkmonitor.so isn't a plugin (doesn't start with mcp-) (process:24491): mc-plugins-DEBUG: loader.c:160 libgiofakenetworkmonitor.la isn't a plugin (doesn't start with mcp-) (process:24491): mc-plugins-DEBUG: loader.c:166 mcp-account-diversion.o is not a loadable module (process:24491): mc-plugins-DEBUG: loader.c:166 mcp-plugin.la is not a loadable module (process:24491): mc-plugins-DEBUG: loader.c:160 lt-mc-debug-server isn't a plugin (doesn't start with mcp-) (process:24491): mc-plugins-DEBUG: loader.c:166 mcp-plugin.lai is not a loadable module (process:24491): mc-plugins-DEBUG: loader.c:174 g_module_open (/home/cassidy/dev/telepathy-mission-control/tests/twisted/.libs/mcp-plugin.so, ...) = 0xc71eb0 ** (process:24491): DEBUG: Initializing mcp-plugin (n=0) ** (process:24491): DEBUG: Initializing mcp-plugin (n=1) ** (process:24491): DEBUG: Initializing mcp-plugin (n=2) ** (process:24491): DEBUG: Initializing mcp-plugin (n=3) ** (process:24491): DEBUG: Initializing mcp-plugin (n=4) ** (process:24491): DEBUG: test_dbus_account_plugin_class_init: called ** (process:24491): DEBUG: test_dbus_account_plugin_init: called ** (process:24491): DEBUG: Initializing mcp-plugin (n=5) (process:24491): mc-plugins-DEBUG: loader.c:202 5 plugin object(s) found in mcp-plugin.so (process:24491): mcd-DEBUG: mcd_service_init: called (process:24491): mcd-DEBUG: _mcd_account_manager_constructed: ** (process:24491): DEBUG: g_io_module_load: FakeNetworkMonitor plugin ** (process:24491): DEBUG: g_io_module_load: FakeNetworkMonitor plugin ** (process:24491): DEBUG: fake_network_monitor_class_init: enter ** (process:24491): DEBUG: fake_network_monitor_init: enter (process:24491): mcd-DEBUG: connectivity_monitor_network_changed: GNetworkMonitor (FakeNetworkMonitor) says we are offline (process:24491): mcd-DEBUG: connectivity_monitor_change_states: awake: 1 -> 1; up: 2 -> 0; stable: 4 -> 4; running: 8 -> 8 (process:24491): mcd-DEBUG: connectivity_monitor_change_states: disconnected (process:24491): mcd-DEBUG: connectivity_monitor_nm_state_change_cb: New NetworkManager network state 70 (stable state) (process:24491): mcd-DEBUG: plugin_iface_init: (process:24491): mcd-DEBUG: _mcd_account_manager_constructed: loading plugins (process:24491): mcd-DEBUG: mcd_account_manager_default_class_init: mcd_account_manager_default_class_init (process:24491): mcd-DEBUG: mcd_account_manager_default_init: mcd_account_manager_default_init (process:24491): mcd-DEBUG: sort_and_cache_plugins: found plugin TestDBusAccount [TestDBusAccountPlugin; priority 200] Regression test plugin (process:24491): mcd-DEBUG: sort_and_cache_plugins: found plugin diverted-keyfile [AccountDiversionPlugin; priority 100] Test plugin that grabs all accounts it receives (except 'fakecm/fakeprotocol/dontdivert*') and diverts them to 'mcp-test-diverted-account-plugin.conf' in g_get_user_cache_dir () instead of the usual location. (process:24491): mcd-DEBUG: sort_and_cache_plugins: found plugin default [McdAccountManagerDefault; priority 0] Default account storage backend (process:24491): mc-plugins-DEBUG: account-storage.c:823: default: (process:24491): mcd-DEBUG: am_default_load_directory: Looking for accounts in /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24491): mcd-DEBUG: am_default_load_directory: Looking for accounts in /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/share/telepathy-1/mission-control (process:24491): mcd-DEBUG: am_default_load_directory: Error opening directory '/home/cassidy/dev/telepathy-mission-control/tests/twisted/telepathy-1/mission-control': No such file or directory (process:24491): mcd-DEBUG: _list: Creating initial account data (process:24491): mcd-DEBUG: _list: Saving initial or migrated account data (process:24491): mcd-DEBUG: _commit: Saving accounts to /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24491): mcd-DEBUG: mcd_ensure_directory: /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/localshare/telepathy-1/mission-control (process:24491): mcd-DEBUG: mcd_storage_load: listing from plugin default [prio: 0] (process:24491): mc-plugins-DEBUG: account-storage.c:823: diverted-keyfile: ** (process:24491): DEBUG: checking for /home/cassidy/dev/telepathy-mission-control/tests/twisted/tmp-account-storage_default-keyring-storage_py/cache/mcp-test-diverted-account-plugin.conf (process:24491): mcd-DEBUG: mcd_storage_load: listing from plugin diverted-keyfile [prio: 100] (process:24491): mc-plugins-DEBUG: account-storage.c:823: TestDBusAccount: ** (process:24491): DEBUG: test_dbus_account_plugin_list: called (process:24491): mcd-DEBUG: mcd_storage_load: listing from plugin TestDBusAccount [prio: 200] (process:24491): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal created (process:24491): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal created (process:24491): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal created (process:24491): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal toggled (process:24491): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal toggled (process:24491): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal toggled (process:24491): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal deleted (process:24491): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal deleted (process:24491): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal deleted (process:24491): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal altered-one (process:24491): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal altered-one (process:24491): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal altered-one (process:24491): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to TestDBusAccount plugin signal reconnect (process:24491): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to diverted-keyfile plugin signal reconnect (process:24491): mcd-DEBUG: mcd_storage_connect_signal: connecting handler to default plugin signal reconnect (process:24491): mcd-DEBUG: mcd_client_registry_constructed: Starting to look for clients (process:24491): tp-glib/proxy-DEBUG: _tp_proxy_get_interface_by_id: 0xc559f0: org.freedesktop.DBus DBusGProxy is 0xcac970 (process:24491): mcd-DEBUG: mcd_account_manager_write_conf_async: updating all 0 accounts (process:24491): mcd-DEBUG: mcd_storage_commit: flushing plugin TestDBusAccount to long term storage (process:24491): mc-plugins-DEBUG: account-storage.c:723: TestDBusAccount: committing all accounts ** (process:24491): DEBUG: test_dbus_account_plugin_commit: called (process:24491): mcd-DEBUG: mcd_storage_commit: flushing plugin diverted-keyfile to long term storage (process:24491): mc-plugins-DEBUG: account-storage.c:723: diverted-keyfile: committing all accounts (process:24491): mcd-DEBUG: mcd_storage_commit: flushing plugin default to long term storage (process:24491): mc-plugins-DEBUG: account-storage.c:723: default: committing all accounts (process:24491): mcd-DEBUG: mcd_storage_ready: Unblocking async account ops by TestDBusAccount ** (process:24491): DEBUG: test_dbus_account_plugin_ready: called (process:24491): mcd-DEBUG: mcd_storage_ready: Unblocking async account ops by diverted-keyfile (process:24491): mcd-DEBUG: mcd_storage_ready: Unblocking async account ops by default (process:24491): mcd-DEBUG: release_load_accounts_lock: called, count is now 0 (process:24491): mcd-DEBUG: mcd_service_constructed: called (process:24491): mcd-DEBUG: mcd_service_obtain_bus_name: Requesting MC dbus service (process:24491): mcd-DEBUG: Object Hierarchy of object 0xc51c20 (process:24491): mcd-DEBUG: [ (process:24491): mcd-DEBUG: McdService (0xc51c20): 2 (process:24491): mcd-DEBUG: ] ** (process:24491): DEBUG: fake_network_monitor_get_properties_cb: Initial state: online ** (process:24491): DEBUG: fake_network_monitor_set_state: New fake ConnMan network state online (an available state) ** (process:24491): DEBUG: fake_network_monitor_set_state: notify::network-available ** (process:24491): DEBUG: fake_network_monitor_emit_network_changed: available=1 (process:24491): mcd-DEBUG: connectivity_monitor_network_changed: GNetworkMonitor (FakeNetworkMonitor) says we are at least partially online (process:24491): mcd-DEBUG: connectivity_monitor_change_states: awake: 1 -> 1; up: 0 -> 2; stable: 4 -> 4; running: 8 -> 8 (process:24491): mcd-DEBUG: connectivity_monitor_change_states: connected (process:24491): mcd-DEBUG: mcd_client_registry_list_names_cb: ListNames returned (process:24491): mcd-DEBUG: login1_inhibit_cb: unable to delay sleep and shutdown: g-dbus-error-quark #2: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.login1 was not provided by any .service files (process:24491): tp-glib/proxy-DEBUG: noc_idle_context_invoke: NameOwnerChanged(im.telepathy.v1.ChannelDispatcher, -> :1.11) (process:24491): mcd-DEBUG: list_connection_names_cb: 0 connections (process:24491): tp-glib/proxy-DEBUG: noc_idle_context_invoke: NameOwnerChanged(im.telepathy.v1.AccountManager, -> :1.11) (process:24491): tp-glib/proxy-DEBUG: noc_idle_context_invoke: NameOwnerChanged(im.telepathy.v1.MissionControl6, -> :1.11) (process:24491): mcd-DEBUG: mcd_client_registry_list_activatable_names_cb: ListActivatableNames returned (process:24491): mcd-DEBUG: _mcd_client_registry_found_name: Registering client im.telepathy.v1.Client.AbiWord (process:24491): mcd-DEBUG: mcd_client_proxy_constructed: im.telepathy.v1.Client.AbiWord (process:24491): tp-glib/proxy-DEBUG: tp_dbus_daemon_watch_name_owner: Adding match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.Client.AbiWord' (process:24491): mcd-DEBUG: _mcd_client_registry_inc_startup_lock: 1 -> 2 (process:24491): mcd-DEBUG: _mcd_client_registry_found_name: Registering client im.telepathy.v1.Client.Logger (process:24491): mcd-DEBUG: mcd_client_proxy_constructed: im.telepathy.v1.Client.Logger (process:24491): tp-glib/proxy-DEBUG: tp_dbus_daemon_watch_name_owner: Adding match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.Client.Logger' (process:24491): mcd-DEBUG: _mcd_client_registry_inc_startup_lock: 2 -> 3 (process:24491): mcd-DEBUG: _mcd_client_registry_dec_startup_lock: 3 -> 2 (process:24491): tp-glib/proxy-DEBUG: _tp_dbus_daemon_get_name_owner_idle: GetNameOwner(im.telepathy.v1.Client.AbiWord) raised org.freedesktop.DBus.Error.NameHasNoOwner: Could not get owner of name 'im.telepathy.v1.Client.AbiWord': no such name (process:24491): mcd-DEBUG: _mcd_client_proxy_parse_client_file: File found for im.telepathy.v1.Client.AbiWord: /home/cassidy/dev/telepathy-mission-control/tests/twisted/telepathy-1/clients/AbiWord.client (process:24491): mcd-DEBUG: _mcd_client_proxy_add_interfaces: im.telepathy.v1.Client.AbiWord: im.telepathy.v1.Client.Handler (process:24491): mcd-DEBUG: mcd_client_proxy_introspect: im.telepathy.v1.Client.AbiWord is a Handler but not active (process:24491): mcd-DEBUG: mcd_client_registry_ready_cb: im.telepathy.v1.Client.AbiWord (process:24491): mcd-DEBUG: _mcd_client_registry_dec_startup_lock: 2 -> 1 (process:24491): tp-glib/proxy-DEBUG: _tp_dbus_daemon_get_name_owner_idle: GetNameOwner(im.telepathy.v1.Client.Logger) raised org.freedesktop.DBus.Error.NameHasNoOwner: Could not get owner of name 'im.telepathy.v1.Client.Logger': no such name (process:24491): mcd-DEBUG: _mcd_client_proxy_parse_client_file: File found for im.telepathy.v1.Client.Logger: /home/cassidy/dev/telepathy-mission-control/tests/twisted/telepathy-1/clients/Logger.client (process:24491): mcd-DEBUG: _mcd_client_proxy_add_interfaces: im.telepathy.v1.Client.Logger: im.telepathy.v1.Client.Observer (process:24491): mcd-DEBUG: mcd_client_registry_ready_cb: im.telepathy.v1.Client.Logger (process:24491): mcd-DEBUG: _mcd_client_registry_dec_startup_lock: 1 -> 0 (process:24491): mcd-DEBUG: mcd_dispatcher_client_registry_ready_cb: All initial clients have been inspected (process:24491): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: im.telepathy.v1.Client.AbiWord: (process:24491): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: - 2 channel filters (process:24491): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: - 2 capability tokens: (process:24491): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: com.example.Foo (process:24491): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: com.example.Bar (process:24491): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: -end- (process:24491): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: im.telepathy.v1.Client.Logger: (process:24491): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: - 0 channel filters (process:24491): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: - 0 capability tokens: (process:24491): mcd-DEBUG: _mcd_client_proxy_dup_handler_capabilities: -end- (process:24491): mcd-DEBUG: connectivity_monitor_nm_state_change_cb: New NetworkManager network state 70 (stable state) (process:24491): mcd-DEBUG: dbusprop_get_all: im.telepathy.v1.AccountManager (process:24491): mcd-DEBUG: get_usable_accounts: called (process:24491): mcd-DEBUG: get_unusable_accounts: called (process:24491): mcd-DEBUG: mcd_dbus_get_interfaces: called (process:24491): mcd-DEBUG: dbusprop_get_all: im.telepathy.v1.AccountManager (process:24491): mcd-DEBUG: get_usable_accounts: called (process:24491): mcd-DEBUG: get_unusable_accounts: called (process:24491): mcd-DEBUG: mcd_dbus_get_interfaces: called (process:24491): tp-glib/proxy-DEBUG: noc_idle_context_invoke: NameOwnerChanged(im.telepathy.v1.ConnectionManager.fakecm, :1.0 -> ) (process:24491): tp-glib/proxy-DEBUG: noc_idle_context_invoke: NameOwnerChanged(net.connman, :1.0 -> ) (process:24491): tp-glib/proxy-DEBUG: noc_idle_context_invoke: NameOwnerChanged(org.freedesktop.NetworkManager, :1.0 -> ) (process:24491): tp-glib/proxy-DEBUG: noc_idle_context_invoke: NameOwnerChanged(:1.0, :1.0 -> ) ** Message: Got disconnected from the session bus (process:24491): mcd-DEBUG: _mcd_operation_abort: Operation abort received, aborting all children ** (process:24491): DEBUG: McdService aborted, unreffing it (process:24491): mcd-DEBUG: Object Hierarchy of object 0xc51c20 (process:24491): mcd-DEBUG: [ (process:24491): mcd-DEBUG: McdService (0xc51c20): 2 (process:24491): mcd-DEBUG: ] (process:24491): mcd-DEBUG: mcd_inhibit_release: 0xc4dc60 (fd -1): 1 -> 0 (process:24491): tp-glib/proxy-DEBUG: _tp_dbus_daemon_stop_watching: Removing match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.Client.AbiWord' (process:24491): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0xcb20f0 (process:24491): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0xcb20f0: Proxy unreferenced (process:24491): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0xcb20f0 (process:24491): tp-glib/proxy-DEBUG: _tp_dbus_daemon_stop_watching: Removing match rule type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='im.telepathy.v1.Client.Logger' (process:24491): tp-glib/proxy-DEBUG: tp_proxy_dispose: 0xcb21e0 (process:24491): tp-glib/proxy-DEBUG: tp_proxy_invalidate: 0xcb21e0: Proxy unreferenced (process:24491): tp-glib/proxy-DEBUG: tp_proxy_finalize: 0xcb21e0 (process:24491): mcd-DEBUG: _mcd_operation_dispose: operation disposed (process:24491): mcd-DEBUG: _mcd_mission_dispose: mission disposed 0xc51c20 (process:24491): mcd-DEBUG: _mcd_mission_finalize: mission finalized 0xc51c20 (process:24491): tp-glib/proxy-DEBUG: tp_proxy_iface_destroyed_cb: 0xc559f0 g_dbus_connection_real_closed: Remote peer vanished with error: Underlying GIOStream returned 0 bytes on an async read (g-io-error-quark, 0). Exiting. ===== log file: test.log ===== Temporary bus daemon is unix:abstract=/tmp/dbus-YMCKNCIZBP,guid=9974e18c2418c3f4577ec51752de946d Temporary bus daemon PID is 24409 Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u':1.0'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameAcquired' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'org.freedesktop.NetworkManager', u'', u':1.0'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'org.freedesktop.NetworkManager'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameAcquired' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'net.connman', u'', u':1.0'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'net.connman'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameAcquired' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [{u'State': 70L}] - interface: 'org.freedesktop.NetworkManager' - path: '/org/freedesktop/NetworkManager' - signal: 'PropertiesChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [70L] - interface: 'org.freedesktop.NetworkManager' - path: '/org/freedesktop/NetworkManager' - signal: 'StateChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'State', u'online'] - interface: 'net.connman.Manager' - path: '/' - signal: 'PropertyChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u':1.0'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameAcquired' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u'org.freedesktop.NetworkManager', u'', u':1.0'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u'org.freedesktop.NetworkManager'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameAcquired' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u'net.connman', u'', u':1.0'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u'net.connman'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameAcquired' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [{u'State': 70L}] - interface: 'org.freedesktop.NetworkManager' - path: '/org/freedesktop/NetworkManager' - signal: 'PropertiesChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [70L] - interface: 'org.freedesktop.NetworkManager' - path: '/org/freedesktop/NetworkManager' - signal: 'StateChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u'State', u'online'] - interface: 'net.connman.Manager' - path: '/' - signal: 'PropertyChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u':1.1', u'', u':1.1'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u':1.2', u'', u':1.2'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u':1.1', u'', u':1.1'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u':1.2', u'', u':1.2'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u':1.3', u'', u':1.3'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u':1.3', u'', u':1.3'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-method-call - args: [] - destination: ':1.0' - handled: True - interface: 'net.connman.Manager' - message: - method: 'GetProperties' - path: dbus.ObjectPath('/') - raw_args: [] - sender: ':1.3' - subqueue: 'dbus' - subtype: 'method-call' got event: - type dbus-method-call - args: [] - destination: ':1.0' - handled: True - interface: 'net.connman.Manager' - message: - method: 'GetProperties' - path: dbus.ObjectPath('/') - raw_args: [] - sender: ':1.3' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-method-call - args: [u'org.freedesktop.NetworkManager'] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.DBus.Properties' - message: - method: 'GetAll' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [dbus.String(u'org.freedesktop.NetworkManager')] - sender: ':1.2' - subqueue: 'dbus' - subtype: 'method-call' Adding to queue got event: - type dbus-method-call - args: [] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.NetworkManager' - message: - method: 'GetDevices' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [] - sender: ':1.2' - subqueue: 'dbus' - subtype: 'method-call' Adding to queue got event: - type dbus-method-call - args: [] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.NetworkManager' - message: - method: 'GetPermissions' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [] - sender: ':1.2' - subqueue: 'dbus' - subtype: 'method-call' got event: - type dbus-method-call - args: [u'org.freedesktop.NetworkManager'] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.DBus.Properties' - message: - method: 'GetAll' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [dbus.String(u'org.freedesktop.NetworkManager')] - sender: ':1.2' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus got event: - type dbus-method-call - args: [] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.NetworkManager' - message: - method: 'GetDevices' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [] - sender: ':1.2' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus got event: - type dbus-method-call - args: [] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.NetworkManager' - message: - method: 'GetPermissions' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [] - sender: ':1.2' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Listing' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Listing' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.ChannelDispatcher', u'', u':1.2'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Ready' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.AccountManager', u'', u':1.2'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.MissionControl6', u'', u':1.2'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u'im.telepathy.v1.ChannelDispatcher', u'', u':1.2'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' handled, took 55.356 ms Waiting for event on: dbus got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Ready' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u'im.telepathy.v1.AccountManager', u'', u':1.2'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' handled, took 55.879 ms Waiting for event on: dbus got event: - type dbus-signal - args: [u'im.telepathy.v1.MissionControl6', u'', u':1.2'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' handled, took 56.107 ms Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Inactive' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.ConnectionManager.fakecm', u'', u':1.0'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.ConnectionManager.fakecm'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameAcquired' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Inactive' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u'im.telepathy.v1.ConnectionManager.fakecm', u'', u':1.0'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u'im.telepathy.v1.ConnectionManager.fakecm'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameAcquired' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-method-call - args: [{u'account': u'dontdivert@example.com', u'password': u'secrecy', u'snakes': 23L}] - destination: 'im.telepathy.v1.ConnectionManager.fakecm' - handled: True - interface: 'im.telepathy.v1.Protocol' - message: - method: 'IdentifyAccount' - path: dbus.ObjectPath('/im/telepathy/v1/ConnectionManager/fakecm/fakeprotocol') - raw_args: [dbus.Dictionary({dbus.String(u'account'): dbus.String(u'dontdivert@example.com', variant_level=1), dbus.String(u'password'): dbus.String(u'secrecy', variant_level=1), dbus.String(u'snakes'): dbus.UInt32(23L, variant_level=1)}, signature=dbus.Signature('sv'))] - sender: ':1.2' - subqueue: 'dbus' - subtype: 'method-call' got event: - type dbus-method-call - args: [{u'account': u'dontdivert@example.com', u'password': u'secrecy', u'snakes': 23L}] - destination: 'im.telepathy.v1.ConnectionManager.fakecm' - handled: True - interface: 'im.telepathy.v1.Protocol' - message: - method: 'IdentifyAccount' - path: dbus.ObjectPath('/im/telepathy/v1/ConnectionManager/fakecm/fakeprotocol') - raw_args: [dbus.Dictionary({dbus.String(u'account'): dbus.String(u'dontdivert@example.com', variant_level=1), dbus.String(u'password'): dbus.String(u'secrecy', variant_level=1), dbus.String(u'snakes'): dbus.UInt32(23L, variant_level=1)}, signature=dbus.Signature('sv'))] - sender: ':1.2' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-method-call - args: [u'im.telepathy.v1.ConnectionManager'] - destination: 'im.telepathy.v1.ConnectionManager.fakecm' - handled: True - interface: 'org.freedesktop.DBus.Properties' - message: - method: 'GetAll' - path: dbus.ObjectPath('/im/telepathy/v1/ConnectionManager/fakecm') - raw_args: [dbus.String(u'im.telepathy.v1.ConnectionManager')] - sender: ':1.2' - subqueue: 'dbus' - subtype: 'method-call' got event: - type dbus-method-call - args: [u'im.telepathy.v1.ConnectionManager'] - destination: 'im.telepathy.v1.ConnectionManager.fakecm' - handled: True - interface: 'org.freedesktop.DBus.Properties' - message: - method: 'GetAll' - path: dbus.ObjectPath('/im/telepathy/v1/ConnectionManager/fakecm') - raw_args: [dbus.String(u'im.telepathy.v1.ConnectionManager')] - sender: ':1.2' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: ['/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0', True] - interface: 'im.telepathy.v1.AccountManager' - path: '/im/telepathy/v1/AccountManager' - signal: 'AccountUsabilityChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: ['/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0', True] - interface: 'im.telepathy.v1.AccountManager' - path: '/im/telepathy/v1/AccountManager' - signal: 'AccountUsabilityChanged' - subqueue: 'dbus' - subtype: 'signal' handled, took 6.504 ms Waiting for event on: dbus Adding to queue got event: - type dbus-return - method: 'CreateAccount' - subqueue: 'dbus' - subtype: 'return' - value: ('/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0',) got event: - type dbus-return - method: 'CreateAccount' - subqueue: 'dbus' - subtype: 'return' - value: ('/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0',) handled, took 7.797 ms Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [{u'Usable': True}] - interface: 'im.telepathy.v1.Account' - path: '/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0' - signal: 'AccountPropertyChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [{u'Usable': True}] - interface: 'im.telepathy.v1.Account' - path: '/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0' - signal: 'AccountPropertyChanged' - subqueue: 'dbus' - subtype: 'signal' handled, took 16.947 ms Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [{u'AutomaticPresence': (4L, u'xa', u'never online'), u'ConnectAutomatically': True, u'DisplayName': u'Work account', u'Icon': u'im-jabber', u'Nickname': u'Joe Bloggs'}] - interface: 'im.telepathy.v1.Account' - path: '/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0' - signal: 'AccountPropertyChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Account' - path: '/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0' - signal: 'Removed' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [{u'AutomaticPresence': (4L, u'xa', u'never online'), u'ConnectAutomatically': True, u'DisplayName': u'Work account', u'Icon': u'im-jabber', u'Nickname': u'Joe Bloggs'}] - interface: 'im.telepathy.v1.Account' - path: '/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0' - signal: 'AccountPropertyChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Account' - path: '/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0' - signal: 'Removed' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.MissionControl6', u':1.2', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.AccountManager', u':1.2', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.ChannelDispatcher', u':1.2', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u':1.2', u':1.2', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u':1.1', u':1.1', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u':1.3', u':1.3', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u'im.telepathy.v1.MissionControl6', u':1.2', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u'im.telepathy.v1.AccountManager', u':1.2', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' handled, took 5001.521 ms Waiting for event on: dbus got event: - type dbus-signal - args: [u'im.telepathy.v1.ChannelDispatcher', u':1.2', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u':1.2', u':1.2', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u':1.1', u':1.1', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u':1.3', u':1.3', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u':1.4', u'', u':1.4'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u':1.5', u'', u':1.5'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u':1.4', u'', u':1.4'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u':1.5', u'', u':1.5'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u':1.6', u'', u':1.6'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u':1.6', u'', u':1.6'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-method-call - args: [] - destination: ':1.0' - handled: True - interface: 'net.connman.Manager' - message: - method: 'GetProperties' - path: dbus.ObjectPath('/') - raw_args: [] - sender: ':1.6' - subqueue: 'dbus' - subtype: 'method-call' got event: - type dbus-method-call - args: [] - destination: ':1.0' - handled: True - interface: 'net.connman.Manager' - message: - method: 'GetProperties' - path: dbus.ObjectPath('/') - raw_args: [] - sender: ':1.6' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-method-call - args: [u'org.freedesktop.NetworkManager'] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.DBus.Properties' - message: - method: 'GetAll' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [dbus.String(u'org.freedesktop.NetworkManager')] - sender: ':1.5' - subqueue: 'dbus' - subtype: 'method-call' Adding to queue got event: - type dbus-method-call - args: [] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.NetworkManager' - message: - method: 'GetDevices' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [] - sender: ':1.5' - subqueue: 'dbus' - subtype: 'method-call' Adding to queue got event: - type dbus-method-call - args: [] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.NetworkManager' - message: - method: 'GetPermissions' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [] - sender: ':1.5' - subqueue: 'dbus' - subtype: 'method-call' got event: - type dbus-method-call - args: [u'org.freedesktop.NetworkManager'] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.DBus.Properties' - message: - method: 'GetAll' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [dbus.String(u'org.freedesktop.NetworkManager')] - sender: ':1.5' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus got event: - type dbus-method-call - args: [] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.NetworkManager' - message: - method: 'GetDevices' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [] - sender: ':1.5' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus got event: - type dbus-method-call - args: [] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.NetworkManager' - message: - method: 'GetPermissions' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [] - sender: ':1.5' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Listing' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Listing' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.ChannelDispatcher', u'', u':1.5'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u'im.telepathy.v1.ChannelDispatcher', u'', u':1.5'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' handled, took 52.513 ms Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Ready' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.MissionControl6', u'', u':1.5'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Ready' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u'im.telepathy.v1.MissionControl6', u'', u':1.5'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' handled, took 54.068 ms Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Inactive' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Inactive' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-method-call - args: [u'im.telepathy.v1.ConnectionManager'] - destination: 'im.telepathy.v1.ConnectionManager.fakecm' - handled: True - interface: 'org.freedesktop.DBus.Properties' - message: - method: 'GetAll' - path: dbus.ObjectPath('/im/telepathy/v1/ConnectionManager/fakecm') - raw_args: [dbus.String(u'im.telepathy.v1.ConnectionManager')] - sender: ':1.5' - subqueue: 'dbus' - subtype: 'method-call' got event: - type dbus-method-call - args: [u'im.telepathy.v1.ConnectionManager'] - destination: 'im.telepathy.v1.ConnectionManager.fakecm' - handled: True - interface: 'org.freedesktop.DBus.Properties' - message: - method: 'GetAll' - path: dbus.ObjectPath('/im/telepathy/v1/ConnectionManager/fakecm') - raw_args: [dbus.String(u'im.telepathy.v1.ConnectionManager')] - sender: ':1.5' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.AccountManager', u'', u':1.5'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u'im.telepathy.v1.AccountManager', u'', u':1.5'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' handled, took 57.140 ms Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: ['/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0'] - interface: 'im.telepathy.v1.AccountManager' - path: '/im/telepathy/v1/AccountManager' - signal: 'AccountRemoved' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Account' - path: '/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0' - signal: 'Removed' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: ['/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0'] - interface: 'im.telepathy.v1.AccountManager' - path: '/im/telepathy/v1/AccountManager' - signal: 'AccountRemoved' - subqueue: 'dbus' - subtype: 'signal' handled, took 0.884 ms Waiting for event on: dbus got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Account' - path: '/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0' - signal: 'Removed' - subqueue: 'dbus' - subtype: 'signal' handled, took 1.101 ms Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.AccountManager', u':1.5', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.MissionControl6', u':1.5', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.ChannelDispatcher', u':1.5', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u':1.5', u':1.5', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u':1.4', u':1.4', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u':1.6', u':1.6', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u'im.telepathy.v1.AccountManager', u':1.5', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' handled, took 4906.018 ms Waiting for event on: dbus got event: - type dbus-signal - args: [u'im.telepathy.v1.MissionControl6', u':1.5', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u'im.telepathy.v1.ChannelDispatcher', u':1.5', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u':1.5', u':1.5', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u':1.4', u':1.4', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u':1.6', u':1.6', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u':1.7', u'', u':1.7'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u':1.7', u'', u':1.7'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u':1.8', u'', u':1.8'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u':1.8', u'', u':1.8'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u':1.9', u'', u':1.9'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u':1.9', u'', u':1.9'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-method-call - args: [] - destination: ':1.0' - handled: True - interface: 'net.connman.Manager' - message: - method: 'GetProperties' - path: dbus.ObjectPath('/') - raw_args: [] - sender: ':1.9' - subqueue: 'dbus' - subtype: 'method-call' got event: - type dbus-method-call - args: [] - destination: ':1.0' - handled: True - interface: 'net.connman.Manager' - message: - method: 'GetProperties' - path: dbus.ObjectPath('/') - raw_args: [] - sender: ':1.9' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-method-call - args: [u'org.freedesktop.NetworkManager'] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.DBus.Properties' - message: - method: 'GetAll' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [dbus.String(u'org.freedesktop.NetworkManager')] - sender: ':1.8' - subqueue: 'dbus' - subtype: 'method-call' got event: - type dbus-method-call - args: [u'org.freedesktop.NetworkManager'] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.DBus.Properties' - message: - method: 'GetAll' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [dbus.String(u'org.freedesktop.NetworkManager')] - sender: ':1.8' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-method-call - args: [] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.NetworkManager' - message: - method: 'GetDevices' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [] - sender: ':1.8' - subqueue: 'dbus' - subtype: 'method-call' Adding to queue got event: - type dbus-method-call - args: [] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.NetworkManager' - message: - method: 'GetPermissions' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [] - sender: ':1.8' - subqueue: 'dbus' - subtype: 'method-call' got event: - type dbus-method-call - args: [] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.NetworkManager' - message: - method: 'GetDevices' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [] - sender: ':1.8' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus got event: - type dbus-method-call - args: [] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.NetworkManager' - message: - method: 'GetPermissions' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [] - sender: ':1.8' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Listing' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Listing' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.ChannelDispatcher', u'', u':1.8'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u'im.telepathy.v1.ChannelDispatcher', u'', u':1.8'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' handled, took 63.247 ms Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Ready' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.MissionControl6', u'', u':1.8'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Ready' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u'im.telepathy.v1.MissionControl6', u'', u':1.8'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' handled, took 65.874 ms Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Inactive' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Inactive' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-method-call - args: [u'im.telepathy.v1.ConnectionManager'] - destination: 'im.telepathy.v1.ConnectionManager.fakecm' - handled: True - interface: 'org.freedesktop.DBus.Properties' - message: - method: 'GetAll' - path: dbus.ObjectPath('/im/telepathy/v1/ConnectionManager/fakecm') - raw_args: [dbus.String(u'im.telepathy.v1.ConnectionManager')] - sender: ':1.8' - subqueue: 'dbus' - subtype: 'method-call' got event: - type dbus-method-call - args: [u'im.telepathy.v1.ConnectionManager'] - destination: 'im.telepathy.v1.ConnectionManager.fakecm' - handled: True - interface: 'org.freedesktop.DBus.Properties' - message: - method: 'GetAll' - path: dbus.ObjectPath('/im/telepathy/v1/ConnectionManager/fakecm') - raw_args: [dbus.String(u'im.telepathy.v1.ConnectionManager')] - sender: ':1.8' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.AccountManager', u'', u':1.8'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u'im.telepathy.v1.AccountManager', u'', u':1.8'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' handled, took 71.657 ms Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: ['/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0', False] - interface: 'im.telepathy.v1.AccountManager' - path: '/im/telepathy/v1/AccountManager' - signal: 'AccountUsabilityChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: ['/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0', False] - interface: 'im.telepathy.v1.AccountManager' - path: '/im/telepathy/v1/AccountManager' - signal: 'AccountUsabilityChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [{u'Parameters': {u'account': u'dontdivert@example.com', u'snakes': 42L}, u'Usable': False}] - interface: 'im.telepathy.v1.Account' - path: '/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0' - signal: 'AccountPropertyChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [{u'Parameters': {u'account': u'dontdivert@example.com', u'snakes': 42L}, u'Usable': False}] - interface: 'im.telepathy.v1.Account' - path: '/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0' - signal: 'AccountPropertyChanged' - subqueue: 'dbus' - subtype: 'signal' handled, took 10.911 ms Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: ['/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0priority'] - interface: 'im.telepathy.v1.AccountManager' - path: '/im/telepathy/v1/AccountManager' - signal: 'AccountRemoved' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Account' - path: '/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0priority' - signal: 'Removed' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Account' - path: '/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0' - signal: 'Removed' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: ['/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0priority'] - interface: 'im.telepathy.v1.AccountManager' - path: '/im/telepathy/v1/AccountManager' - signal: 'AccountRemoved' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Account' - path: '/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0priority' - signal: 'Removed' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Account' - path: '/im/telepathy/v1/Account/fakecm/fakeprotocol/dontdivert_40example_2ecom0' - signal: 'Removed' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.AccountManager', u':1.8', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.MissionControl6', u':1.8', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.ChannelDispatcher', u':1.8', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u':1.8', u':1.8', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u':1.7', u':1.7', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u':1.9', u':1.9', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u'im.telepathy.v1.AccountManager', u':1.8', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' handled, took 4907.497 ms Waiting for event on: dbus got event: - type dbus-signal - args: [u'im.telepathy.v1.MissionControl6', u':1.8', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u'im.telepathy.v1.ChannelDispatcher', u':1.8', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u':1.8', u':1.8', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u':1.7', u':1.7', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u':1.9', u':1.9', u''] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u':1.10', u'', u':1.10'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u':1.11', u'', u':1.11'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u':1.10', u'', u':1.10'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u':1.11', u'', u':1.11'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u':1.12', u'', u':1.12'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u':1.12', u'', u':1.12'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-method-call - args: [] - destination: ':1.0' - handled: True - interface: 'net.connman.Manager' - message: - method: 'GetProperties' - path: dbus.ObjectPath('/') - raw_args: [] - sender: ':1.12' - subqueue: 'dbus' - subtype: 'method-call' got event: - type dbus-method-call - args: [] - destination: ':1.0' - handled: True - interface: 'net.connman.Manager' - message: - method: 'GetProperties' - path: dbus.ObjectPath('/') - raw_args: [] - sender: ':1.12' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-method-call - args: [u'org.freedesktop.NetworkManager'] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.DBus.Properties' - message: - method: 'GetAll' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [dbus.String(u'org.freedesktop.NetworkManager')] - sender: ':1.11' - subqueue: 'dbus' - subtype: 'method-call' Adding to queue got event: - type dbus-method-call - args: [] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.NetworkManager' - message: - method: 'GetDevices' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [] - sender: ':1.11' - subqueue: 'dbus' - subtype: 'method-call' Adding to queue got event: - type dbus-method-call - args: [] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.NetworkManager' - message: - method: 'GetPermissions' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [] - sender: ':1.11' - subqueue: 'dbus' - subtype: 'method-call' got event: - type dbus-method-call - args: [u'org.freedesktop.NetworkManager'] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.DBus.Properties' - message: - method: 'GetAll' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [dbus.String(u'org.freedesktop.NetworkManager')] - sender: ':1.11' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus got event: - type dbus-method-call - args: [] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.NetworkManager' - message: - method: 'GetDevices' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [] - sender: ':1.11' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus got event: - type dbus-method-call - args: [] - destination: 'org.freedesktop.NetworkManager' - handled: True - interface: 'org.freedesktop.NetworkManager' - message: - method: 'GetPermissions' - path: dbus.ObjectPath('/org/freedesktop/NetworkManager') - raw_args: [] - sender: ':1.11' - subqueue: 'dbus' - subtype: 'method-call' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Listing' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Listing' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.ChannelDispatcher', u'', u':1.11'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Ready' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.AccountManager', u'', u':1.11'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [u'im.telepathy.v1.MissionControl6', u'', u':1.11'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' Adding to queue got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Inactive' - subqueue: 'dbus' - subtype: 'signal' got event: - type dbus-signal - args: [u'im.telepathy.v1.ChannelDispatcher', u'', u':1.11'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' handled, took 51.397 ms Waiting for event on: dbus got event: - type dbus-signal - args: [] - interface: 'im.telepathy.v1.Test.DBusAccountPlugin' - path: '/im/telepathy/v1/Test/DBusAccountPlugin' - signal: 'Ready' - subqueue: 'dbus' - subtype: 'signal' not handled Waiting for event on: dbus got event: - type dbus-signal - args: [u'im.telepathy.v1.AccountManager', u'', u':1.11'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' handled, took 51.929 ms Waiting for event on: dbus got event: - type dbus-signal - args: [u'im.telepathy.v1.MissionControl6', u'', u':1.11'] - interface: 'org.freedesktop.DBus' - path: '/org/freedesktop/DBus' - signal: 'NameOwnerChanged' - subqueue: 'dbus' - subtype: 'signal' handled, took 52.310 ms Traceback (most recent call last): File "/home/cassidy/dev/telepathy-mission-control/tests/twisted/mctest.py", line 151, in exec_test_deferred fun(queue, bus, mc, **kwargs) File "/home/cassidy/dev/telepathy-mission-control/tests/twisted/account-storage/default-keyring-storage.py", line 304, in test assert not os.path.exists(old_key_file_name) AssertionError Killing temporary bus daemon: 24409 ===== end of log files for account-storage/default-keyring-storage.py ===== Tests run: 1; tests failed: 1 make: *** [check-twisted] Error 1