Attachment 46717 Details for Bug 37207 – Debug log

Debug log

misson-control-15-05-11_01-57-33.log (text/x-log), 80.92 KB, created by ktowns on 2011-05-14 08:58:08 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: ktowns

Created: 2011-05-14 08:58:08 UTC

Size: 80.92 KB

patch

obsolete

>mcd-DEBUG: 15/05/11 01:45:47.941243: mcd_account_changed_property: First changed property
>mcd-DEBUG: 15/05/11 01:45:47.941252: _mcd_connection_request_presence: Presence requested: 1
>mcd-DEBUG: 15/05/11 01:45:47.941313: mcd_account_changed_property: called: RequestedPresence
>mcd-DEBUG: 15/05/11 01:45:47.941384: get_mcddbusprop: org.freedesktop.Telepathy.Account, RequestedPresence
>mcd-DEBUG: 15/05/11 01:45:47.941390: set_requested_presence: called for butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:45:47.941394: set_requested_presence: setting requested presence: 1, offline, 
>mcd-DEBUG: 15/05/11 01:45:47.941400: mcd_account_request_presence_int: Combined presence: 1 offline 
>mcd-DEBUG: 15/05/11 01:45:47.941404: mcd_account_changed_property: called: ChangingPresence
>mcd-DEBUG: 15/05/11 01:45:47.941406: mcd_account_changed_property: First changed property
>mcd-DEBUG: 15/05/11 01:45:47.941415: _mcd_connection_request_presence: Presence requested: 1
>mcd-DEBUG: 15/05/11 01:45:47.941463: mcd_account_changed_property: called: RequestedPresence
>mcd-DEBUG: 15/05/11 01:45:47.946402: on_connection_status_changed: status_changed called from tp (2)
>mcd-DEBUG: 15/05/11 01:45:47.946449: mcd_connection_invalidated_cb: Proxy destroyed (User requested disconnection)!
>mcd-DEBUG: 15/05/11 01:45:47.946455: _mcd_connection_release_tp_connection: 0x951dd58
>mcd-DEBUG: 15/05/11 01:45:47.946506: mcd_account_changed_property: called: ChangingPresence
>mcd-DEBUG: 15/05/11 01:45:47.946511: mcd_account_changed_property: Forcibly emit PropertiesChanged now
>mcd-DEBUG: 15/05/11 01:45:47.946515: emit_property_changed: called
>mcd-DEBUG: 15/05/11 01:45:47.946599: mcd_account_changed_property: First changed property
>mcd-DEBUG: 15/05/11 01:45:47.946611: mcd_account_changed_property: called: CurrentPresence
>mcd-DEBUG: 15/05/11 01:45:47.946628: _mcd_account_set_connection_status: butterfly/msn/ktowns_40live_2ecom_2eau0: 2 because 1
>mcd-DEBUG: 15/05/11 01:45:47.946631: mcd_account_freeze_properties: butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:45:47.946638: _mcd_account_set_connection_status: changing detailed D-Bus error from '' to 'org.freedesktop.Telepathy.Error.Cancelled'
>mcd-DEBUG: 15/05/11 01:45:47.946643: _mcd_account_set_connection_status: changing connection status from 0 to 2
>mcd-DEBUG: 15/05/11 01:45:47.946646: mcd_account_changed_property: called: Connection
>mcd-DEBUG: 15/05/11 01:45:47.947005: mcd_account_changed_property: called: ConnectionStatus
>mcd-DEBUG: 15/05/11 01:45:47.947011: mcd_account_changed_property: called: ConnectionStatusReason
>mcd-DEBUG: 15/05/11 01:45:47.947016: mcd_account_changed_property: called: ConnectionError
>mcd-DEBUG: 15/05/11 01:45:47.947026: mcd_account_changed_property: called: ConnectionErrorDetails
>mcd-DEBUG: 15/05/11 01:45:47.947035: mcd_account_thaw_properties: butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:45:47.947040: emit_property_changed: called
>mcd-DEBUG: 15/05/11 01:45:47.947174: _mcd_operation_abort: Operation abort received, aborting all children
>mcd-DEBUG: 15/05/11 01:45:47.947182: mcd_channel_abort: 0x9529600
>mcd-DEBUG: 15/05/11 01:45:47.947186: _mcd_channel_set_status: 0x9529600, 7
>mcd-DEBUG: 15/05/11 01:45:47.947200: mcd_channel_abort: 0x9529780
>mcd-DEBUG: 15/05/11 01:45:47.947204: _mcd_channel_set_status: 0x9529780, 7
>mcd-DEBUG: 15/05/11 01:45:47.947215: mcd_channel_abort: 0x9529680
>mcd-DEBUG: 15/05/11 01:45:47.947218: _mcd_channel_set_status: 0x9529680, 7
>mcd-DEBUG: 15/05/11 01:45:47.947227: mcd_channel_abort: 0x9529700
>mcd-DEBUG: 15/05/11 01:45:47.947232: _mcd_channel_set_status: 0x9529700, 7
>mcd-DEBUG: 15/05/11 01:45:47.947241: mcd_channel_abort: 0x9529a48
>mcd-DEBUG: 15/05/11 01:45:47.947244: _mcd_channel_set_status: 0x9529a48, 7
>mcd-DEBUG: 15/05/11 01:45:47.947253: mcd_channel_abort: 0x9529500
>mcd-DEBUG: 15/05/11 01:45:47.947257: _mcd_channel_set_status: 0x9529500, 7
>mcd-DEBUG: 15/05/11 01:45:47.947268: on_connection_abort: called (0x951dd58, account butterfly/msn/ktowns_40live_2ecom_2eau0)
>mcd-DEBUG: 15/05/11 01:45:47.947283: _mcd_mission_set_parent: child = 0x951dd58, parent = (nil)
>mcd-DEBUG: 15/05/11 01:45:47.947290: _mcd_operation_remove_mission: removing mission: 0x951dd58
>mcd-DEBUG: 15/05/11 01:45:47.947295: on_parent_abort: called
>mcd-DEBUG: 15/05/11 01:45:47.947299: _mcd_mission_set_parent: child = 0x9529500, parent = (nil)
>mcd-DEBUG: 15/05/11 01:45:47.947307: on_parent_abort: called
>mcd-DEBUG: 15/05/11 01:45:47.947310: _mcd_mission_set_parent: child = 0x9529a48, parent = (nil)
>mcd-DEBUG: 15/05/11 01:45:47.947316: on_parent_abort: called
>mcd-DEBUG: 15/05/11 01:45:47.947320: _mcd_mission_set_parent: child = 0x9529700, parent = (nil)
>mcd-DEBUG: 15/05/11 01:45:47.947326: on_parent_abort: called
>mcd-DEBUG: 15/05/11 01:45:47.947330: _mcd_mission_set_parent: child = 0x9529680, parent = (nil)
>mcd-DEBUG: 15/05/11 01:45:47.947335: on_parent_abort: called
>mcd-DEBUG: 15/05/11 01:45:47.947340: _mcd_mission_set_parent: child = 0x9529780, parent = (nil)
>mcd-DEBUG: 15/05/11 01:45:47.947345: on_parent_abort: called
>mcd-DEBUG: 15/05/11 01:45:47.947350: _mcd_mission_set_parent: child = 0x9529600, parent = (nil)
>mcd-DEBUG: 15/05/11 01:45:47.947357: _mcd_connection_dispose: called for object 0x951dd58
>mcd-DEBUG: 15/05/11 01:45:47.947364: _mcd_mission_set_parent: child = 0x9529600, parent = (nil)
>mcd-DEBUG: 15/05/11 01:45:47.947838: _mcd_operation_remove_mission: removing mission: 0x9529600
>mcd-DEBUG: 15/05/11 01:45:47.947843: _mcd_channel_dispose: 0x9529600 (is disposed = 0)
>mcd-DEBUG: 15/05/11 01:45:47.947846: _mcd_request_dispose: 0x951b770
>mcd-DEBUG: 15/05/11 01:45:47.947851: _mcd_request_finalize: 0x951b770
>mcd-DEBUG: 15/05/11 01:45:47.947860: _mcd_mission_dispose: mission disposed 0x9529600
>mcd-DEBUG: 15/05/11 01:45:47.947873: _mcd_mission_finalize: mission finalized 0x9529600
>mcd-DEBUG: 15/05/11 01:45:47.947879: _mcd_mission_set_parent: child = 0x9529780, parent = (nil)
>mcd-DEBUG: 15/05/11 01:45:47.948347: _mcd_operation_remove_mission: removing mission: 0x9529780
>mcd-DEBUG: 15/05/11 01:45:47.948352: _mcd_channel_dispose: 0x9529780 (is disposed = 0)
>mcd-DEBUG: 15/05/11 01:45:47.948564: _mcd_mission_dispose: mission disposed 0x9529780
>mcd-DEBUG: 15/05/11 01:45:47.948570: _mcd_mission_finalize: mission finalized 0x9529780
>mcd-DEBUG: 15/05/11 01:45:47.948576: _mcd_mission_set_parent: child = 0x9529680, parent = (nil)
>mcd-DEBUG: 15/05/11 01:45:47.949048: _mcd_operation_remove_mission: removing mission: 0x9529680
>mcd-DEBUG: 15/05/11 01:45:47.949054: _mcd_channel_dispose: 0x9529680 (is disposed = 0)
>mcd-DEBUG: 15/05/11 01:45:47.949259: _mcd_mission_dispose: mission disposed 0x9529680
>mcd-DEBUG: 15/05/11 01:45:47.949266: _mcd_mission_finalize: mission finalized 0x9529680
>mcd-DEBUG: 15/05/11 01:45:47.949275: _mcd_mission_set_parent: child = 0x9529700, parent = (nil)
>mcd-DEBUG: 15/05/11 01:45:47.949742: _mcd_operation_remove_mission: removing mission: 0x9529700
>mcd-DEBUG: 15/05/11 01:45:47.949745: _mcd_channel_dispose: 0x9529700 (is disposed = 0)
>mcd-DEBUG: 15/05/11 01:45:47.949948: _mcd_mission_dispose: mission disposed 0x9529700
>mcd-DEBUG: 15/05/11 01:45:47.949954: _mcd_mission_finalize: mission finalized 0x9529700
>mcd-DEBUG: 15/05/11 01:45:47.949960: _mcd_mission_set_parent: child = 0x9529a48, parent = (nil)
>mcd-DEBUG: 15/05/11 01:45:47.953083: _mcd_operation_remove_mission: removing mission: 0x9529a48
>mcd-DEBUG: 15/05/11 01:45:47.953088: _mcd_channel_dispose: 0x9529a48 (is disposed = 0)
>mcd-DEBUG: 15/05/11 01:45:47.953300: _mcd_mission_dispose: mission disposed 0x9529a48
>mcd-DEBUG: 15/05/11 01:45:47.953306: _mcd_mission_finalize: mission finalized 0x9529a48
>mcd-DEBUG: 15/05/11 01:45:47.953315: _mcd_mission_set_parent: child = 0x9529500, parent = (nil)
>mcd-DEBUG: 15/05/11 01:45:47.953788: _mcd_operation_remove_mission: removing mission: 0x9529500
>mcd-DEBUG: 15/05/11 01:45:47.953792: _mcd_channel_dispose: 0x9529500 (is disposed = 0)
>mcd-DEBUG: 15/05/11 01:45:47.953993: _mcd_mission_dispose: mission disposed 0x9529500
>mcd-DEBUG: 15/05/11 01:45:47.953999: _mcd_mission_finalize: mission finalized 0x9529500
>mcd-DEBUG: 15/05/11 01:45:47.954004: _mcd_connection_release_tp_connection: 0x951dd58
>mcd-DEBUG: 15/05/11 01:45:47.954013: _mcd_operation_dispose: operation disposed
>mcd-DEBUG: 15/05/11 01:45:47.954019: _mcd_mission_dispose: mission disposed 0x951dd58
>mcd-DEBUG: 15/05/11 01:45:47.954030: mcd_dispatcher_lost_connection: 0x951b4a0: 0x951dd58
>mcd-DEBUG: 15/05/11 01:45:47.954035: mcd_dispatcher_lost_connection: 0x951b4a0: 0x951dd58
>mcd-DEBUG: 15/05/11 01:45:47.954039: mcd_dispatcher_lost_connection: 0x951b4a0: 0x951dd58
>mcd-DEBUG: 15/05/11 01:45:47.954041: mcd_dispatcher_lost_connection: 0x951b4a0: 0x951dd58
>mcd-DEBUG: 15/05/11 01:45:47.954046: mcd_dispatcher_lost_connection: 0x951b4a0: 0x951dd58
>mcd-DEBUG: 15/05/11 01:45:47.954049: mcd_dispatcher_lost_connection: 0x951b4a0: 0x951dd58
>mcd-DEBUG: 15/05/11 01:45:47.954052: mcd_dispatcher_lost_connection: 0x951b4a0: 0x951dd58
>mcd-DEBUG: 15/05/11 01:45:47.954056: mcd_dispatcher_lost_connection: 0x951b4a0: 0x951dd58
>mcd-DEBUG: 15/05/11 01:45:47.954060: mcd_dispatcher_lost_connection: 0x951b4a0: 0x951dd58
>mcd-DEBUG: 15/05/11 01:45:47.954117: _mcd_mission_finalize: mission finalized 0x951dd58
>mcd-DEBUG: 15/05/11 01:45:47.954641: emit_property_changed: called
>mcd-DEBUG: 15/05/11 01:45:47.961494: proxy_destroyed: Channel proxy invalidated: tp_dbus_errors_quark 8: Channel was closed
>mcd-DEBUG: 15/05/11 01:45:47.961500: mcd_channel_abort: 0x9529400
>mcd-DEBUG: 15/05/11 01:45:47.961505: _mcd_channel_set_status: 0x9529400, 7
>mcd-DEBUG: 15/05/11 01:45:47.961522: _mcd_mission_set_parent: child = 0x9529400, parent = (nil)
>mcd-DEBUG: 15/05/11 01:45:47.961611: _mcd_operation_remove_mission: removing mission: 0x9529400
>mcd-DEBUG: 15/05/11 01:45:47.961616: _mcd_channel_dispose: 0x9529400 (is disposed = 0)
>mcd-DEBUG: 15/05/11 01:45:47.961632: _mcd_mission_dispose: mission disposed 0x9529400
>mcd-DEBUG: 15/05/11 01:45:47.961638: _mcd_mission_finalize: mission finalized 0x9529400
>mcd-DEBUG: 15/05/11 01:45:47.961890: proxy_destroyed: Channel proxy invalidated: tp_dbus_errors_quark 8: Channel was closed
>mcd-DEBUG: 15/05/11 01:45:47.961895: mcd_channel_abort: 0x9529640
>mcd-DEBUG: 15/05/11 01:45:47.961899: _mcd_channel_set_status: 0x9529640, 7
>mcd-DEBUG: 15/05/11 01:45:47.961911: _mcd_mission_set_parent: child = 0x9529640, parent = (nil)
>mcd-DEBUG: 15/05/11 01:45:47.961981: _mcd_operation_remove_mission: removing mission: 0x9529640
>mcd-DEBUG: 15/05/11 01:45:47.961987: _mcd_channel_dispose: 0x9529640 (is disposed = 0)
>mcd-DEBUG: 15/05/11 01:45:47.961993: _mcd_mission_dispose: mission disposed 0x9529640
>mcd-DEBUG: 15/05/11 01:45:47.961999: _mcd_mission_finalize: mission finalized 0x9529640
>mcd-DEBUG: 15/05/11 01:45:47.962210: on_connection_status_changed: status_changed called from tp (2)
>mcd-DEBUG: 15/05/11 01:45:47.962249: mcd_connection_invalidated_cb: Proxy destroyed (User requested disconnection)!
>mcd-DEBUG: 15/05/11 01:45:47.962255: _mcd_connection_release_tp_connection: 0x951dcf0
>mcd-DEBUG: 15/05/11 01:45:47.962263: mcd_account_changed_property: called: ChangingPresence
>mcd-DEBUG: 15/05/11 01:45:47.962266: mcd_account_changed_property: First changed property
>mcd-DEBUG: 15/05/11 01:45:47.962280: mcd_account_changed_property: called: CurrentPresence
>mcd-DEBUG: 15/05/11 01:45:47.962292: _mcd_account_set_connection_status: salut/local_xmpp/account0: 2 because 1
>mcd-DEBUG: 15/05/11 01:45:47.962311: mcd_account_freeze_properties: salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:45:47.962317: _mcd_account_set_connection_status: changing detailed D-Bus error from '' to 'org.freedesktop.Telepathy.Error.Cancelled'
>mcd-DEBUG: 15/05/11 01:45:47.962322: _mcd_account_set_connection_status: changing connection status from 0 to 2
>mcd-DEBUG: 15/05/11 01:45:47.962327: mcd_account_changed_property: called: Connection
>mcd-DEBUG: 15/05/11 01:45:47.962620: mcd_account_changed_property: called: ConnectionStatus
>mcd-DEBUG: 15/05/11 01:45:47.962627: mcd_account_changed_property: called: ConnectionStatusReason
>mcd-DEBUG: 15/05/11 01:45:47.962632: mcd_account_changed_property: called: ConnectionError
>mcd-DEBUG: 15/05/11 01:45:47.962644: mcd_account_changed_property: called: ConnectionErrorDetails
>mcd-DEBUG: 15/05/11 01:45:47.962652: mcd_account_thaw_properties: salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:45:47.962656: emit_property_changed: called
>mcd-DEBUG: 15/05/11 01:45:47.962794: _mcd_operation_abort: Operation abort received, aborting all children
>mcd-DEBUG: 15/05/11 01:45:47.962800: on_connection_abort: called (0x951dcf0, account salut/local_xmpp/account0)
>mcd-DEBUG: 15/05/11 01:45:47.962812: _mcd_mission_set_parent: child = 0x951dcf0, parent = (nil)
>mcd-DEBUG: 15/05/11 01:45:47.962821: _mcd_operation_remove_mission: removing mission: 0x951dcf0
>mcd-DEBUG: 15/05/11 01:45:47.962826: _mcd_connection_dispose: called for object 0x951dcf0
>mcd-DEBUG: 15/05/11 01:45:47.962831: _mcd_connection_release_tp_connection: 0x951dcf0
>mcd-DEBUG: 15/05/11 01:45:47.962838: _mcd_operation_dispose: operation disposed
>mcd-DEBUG: 15/05/11 01:45:47.962843: _mcd_mission_dispose: mission disposed 0x951dcf0
>mcd-DEBUG: 15/05/11 01:45:47.962851: mcd_dispatcher_lost_connection: 0x951b4a0: 0x951dcf0
>mcd-DEBUG: 15/05/11 01:45:47.962896: _mcd_mission_finalize: mission finalized 0x951dcf0
>mcd-DEBUG: 15/05/11 01:47:43.118000: dbusprop_get_all: org.freedesktop.Telepathy.AccountManager
>mcd-DEBUG: 15/05/11 01:47:43.118010: get_valid_accounts: called
>mcd-DEBUG: 15/05/11 01:47:43.118027: get_invalid_accounts: called
>mcd-DEBUG: 15/05/11 01:47:43.118035: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:47:43.309705: _mcd_client_registry_found_name: Registering client org.freedesktop.Telepathy.Client.Empathy.EventManager
>mcd-DEBUG: 15/05/11 01:47:43.309792: mcd_client_proxy_constructed: org.freedesktop.Telepathy.Client.Empathy.EventManager
>mcd-DEBUG: 15/05/11 01:47:43.309940: mcd_client_proxy_introspect: No .client file for org.freedesktop.Telepathy.Client.Empathy.EventManager. Ask on D-Bus.
>mcd-DEBUG: 15/05/11 01:47:43.402837: _mcd_client_registry_found_name: Registering client org.freedesktop.Telepathy.Client.Empathy.FileTransfer
>mcd-DEBUG: 15/05/11 01:47:43.402921: mcd_client_proxy_constructed: org.freedesktop.Telepathy.Client.Empathy.FileTransfer
>mcd-DEBUG: 15/05/11 01:47:43.403047: mcd_client_proxy_introspect: No .client file for org.freedesktop.Telepathy.Client.Empathy.FileTransfer. Ask on D-Bus.
>mcd-DEBUG: 15/05/11 01:47:43.405011: dbusprop_get_all: org.freedesktop.Telepathy.Account
>mcd-DEBUG: 15/05/11 01:47:43.405019: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:47:43.405052: _mcd_account_dup_parameters: called
>mcd-DEBUG: 15/05/11 01:47:43.405112: get_connect_automatically: called for salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:47:43.405832: dbusprop_get_all: org.freedesktop.Telepathy.Account
>mcd-DEBUG: 15/05/11 01:47:43.405838: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:47:43.405865: _mcd_account_dup_parameters: called
>mcd-DEBUG: 15/05/11 01:47:43.405917: get_connect_automatically: called for salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:47:43.406176: dbusprop_get_all: org.freedesktop.Telepathy.Account
>mcd-DEBUG: 15/05/11 01:47:43.406181: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:47:43.406205: _mcd_account_dup_parameters: called
>mcd-DEBUG: 15/05/11 01:47:43.406270: get_connect_automatically: called for butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:47:43.406518: dbusprop_get_all: org.freedesktop.Telepathy.Account
>mcd-DEBUG: 15/05/11 01:47:43.406524: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:47:43.406548: _mcd_account_dup_parameters: called
>mcd-DEBUG: 15/05/11 01:47:43.406606: get_connect_automatically: called for butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:47:43.406841: _mcd_client_proxy_add_interfaces: org.freedesktop.Telepathy.Client.Empathy.EventManager: org.freedesktop.Telepathy.Client.Approver
>mcd-DEBUG: 15/05/11 01:47:43.406847: _mcd_client_proxy_get_interfaces_cb: Client org.freedesktop.Telepathy.Client.Empathy.EventManager
>mcd-DEBUG: 15/05/11 01:47:43.406853: _mcd_client_proxy_get_interfaces_cb: org.freedesktop.Telepathy.Client.Empathy.EventManager is an Approver
>mcd-DEBUG: 15/05/11 01:47:43.408730: _mcd_client_proxy_add_interfaces: org.freedesktop.Telepathy.Client.Empathy.FileTransfer: org.freedesktop.Telepathy.Client.Handler
>mcd-DEBUG: 15/05/11 01:47:43.408737: _mcd_client_proxy_get_interfaces_cb: Client org.freedesktop.Telepathy.Client.Empathy.FileTransfer
>mcd-DEBUG: 15/05/11 01:47:43.408742: _mcd_client_proxy_get_interfaces_cb: org.freedesktop.Telepathy.Client.Empathy.FileTransfer is a Handler
>mcd-DEBUG: 15/05/11 01:47:43.411829: mcd_client_registry_ready_cb: org.freedesktop.Telepathy.Client.Empathy.EventManager
>mcd-DEBUG: 15/05/11 01:47:43.412530: get_mcddbusprop: org.freedesktop.Telepathy.Account, RequestedPresence
>mcd-DEBUG: 15/05/11 01:47:43.412537: set_requested_presence: called for salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:47:43.412542: set_requested_presence: setting requested presence: 2, available, 
>mcd-DEBUG: 15/05/11 01:47:43.412548: mcd_account_request_presence_int: Combined presence: 2 available 
>mcd-DEBUG: 15/05/11 01:47:43.412552: mcd_account_changed_property: called: ChangingPresence
>mcd-DEBUG: 15/05/11 01:47:43.412561: mcd_account_changed_property: First changed property
>mcd-DEBUG: 15/05/11 01:47:43.412569: _mcd_account_dup_parameters: called
>mcd-DEBUG: 15/05/11 01:47:43.412647: _mcd_mission_set_parent: child = 0x951ddc0, parent = 0x9529440
>mcd-DEBUG: 15/05/11 01:47:43.412659: mcd_manager_create_connection: Created a connection 0x951ddc0 for account: salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:47:43.412663: _mcd_connection_connect: called for 0x951ddc0, account salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:47:43.412667: _mcd_connection_connect_with_params: Trying connect account: salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:47:43.412674: _mcd_account_set_connection_status: salut/local_xmpp/account0: 1 because 1
>mcd-DEBUG: 15/05/11 01:47:43.412678: mcd_account_freeze_properties: salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:47:43.412683: _mcd_account_set_connection_status: changing connection status from 2 to 1
>mcd-DEBUG: 15/05/11 01:47:43.412687: mcd_account_changed_property: called: Connection
>mcd-DEBUG: 15/05/11 01:47:43.412866: mcd_account_changed_property: called: ConnectionStatus
>mcd-DEBUG: 15/05/11 01:47:43.412872: mcd_account_changed_property: called: ConnectionStatusReason
>mcd-DEBUG: 15/05/11 01:47:43.412875: mcd_account_changed_property: called: ConnectionError
>mcd-DEBUG: 15/05/11 01:47:43.412885: mcd_account_changed_property: called: ConnectionErrorDetails
>mcd-DEBUG: 15/05/11 01:47:43.412894: mcd_account_thaw_properties: salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:47:43.412898: emit_property_changed: called
>mcd-DEBUG: 15/05/11 01:47:43.413086: mcd_account_changed_property: called: RequestedPresence
>mcd-DEBUG: 15/05/11 01:47:43.413091: mcd_account_changed_property: First changed property
>mcd-DEBUG: 15/05/11 01:47:43.414057: get_mcddbusprop: org.freedesktop.Telepathy.Account, RequestedPresence
>mcd-DEBUG: 15/05/11 01:47:43.414311: set_requested_presence: called for butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:47:43.414319: set_requested_presence: setting requested presence: 2, available, 
>mcd-DEBUG: 15/05/11 01:47:43.414325: mcd_account_request_presence_int: Combined presence: 2 available 
>mcd-DEBUG: 15/05/11 01:47:43.414330: mcd_account_changed_property: called: ChangingPresence
>mcd-DEBUG: 15/05/11 01:47:43.414335: mcd_account_changed_property: First changed property
>mcd-DEBUG: 15/05/11 01:47:43.414344: _mcd_account_dup_parameters: called
>mcd-DEBUG: 15/05/11 01:47:43.414448: _mcd_mission_set_parent: child = 0x951de28, parent = 0x9529480
>mcd-DEBUG: 15/05/11 01:47:43.414463: mcd_manager_create_connection: Created a connection 0x951de28 for account: butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:47:43.414467: _mcd_connection_connect: called for 0x951de28, account butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:47:43.414470: _mcd_connection_connect_with_params: Trying connect account: butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:47:43.414479: _mcd_account_set_connection_status: butterfly/msn/ktowns_40live_2ecom_2eau0: 1 because 1
>mcd-DEBUG: 15/05/11 01:47:43.414483: mcd_account_freeze_properties: butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:47:43.414486: _mcd_account_set_connection_status: changing connection status from 2 to 1
>mcd-DEBUG: 15/05/11 01:47:43.414490: mcd_account_changed_property: called: Connection
>mcd-DEBUG: 15/05/11 01:47:43.414546: mcd_account_changed_property: called: ConnectionStatus
>mcd-DEBUG: 15/05/11 01:47:43.414551: mcd_account_changed_property: called: ConnectionStatusReason
>mcd-DEBUG: 15/05/11 01:47:43.414556: mcd_account_changed_property: called: ConnectionError
>mcd-DEBUG: 15/05/11 01:47:43.414566: mcd_account_changed_property: called: ConnectionErrorDetails
>mcd-DEBUG: 15/05/11 01:47:43.414577: mcd_account_thaw_properties: butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:47:43.414581: emit_property_changed: called
>mcd-DEBUG: 15/05/11 01:47:43.415388: mcd_account_changed_property: called: RequestedPresence
>mcd-DEBUG: 15/05/11 01:47:43.415393: mcd_account_changed_property: First changed property
>mcd-DEBUG: 15/05/11 01:47:43.418091: _mcd_client_proxy_handler_get_all_cb: org.freedesktop.Telepathy.Client.Empathy.FileTransfer has 1 HandlerChannelFilter entries
>mcd-DEBUG: 15/05/11 01:47:43.418104: _mcd_client_proxy_handler_get_all_cb: org.freedesktop.Telepathy.Client.Empathy.FileTransfer has BypassApproval=F
>mcd-DEBUG: 15/05/11 01:47:43.418118: mcd_client_registry_ready_cb: org.freedesktop.Telepathy.Client.Empathy.FileTransfer
>mcd-DEBUG: 15/05/11 01:47:43.422390: emit_property_changed: called
>mcd-DEBUG: 15/05/11 01:47:43.424587: emit_property_changed: called
>mcd-DEBUG: 15/05/11 01:47:43.476841: request_connection_cb: created /org/freedesktop/Telepathy/Connection/salut/local_xmpp/kyle
>mcd-DEBUG: 15/05/11 01:47:43.477278: _mcd_connection_set_tp_connection: new connection is 0x9545d08
>mcd-DEBUG: 15/05/11 01:47:43.477287: mcd_account_changed_property: called: Connection
>mcd-DEBUG: 15/05/11 01:47:43.477291: mcd_account_changed_property: First changed property
>mcd-DEBUG: 15/05/11 01:47:43.478151: on_connection_status_changed: status_changed called from tp (2)
>mcd-DEBUG: 15/05/11 01:47:43.479785: mcd_connection_early_get_statuses_cb: /org/freedesktop/Telepathy/Connection/salut/local_xmpp/kyle: Early Get(Statuses) succeeded
>mcd-DEBUG: 15/05/11 01:47:43.479794: presence_get_statuses_cb: account salut/local_xmpp/account0:
>mcd-DEBUG: 15/05/11 01:47:43.479799: presence_get_statuses_cb:   away
>mcd-DEBUG: 15/05/11 01:47:43.479804: presence_get_statuses_cb:   dnd
>mcd-DEBUG: 15/05/11 01:47:43.479808: presence_get_statuses_cb:   available
>mcd-DEBUG: 15/05/11 01:47:43.479814: _mcd_connection_set_presence: Setting status 'available' of type 2 ('available' was requested)
>mcd-DEBUG: 15/05/11 01:47:43.479960: _mcd_dispatcher_add_connection: 0x951b4a0: 0x951ddc0 (/org/freedesktop/Telepathy/Connection/salut/local_xmpp/kyle)
>mcd-DEBUG: 15/05/11 01:47:43.480015: _mcd_connection_start_dispatching: 0x951ddc0
>mcd-DEBUG: 15/05/11 01:47:43.480158: _mcd_connection_update_client_caps: ContactCapabilities unsupported
>mcd-DEBUG: 15/05/11 01:47:43.480190: mcd_connection_done_task_before_connect: /org/freedesktop/Telepathy/Connection/salut/local_xmpp/kyle: Calling Connect()
>mcd-DEBUG: 15/05/11 01:47:43.487313: emit_property_changed: called
>mcd-DEBUG: 15/05/11 01:47:43.491702: on_connection_status_changed: status_changed called from tp (1)
>mcd-DEBUG: 15/05/11 01:47:43.491714: _mcd_account_set_connection_status: salut/local_xmpp/account0: 1 because 1
>mcd-DEBUG: 15/05/11 01:47:43.491719: mcd_account_freeze_properties: salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:47:43.491728: mcd_account_changed_property: called: Connection
>mcd-DEBUG: 15/05/11 01:47:43.491731: mcd_account_changed_property: First changed property
>mcd-DEBUG: 15/05/11 01:47:43.492017: mcd_account_changed_property: called: ConnectionStatus
>mcd-DEBUG: 15/05/11 01:47:43.492024: mcd_account_changed_property: called: ConnectionStatusReason
>mcd-DEBUG: 15/05/11 01:47:43.492031: mcd_account_changed_property: called: ConnectionError
>mcd-DEBUG: 15/05/11 01:47:43.492042: mcd_account_changed_property: called: ConnectionErrorDetails
>mcd-DEBUG: 15/05/11 01:47:43.492050: mcd_account_thaw_properties: salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:47:43.492054: emit_property_changed: called
>mcd-DEBUG: 15/05/11 01:47:43.493130: connect_cb: called for connection 0x951ddc0
>mcd-DEBUG: 15/05/11 01:47:44.34441: request_connection_cb: created /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau
>mcd-DEBUG: 15/05/11 01:47:44.34873: _mcd_connection_set_tp_connection: new connection is 0x9545d88
>mcd-DEBUG: 15/05/11 01:47:44.34879: mcd_account_changed_property: called: Connection
>mcd-DEBUG: 15/05/11 01:47:44.34884: mcd_account_changed_property: First changed property
>mcd-DEBUG: 15/05/11 01:47:44.35319: on_connection_status_changed: status_changed called from tp (2)
>mcd-DEBUG: 15/05/11 01:47:44.35890: _mcd_connection_update_client_caps: Sending client caps to connection
>mcd-DEBUG: 15/05/11 01:47:44.37683: mcd_connection_early_get_statuses_cb: /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau: Early Get(Statuses) succeeded
>mcd-DEBUG: 15/05/11 01:47:44.37688: presence_get_statuses_cb: account butterfly/msn/ktowns_40live_2ecom_2eau0:
>mcd-DEBUG: 15/05/11 01:47:44.37693: presence_get_statuses_cb:   xa
>mcd-DEBUG: 15/05/11 01:47:44.37698: presence_get_statuses_cb:   brb
>mcd-DEBUG: 15/05/11 01:47:44.37700: presence_get_statuses_cb:   dnd
>mcd-DEBUG: 15/05/11 01:47:44.37703: presence_get_statuses_cb:   phone
>mcd-DEBUG: 15/05/11 01:47:44.37708: presence_get_statuses_cb:   offline
>mcd-DEBUG: 15/05/11 01:47:44.37710: presence_get_statuses_cb:   lunch
>mcd-DEBUG: 15/05/11 01:47:44.37714: presence_get_statuses_cb:   hidden
>mcd-DEBUG: 15/05/11 01:47:44.37718: presence_get_statuses_cb:   available
>mcd-DEBUG: 15/05/11 01:47:44.37722: presence_get_statuses_cb:   away
>mcd-DEBUG: 15/05/11 01:47:44.37727: _mcd_connection_set_presence: Setting status 'available' of type 2 ('available' was requested)
>mcd-DEBUG: 15/05/11 01:47:44.37873: _mcd_dispatcher_add_connection: 0x951b4a0: 0x951de28 (/org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau)
>mcd-DEBUG: 15/05/11 01:47:44.37918: _mcd_connection_start_dispatching: 0x951de28
>mcd-DEBUG: 15/05/11 01:47:44.38161: _mcd_connection_update_client_caps: Sending client caps to connection
>mcd-DEBUG: 15/05/11 01:47:44.39099: mcd_connection_done_task_before_connect: /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau: Calling Connect()
>mcd-DEBUG: 15/05/11 01:47:44.40200: on_connection_status_changed: status_changed called from tp (1)
>mcd-DEBUG: 15/05/11 01:47:44.40210: _mcd_account_set_connection_status: butterfly/msn/ktowns_40live_2ecom_2eau0: 1 because 1
>mcd-DEBUG: 15/05/11 01:47:44.40215: mcd_account_freeze_properties: butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:47:44.40220: mcd_account_changed_property: called: Connection
>mcd-DEBUG: 15/05/11 01:47:44.40225: mcd_account_changed_property: Forcibly emit PropertiesChanged now
>mcd-DEBUG: 15/05/11 01:47:44.40227: emit_property_changed: called
>mcd-DEBUG: 15/05/11 01:47:44.40282: mcd_account_changed_property: First changed property
>mcd-DEBUG: 15/05/11 01:47:44.40563: mcd_account_changed_property: called: ConnectionStatus
>mcd-DEBUG: 15/05/11 01:47:44.40570: mcd_account_changed_property: called: ConnectionStatusReason
>mcd-DEBUG: 15/05/11 01:47:44.40575: mcd_account_changed_property: called: ConnectionError
>mcd-DEBUG: 15/05/11 01:47:44.40585: mcd_account_changed_property: called: ConnectionErrorDetails
>mcd-DEBUG: 15/05/11 01:47:44.40592: mcd_account_thaw_properties: butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:47:44.40597: emit_property_changed: called
>mcd-DEBUG: 15/05/11 01:47:44.41507: connect_cb: called for connection 0x951de28
>mcd-DEBUG: 15/05/11 01:47:44.386735: on_connection_status_changed: status_changed called from tp (0)
>mcd-DEBUG: 15/05/11 01:47:44.386760: _mcd_account_set_connection_status: salut/local_xmpp/account0: 0 because 1
>mcd-DEBUG: 15/05/11 01:47:44.386770: mcd_account_freeze_properties: salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:47:44.386780: _mcd_account_dup_parameters: called
>mcd-DEBUG: 15/05/11 01:47:44.386879: _mcd_account_set_connection_status: clearing connection error details
>mcd-DEBUG: 15/05/11 01:47:44.386890: _mcd_account_set_connection_status: changing connection status from 1 to 0
>mcd-DEBUG: 15/05/11 01:47:44.386904: mcd_account_changed_property: called: Connection
>mcd-DEBUG: 15/05/11 01:47:44.386912: mcd_account_changed_property: First changed property
>mcd-DEBUG: 15/05/11 01:47:44.387609: mcd_account_changed_property: called: ConnectionStatus
>mcd-DEBUG: 15/05/11 01:47:44.387624: mcd_account_changed_property: called: ConnectionStatusReason
>mcd-DEBUG: 15/05/11 01:47:44.387635: mcd_account_changed_property: called: ConnectionError
>mcd-DEBUG: 15/05/11 01:47:44.387661: mcd_account_changed_property: called: ConnectionErrorDetails
>mcd-DEBUG: 15/05/11 01:47:44.387684: mcd_account_thaw_properties: salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:47:44.387691: emit_property_changed: called
>mcd-DEBUG: 15/05/11 01:47:44.387972: on_connection_status_changed: setting probation timer (120) seconds, for /org/freedesktop/Telepathy/Connection/salut/local_xmpp/kyle
>mcd-DEBUG: 15/05/11 01:47:44.388026: on_connection_ready: connection is ready
>mcd-DEBUG: 15/05/11 01:47:44.388648: _mcd_account_get_avatar_filename: data dir: /home/kyle/.mission-control/accounts/salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:47:44.389055: request_unrequested_channels: called
>mcd-DEBUG: 15/05/11 01:47:44.389075: _mcd_account_set_connection_status: salut/local_xmpp/account0: 0 because 1
>mcd-DEBUG: 15/05/11 01:47:44.389084: mcd_account_freeze_properties: salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:47:44.389091: _mcd_account_dup_parameters: called
>mcd-DEBUG: 15/05/11 01:47:44.389178: _mcd_account_set_connection_status: clearing connection error details
>mcd-DEBUG: 15/05/11 01:47:44.389187: mcd_account_thaw_properties: salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:47:44.389388: _mcd_connection_set_nickname: setting nickname 'kyle' using Aliasing
>mcd-DEBUG: 15/05/11 01:47:44.391952: mcd_account_changed_property: called: CurrentPresence
>mcd-DEBUG: 15/05/11 01:47:44.391963: mcd_account_changed_property: First changed property
>mcd-DEBUG: 15/05/11 01:47:44.392921: presence_get_statuses_cb: account salut/local_xmpp/account0:
>mcd-DEBUG: 15/05/11 01:47:44.392936: presence_get_statuses_cb:   away
>mcd-DEBUG: 15/05/11 01:47:44.392946: presence_get_statuses_cb:   dnd
>mcd-DEBUG: 15/05/11 01:47:44.392955: presence_get_statuses_cb:   available
>mcd-DEBUG: 15/05/11 01:47:44.392968: _mcd_connection_set_presence: Setting status 'available' of type 2 ('available' was requested)
>mcd-DEBUG: 15/05/11 01:47:44.392977: mcd_account_changed_property: called: ChangingPresence
>mcd-DEBUG: 15/05/11 01:47:44.393260: _mcd_connection_get_aliases_cb: called
>mcd-DEBUG: 15/05/11 01:47:44.393357: _mcd_account_set_normalized_name: called (kyle@Kyle)
>mcd-DEBUG: 15/05/11 01:47:44.393383: _storage_commit: flushing plugin default-gkeyfile salut/local_xmpp/account0 to long term storage
>mcd-DEBUG: 15/05/11 01:47:44.393476: mcd_account_changed_property: called: NormalizedName
>mc-plugins-DEBUG: 15/05/11 01:47:44.393462: mcp_account_storage_commit_one: default-gkeyfile: 
>mcd-DEBUG: 15/05/11 01:47:44.395884: mcd_account_changed_property: called: ChangingPresence
>mcd-DEBUG: 15/05/11 01:47:44.395889: mcd_account_changed_property: Forcibly emit PropertiesChanged now
>mcd-DEBUG: 15/05/11 01:47:44.395893: emit_property_changed: called
>mcd-DEBUG: 15/05/11 01:47:44.395972: mcd_account_changed_property: First changed property
>mcd-DEBUG: 15/05/11 01:47:44.405101: emit_property_changed: called
>mcd-DEBUG: 15/05/11 01:47:44.411453: _mcd_mission_set_parent: child = 0x9529a08, parent = 0x951ddc0
>mcd-DEBUG: 15/05/11 01:47:44.411540: _mcd_dispatcher_take_channels: requested channel 0x9529a08 (only): /org/freedesktop/Telepathy/Connection/salut/local_xmpp/kyle/ContactChannel/publish
>mcd-DEBUG: 15/05/11 01:47:44.411545: _mcd_dispatcher_enter_state_machine: CTXREF11 on 0x951efd8
>mcd-DEBUG: 15/05/11 01:47:44.411551: _mcd_dispatcher_enter_state_machine: new dispatcher context 0x951efd8 for requested channel 0x9529a08 (only): /org/freedesktop/Telepathy/Connection/salut/local_xmpp/kyle/ContactChannel/publish
>mcd-DEBUG: 15/05/11 01:47:44.411580: mcd_dispatch_operation_constructor: do11/0x952fc60: needs_approval=F
>mcd-DEBUG: 15/05/11 01:47:44.411588: _mcd_plugin_dispatch_operation_init: 0x955c720
>mcd-DEBUG: 15/05/11 01:47:44.411596: _mcd_plugin_dispatch_operation_new: 0x955c720 (for 0x952fc60)
>mcd-DEBUG: 15/05/11 01:47:44.411604: _mcd_dispatcher_enter_state_machine: entering state machine for context 0x951efd8
>mcd-DEBUG: 15/05/11 01:47:44.411608: mcd_dispatcher_context_ref: CTXREF01 on 0x951efd8 (ref = 1)
>mcd-DEBUG: 15/05/11 01:47:44.411613: _mcd_dispatch_operation_run_clients: do11 0x952fc60
>mcd-DEBUG: 15/05/11 01:47:44.411617: _mcd_dispatch_operation_run_clients: Running observers
>mcd-DEBUG: 15/05/11 01:47:44.411629: _mcd_dispatch_operation_run_clients: All necessary observers invoked
>mcd-DEBUG: 15/05/11 01:47:44.411633: _mcd_dispatch_operation_run_clients: Checking finished/locks
>mcd-DEBUG: 15/05/11 01:47:44.411638: _mcd_plugin_dispatch_operation_observers_finished: 0x955c720
>mcd-DEBUG: 15/05/11 01:47:44.411642: _mcd_dispatch_operation_check_client_locks: only observing
>mcd-DEBUG: 15/05/11 01:47:44.411647: mcd_dispatcher_context_unref: CTXREF01 on 0x951efd8 (ref = 2)
>mcd-DEBUG: 15/05/11 01:47:44.411649: mcd_dispatcher_context_unref: CTXREF11 on 0x951efd8 (ref = 1)
>mcd-DEBUG: 15/05/11 01:47:44.411653: mcd_dispatcher_context_unref: freeing the context 0x951efd8
>mcd-DEBUG: 15/05/11 01:47:44.411667: _mcd_dispatch_operation_check_client_locks: only observing
>mcd-DEBUG: 15/05/11 01:47:44.411673: plugin_do_finalize: 0x955c720
>mcd-DEBUG: 15/05/11 01:47:44.414758: _mcd_mission_set_parent: child = 0x95295c0, parent = 0x951ddc0
>mcd-DEBUG: 15/05/11 01:47:44.414844: _mcd_dispatcher_take_channels: requested channel 0x95295c0 (only): /org/freedesktop/Telepathy/Connection/salut/local_xmpp/kyle/ContactChannel/subscribe
>mcd-DEBUG: 15/05/11 01:47:44.414850: _mcd_dispatcher_enter_state_machine: CTXREF11 on 0x953a788
>mcd-DEBUG: 15/05/11 01:47:44.414855: _mcd_dispatcher_enter_state_machine: new dispatcher context 0x953a788 for requested channel 0x95295c0 (only): /org/freedesktop/Telepathy/Connection/salut/local_xmpp/kyle/ContactChannel/subscribe
>mcd-DEBUG: 15/05/11 01:47:44.414884: mcd_dispatch_operation_constructor: do12/0x952fd70: needs_approval=F
>mcd-DEBUG: 15/05/11 01:47:44.414891: _mcd_plugin_dispatch_operation_init: 0x955d880
>mcd-DEBUG: 15/05/11 01:47:44.414899: _mcd_plugin_dispatch_operation_new: 0x955d880 (for 0x952fd70)
>mcd-DEBUG: 15/05/11 01:47:44.414907: _mcd_dispatcher_enter_state_machine: entering state machine for context 0x953a788
>mcd-DEBUG: 15/05/11 01:47:44.414912: mcd_dispatcher_context_ref: CTXREF01 on 0x953a788 (ref = 1)
>mcd-DEBUG: 15/05/11 01:47:44.414917: _mcd_dispatch_operation_run_clients: do12 0x952fd70
>mcd-DEBUG: 15/05/11 01:47:44.414921: _mcd_dispatch_operation_run_clients: Running observers
>mcd-DEBUG: 15/05/11 01:47:44.414932: _mcd_dispatch_operation_run_clients: All necessary observers invoked
>mcd-DEBUG: 15/05/11 01:47:44.414936: _mcd_dispatch_operation_run_clients: Checking finished/locks
>mcd-DEBUG: 15/05/11 01:47:44.414940: _mcd_plugin_dispatch_operation_observers_finished: 0x955d880
>mcd-DEBUG: 15/05/11 01:47:44.414944: _mcd_dispatch_operation_check_client_locks: only observing
>mcd-DEBUG: 15/05/11 01:47:44.414948: mcd_dispatcher_context_unref: CTXREF01 on 0x953a788 (ref = 2)
>mcd-DEBUG: 15/05/11 01:47:44.414952: mcd_dispatcher_context_unref: CTXREF11 on 0x953a788 (ref = 1)
>mcd-DEBUG: 15/05/11 01:47:44.414956: mcd_dispatcher_context_unref: freeing the context 0x953a788
>mcd-DEBUG: 15/05/11 01:47:44.414969: _mcd_dispatch_operation_check_client_locks: only observing
>mcd-DEBUG: 15/05/11 01:47:44.414975: plugin_do_finalize: 0x955d880
>mcd-DEBUG: 15/05/11 01:47:44.418319: on_channel_ready: channel 0x9529a08 is ready
>mcd-DEBUG: 15/05/11 01:47:44.419013: on_channel_ready: channel 0x95295c0 is ready
>mcd-DEBUG: 15/05/11 01:47:53.799386: _mcd_mission_set_parent: child = 0x9529600, parent = 0x951de28
>mcd-DEBUG: 15/05/11 01:47:53.799401: _mcd_dispatcher_take_channels: unrequested channel 0x9529600 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/RosterChannel/List/subscribe
>mcd-DEBUG: 15/05/11 01:47:53.799420: _mcd_dispatcher_take_channels: One channel, which cannot be handled
>mcd-DEBUG: 15/05/11 01:47:53.799426: _mcd_tp_channel_should_close: Not destroying 0x954c5a0:/org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/RosterChannel/List/subscribe, it's a ContactList
>mcd-DEBUG: 15/05/11 01:47:53.800975: _mcd_mission_set_parent: child = 0x9529500, parent = 0x951de28
>mcd-DEBUG: 15/05/11 01:47:53.800987: _mcd_dispatcher_take_channels: unrequested channel 0x9529500 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/RosterChannel/List/publish
>mcd-DEBUG: 15/05/11 01:47:53.801000: _mcd_dispatcher_take_channels: One channel, which cannot be handled
>mcd-DEBUG: 15/05/11 01:47:53.801004: _mcd_tp_channel_should_close: Not destroying 0x954c090:/org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/RosterChannel/List/publish, it's a ContactList
>mcd-DEBUG: 15/05/11 01:47:53.802983: _mcd_mission_set_parent: child = 0x9529888, parent = 0x951de28
>mcd-DEBUG: 15/05/11 01:47:53.802998: _mcd_dispatcher_take_channels: unrequested channel 0x9529888 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/RosterChannel/Group/All
>mcd-DEBUG: 15/05/11 01:47:53.803014: _mcd_dispatcher_take_channels: One channel, which cannot be handled
>mcd-DEBUG: 15/05/11 01:47:53.803020: _mcd_tp_channel_should_close: Not destroying 0x954c000:/org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/RosterChannel/Group/All, it's a ContactList
>mcd-DEBUG: 15/05/11 01:47:53.803525: _mcd_mission_set_parent: child = 0x9529400, parent = 0x951de28
>mcd-DEBUG: 15/05/11 01:47:53.803539: _mcd_dispatcher_take_channels: unrequested channel 0x9529400 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/RosterChannel/Group/Family
>mcd-DEBUG: 15/05/11 01:47:53.803553: _mcd_dispatcher_take_channels: One channel, which cannot be handled
>mcd-DEBUG: 15/05/11 01:47:53.803558: _mcd_tp_channel_should_close: Not destroying 0x954c2d0:/org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/RosterChannel/Group/Family, it's a ContactList
>mcd-DEBUG: 15/05/11 01:47:53.804066: _mcd_mission_set_parent: child = 0x9529780, parent = 0x951de28
>mcd-DEBUG: 15/05/11 01:47:53.804080: _mcd_dispatcher_take_channels: unrequested channel 0x9529780 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/RosterChannel/Group/Favorites
>mcd-DEBUG: 15/05/11 01:47:53.804092: _mcd_dispatcher_take_channels: One channel, which cannot be handled
>mcd-DEBUG: 15/05/11 01:47:53.804097: _mcd_tp_channel_should_close: Not destroying 0x954c3f0:/org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/RosterChannel/Group/Favorites, it's a ContactList
>mcd-DEBUG: 15/05/11 01:47:53.877681: on_channel_ready: channel 0x9529600 is ready
>mcd-DEBUG: 15/05/11 01:47:53.877754: on_channel_ready: channel 0x9529500 is ready
>mcd-DEBUG: 15/05/11 01:47:53.877820: on_channel_ready: channel 0x9529888 is ready
>mcd-DEBUG: 15/05/11 01:47:53.877877: on_channel_ready: channel 0x9529400 is ready
>mcd-DEBUG: 15/05/11 01:47:53.878304: on_channel_ready: channel 0x9529780 is ready
>mcd-DEBUG: 15/05/11 01:47:54.987168: on_connection_status_changed: status_changed called from tp (0)
>mcd-DEBUG: 15/05/11 01:47:54.987180: _mcd_account_set_connection_status: butterfly/msn/ktowns_40live_2ecom_2eau0: 0 because 1
>mcd-DEBUG: 15/05/11 01:47:54.987185: mcd_account_freeze_properties: butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:47:54.987190: _mcd_account_dup_parameters: called
>mcd-DEBUG: 15/05/11 01:47:54.987255: _mcd_account_set_connection_status: clearing connection error details
>mcd-DEBUG: 15/05/11 01:47:54.987260: _mcd_account_set_connection_status: changing connection status from 1 to 0
>mcd-DEBUG: 15/05/11 01:47:54.987267: mcd_account_changed_property: called: Connection
>mcd-DEBUG: 15/05/11 01:47:54.987271: mcd_account_changed_property: First changed property
>mcd-DEBUG: 15/05/11 01:47:54.987579: mcd_account_changed_property: called: ConnectionStatus
>mcd-DEBUG: 15/05/11 01:47:54.987586: mcd_account_changed_property: called: ConnectionStatusReason
>mcd-DEBUG: 15/05/11 01:47:54.987591: mcd_account_changed_property: called: ConnectionError
>mcd-DEBUG: 15/05/11 01:47:54.987601: mcd_account_changed_property: called: ConnectionErrorDetails
>mcd-DEBUG: 15/05/11 01:47:54.987616: mcd_account_thaw_properties: butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:47:54.987620: emit_property_changed: called
>mcd-DEBUG: 15/05/11 01:47:54.987731: on_connection_status_changed: setting probation timer (120) seconds, for /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau
>mcd-DEBUG: 15/05/11 01:47:54.987756: on_connection_ready: connection is ready
>mcd-DEBUG: 15/05/11 01:47:54.987884: _mcd_connection_setup_capabilities: ContactCapabilities in use, avoiding Capabilities
>mcd-DEBUG: 15/05/11 01:47:54.988010: _mcd_account_get_avatar_filename: data dir: /home/kyle/.mission-control/accounts/butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:47:54.988163: request_unrequested_channels: called
>mcd-DEBUG: 15/05/11 01:47:54.988173: _mcd_account_set_connection_status: butterfly/msn/ktowns_40live_2ecom_2eau0: 0 because 1
>mcd-DEBUG: 15/05/11 01:47:54.988178: mcd_account_freeze_properties: butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:47:54.988182: _mcd_account_dup_parameters: called
>mcd-DEBUG: 15/05/11 01:47:54.988234: _mcd_account_set_connection_status: clearing connection error details
>mcd-DEBUG: 15/05/11 01:47:54.988238: mcd_account_thaw_properties: butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:47:54.988306: _mcd_connection_set_nickname: setting nickname 'Kyle' using Aliasing
>mcd-DEBUG: 15/05/11 01:47:55.4192: presence_get_statuses_cb: account butterfly/msn/ktowns_40live_2ecom_2eau0:
>mcd-DEBUG: 15/05/11 01:47:55.4203: presence_get_statuses_cb:   xa
>mcd-DEBUG: 15/05/11 01:47:55.4206: presence_get_statuses_cb:   brb
>mcd-DEBUG: 15/05/11 01:47:55.4210: presence_get_statuses_cb:   dnd
>mcd-DEBUG: 15/05/11 01:47:55.4215: presence_get_statuses_cb:   phone
>mcd-DEBUG: 15/05/11 01:47:55.4218: presence_get_statuses_cb:   offline
>mcd-DEBUG: 15/05/11 01:47:55.4221: presence_get_statuses_cb:   lunch
>mcd-DEBUG: 15/05/11 01:47:55.4225: presence_get_statuses_cb:   hidden
>mcd-DEBUG: 15/05/11 01:47:55.4230: presence_get_statuses_cb:   available
>mcd-DEBUG: 15/05/11 01:47:55.4235: presence_get_statuses_cb:   away
>mcd-DEBUG: 15/05/11 01:47:55.4241: _mcd_connection_set_presence: Setting status 'available' of type 2 ('available' was requested)
>mcd-DEBUG: 15/05/11 01:47:55.4595: _mcd_connection_get_aliases_cb: called
>mcd-DEBUG: 15/05/11 01:47:55.4637: _mcd_account_set_normalized_name: called (ktowns@live.com.au)
>mcd-DEBUG: 15/05/11 01:47:55.4650: _storage_commit: flushing plugin default-gkeyfile butterfly/msn/ktowns_40live_2ecom_2eau0 to long term storage
>mcd-DEBUG: 15/05/11 01:47:55.4690: mcd_account_changed_property: called: NormalizedName
>mcd-DEBUG: 15/05/11 01:47:55.4694: mcd_account_changed_property: First changed property
>mc-plugins-DEBUG: 15/05/11 01:47:55.4684: mcp_account_storage_commit_one: default-gkeyfile: 
>mcd-DEBUG: 15/05/11 01:47:55.8553: on_aliases_changed: called
>mcd-DEBUG: 15/05/11 01:47:55.8562: on_aliases_changed: Our alias on /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau changed to Kyle
>mcd-DEBUG: 15/05/11 01:47:55.14688: emit_property_changed: called
>mcd-DEBUG: 15/05/11 01:47:55.17806: _mcd_dispatcher_take_channels: unrequested channel 0x9529500 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/RosterChannel/List/publish
>mcd-DEBUG: 15/05/11 01:47:55.17826: _mcd_dispatcher_take_channels: One channel, which cannot be handled
>mcd-DEBUG: 15/05/11 01:47:55.17832: _mcd_tp_channel_should_close: Not destroying 0x954c090:/org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/RosterChannel/List/publish, it's a ContactList
>mcd-DEBUG: 15/05/11 01:47:55.18531: _mcd_dispatcher_take_channels: unrequested channel 0x9529600 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/RosterChannel/List/subscribe
>mcd-DEBUG: 15/05/11 01:47:55.18543: _mcd_dispatcher_take_channels: One channel, which cannot be handled
>mcd-DEBUG: 15/05/11 01:47:55.18548: _mcd_tp_channel_should_close: Not destroying 0x954c5a0:/org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/RosterChannel/List/subscribe, it's a ContactList
>mcd-DEBUG: 15/05/11 01:47:55.19259: _mcd_dispatcher_take_channels: unrequested channel 0x9529500 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/RosterChannel/List/publish
>mcd-DEBUG: 15/05/11 01:47:55.19272: _mcd_dispatcher_take_channels: One channel, which cannot be handled
>mcd-DEBUG: 15/05/11 01:47:55.19277: _mcd_tp_channel_should_close: Not destroying 0x954c090:/org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/RosterChannel/List/publish, it's a ContactList
>mcd-DEBUG: 15/05/11 01:47:55.22315: _mcd_dispatcher_take_channels: unrequested channel 0x9529600 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/RosterChannel/List/subscribe
>mcd-DEBUG: 15/05/11 01:47:55.22330: _mcd_dispatcher_take_channels: One channel, which cannot be handled
>mcd-DEBUG: 15/05/11 01:47:55.22336: _mcd_tp_channel_should_close: Not destroying 0x954c5a0:/org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/RosterChannel/List/subscribe, it's a ContactList
>mcd-DEBUG: 15/05/11 01:47:57.516356: mcd_account_changed_property: called: ChangingPresence
>mcd-DEBUG: 15/05/11 01:47:57.516362: mcd_account_changed_property: First changed property
>mcd-DEBUG: 15/05/11 01:47:57.516376: mcd_account_changed_property: called: CurrentPresence
>mcd-DEBUG: 15/05/11 01:47:57.520235: on_aliases_changed: called
>mcd-DEBUG: 15/05/11 01:47:57.521122: on_aliases_changed: called
>mcd-DEBUG: 15/05/11 01:47:57.525953: emit_property_changed: called
>mcd-DEBUG: 15/05/11 01:48:13.930958: _mcd_mission_set_parent: child = 0x95299c8, parent = 0x951de28
>mcd-DEBUG: 15/05/11 01:48:13.931054: _mcd_dispatcher_take_channels: unrequested channel 0x95299c8 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/TextChannel1
>mcd-DEBUG: 15/05/11 01:48:13.931077: _mcd_dispatcher_take_channels: possible handlers found, dispatching
>mcd-DEBUG: 15/05/11 01:48:13.931082: _mcd_channel_set_status: 0x95299c8, 3
>mcd-DEBUG: 15/05/11 01:48:13.931092: _mcd_dispatcher_enter_state_machine: CTXREF11 on 0x955e138
>mcd-DEBUG: 15/05/11 01:48:13.931097: _mcd_dispatcher_enter_state_machine: new dispatcher context 0x955e138 for unrequested channel 0x95299c8 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/TextChannel1
>mcd-DEBUG: 15/05/11 01:48:13.931126: mcd_dispatch_operation_constructor: do13/0x952fdf8: needs_approval=T
>mcd-DEBUG: 15/05/11 01:48:13.931154: _mcd_plugin_dispatch_operation_init: 0x954f620
>mcd-DEBUG: 15/05/11 01:48:13.931162: _mcd_plugin_dispatch_operation_new: 0x954f620 (for 0x952fdf8)
>mcd-DEBUG: 15/05/11 01:48:13.931176: _mcd_dispatcher_enter_state_machine: entering state machine for context 0x955e138
>mcd-DEBUG: 15/05/11 01:48:13.931181: mcd_dispatcher_context_ref: CTXREF01 on 0x955e138 (ref = 1)
>mcd-DEBUG: 15/05/11 01:48:13.931186: _mcd_dispatch_operation_run_clients: do13 0x952fdf8
>mcd-DEBUG: 15/05/11 01:48:13.931190: _mcd_dispatch_operation_run_clients: Running observers
>mcd-DEBUG: 15/05/11 01:48:13.931266: _mcd_dispatch_operation_inc_observers_pending: 0 -> 1
>mcd-DEBUG: 15/05/11 01:48:13.931272: _mcd_dispatch_operation_run_observers: calling ObserveChannels on org.freedesktop.Telepathy.Client.Logger for CDO 0x952fdf8
>mcd-DEBUG: 15/05/11 01:48:13.931529: _mcd_dispatch_operation_run_clients: All necessary observers invoked
>mcd-DEBUG: 15/05/11 01:48:13.931534: _mcd_dispatch_operation_run_clients: Checking finished/locks
>mcd-DEBUG: 15/05/11 01:48:13.931539: _mcd_dispatch_operation_handlers_can_bypass_approval: org.freedesktop.Telepathy.Client.Empathy has BypassApproval=F
>mcd-DEBUG: 15/05/11 01:48:13.931545: mcd_dispatcher_context_unref: CTXREF01 on 0x955e138 (ref = 2)
>mcd-DEBUG: 15/05/11 01:48:13.931550: mcd_dispatcher_context_unref: CTXREF11 on 0x955e138 (ref = 1)
>mcd-DEBUG: 15/05/11 01:48:13.931552: mcd_dispatcher_context_unref: freeing the context 0x955e138
>mcd-DEBUG: 15/05/11 01:48:13.931567: _mcd_dispatch_operation_inc_ado_pending: 0 -> 1
>mcd-DEBUG: 15/05/11 01:48:13.931579: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:48:13.931586: get_connection: called for do13
>mcd-DEBUG: 15/05/11 01:48:13.931592: get_possible_handlers: called for do13
>mcd-DEBUG: 15/05/11 01:48:13.931654: _mcd_dispatch_operation_run_approvers: Calling AddDispatchOperation on approver org.freedesktop.Telepathy.Client.Empathy.EventManager for CDO /org/freedesktop/Telepathy/DispatchOperation/do13 @ 0x952fdf8
>mcd-DEBUG: 15/05/11 01:48:13.931658: _mcd_dispatch_operation_inc_ado_pending: 1 -> 2
>mcd-DEBUG: 15/05/11 01:48:13.931974: _mcd_dispatch_operation_dec_ado_pending: 2 -> 1
>mcd-DEBUG: 15/05/11 01:48:13.934670: dbusprop_get_all: org.freedesktop.Telepathy.ChannelDispatchOperation
>mcd-DEBUG: 15/05/11 01:48:13.934678: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:48:13.934684: get_connection: called for do13
>mcd-DEBUG: 15/05/11 01:48:13.934694: get_channels: called for do13
>mcd-DEBUG: 15/05/11 01:48:13.934787: get_possible_handlers: called for do13
>mcd-DEBUG: 15/05/11 01:48:13.936994: on_channel_ready: channel 0x95299c8 is ready
>mcd-DEBUG: 15/05/11 01:48:13.937752: add_dispatch_operation_cb: Approver /org/freedesktop/Telepathy/Client/Empathy/EventManager accepted AddDispatchOperation /org/freedesktop/Telepathy/DispatchOperation/do13 (0x952fdf8)
>mcd-DEBUG: 15/05/11 01:48:13.937758: _mcd_dispatch_operation_dec_ado_pending: 1 -> 0
>mcd-DEBUG: 15/05/11 01:48:20.700416: dispatch_operation_handle_with_time: do13/0x952fdf8
>mcd-DEBUG: 15/05/11 01:48:38.936458: observe_channels_cb: Observer /org/freedesktop/Telepathy/Client/Logger returned error: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken.
>mcd-DEBUG: 15/05/11 01:48:38.936476: _mcd_dispatch_operation_dec_observers_pending: 1 -> 0
>mcd-DEBUG: 15/05/11 01:48:38.936487: _mcd_plugin_dispatch_operation_observers_finished: 0x954f620
>mcd-DEBUG: 15/05/11 01:48:38.936497: _mcd_dispatch_operation_check_client_locks: trying next handler
>mcd-DEBUG: 15/05/11 01:48:38.936513: _mcd_dispatch_operation_try_next_handler: Possible handler: org.freedesktop.Telepathy.Client.Empathy (still exists: Y, already failed: N)
>mcd-DEBUG: 15/05/11 01:48:38.936526: _mcd_client_proxy_handle_channels: calling HandleChannels on org.freedesktop.Telepathy.Client.Empathy
>mcd-DEBUG: 15/05/11 01:48:38.936726: _mcd_channel_set_status: 0x95299c8, 4
>mcd-DEBUG: 15/05/11 01:48:38.940888: _mcd_channel_set_status: 0x95299c8, 5
>mcd-DEBUG: 15/05/11 01:48:38.941092: _mcd_dispatch_operation_finish: Result: Channel successfully handled by org.freedesktop.Telepathy.Client.Empathy
>mcd-DEBUG: 15/05/11 01:48:38.941104: _mcd_dispatch_operation_finish: successful HandleWith, channel went to org.freedesktop.Telepathy.Client.Empathy
>mcd-DEBUG: 15/05/11 01:48:38.941153: _mcd_dispatch_operation_finish: do13/0x952fdf8 has finished
>mcd-DEBUG: 15/05/11 01:48:38.941163: mcd_dispatch_operation_actually_finish: do13/0x952fdf8: finished
>mcd-DEBUG: 15/05/11 01:48:38.941231: _mcd_dispatch_operation_check_client_locks: waiting for HandleChannels to return
>mcd-DEBUG: 15/05/11 01:48:38.941242: _mcd_dispatch_operation_check_client_locks: already finished (or finishing): Channel successfully handled by org.freedesktop.Telepathy.Client.Empathy
>mcd-DEBUG: 15/05/11 01:48:38.941258: plugin_do_finalize: 0x954f620
>mcd-DEBUG: 15/05/11 01:49:00.252006: dispatcher_channel_request_acl_start: start /org/freedesktop/Telepathy/Account/butterfly/msn/ktowns_40live_2ecom_2eau0.org.freedesktop.Telepathy.Connection.Interface.Requests.CreateChannel acl (0x9557fc0)
>mcd-DEBUG: 15/05/11 01:49:00.252023: dispatcher_channel_request_acl_success: complete acl (0x9557fc0)
>mcd-DEBUG: 15/05/11 01:49:00.252160: _mcd_request_init: 0x951b818
>mcd-DEBUG: 15/05/11 01:49:00.252253: _mcd_request_new: 0x951b818 (for 0x952e8d0)
>mcd-DEBUG: 15/05/11 01:49:00.252263: _mcd_channel_set_status: 0x9529848, 1
>mcd-DEBUG: 15/05/11 01:49:00.252427: _mcd_channel_get_immutable_properties: Channel 0x9529848 has no associated TpChannel
>mcd-DEBUG: 15/05/11 01:49:00.252449: _mcd_channel_get_immutable_properties: Channel 0x9529848 has no associated TpChannel
>mcd-DEBUG: 15/05/11 01:49:00.252461: _mcd_channel_get_immutable_properties: Channel 0x9529848 has no associated TpChannel
>mcd-DEBUG: 15/05/11 01:49:00.252480: _mcd_channel_get_immutable_properties: Channel 0x9529848 has no associated TpChannel
>mcd-DEBUG: 15/05/11 01:49:00.252496: _mcd_channel_get_immutable_properties: Channel 0x9529848 has no associated TpChannel
>mcd-DEBUG: 15/05/11 01:49:00.252512: _mcd_dispatcher_add_request: Default handler org.freedesktop.Telepathy.Client.Empathy.AudioVideo for request /com/nokia/MissionControl/requests/r9 doesn't want AddRequest
>mcd-DEBUG: 15/05/11 01:49:00.252522: dispatcher_channel_request_acl_cleanup: cleanup acl (0x9557fc0)
>mcd-DEBUG: 15/05/11 01:49:00.258660: ready_to_request_cb: Starting online request
>mcd-DEBUG: 15/05/11 01:49:00.258667: _mcd_account_online_request: connection status for butterfly/msn/ktowns_40live_2ecom_2eau0 is 0
>mcd-DEBUG: 15/05/11 01:49:00.258671: _mcd_account_online_request: butterfly/msn/ktowns_40live_2ecom_2eau0 is already connected
>mcd-DEBUG: 15/05/11 01:49:00.258676: online_request_cb: called
>mcd-DEBUG: 15/05/11 01:49:00.258685: _mcd_mission_set_parent: child = 0x9529848, parent = 0x951de28
>mcd-DEBUG: 15/05/11 01:49:00.258872: _mcd_channel_set_status: 0x9529848, 2
>mcd-DEBUG: 15/05/11 01:49:00.266218: common_request_channel_cb: 0x9529848, object /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/MediaChannel/1
>mcd-DEBUG: 15/05/11 01:49:00.266951: _mcd_dispatcher_take_channels: requested channel 0x9529848 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/MediaChannel/1
>mcd-DEBUG: 15/05/11 01:49:00.266974: _mcd_dispatcher_take_channels: possible handlers found, dispatching
>mcd-DEBUG: 15/05/11 01:49:00.266979: _mcd_channel_set_status: 0x9529848, 3
>mcd-DEBUG: 15/05/11 01:49:00.266990: _mcd_dispatcher_enter_state_machine: CTXREF11 on 0x9566288
>mcd-DEBUG: 15/05/11 01:49:00.266995: _mcd_dispatcher_enter_state_machine: new dispatcher context 0x9566288 for requested channel 0x9529848 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/MediaChannel/1
>mcd-DEBUG: 15/05/11 01:49:00.267028: mcd_dispatch_operation_constructor: do14/0x952fdf8: needs_approval=F
>mcd-DEBUG: 15/05/11 01:49:00.267035: _mcd_plugin_dispatch_operation_init: 0x955c700
>mcd-DEBUG: 15/05/11 01:49:00.267044: _mcd_plugin_dispatch_operation_new: 0x955c700 (for 0x952fdf8)
>mcd-DEBUG: 15/05/11 01:49:00.267052: _mcd_dispatcher_enter_state_machine: entering state machine for context 0x9566288
>mcd-DEBUG: 15/05/11 01:49:00.267056: mcd_dispatcher_context_ref: CTXREF01 on 0x9566288 (ref = 1)
>mcd-DEBUG: 15/05/11 01:49:00.267061: _mcd_dispatch_operation_run_clients: do14 0x952fdf8
>mcd-DEBUG: 15/05/11 01:49:00.267066: _mcd_dispatch_operation_run_clients: Running observers
>mcd-DEBUG: 15/05/11 01:49:00.267076: _mcd_dispatch_operation_run_clients: All necessary observers invoked
>mcd-DEBUG: 15/05/11 01:49:00.267080: _mcd_dispatch_operation_run_clients: Checking finished/locks
>mcd-DEBUG: 15/05/11 01:49:00.267083: _mcd_plugin_dispatch_operation_observers_finished: 0x955c700
>mcd-DEBUG: 15/05/11 01:49:00.267090: _mcd_dispatch_operation_handlers_can_bypass_approval: org.freedesktop.Telepathy.Client.Empathy.AudioVideo has BypassApproval=F
>mcd-DEBUG: 15/05/11 01:49:00.267095: mcd_dispatcher_context_unref: CTXREF01 on 0x9566288 (ref = 2)
>mcd-DEBUG: 15/05/11 01:49:00.267098: mcd_dispatcher_context_unref: CTXREF11 on 0x9566288 (ref = 1)
>mcd-DEBUG: 15/05/11 01:49:00.267102: mcd_dispatcher_context_unref: freeing the context 0x9566288
>mcd-DEBUG: 15/05/11 01:49:00.267117: _mcd_dispatch_operation_check_client_locks: trying next handler
>mcd-DEBUG: 15/05/11 01:49:00.267122: _mcd_dispatch_operation_try_next_handler: Possible handler: org.freedesktop.Telepathy.Client.Empathy.AudioVideo (still exists: Y, already failed: N)
>mcd-DEBUG: 15/05/11 01:49:00.267127: _mcd_client_proxy_handle_channels: calling HandleChannels on org.freedesktop.Telepathy.Client.Empathy.AudioVideo
>mcd-DEBUG: 15/05/11 01:49:00.267188: _mcd_channel_set_status: 0x9529848, 4
>mcd-DEBUG: 15/05/11 01:49:00.312916: on_channel_ready: channel 0x9529848 is ready
>mcd-DEBUG: 15/05/11 01:49:00.373081: dbusprop_get_all: org.freedesktop.Telepathy.AccountManager
>mcd-DEBUG: 15/05/11 01:49:00.373090: get_valid_accounts: called
>mcd-DEBUG: 15/05/11 01:49:00.373106: get_invalid_accounts: called
>mcd-DEBUG: 15/05/11 01:49:00.373115: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:49:00.374500: dbusprop_get_all: org.freedesktop.Telepathy.Account
>mcd-DEBUG: 15/05/11 01:49:00.374507: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:49:00.374543: _mcd_account_dup_parameters: called
>mcd-DEBUG: 15/05/11 01:49:00.374614: get_connect_automatically: called for butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:49:00.376828: dbusprop_get_all: org.freedesktop.Telepathy.Account
>mcd-DEBUG: 15/05/11 01:49:00.376835: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:49:00.376863: _mcd_account_dup_parameters: called
>mcd-DEBUG: 15/05/11 01:49:00.376913: get_connect_automatically: called for salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:49:00.377242: dbusprop_get_all: org.freedesktop.Telepathy.Account
>mcd-DEBUG: 15/05/11 01:49:00.377248: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:49:00.377270: _mcd_account_dup_parameters: called
>mcd-DEBUG: 15/05/11 01:49:00.377317: get_connect_automatically: called for salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:49:00.377641: dbusprop_get_all: org.freedesktop.Telepathy.Account
>mcd-DEBUG: 15/05/11 01:49:00.377646: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:49:00.377671: _mcd_account_dup_parameters: called
>mcd-DEBUG: 15/05/11 01:49:00.377733: get_connect_automatically: called for butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:49:00.397732: _mcd_channel_set_status: 0x9529848, 5
>mcd-DEBUG: 15/05/11 01:49:00.397746: _mcd_request_set_success: Request succeeded
>mcd-DEBUG: 15/05/11 01:49:00.397903: _mcd_dispatch_operation_finish: Result: Channel successfully handled by org.freedesktop.Telepathy.Client.Empathy.AudioVideo
>mcd-DEBUG: 15/05/11 01:49:00.397910: _mcd_dispatch_operation_finish: do14/0x952fdf8 has finished
>mcd-DEBUG: 15/05/11 01:49:00.397913: mcd_dispatch_operation_actually_finish: do14/0x952fdf8: finished
>mcd-DEBUG: 15/05/11 01:49:00.397921: _mcd_dispatch_operation_check_client_locks: waiting for HandleChannels to return
>mcd-DEBUG: 15/05/11 01:49:00.397926: _mcd_dispatch_operation_check_client_locks: already finished (or finishing): Channel successfully handled by org.freedesktop.Telepathy.Client.Empathy.AudioVideo
>mcd-DEBUG: 15/05/11 01:49:00.397933: plugin_do_finalize: 0x955c700
>mcd-DEBUG: 15/05/11 01:49:04.246961: proxy_destroyed: Channel proxy invalidated: tp_dbus_errors_quark 8: Channel was closed
>mcd-DEBUG: 15/05/11 01:49:04.246968: mcd_channel_abort: 0x9529848
>mcd-DEBUG: 15/05/11 01:49:04.246973: _mcd_channel_set_status: 0x9529848, 7
>mcd-DEBUG: 15/05/11 01:49:04.246989: _mcd_mission_set_parent: child = 0x9529848, parent = (nil)
>mcd-DEBUG: 15/05/11 01:49:04.247091: _mcd_operation_remove_mission: removing mission: 0x9529848
>mcd-DEBUG: 15/05/11 01:49:04.247097: _mcd_channel_dispose: 0x9529848 (is disposed = 0)
>mcd-DEBUG: 15/05/11 01:49:04.247102: _mcd_request_dispose: 0x951b818
>mcd-DEBUG: 15/05/11 01:49:04.247108: _mcd_request_finalize: 0x951b818
>mcd-DEBUG: 15/05/11 01:49:04.247121: _mcd_mission_dispose: mission disposed 0x9529848
>mcd-DEBUG: 15/05/11 01:49:04.247133: _mcd_mission_finalize: mission finalized 0x9529848
>mcd-DEBUG: 15/05/11 01:49:44.966398: mcd_connection_probation_ended_cb: probation finished, assuming connection is stable: /org/freedesktop/Telepathy/Connection/salut/local_xmpp/kyle
>mcd-DEBUG: 15/05/11 01:49:54.162689: _mcd_mission_set_parent: child = 0x95296c0, parent = 0x951de28
>mcd-DEBUG: 15/05/11 01:49:54.162787: _mcd_dispatcher_take_channels: unrequested channel 0x95296c0 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/MediaChannel/2
>mcd-DEBUG: 15/05/11 01:49:54.162811: _mcd_dispatcher_take_channels: possible handlers found, dispatching
>mcd-DEBUG: 15/05/11 01:49:54.162816: _mcd_channel_set_status: 0x95296c0, 3
>mcd-DEBUG: 15/05/11 01:49:54.162826: _mcd_dispatcher_enter_state_machine: CTXREF11 on 0x9531ef0
>mcd-DEBUG: 15/05/11 01:49:54.162831: _mcd_dispatcher_enter_state_machine: new dispatcher context 0x9531ef0 for unrequested channel 0x95296c0 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/MediaChannel/2
>mcd-DEBUG: 15/05/11 01:49:54.162861: mcd_dispatch_operation_constructor: do15/0x952fd70: needs_approval=T
>mcd-DEBUG: 15/05/11 01:49:54.162889: _mcd_plugin_dispatch_operation_init: 0x9557fa0
>mcd-DEBUG: 15/05/11 01:49:54.162898: _mcd_plugin_dispatch_operation_new: 0x9557fa0 (for 0x952fd70)
>mcd-DEBUG: 15/05/11 01:49:54.162909: _mcd_dispatcher_enter_state_machine: entering state machine for context 0x9531ef0
>mcd-DEBUG: 15/05/11 01:49:54.162914: mcd_dispatcher_context_ref: CTXREF01 on 0x9531ef0 (ref = 1)
>mcd-DEBUG: 15/05/11 01:49:54.162919: _mcd_dispatch_operation_run_clients: do15 0x952fd70
>mcd-DEBUG: 15/05/11 01:49:54.162923: _mcd_dispatch_operation_run_clients: Running observers
>mcd-DEBUG: 15/05/11 01:49:54.162934: _mcd_dispatch_operation_run_clients: All necessary observers invoked
>mcd-DEBUG: 15/05/11 01:49:54.162936: _mcd_dispatch_operation_run_clients: Checking finished/locks
>mcd-DEBUG: 15/05/11 01:49:54.162941: _mcd_plugin_dispatch_operation_observers_finished: 0x9557fa0
>mcd-DEBUG: 15/05/11 01:49:54.162946: _mcd_dispatch_operation_handlers_can_bypass_approval: org.freedesktop.Telepathy.Client.Empathy.AudioVideo has BypassApproval=F
>mcd-DEBUG: 15/05/11 01:49:54.162961: mcd_dispatcher_context_unref: CTXREF01 on 0x9531ef0 (ref = 2)
>mcd-DEBUG: 15/05/11 01:49:54.162966: mcd_dispatcher_context_unref: CTXREF11 on 0x9531ef0 (ref = 1)
>mcd-DEBUG: 15/05/11 01:49:54.162971: mcd_dispatcher_context_unref: freeing the context 0x9531ef0
>mcd-DEBUG: 15/05/11 01:49:54.162986: _mcd_dispatch_operation_inc_ado_pending: 0 -> 1
>mcd-DEBUG: 15/05/11 01:49:54.162996: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:49:54.163002: get_connection: called for do15
>mcd-DEBUG: 15/05/11 01:49:54.163009: get_possible_handlers: called for do15
>mcd-DEBUG: 15/05/11 01:49:54.163073: _mcd_dispatch_operation_run_approvers: Calling AddDispatchOperation on approver org.freedesktop.Telepathy.Client.Empathy.EventManager for CDO /org/freedesktop/Telepathy/DispatchOperation/do15 @ 0x952fd70
>mcd-DEBUG: 15/05/11 01:49:54.163077: _mcd_dispatch_operation_inc_ado_pending: 1 -> 2
>mcd-DEBUG: 15/05/11 01:49:54.163278: _mcd_dispatch_operation_dec_ado_pending: 2 -> 1
>mcd-DEBUG: 15/05/11 01:49:54.163283: _mcd_dispatch_operation_check_client_locks: waiting for AddDispatchOperation to return
>mcd-DEBUG: 15/05/11 01:49:54.163285: _mcd_dispatch_operation_check_client_locks: waiting for AddDispatchOperation to return
>mcd-DEBUG: 15/05/11 01:49:54.165222: dbusprop_get_all: org.freedesktop.Telepathy.ChannelDispatchOperation
>mcd-DEBUG: 15/05/11 01:49:54.165230: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:49:54.165237: get_connection: called for do15
>mcd-DEBUG: 15/05/11 01:49:54.165246: get_channels: called for do15
>mcd-DEBUG: 15/05/11 01:49:54.165343: get_possible_handlers: called for do15
>mcd-DEBUG: 15/05/11 01:49:54.328999: on_channel_ready: channel 0x95296c0 is ready
>mcd-DEBUG: 15/05/11 01:49:54.331795: add_dispatch_operation_cb: Approver /org/freedesktop/Telepathy/Client/Empathy/EventManager accepted AddDispatchOperation /org/freedesktop/Telepathy/DispatchOperation/do15 (0x952fd70)
>mcd-DEBUG: 15/05/11 01:49:54.331809: _mcd_dispatch_operation_dec_ado_pending: 1 -> 0
>mcd-DEBUG: 15/05/11 01:49:54.331819: _mcd_dispatch_operation_check_client_locks: waiting for approval
>mcd-DEBUG: 15/05/11 01:49:54.942506: mcd_connection_probation_ended_cb: probation finished, assuming connection is stable: /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau
>mcd-DEBUG: 15/05/11 01:50:41.703856: dispatch_operation_handle_with_time: do15/0x952fd70
>mcd-DEBUG: 15/05/11 01:50:41.703880: _mcd_dispatch_operation_check_client_locks: trying next handler
>mcd-DEBUG: 15/05/11 01:50:41.703896: _mcd_dispatch_operation_try_next_handler: Possible handler: org.freedesktop.Telepathy.Client.Empathy.AudioVideo (still exists: Y, already failed: N)
>mcd-DEBUG: 15/05/11 01:50:41.703912: _mcd_client_proxy_handle_channels: calling HandleChannels on org.freedesktop.Telepathy.Client.Empathy.AudioVideo
>mcd-DEBUG: 15/05/11 01:50:41.704103: _mcd_channel_set_status: 0x95296c0, 4
>mcd-DEBUG: 15/05/11 01:50:41.717485: _mcd_channel_set_status: 0x95296c0, 5
>mcd-DEBUG: 15/05/11 01:50:41.717561: _mcd_dispatch_operation_finish: Result: Channel successfully handled by org.freedesktop.Telepathy.Client.Empathy.AudioVideo
>mcd-DEBUG: 15/05/11 01:50:41.717572: _mcd_dispatch_operation_finish: successful HandleWith, channel went to org.freedesktop.Telepathy.Client.Empathy.AudioVideo
>mcd-DEBUG: 15/05/11 01:50:41.717628: _mcd_dispatch_operation_finish: do15/0x952fd70 has finished
>mcd-DEBUG: 15/05/11 01:50:41.717638: mcd_dispatch_operation_actually_finish: do15/0x952fd70: finished
>mcd-DEBUG: 15/05/11 01:50:41.717708: _mcd_dispatch_operation_check_client_locks: waiting for HandleChannels to return
>mcd-DEBUG: 15/05/11 01:50:41.717720: _mcd_dispatch_operation_check_client_locks: already finished (or finishing): Channel successfully handled by org.freedesktop.Telepathy.Client.Empathy.AudioVideo
>mcd-DEBUG: 15/05/11 01:50:41.717737: plugin_do_finalize: 0x9557fa0
>mcd-DEBUG: 15/05/11 01:50:42.260454: proxy_destroyed: Channel proxy invalidated: tp_dbus_errors_quark 8: Channel was closed
>mcd-DEBUG: 15/05/11 01:50:42.260462: mcd_channel_abort: 0x95296c0
>mcd-DEBUG: 15/05/11 01:50:42.260468: _mcd_channel_set_status: 0x95296c0, 7
>mcd-DEBUG: 15/05/11 01:50:42.260484: _mcd_mission_set_parent: child = 0x95296c0, parent = (nil)
>mcd-DEBUG: 15/05/11 01:50:42.260585: _mcd_operation_remove_mission: removing mission: 0x95296c0
>mcd-DEBUG: 15/05/11 01:50:42.260591: _mcd_channel_dispose: 0x95296c0 (is disposed = 0)
>mcd-DEBUG: 15/05/11 01:50:42.260598: _mcd_mission_dispose: mission disposed 0x95296c0
>mcd-DEBUG: 15/05/11 01:50:42.260605: _mcd_mission_finalize: mission finalized 0x95296c0
>mcd-DEBUG: 15/05/11 01:51:35.854778: _mcd_mission_set_parent: child = 0x9529740, parent = 0x951de28
>mcd-DEBUG: 15/05/11 01:51:35.854875: _mcd_dispatcher_take_channels: unrequested channel 0x9529740 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/MediaChannel/3
>mcd-DEBUG: 15/05/11 01:51:35.854898: _mcd_dispatcher_take_channels: possible handlers found, dispatching
>mcd-DEBUG: 15/05/11 01:51:35.854902: _mcd_channel_set_status: 0x9529740, 3
>mcd-DEBUG: 15/05/11 01:51:35.854912: _mcd_dispatcher_enter_state_machine: CTXREF11 on 0x9563338
>mcd-DEBUG: 15/05/11 01:51:35.854918: _mcd_dispatcher_enter_state_machine: new dispatcher context 0x9563338 for unrequested channel 0x9529740 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/MediaChannel/3
>mcd-DEBUG: 15/05/11 01:51:35.854948: mcd_dispatch_operation_constructor: do16/0x952fc60: needs_approval=T
>mcd-DEBUG: 15/05/11 01:51:35.854976: _mcd_plugin_dispatch_operation_init: 0x955c6c0
>mcd-DEBUG: 15/05/11 01:51:35.854984: _mcd_plugin_dispatch_operation_new: 0x955c6c0 (for 0x952fc60)
>mcd-DEBUG: 15/05/11 01:51:35.854996: _mcd_dispatcher_enter_state_machine: entering state machine for context 0x9563338
>mcd-DEBUG: 15/05/11 01:51:35.855001: mcd_dispatcher_context_ref: CTXREF01 on 0x9563338 (ref = 1)
>mcd-DEBUG: 15/05/11 01:51:35.855005: _mcd_dispatch_operation_run_clients: do16 0x952fc60
>mcd-DEBUG: 15/05/11 01:51:35.855010: _mcd_dispatch_operation_run_clients: Running observers
>mcd-DEBUG: 15/05/11 01:51:35.855020: _mcd_dispatch_operation_run_clients: All necessary observers invoked
>mcd-DEBUG: 15/05/11 01:51:35.855024: _mcd_dispatch_operation_run_clients: Checking finished/locks
>mcd-DEBUG: 15/05/11 01:51:35.855027: _mcd_plugin_dispatch_operation_observers_finished: 0x955c6c0
>mcd-DEBUG: 15/05/11 01:51:35.855032: _mcd_dispatch_operation_handlers_can_bypass_approval: org.freedesktop.Telepathy.Client.Empathy.AudioVideo has BypassApproval=F
>mcd-DEBUG: 15/05/11 01:51:35.855040: mcd_dispatcher_context_unref: CTXREF01 on 0x9563338 (ref = 2)
>mcd-DEBUG: 15/05/11 01:51:35.855042: mcd_dispatcher_context_unref: CTXREF11 on 0x9563338 (ref = 1)
>mcd-DEBUG: 15/05/11 01:51:35.855046: mcd_dispatcher_context_unref: freeing the context 0x9563338
>mcd-DEBUG: 15/05/11 01:51:35.855062: _mcd_dispatch_operation_inc_ado_pending: 0 -> 1
>mcd-DEBUG: 15/05/11 01:51:35.855071: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:51:35.855077: get_connection: called for do16
>mcd-DEBUG: 15/05/11 01:51:35.855084: get_possible_handlers: called for do16
>mcd-DEBUG: 15/05/11 01:51:35.855146: _mcd_dispatch_operation_run_approvers: Calling AddDispatchOperation on approver org.freedesktop.Telepathy.Client.Empathy.EventManager for CDO /org/freedesktop/Telepathy/DispatchOperation/do16 @ 0x952fc60
>mcd-DEBUG: 15/05/11 01:51:35.855151: _mcd_dispatch_operation_inc_ado_pending: 1 -> 2
>mcd-DEBUG: 15/05/11 01:51:35.855350: _mcd_dispatch_operation_dec_ado_pending: 2 -> 1
>mcd-DEBUG: 15/05/11 01:51:35.855355: _mcd_dispatch_operation_check_client_locks: waiting for AddDispatchOperation to return
>mcd-DEBUG: 15/05/11 01:51:35.855360: _mcd_dispatch_operation_check_client_locks: waiting for AddDispatchOperation to return
>mcd-DEBUG: 15/05/11 01:51:35.857111: dbusprop_get_all: org.freedesktop.Telepathy.ChannelDispatchOperation
>mcd-DEBUG: 15/05/11 01:51:35.857117: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:51:35.857125: get_connection: called for do16
>mcd-DEBUG: 15/05/11 01:51:35.857132: get_channels: called for do16
>mcd-DEBUG: 15/05/11 01:51:35.857219: get_possible_handlers: called for do16
>mcd-DEBUG: 15/05/11 01:51:35.982507: on_channel_ready: channel 0x9529740 is ready
>mcd-DEBUG: 15/05/11 01:51:35.984067: add_dispatch_operation_cb: Approver /org/freedesktop/Telepathy/Client/Empathy/EventManager accepted AddDispatchOperation /org/freedesktop/Telepathy/DispatchOperation/do16 (0x952fc60)
>mcd-DEBUG: 15/05/11 01:51:35.984081: _mcd_dispatch_operation_dec_ado_pending: 1 -> 0
>mcd-DEBUG: 15/05/11 01:51:35.984091: _mcd_dispatch_operation_check_client_locks: waiting for approval
>mcd-DEBUG: 15/05/11 01:51:39.929001: dispatch_operation_handle_with_time: do16/0x952fc60
>mcd-DEBUG: 15/05/11 01:51:39.929018: _mcd_dispatch_operation_check_client_locks: trying next handler
>mcd-DEBUG: 15/05/11 01:51:39.929035: _mcd_dispatch_operation_try_next_handler: Possible handler: org.freedesktop.Telepathy.Client.Empathy.AudioVideo (still exists: Y, already failed: N)
>mcd-DEBUG: 15/05/11 01:51:39.929049: _mcd_client_proxy_handle_channels: calling HandleChannels on org.freedesktop.Telepathy.Client.Empathy.AudioVideo
>mcd-DEBUG: 15/05/11 01:51:39.929240: _mcd_channel_set_status: 0x9529740, 4
>mcd-DEBUG: 15/05/11 01:51:39.939294: _mcd_channel_set_status: 0x9529740, 5
>mcd-DEBUG: 15/05/11 01:51:39.939341: _mcd_dispatch_operation_finish: Result: Channel successfully handled by org.freedesktop.Telepathy.Client.Empathy.AudioVideo
>mcd-DEBUG: 15/05/11 01:51:39.939393: _mcd_dispatch_operation_finish: successful HandleWith, channel went to org.freedesktop.Telepathy.Client.Empathy.AudioVideo
>mcd-DEBUG: 15/05/11 01:51:39.939418: _mcd_dispatch_operation_finish: do16/0x952fc60 has finished
>mcd-DEBUG: 15/05/11 01:51:39.939424: mcd_dispatch_operation_actually_finish: do16/0x952fc60: finished
>mcd-DEBUG: 15/05/11 01:51:39.939455: _mcd_dispatch_operation_check_client_locks: waiting for HandleChannels to return
>mcd-DEBUG: 15/05/11 01:51:39.939460: _mcd_dispatch_operation_check_client_locks: already finished (or finishing): Channel successfully handled by org.freedesktop.Telepathy.Client.Empathy.AudioVideo
>mcd-DEBUG: 15/05/11 01:51:39.939470: plugin_do_finalize: 0x955c6c0
>mcd-DEBUG: 15/05/11 01:51:40.487754: proxy_destroyed: Channel proxy invalidated: tp_dbus_errors_quark 8: Channel was closed
>mcd-DEBUG: 15/05/11 01:51:40.487761: mcd_channel_abort: 0x9529740
>mcd-DEBUG: 15/05/11 01:51:40.487766: _mcd_channel_set_status: 0x9529740, 7
>mcd-DEBUG: 15/05/11 01:51:40.487782: _mcd_mission_set_parent: child = 0x9529740, parent = (nil)
>mcd-DEBUG: 15/05/11 01:51:40.487885: _mcd_operation_remove_mission: removing mission: 0x9529740
>mcd-DEBUG: 15/05/11 01:51:40.487893: _mcd_channel_dispose: 0x9529740 (is disposed = 0)
>mcd-DEBUG: 15/05/11 01:51:40.487900: _mcd_mission_dispose: mission disposed 0x9529740
>mcd-DEBUG: 15/05/11 01:51:40.487906: _mcd_mission_finalize: mission finalized 0x9529740
>mcd-DEBUG: 15/05/11 01:52:21.770092: _mcd_mission_set_parent: child = 0x9529700, parent = 0x951de28
>mcd-DEBUG: 15/05/11 01:52:21.770190: _mcd_dispatcher_take_channels: unrequested channel 0x9529700 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/MediaChannel/4
>mcd-DEBUG: 15/05/11 01:52:21.770215: _mcd_dispatcher_take_channels: possible handlers found, dispatching
>mcd-DEBUG: 15/05/11 01:52:21.770219: _mcd_channel_set_status: 0x9529700, 3
>mcd-DEBUG: 15/05/11 01:52:21.770229: _mcd_dispatcher_enter_state_machine: CTXREF11 on 0x95480b8
>mcd-DEBUG: 15/05/11 01:52:21.770258: _mcd_dispatcher_enter_state_machine: new dispatcher context 0x95480b8 for unrequested channel 0x9529700 (only): /org/freedesktop/Telepathy/Connection/butterfly/msn/ktowns_40live_2ecom_2eau/MediaChannel/4
>mcd-DEBUG: 15/05/11 01:52:21.770288: mcd_dispatch_operation_constructor: do17/0x952fc60: needs_approval=T
>mcd-DEBUG: 15/05/11 01:52:21.770318: _mcd_plugin_dispatch_operation_init: 0x9557dc0
>mcd-DEBUG: 15/05/11 01:52:21.770325: _mcd_plugin_dispatch_operation_new: 0x9557dc0 (for 0x952fc60)
>mcd-DEBUG: 15/05/11 01:52:21.770338: _mcd_dispatcher_enter_state_machine: entering state machine for context 0x95480b8
>mcd-DEBUG: 15/05/11 01:52:21.770342: mcd_dispatcher_context_ref: CTXREF01 on 0x95480b8 (ref = 1)
>mcd-DEBUG: 15/05/11 01:52:21.770347: _mcd_dispatch_operation_run_clients: do17 0x952fc60
>mcd-DEBUG: 15/05/11 01:52:21.770350: _mcd_dispatch_operation_run_clients: Running observers
>mcd-DEBUG: 15/05/11 01:52:21.770361: _mcd_dispatch_operation_run_clients: All necessary observers invoked
>mcd-DEBUG: 15/05/11 01:52:21.770365: _mcd_dispatch_operation_run_clients: Checking finished/locks
>mcd-DEBUG: 15/05/11 01:52:21.770370: _mcd_plugin_dispatch_operation_observers_finished: 0x9557dc0
>mcd-DEBUG: 15/05/11 01:52:21.770375: _mcd_dispatch_operation_handlers_can_bypass_approval: org.freedesktop.Telepathy.Client.Empathy.AudioVideo has BypassApproval=F
>mcd-DEBUG: 15/05/11 01:52:21.770380: mcd_dispatcher_context_unref: CTXREF01 on 0x95480b8 (ref = 2)
>mcd-DEBUG: 15/05/11 01:52:21.770384: mcd_dispatcher_context_unref: CTXREF11 on 0x95480b8 (ref = 1)
>mcd-DEBUG: 15/05/11 01:52:21.770387: mcd_dispatcher_context_unref: freeing the context 0x95480b8
>mcd-DEBUG: 15/05/11 01:52:21.770402: _mcd_dispatch_operation_inc_ado_pending: 0 -> 1
>mcd-DEBUG: 15/05/11 01:52:21.770411: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:52:21.770418: get_connection: called for do17
>mcd-DEBUG: 15/05/11 01:52:21.770426: get_possible_handlers: called for do17
>mcd-DEBUG: 15/05/11 01:52:21.770487: _mcd_dispatch_operation_run_approvers: Calling AddDispatchOperation on approver org.freedesktop.Telepathy.Client.Empathy.EventManager for CDO /org/freedesktop/Telepathy/DispatchOperation/do17 @ 0x952fc60
>mcd-DEBUG: 15/05/11 01:52:21.770490: _mcd_dispatch_operation_inc_ado_pending: 1 -> 2
>mcd-DEBUG: 15/05/11 01:52:21.770697: _mcd_dispatch_operation_dec_ado_pending: 2 -> 1
>mcd-DEBUG: 15/05/11 01:52:21.770701: _mcd_dispatch_operation_check_client_locks: waiting for AddDispatchOperation to return
>mcd-DEBUG: 15/05/11 01:52:21.770704: _mcd_dispatch_operation_check_client_locks: waiting for AddDispatchOperation to return
>mcd-DEBUG: 15/05/11 01:52:21.773243: dbusprop_get_all: org.freedesktop.Telepathy.ChannelDispatchOperation
>mcd-DEBUG: 15/05/11 01:52:21.773252: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:52:21.773262: get_connection: called for do17
>mcd-DEBUG: 15/05/11 01:52:21.773271: get_channels: called for do17
>mcd-DEBUG: 15/05/11 01:52:21.773367: get_possible_handlers: called for do17
>mcd-DEBUG: 15/05/11 01:52:21.902951: on_channel_ready: channel 0x9529700 is ready
>mcd-DEBUG: 15/05/11 01:52:21.913544: add_dispatch_operation_cb: Approver /org/freedesktop/Telepathy/Client/Empathy/EventManager accepted AddDispatchOperation /org/freedesktop/Telepathy/DispatchOperation/do17 (0x952fc60)
>mcd-DEBUG: 15/05/11 01:52:21.913562: _mcd_dispatch_operation_dec_ado_pending: 1 -> 0
>mcd-DEBUG: 15/05/11 01:52:21.913572: _mcd_dispatch_operation_check_client_locks: waiting for approval
>mcd-DEBUG: 15/05/11 01:52:31.490376: dispatch_operation_handle_with_time: do17/0x952fc60
>mcd-DEBUG: 15/05/11 01:52:31.490394: _mcd_dispatch_operation_check_client_locks: trying next handler
>mcd-DEBUG: 15/05/11 01:52:31.490411: _mcd_dispatch_operation_try_next_handler: Possible handler: org.freedesktop.Telepathy.Client.Empathy.AudioVideo (still exists: Y, already failed: N)
>mcd-DEBUG: 15/05/11 01:52:31.490427: _mcd_client_proxy_handle_channels: calling HandleChannels on org.freedesktop.Telepathy.Client.Empathy.AudioVideo
>mcd-DEBUG: 15/05/11 01:52:31.490618: _mcd_channel_set_status: 0x9529700, 4
>mcd-DEBUG: 15/05/11 01:52:31.503901: _mcd_channel_set_status: 0x9529700, 5
>mcd-DEBUG: 15/05/11 01:52:31.503962: _mcd_dispatch_operation_finish: Result: Channel successfully handled by org.freedesktop.Telepathy.Client.Empathy.AudioVideo
>mcd-DEBUG: 15/05/11 01:52:31.503974: _mcd_dispatch_operation_finish: successful HandleWith, channel went to org.freedesktop.Telepathy.Client.Empathy.AudioVideo
>mcd-DEBUG: 15/05/11 01:52:31.504030: _mcd_dispatch_operation_finish: do17/0x952fc60 has finished
>mcd-DEBUG: 15/05/11 01:52:31.504041: mcd_dispatch_operation_actually_finish: do17/0x952fc60: finished
>mcd-DEBUG: 15/05/11 01:52:31.504112: _mcd_dispatch_operation_check_client_locks: waiting for HandleChannels to return
>mcd-DEBUG: 15/05/11 01:52:31.504123: _mcd_dispatch_operation_check_client_locks: already finished (or finishing): Channel successfully handled by org.freedesktop.Telepathy.Client.Empathy.AudioVideo
>mcd-DEBUG: 15/05/11 01:52:31.504141: plugin_do_finalize: 0x9557dc0
>mcd-DEBUG: 15/05/11 01:52:32.38352: proxy_destroyed: Channel proxy invalidated: tp_dbus_errors_quark 8: Channel was closed
>mcd-DEBUG: 15/05/11 01:52:32.38360: mcd_channel_abort: 0x9529700
>mcd-DEBUG: 15/05/11 01:52:32.38364: _mcd_channel_set_status: 0x9529700, 7
>mcd-DEBUG: 15/05/11 01:52:32.38381: _mcd_mission_set_parent: child = 0x9529700, parent = (nil)
>mcd-DEBUG: 15/05/11 01:52:32.38486: _mcd_operation_remove_mission: removing mission: 0x9529700
>mcd-DEBUG: 15/05/11 01:52:32.38492: _mcd_channel_dispose: 0x9529700 (is disposed = 0)
>mcd-DEBUG: 15/05/11 01:52:32.38500: _mcd_mission_dispose: mission disposed 0x9529700
>mcd-DEBUG: 15/05/11 01:52:32.38506: _mcd_mission_finalize: mission finalized 0x9529700
>mcd-DEBUG: 15/05/11 01:56:39.510627: dbusprop_get_all: org.freedesktop.Telepathy.AccountManager
>mcd-DEBUG: 15/05/11 01:56:39.510637: get_valid_accounts: called
>mcd-DEBUG: 15/05/11 01:56:39.510656: get_invalid_accounts: called
>mcd-DEBUG: 15/05/11 01:56:39.510663: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:56:39.511980: dbusprop_get_all: org.freedesktop.Telepathy.Account
>mcd-DEBUG: 15/05/11 01:56:39.511986: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:56:39.512020: _mcd_account_dup_parameters: called
>mcd-DEBUG: 15/05/11 01:56:39.512079: get_connect_automatically: called for salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:56:39.512336: dbusprop_get_all: org.freedesktop.Telepathy.Account
>mcd-DEBUG: 15/05/11 01:56:39.512341: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:56:39.512365: _mcd_account_dup_parameters: called
>mcd-DEBUG: 15/05/11 01:56:39.512412: get_connect_automatically: called for salut/local_xmpp/account0
>mcd-DEBUG: 15/05/11 01:56:39.512654: dbusprop_get_all: org.freedesktop.Telepathy.Account
>mcd-DEBUG: 15/05/11 01:56:39.512659: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:56:39.512682: _mcd_account_dup_parameters: called
>mcd-DEBUG: 15/05/11 01:56:39.512746: get_connect_automatically: called for butterfly/msn/ktowns_40live_2ecom_2eau0
>mcd-DEBUG: 15/05/11 01:56:39.512979: dbusprop_get_all: org.freedesktop.Telepathy.Account
>mcd-DEBUG: 15/05/11 01:56:39.512985: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 15/05/11 01:56:39.513010: _mcd_account_dup_parameters: called
>mcd-DEBUG: 15/05/11 01:56:39.513067: get_connect_automatically: called for butterfly/msn/ktowns_40live_2ecom_2eau0

Actions: View

Attachments on bug 37207: 46717

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.