Attachment 69453 Details for Bug 56688 – debug log from 3.6 to 3.4

debug log from 3.6 to 3.4

telepathy-salut 36 to 34.log (text/plain), 16.27 KB, created by Jean-François Fortin Tam on 2012-11-02 17:49:15 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Jean-François Fortin Tam

Created: 2012-11-02 17:49:15 UTC

Size: 16.27 KB

patch

obsolete

>salut/ft-DEBUG: 2012-09-27 14:24:14.175319: message_stanza_callback: new incoming channel
>salut/ft-DEBUG: 2012-09-27 14:24:14.175482: salut_file_transfer_channel_new_from_stanza: Received file offer with id 'gibber-file-transfer-1'
>salut/ft-DEBUG: 2012-09-27 14:24:14.175614: salut_file_transfer_channel_constructed: New FT channel created: /org/freedesktop/Telepathy/Connection/salut/local_xmpp/jeff/FileTransferChannel/0x8fbc9f8 (contact: jeff@f18-hornet, initiator: jeff@f18-hornet, file: "solve-gst-deps.py", size: 346)
>mcd-DEBUG: 2012-09-27 14:24:14.177498: _mcd_mission_set_parent: child = 0xa02b698, parent = 0xa028ed8
>mcd-DEBUG: 2012-09-27 14:24:14.177573: _mcd_dispatcher_take_channels: unrequested channel 0xa02b698 (only): /org/freedesktop/Telepathy/Connection/salut/local_xmpp/jeff/FileTransferChannel/0x8fbc9f8
>mcd-DEBUG: 2012-09-27 14:24:14.177663: _mcd_dispatcher_take_channels: possible handler(s) found, dispatching 1 channels
>mcd-DEBUG: 2012-09-27 14:24:14.177721: _mcd_channel_set_status: 0xa02b698, 3
>mcd-DEBUG: 2012-09-27 14:24:14.177781: _mcd_dispatcher_enter_state_machine: CTXREF11 on 0xa1b2610
>mcd-DEBUG: 2012-09-27 14:24:14.177840: _mcd_dispatcher_enter_state_machine: new dispatcher context 0xa1b2610 for unrequested channel 0xa02b698 (only): /org/freedesktop/Telepathy/Connection/salut/local_xmpp/jeff/FileTransferChannel/0x8fbc9f8
>mcd-DEBUG: 2012-09-27 14:24:14.177925: mcd_dispatch_operation_constructor: do1285/0xa065e10: needs_approval=T
>mcd-DEBUG: 2012-09-27 14:24:14.178014: _mcd_plugin_dispatch_operation_init: 0xa1920a0
>mcd-DEBUG: 2012-09-27 14:24:14.178075: _mcd_plugin_dispatch_operation_new: 0xa1920a0 (for 0xa065e10)
>mcd-DEBUG: 2012-09-27 14:24:14.178138: _mcd_dispatcher_enter_state_machine: entering state machine for context 0xa1b2610
>mcd-DEBUG: 2012-09-27 14:24:14.178190: mcd_dispatcher_context_ref: CTXREF01 on 0xa1b2610 (ref = 1)
>mcd-DEBUG: 2012-09-27 14:24:14.178245: _mcd_dispatch_operation_run_clients: do1285 0xa065e10
>mcd-DEBUG: 2012-09-27 14:24:14.178299: _mcd_dispatch_operation_handlers_can_bypass_observers: org.freedesktop.Telepathy.Client.Empathy.FileTransfer has BypassObservers=F
>mcd-DEBUG: 2012-09-27 14:24:14.178354: _mcd_dispatch_operation_run_clients: Running observers
>mcd-DEBUG: 2012-09-27 14:24:14.178419: _mcd_dispatch_operation_run_clients: All necessary observers invoked
>mcd-DEBUG: 2012-09-27 14:24:14.178472: _mcd_dispatch_operation_run_clients: Checking finished/locks
>mcd-DEBUG: 2012-09-27 14:24:14.178527: _mcd_plugin_dispatch_operation_observers_finished: 0xa1920a0
>mcd-DEBUG: 2012-09-27 14:24:14.178585: _mcd_dispatch_operation_handlers_can_bypass_approval: org.freedesktop.Telepathy.Client.Empathy.FileTransfer has BypassApproval=F
>mcd-DEBUG: 2012-09-27 14:24:14.178651: mcd_dispatcher_context_unref: CTXREF01 on 0xa1b2610 (ref = 2)
>mcd-DEBUG: 2012-09-27 14:24:14.178708: mcd_dispatcher_context_unref: CTXREF11 on 0xa1b2610 (ref = 1)
>mcd-DEBUG: 2012-09-27 14:24:14.178765: mcd_dispatcher_context_unref: freeing the context 0xa1b2610
>mcd-DEBUG: 2012-09-27 14:24:14.178841: _mcd_dispatch_operation_inc_ado_pending: 0 -> 1
>mcd-DEBUG: 2012-09-27 14:24:14.178905: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 2012-09-27 14:24:14.178966: get_connection: called for do1285
>mcd-DEBUG: 2012-09-27 14:24:14.179023: get_possible_handlers: called for do1285
>mcd-DEBUG: 2012-09-27 14:24:14.179179: _mcd_dispatch_operation_run_approvers: Calling AddDispatchOperation on approver org.freedesktop.Telepathy.Client.GnomeShell._3a1_2e1885.n0 for CDO /org/freedesktop/Telepathy/DispatchOperation/do1285 @ 0xa065e10
>mcd-DEBUG: 2012-09-27 14:24:14.179239: _mcd_dispatch_operation_inc_ado_pending: 1 -> 2
>mcd-DEBUG: 2012-09-27 14:24:14.179694: _mcd_dispatch_operation_run_approvers: Calling AddDispatchOperation on approver org.freedesktop.Telepathy.Client.Empathy.EventManager for CDO /org/freedesktop/Telepathy/DispatchOperation/do1285 @ 0xa065e10
>mcd-DEBUG: 2012-09-27 14:24:14.179754: _mcd_dispatch_operation_inc_ado_pending: 2 -> 3
>mcd-DEBUG: 2012-09-27 14:24:14.180099: _mcd_dispatch_operation_dec_ado_pending: 3 -> 2
>mcd-DEBUG: 2012-09-27 14:24:14.180156: _mcd_dispatch_operation_check_client_locks: waiting for AddDispatchOperation to return
>mcd-DEBUG: 2012-09-27 14:24:14.180212: _mcd_dispatch_operation_check_client_locks: waiting for AddDispatchOperation to return
>mcd-DEBUG: 2012-09-27 14:24:14.181809: on_channel_ready: channel 0xa02b698 is ready
>tp-glib/channel-DEBUG: 2012-09-27 14:24:14.182800: tp_channel_init: 0xc30c008
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.183015: tp_proxy_constructor: 0xc30c008: request 0xb6ebd80 represents core features on TpFileTransferChannel
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.183082: tp_proxy_constructor: 0xc30c008: request 0xb949c80 represents core features on TpChannel
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.183192: tp_proxy_borrow_interface_by_id: 0xc30c008: org.freedesktop.Telepathy.Channel DBusGProxy is 0xbdb84a8
>tp-glib/channel-DEBUG: 2012-09-27 14:24:14.183269: tp_channel_constructor: 0xc30c008: constructed with channel type "org.freedesktop.Telepathy.Channel.Type.FileTransfer", handle #5 of type 1
>tp-glib/channel-DEBUG: 2012-09-27 14:24:14.183326: _tp_channel_continue_introspection: 0xc30c008
>tp-glib/channel-DEBUG: 2012-09-27 14:24:14.183381: _tp_channel_continue_introspection: 0xc30c008
>tp-glib/channel-DEBUG: 2012-09-27 14:24:14.183437: _tp_channel_continue_introspection: 0xc30c008
>tp-glib/channel-DEBUG: 2012-09-27 14:24:14.183490: _tp_channel_get_handle: 0xc30c008: handle already known to be 5 of type 1
>tp-glib/channel-DEBUG: 2012-09-27 14:24:14.183543: _tp_channel_continue_introspection: 0xc30c008
>tp-glib/channel-DEBUG: 2012-09-27 14:24:14.183598: _tp_channel_get_identifier: 0xc30c008: identifier already known to be jeff@f18-hornet
>tp-glib/channel-DEBUG: 2012-09-27 14:24:14.183655: _tp_channel_continue_introspection: 0xc30c008
>tp-glib/channel-DEBUG: 2012-09-27 14:24:14.183711: _tp_channel_get_channel_type: 0xc30c008: channel type org.freedesktop.Telepathy.Channel.Type.FileTransfer already determined
>tp-glib/channel-DEBUG: 2012-09-27 14:24:14.183762: _tp_channel_continue_introspection: 0xc30c008
>tp-glib/channel-DEBUG: 2012-09-27 14:24:14.183820: _tp_channel_continue_introspection: 0xc30c008
>tp-glib/channel-DEBUG: 2012-09-27 14:24:14.183871: _tp_channel_get_interfaces: 0xc30c008
>mcd-DEBUG: 2012-09-27 14:24:14.184504: dbusprop_get_all: org.freedesktop.Telepathy.ChannelDispatchOperation
>mcd-DEBUG: 2012-09-27 14:24:14.184571: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 2012-09-27 14:24:14.184638: get_connection: called for do1285
>mcd-DEBUG: 2012-09-27 14:24:14.184703: get_channels: called for do1285
>mcd-DEBUG: 2012-09-27 14:24:14.184921: get_possible_handlers: called for do1285
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.185466: tp_proxy_constructor: 0xb9baf08: request 0xbdb6470 represents core features on TpChannelDispatchOperation
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.185626: tp_proxy_borrow_interface_by_id: 0xb9baf08: org.freedesktop.Telepathy.ChannelDispatchOperation DBusGProxy is 0xc0c32e8
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.185759: tp_proxy_poll_features: 0x9d8d0e0: request 0xb2ffd10 prepared
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.185816: tp_proxy_prepare_request_finish: 0xb2ffd10
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.185878: tp_proxy_poll_features: 0xb704358: request 0xb2a73c0 prepared
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.185930: tp_proxy_prepare_request_finish: 0xb2a73c0
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.185990: request_is_complete: 0xb9baf08: calling callback for tp-channel-dispatch-operation-feature-core
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.186085: tp_proxy_borrow_interface_by_id: 0xb9baf08: org.freedesktop.DBus.Properties DBusGProxy is 0xb39f370
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.186187: tp_proxy_poll_features: 0xb9baf08: core features not ready yet, nothing prepared
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.186249: request_is_complete: 0xc30c008: calling callback for tp-channel-feature-core
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.186305: tp_proxy_poll_features: 0xc30c008: core features not ready yet, nothing prepared
>mcd-DEBUG: 2012-09-27 14:24:14.186769: dbusprop_get_all: org.freedesktop.Telepathy.ChannelDispatchOperation
>mcd-DEBUG: 2012-09-27 14:24:14.186830: mcd_dbus_get_interfaces: called
>mcd-DEBUG: 2012-09-27 14:24:14.186887: get_connection: called for do1285
>mcd-DEBUG: 2012-09-27 14:24:14.186945: get_channels: called for do1285
>mcd-DEBUG: 2012-09-27 14:24:14.187150: get_possible_handlers: called for do1285
>empathy/Dispatcher-DEBUG: 2012-09-27 14:24:14.188021: event_manager_add: Adding event 0x95158e8
>empathy/Other-DEBUG: 2012-09-27 14:24:14.190929: roster_window_flash_start: Start flashing
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.186358: tp_proxy_poll_features: 0xc30c008: core features not ready yet, nothing prepared
>tp-glib/channel-DEBUG: 2012-09-27 14:24:14.186549: _tp_channel_continue_introspection: 0xc30c008
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.186618: tp_proxy_poll_features: 0xc30c008: core features not ready yet, nothing prepared
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.186674: tp_proxy_poll_features: 0xc30c008: core features not ready yet, nothing prepared
>tp-glib/groups-DEBUG: 2012-09-27 14:24:14.186728: _tp_channel_get_group_properties: 0xc30c008: not a Group, continuing
>tp-glib/channel-DEBUG: 2012-09-27 14:24:14.186784: _tp_channel_continue_introspection: 0xc30c008
>tp-glib/channel-DEBUG: 2012-09-27 14:24:14.186836: _tp_channel_continue_introspection: 0xc30c008: channel ready
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.186892: tp_proxy_poll_features: 0xc30c008: request 0xb949c80 prepared
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.186945: tp_proxy_prepare_request_finish: 0xb949c80
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.186996: request_is_complete: 0xc30c008: calling callback for tp-file-transfer-channel-feature-core
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.187104: tp_proxy_borrow_interface_by_id: 0xc30c008: org.freedesktop.Telepathy.Channel.Type.FileTransfer DBusGProxy is 0xb3a30f0
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.187258: tp_proxy_borrow_interface_by_id: 0xc30c008: org.freedesktop.DBus.Properties DBusGProxy is 0xb915c90
>mcd-DEBUG: 2012-09-27 14:24:14.194228: add_dispatch_operation_cb: Approver /org/freedesktop/Telepathy/Client/Empathy/EventManager accepted AddDispatchOperation /org/freedesktop/Telepathy/DispatchOperation/do1285 (0xa065e10)
>mcd-DEBUG: 2012-09-27 14:24:14.194299: _mcd_dispatch_operation_dec_ado_pending: 2 -> 1
>mcd-DEBUG: 2012-09-27 14:24:14.194360: _mcd_dispatch_operation_check_client_locks: waiting for AddDispatchOperation to return
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.187361: tp_proxy_poll_features: 0xc30c008: core features not ready yet, nothing prepared
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.187417: tp_proxy_poll_features: 0xc30c008: core features not ready yet, nothing prepared
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.212404: tp_proxy_poll_features: 0xb9baf08: request 0xbdb6470 prepared
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.212480: tp_proxy_prepare_request_finish: 0xbdb6470
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.212538: tp_proxy_poll_features: 0xb9baf08: request 0xb2ffd10 prepared
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.212591: tp_proxy_prepare_request_finish: 0xb2ffd10
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.212802: tp_proxy_poll_features: 0xc30c008: request 0xb6ebd80 prepared
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.212996: tp_proxy_prepare_request_finish: 0xb6ebd80
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.213052: request_is_complete: 0xc30c008: calling callback for tp-channel-feature-contacts
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.213227: check_feature_interfaces: Proxy doesn't implement org.freedesktop.Telepathy.Channel.Interface.Password, can't prepare feature tp-channel-feature-password
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.213336: tp_proxy_poll_features: 0xc30c008: request 0xbb796a0 prepared
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:14.213396: tp_proxy_prepare_request_finish: 0xbb796a0
>mcd-DEBUG: 2012-09-27 14:24:14.238724: add_dispatch_operation_cb: Approver /org/freedesktop/Telepathy/Client/GnomeShell/_3a1_2e1885/n0 accepted AddDispatchOperation /org/freedesktop/Telepathy/DispatchOperation/do1285 (0xa065e10)
>mcd-DEBUG: 2012-09-27 14:24:14.238799: _mcd_dispatch_operation_dec_ado_pending: 1 -> 0
>mcd-DEBUG: 2012-09-27 14:24:14.238859: _mcd_dispatch_operation_check_client_locks: waiting for approval
>empathy/Other-DEBUG: 2012-09-27 14:24:14.396781: empathy_sound_play_internal: Play sound "message-new-instant" (Incoming chat request)
>mcd-DEBUG: 2012-09-27 14:24:17.565920: dispatch_operation_handle_with_time: do1285/0xa065e10
>mcd-DEBUG: 2012-09-27 14:24:17.566124: _mcd_dispatch_operation_check_client_locks: trying next handler
>mcd-DEBUG: 2012-09-27 14:24:17.566358: _mcd_dispatch_operation_try_next_handler: Possible handler: org.freedesktop.Telepathy.Client.Empathy.FileTransfer (still exists: Y, already failed: N)
>mcd-DEBUG: 2012-09-27 14:24:17.566586: mcd_dispatch_operation_try_handler: do1285: channel ACL verification [1 channels]
>mcd-DEBUG: 2012-09-27 14:24:17.566884: _mcd_client_proxy_handle_channels: calling HandleChannels on org.freedesktop.Telepathy.Client.Empathy.FileTransfer
>mcd-DEBUG: 2012-09-27 14:24:17.567528: _mcd_channel_set_status: 0xa02b698, 4
>mcd-DEBUG: 2012-09-27 14:24:17.571546: _mcd_channel_set_status: 0xa02b698, 5
>mcd-DEBUG: 2012-09-27 14:24:17.571857: _mcd_dispatch_operation_finish: Result: Channel successfully handled by org.freedesktop.Telepathy.Client.Empathy.FileTransfer
>mcd-DEBUG: 2012-09-27 14:24:17.572079: _mcd_dispatch_operation_finish: successful HandleWith, channel went to org.freedesktop.Telepathy.Client.Empathy.FileTransfer
>mcd-DEBUG: 2012-09-27 14:24:17.572294: _mcd_dispatch_operation_finish: do1285/0xa065e10 has finished
>mcd-DEBUG: 2012-09-27 14:24:17.572422: mcd_dispatch_operation_actually_finish: do1285/0xa065e10: finished
>mcd-DEBUG: 2012-09-27 14:24:17.572592: _mcd_dispatch_operation_check_client_locks: waiting for handler_is_suitable or HandleChannels to return
>mcd-DEBUG: 2012-09-27 14:24:17.572741: _mcd_dispatch_operation_check_client_locks: already finished (or finishing): Channel successfully handled by org.freedesktop.Telepathy.Client.Empathy.FileTransfer
>mcd-DEBUG: 2012-09-27 14:24:17.572873: plugin_do_finalize: 0xa1920a0
>empathy/Dispatcher-DEBUG: 2012-09-27 14:24:17.574280: cdo_invalidated_cb: ChannelDispatchOperation has been invalidated: ChannelDispatchOperation finished and was removed
>empathy/Dispatcher-DEBUG: 2012-09-27 14:24:17.574489: event_remove: Removing event 0x95158e8
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:17.592112: tp_proxy_invalidate: 0xb9baf08: ChannelDispatchOperation finished and was removed
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:17.592181: tp_proxy_signal_connection_proxy_invalidated: 0x9a9c930: TpProxy 0xb9baf08 invalidated (I have 0xb9baf08): ChannelDispatchOperation finished and was removed
>tp-glib/proxy-DEBUG: 2012-09-27 14:24:17.592230: tp_proxy_signal_connection_proxy_invalidated: 0xbf1b570: TpProxy 0xb9baf08 invalidated (I have 0xb9baf08): ChannelDispatchOperation finished and was removed
>empathy/Other-DEBUG: 2012-09-27 14:24:17.697297: roster_window_flash_stop: Stop flashing
>empathy/Ft-DEBUG: 2012-09-27 14:24:24.68767: empathy_ft_manager_add_handler: Adding handler
>empathy/Other-DEBUG: 2012-09-27 14:24:24.68969: builder_get_file_valist: Loading file /usr/share/empathy/empathy-ft-manager.ui
>empathy/Ft-DEBUG: 2012-09-27 14:24:24.78723: ft_manager_start_transfer: Start transfer, is outgoing False
>salut/ft-DEBUG: 2012-09-27 14:24:24.178209: accept_local_socket_connection: Client connected to local socket
>empathy/Ft-DEBUG: 2012-09-27 14:24:24.188484: emit_error_signal: Error in transfer: Le correspondant a annulÃ© le transfert de fichier
>empathy/Ft-DEBUG: 2012-09-27 14:24:24.188885: ft_handler_transfer_error_cb: Transfer error Le correspondant a annulÃ© le transfert de fichier
>tp-glib/channel-DEBUG: 2012-09-27 14:24:24.195228: tp_file_transfer_channel_state_changed_cb: File transfer state changed: old state = 1, state = 2, reason = 1, requested = no, in_stream = not present, out_stream = not present
>tp-glib/channel-DEBUG: 2012-09-27 14:24:24.195728: tp_file_transfer_channel_state_changed_cb: File transfer state changed: old state = 2, state = 3, reason = 0, requested = no, in_stream = not present, out_stream = not present
>tp-glib/channel-DEBUG: 2012-09-27 14:24:24.195939: tp_file_transfer_channel_state_changed_cb: File transfer state changed: old state = 3, state = 5, reason = 3, requested = no, in_stream = not present, out_stream = not present

Actions: View

Attachments on bug 56688: 69452 | 69453

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.