[raring] Portable Players - MTP Rhythmbox plugin not working, can't sync music with Android device

[Alin Andrei]

Gvfs has been updated in Ubuntu 13.04 Raring Ringtail recently, allowing us to connect Android 4.0+ devices with MTP. But Rhythmbox doesn't mount such Android devices, even though I can browse the Android filesystem just fine in Nautilus.

Tested with a Samsung Galaxy S2 with Android 4.2.1.

Here's the "rhythmbox --debug" relevant output:

(19:55:12) [0x7f4c58005090] [uevent_cb] rb-removable-media-manager.c:563: add event for /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2/2-1.2.3 (bd96)
(19:55:12) [0x7f4c58005090] [create_source_device_cb] rb-mtp-plugin.c:371: matching device 4e8:6860 against libmtp device list
(19:55:12) [0x7f4c58005090] [create_source_device_cb] rb-mtp-plugin.c:381: found libmtp device list entry (model: Samsung, vendor: Galaxy models (MTP))
(19:55:12) [0x7f4c58005090] [rb_property_view_selection_changed_cb] rb-property-view.c:839: selection changed
(19:55:12) [0x7f4c58005090] [rhythmdb_query_model_chain] rhythmdb-query-model.c:896: query model 0x4839c00 chaining to base model (nil)
(19:55:12) [0x7f4c58005090] [rb_entry_view_insert_column_custom] rb-entry-view.c:1716: appending column: 0x4b0ba20 (title: Track, key: Track)
(19:55:12) [0x7f4c58005090] [rb_entry_view_insert_column_custom] rb-entry-view.c:1716: appending column: 0x4b0bcc0 (title: Title, key: Title)
(19:55:12) [0x7f4c58005090] [rb_entry_view_insert_column_custom] rb-entry-view.c:1716: appending column: 0x4b130c0 (title: Genre, key: Genre)
(19:55:12) [0x7f4c58005090] [rb_entry_view_insert_column_custom] rb-entry-view.c:1716: appending column: 0x4b13360 (title: Artist, key: Artist)
(19:55:12) [0x7f4c58005090] [rb_entry_view_insert_column_custom] rb-entry-view.c:1716: appending column: 0x4b13600 (title: Album, key: Album)
(19:55:12) [0x7f4c58005090] [rb_entry_view_insert_column_custom] rb-entry-view.c:1716: appending column: 0x4b16100 (title: Year, key: Year)
(19:55:12) [0x7f4c58005090] [rb_entry_view_insert_column_custom] rb-entry-view.c:1716: appending column: 0x4b163a0 (title: Time, key: Time)
(19:55:12) [0x7f4c58005090] [rb_entry_view_insert_column_custom] rb-entry-view.c:1716: appending column: 0x4b16640 (title: Quality, key: Quality)
(19:55:12) [0x7f4c58005090] [rb_entry_view_insert_column_custom] rb-entry-view.c:1716: appending column: 0x4b1aa20 (title: Play Count, key: PlayCount)
(19:55:12) [0x7f4c58005090] [rb_entry_view_insert_column_custom] rb-entry-view.c:1716: appending column: 0x4b1acc0 (title: BPM, key: BPM)
(19:55:12) [0x7f4c58005090] [rb_entry_view_insert_column_custom] rb-entry-view.c:1716: appending column: 0x4b1e890 (title: Comment, key: Comment)
(19:55:12) [0x7f4c58005090] [rb_entry_view_insert_column_custom] rb-entry-view.c:1716: appending column: 0x4b1eb30 (title: Location, key: Location)
(19:55:12) [0x7f4c58005090] [rb_source_bind_settings] rb-source.c:1410: binding entry view sort order for (null)
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_sorting] rb-entry-view.c:1085: Updating EntryView sort order to Artist:0
(19:55:12) [0x7f4c58005090] [songs_view_sort_order_changed_cb] rb-browser-source.c:577: sort order changed
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_columns_visible] rb-entry-view.c:2410: visible columns: post-time => 48
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_columns_visible] rb-entry-view.c:2410: visible columns: artist => 4
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_columns_visible] rb-entry-view.c:2410: visible columns: duration => 8
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_columns_visible] rb-entry-view.c:2410: visible columns: genre => 3
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_columns_visible] rb-entry-view.c:2410: visible columns: track-number => 6
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_columns_visible] rb-entry-view.c:2410: visible columns: album => 5
(19:55:12) [0x7f4c58005090] [rb_source_bind_settings] rb-source.c:1425: binding paned position for (null)
(19:55:12) [0x7f4c58005090] [rb_source_bind_settings] rb-source.c:1434: binding show-browser for (null)
(19:55:12) [0x7f4c58005090] [rhythmdb_query_model_chain] rhythmdb-query-model.c:896: query model 0x4839cc0 chaining to base model (nil)
(19:55:12) [0x7f4c58005090] [rebuild_child_model] rb-library-browser.c:686: no selection for browser 0 - reusing parent model
(19:55:12) [0x7f4c58005090] [rebuild_child_model] rb-library-browser.c:686: no selection for browser 1 - reusing parent model
(19:55:12) [0x7f4c58005090] [rebuild_child_model] rb-library-browser.c:686: no selection for browser 2 - reusing parent model
(19:55:12) [0x7f4c58005090] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:730: disposing query model 0x4839c00
(19:55:12) [0x7f4c58005090] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:778: finalizing query model 0x4839c00
(19:55:12) [0x7f4c58005090] [rb_property_view_selection_changed_cb] rb-property-view.c:839: selection changed
(19:55:12) [0x7f4c58005090] [rb_property_view_selection_changed_cb] rb-property-view.c:839: selection changed
(19:55:12) [0x7f4c58005090] [rb_property_view_selection_changed_cb] rb-property-view.c:839: selection changed
(19:55:12) [0x7f4c58005090] [rhythmdb_query_model_chain] rhythmdb-query-model.c:896: query model 0x4839d80 chaining to base model (nil)
(19:55:12) [0x7f4c58005090] [rhythmdb_read_enter] rhythmdb.c:1211: counter: 1
(19:55:12) [0x28e58a0] [query_thread_main] rhythmdb.c:4034: entering query thread
(19:55:12) [0x28e58a0] [rhythmdb_query_internal] rhythmdb.c:4011: doing query
(19:55:12) [0x28e58a0] [do_query_recurse] rhythmdb-tree.c:2316: doing recursive query, 1 conjunctions
(19:55:12) [0x28e58a0] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:2248: adding 0 entries
(19:55:12) [0x28e58a0] [rhythmdb_query_internal] rhythmdb.c:4017: completed
(19:55:12) [0x7f4c58005090] [rb_entry_view_insert_column_custom] rb-entry-view.c:1716: appending column: 0x4b1edd0 (title: Rating, key: Rating)
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_columns_visible] rb-entry-view.c:2410: visible columns: post-time => 48
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_columns_visible] rb-entry-view.c:2410: visible columns: artist => 4
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_columns_visible] rb-entry-view.c:2410: visible columns: duration => 8
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_columns_visible] rb-entry-view.c:2410: visible columns: genre => 3
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_columns_visible] rb-entry-view.c:2410: visible columns: track-number => 6
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_columns_visible] rb-entry-view.c:2410: visible columns: album => 5
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_sorting] rb-entry-view.c:1085: Updating EntryView sort order to Artist:0
(19:55:12) [0x7f4c58005090] [rb_entry_view_insert_column_custom] rb-entry-view.c:1716: appending column: 0x34cc340 (title: Last Played, key: LastPlayed)
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_columns_visible] rb-entry-view.c:2410: visible columns: post-time => 48
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_columns_visible] rb-entry-view.c:2410: visible columns: artist => 4
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_columns_visible] rb-entry-view.c:2410: visible columns: duration => 8
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_columns_visible] rb-entry-view.c:2410: visible columns: genre => 3
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_columns_visible] rb-entry-view.c:2410: visible columns: track-number => 6
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_columns_visible] rb-entry-view.c:2410: visible columns: album => 5
(19:55:12) [0x7f4c58005090] [rb_entry_view_sync_sorting] rb-entry-view.c:1085: Updating EntryView sort order to Artist:0
(19:55:12) [0x7f4c58005090] [uevent_cb] rb-removable-media-manager.c:575: created a source for this device
(19:55:12) [0x7f4c58005090] [rb_display_page_model_add_page] rb-display-page-model.c:661: inserting source Media Player with parent 0x21bfa20
(19:55:12) [0x7f4c58005090] [update_group_visibility] rb-display-page-model.c:561: page group Devices changing visibility from 0 to 1
(19:55:12) [0x7f4c58005090] [uevent_cb] rb-removable-media-manager.c:563: add event for /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2/2-1.2.3/2-1.2.3:1.1 (0)
(19:55:12) [0x7f4c58005090] [create_source_device_cb] rb-mtp-plugin.c:367: couldn't get vendor or model ID for device (0:0)
(19:55:12) [0x7f4c58005090] [uevent_cb] rb-removable-media-manager.c:563: add event for /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2/2-1.2.3/2-1.2.3:1.0 (0)
(19:55:12) [0x7f4c58005090] [create_source_device_cb] rb-mtp-plugin.c:367: couldn't get vendor or model ID for device (0:0)
(19:55:12) [0x7f4c58005090] [idle_process_update] rhythmdb-query-model.c:1187: inserting 0 rows
(19:55:12) [0x7f4c58005090] [rebuild_child_model] rb-library-browser.c:686: no selection for browser 0 - reusing parent model
(19:55:12) [0x7f4c58005090] [rebuild_child_model] rb-library-browser.c:686: no selection for browser 1 - reusing parent model
(19:55:12) [0x7f4c58005090] [rebuild_child_model] rb-library-browser.c:686: no selection for browser 2 - reusing parent model
(19:55:12) [0x7f4c58005090] [rb_property_view_selection_changed_cb] rb-property-view.c:839: selection changed
(19:55:12) [0x7f4c58005090] [rb_property_view_selection_changed_cb] rb-property-view.c:839: selection changed
(19:55:12) [0x7f4c58005090] [rb_property_view_selection_changed_cb] rb-property-view.c:839: selection changed
(19:55:12) [0x7f4c58005090] [expand_rows_cb] rb-display-page-tree.c:413: expanding 1 rows
(19:55:12) [0x7f4c58005090] [dump_volume_identifiers] rb-removable-media-manager.c:637: unix-device = /dev/bus/usb/002/023
(19:55:12) [0x7f4c58005090] [rb_removable_media_manager_add_volume] rb-removable-media-manager.c:680: Unhandled media
(19:55:12) [0x7f4c58005090] [rhythmdb_process_one_event] rhythmdb.c:2525: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(19:55:12) [0x7f4c58005090] [rhythmdb_read_leave] rhythmdb.c:1225: counter: 0
(19:55:12) [0x7f4c58005090] [rhythmdb_process_one_event] rhythmdb.c:2518: processing RHYTHMDB_EVENT_THREAD_EXITED
(19:55:13) [0x7f4c58005090] [rhythmdb_mount_added_cb] rhythmdb-monitor.c:392: volume mtp://[usb:002,023]/ mounted
(19:55:13) [0x7f4c58005090] [rhythmdb_read_enter] rhythmdb.c:1211: counter: 1
(19:55:13) [0x7f4c58005090] [rhythmdb_query_internal] rhythmdb.c:4011: doing query
(19:55:13) [0x7f4c58005090] [do_query_recurse] rhythmdb-tree.c:2316: doing recursive query, 1 conjunctions
(19:55:13) [0x7f4c58005090] [rhythmdb_query_internal] rhythmdb.c:4017: completed
(19:55:13) [0x7f4c58005090] [rhythmdb_mount_added_cb] rhythmdb-monitor.c:406: 0 mounted entries to process
(19:55:13) [0x7f4c58005090] [rb_removable_media_manager_add_mount] rb-removable-media-manager.c:719: Unhandled media, no volume for mount
(19:55:13) [0x7f4c58005090] [rhythmdb_mount_added_cb] rhythmdb-monitor.c:392: volume mtp://[usb:002,023]/ mounted
(19:55:13) [0x7f4c58005090] [rhythmdb_read_enter] rhythmdb.c:1211: counter: 2
(19:55:13) [0x7f4c58005090] [rhythmdb_query_internal] rhythmdb.c:4011: doing query
(19:55:13) [0x7f4c58005090] [do_query_recurse] rhythmdb-tree.c:2316: doing recursive query, 1 conjunctions
(19:55:13) [0x7f4c58005090] [rhythmdb_query_internal] rhythmdb.c:4017: completed
(19:55:13) [0x7f4c58005090] [rhythmdb_mount_added_cb] rhythmdb-monitor.c:406: 0 mounted entries to process
(19:55:13) [0x7f4c58005090] [dump_volume_identifiers] rb-removable-media-manager.c:637: unix-device = /dev/bus/usb/002/023
unable to find device path for mount point /run/user/andrei/gvfs/mtp:host=%5Busb%3A002%2C023%5D
unable to find mount point for device path /run/user/andrei/gvfs/mtp:host=%5Busb%3A002%2C023%5D
(19:55:13) [0x7f4c58005090] [rb_removable_media_manager_add_mount] rb-removable-media-manager.c:753: Unhandled media
(19:55:13) [0x7f4c58005090] [rhythmdb_process_one_event] rhythmdb.c:2525: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(19:55:13) [0x7f4c58005090] [rhythmdb_read_leave] rhythmdb.c:1225: counter: 1
(19:55:13) [0x7f4c58005090] [rhythmdb_process_one_event] rhythmdb.c:2525: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(19:55:13) [0x7f4c58005090] [rhythmdb_read_leave] rhythmdb.c:1225: counter: 0
(19:55:13) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2195: paned position 160
(19:55:13) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2202: right_paned position 400
(19:55:13) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2209: sidebar paned position 300
(19:55:15) [0x7f4c58005090] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:840: No username set
(19:55:16) [0x7f4c58005090] [window_focus_cb] rb-mmkeys-plugin.c:169: window got focus, re-grabbing media keys
(19:55:16) [0x7f4c58005090] [window_focus_cb] rb-mmkeys-plugin.c:169: window got focus, re-grabbing media keys
(19:55:17) [0x7f4c58005090] [display_page_selected_cb] rb-shell.c:2338: page selected
(19:55:17) [0x7f4c58005090] [rb_shell_select_page] rb-shell.c:2583: selecting page 0x4afc260
(19:55:17) [0x7f4c58005090] [ensure_loaded] rb-mtp-source.c:347: device is already mounted, waiting until activated
(19:55:17) [0x7f4c58005090] [rb_shell_clipboard_set_source_internal] rb-shell-clipboard.c:354: selected source 0x4afc260
(19:55:17) [0x7f4c58005090] [rb_shell_clipboard_sync] rb-shell-clipboard.c:600: syncing clipboard
(19:55:17) [0x7f4c58005090] [rebuild_playlist_menu] rb-shell-clipboard.c:1037: rebuilding add-to-playlist menu
(19:55:17) [0x7f4c58005090] [rb_shell_player_set_source_internal] rb-shell-player.c:1117: selected source 0x4afc260
(19:55:17) [0x7f4c58005090] [rb_shell_player_sync_with_selected_source] rb-shell-player.c:3419: syncing with selected source: 0x4afc260
(19:55:17) [0x7f4c58005090] [rb_shell_player_sync_with_selected_source] rb-shell-player.c:3422: no playing source, new source is 0x4afc260
(19:55:17) [0x7f4c58005090] [rb_shell_player_sync_with_source] rb-shell-player.c:2948: playing source: (nil), active entry: (nil)
(19:55:17) [0x7f4c58005090] [rb_shell_set_window_title] rb-shell.c:2638: clearing title
(19:55:17) [0x7f4c58005090] [rb_shell_player_sync_buttons] rb-shell-player.c:3039: syncing with source 0x4afc260
(19:55:17) [0x7f4c58005090] [rb_statusbar_set_property] rb-statusbar.c:339: selected page 0x4afc260
(19:55:17) [0x7f4c58005090] [rb_statusbar_sync_status] rb-statusbar.c:467: updating status with: '0 songs', '', 999.000000
(19:55:17) [0x7f4c58005090] [shell_selected_page_notify_cb] rb-disc-recorder-plugin.c:638: RBDiscRecorderPlugin selected page changed
(19:55:17) [0x7f4c58005090] [unmount_done_cb] rb-mtp-source.c:314: successfully unmounted mtp device
(19:55:17) [0x7f4c58005090] [open_device] rb-mtp-source.c:289: actually opening device
(19:55:17) [0x7f4c58005090] [queue_task] rb-mtp-thread.c:142: queueing task: open device
(19:55:17) [0x4d6b000] [task_thread] rb-mtp-thread.c:634: MTP device worker thread starting
(19:55:17) [0x4d6b000] [run_task] rb-mtp-thread.c:562: running task: open device
(19:55:17) [0x4d6b000] [open_device] rb-mtp-thread.c:155: attempting to open device
Android device detected, assigning default bug flags
(19:55:17) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2195: paned position 160
(19:55:17) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2202: right_paned position 400
(19:55:17) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2209: sidebar paned position 300
(19:55:19) [0x7f4c58005090] [rhythmdb_mount_removed_cb] rhythmdb-monitor.c:461: volume mtp://[usb:002,023]/ unmounted
(19:55:19) [0x7f4c58005090] [rhythmdb_read_enter] rhythmdb.c:1211: counter: 1
(19:55:19) [0x7f4c58005090] [rhythmdb_query_internal] rhythmdb.c:4011: doing query
(19:55:19) [0x7f4c58005090] [do_query_recurse] rhythmdb-tree.c:2316: doing recursive query, 1 conjunctions
(19:55:19) [0x7f4c58005090] [rhythmdb_query_internal] rhythmdb.c:4017: completed
(19:55:19) [0x7f4c58005090] [process_unmounted_entries] rhythmdb-monitor.c:442: 0 unmounted entries to process
(19:55:19) [0x7f4c58005090] [rhythmdb_read_enter] rhythmdb.c:1211: counter: 2
(19:55:19) [0x7f4c58005090] [rhythmdb_query_internal] rhythmdb.c:4011: doing query
(19:55:19) [0x7f4c58005090] [do_query_recurse] rhythmdb-tree.c:2316: doing recursive query, 1 conjunctions
(19:55:19) [0x7f4c58005090] [rhythmdb_query_internal] rhythmdb.c:4017: completed
(19:55:19) [0x7f4c58005090] [process_unmounted_entries] rhythmdb-monitor.c:442: 0 unmounted entries to process
(19:55:19) [0x7f4c58005090] [rb_removable_media_manager_remove_mount] rb-removable-media-manager.c:767: mount removed
(19:55:19) [0x7f4c58005090] [rhythmdb_mount_removed_cb] rhythmdb-monitor.c:461: volume mtp://[usb:002,023]/ unmounted
(19:55:19) [0x7f4c58005090] [rhythmdb_read_enter] rhythmdb.c:1211: counter: 3
(19:55:19) [0x7f4c58005090] [rhythmdb_query_internal] rhythmdb.c:4011: doing query
(19:55:19) [0x7f4c58005090] [do_query_recurse] rhythmdb-tree.c:2316: doing recursive query, 1 conjunctions
(19:55:19) [0x7f4c58005090] [rhythmdb_query_internal] rhythmdb.c:4017: completed
(19:55:19) [0x7f4c58005090] [process_unmounted_entries] rhythmdb-monitor.c:442: 0 unmounted entries to process
(19:55:19) [0x7f4c58005090] [rhythmdb_read_enter] rhythmdb.c:1211: counter: 4
(19:55:19) [0x7f4c58005090] [rhythmdb_query_internal] rhythmdb.c:4011: doing query
(19:55:19) [0x7f4c58005090] [do_query_recurse] rhythmdb-tree.c:2316: doing recursive query, 1 conjunctions
(19:55:19) [0x7f4c58005090] [rhythmdb_query_internal] rhythmdb.c:4017: completed
(19:55:19) [0x7f4c58005090] [process_unmounted_entries] rhythmdb-monitor.c:442: 0 unmounted entries to process
(19:55:19) [0x7f4c58005090] [rb_removable_media_manager_remove_mount] rb-removable-media-manager.c:767: mount removed
(19:55:19) [0x7f4c58005090] [rhythmdb_process_one_event] rhythmdb.c:2525: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(19:55:19) [0x7f4c58005090] [rhythmdb_read_leave] rhythmdb.c:1225: counter: 3
(19:55:19) [0x7f4c58005090] [rhythmdb_process_one_event] rhythmdb.c:2525: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(19:55:19) [0x7f4c58005090] [rhythmdb_read_leave] rhythmdb.c:1225: counter: 2
(19:55:19) [0x7f4c58005090] [rhythmdb_process_one_event] rhythmdb.c:2525: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(19:55:19) [0x7f4c58005090] [rhythmdb_read_leave] rhythmdb.c:1225: counter: 1
(19:55:19) [0x7f4c58005090] [rhythmdb_process_one_event] rhythmdb.c:2525: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(19:55:19) [0x7f4c58005090] [rhythmdb_read_leave] rhythmdb.c:1225: counter: 0
(19:55:22) [0x7f4c58005090] [window_focus_cb] rb-mmkeys-plugin.c:169: window got focus, re-grabbing media keys
(19:55:22) [0x7f4c58005090] [window_focus_cb] rb-mmkeys-plugin.c:169: window got focus, re-grabbing media keys
(19:55:23) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2195: paned position 160
(19:55:23) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2202: right_paned position 400
(19:55:23) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2209: sidebar paned position 300
(19:55:30) [0x7f4c58005090] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:840: No username set
(19:55:42) [0xa05ac0] [electromagnetic_shotgun] rb-metadata-dbus-service.c:176: shutting down (45s idle)
(19:55:44) [0x7f4c58005090] [rhythmdb_read_enter] rhythmdb.c:1211: counter: 1
(19:55:44) [0x7f4c58005090] [rhythmdb_read_enter] rhythmdb.c:1211: counter: 2
(19:55:44) [0x7f4c400052d0] [query_thread_main] rhythmdb.c:4034: entering query thread
(19:55:44) [0x4d6acf0] [query_thread_main] rhythmdb.c:4034: entering query thread
(19:55:44) [0x4d6acf0] [rhythmdb_query_internal] rhythmdb.c:4011: doing query
(19:55:44) [0x4d6acf0] [do_query_recurse] rhythmdb-tree.c:2316: doing recursive query, 1 conjunctions
(19:55:44) [0x4d6acf0] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:2248: adding 0 entries
(19:55:44) [0x4d6acf0] [rhythmdb_query_internal] rhythmdb.c:4017: completed
(19:55:44) [0x7f4c400052d0] [rhythmdb_query_internal] rhythmdb.c:4011: doing query
(19:55:44) [0x7f4c400052d0] [do_query_recurse] rhythmdb-tree.c:2316: doing recursive query, 1 conjunctions
(19:55:44) [0x7f4c400052d0] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:2248: adding 0 entries
(19:55:44) [0x7f4c400052d0] [rhythmdb_query_internal] rhythmdb.c:4017: completed
(19:55:44) [0x7f4c58005090] [idle_process_update] rhythmdb-query-model.c:1187: inserting 0 rows
(19:55:44) [0x7f4c58005090] [idle_process_update] rhythmdb-query-model.c:1187: inserting 0 rows
(19:55:44) [0x7f4c58005090] [rhythmdb_process_one_event] rhythmdb.c:2525: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(19:55:44) [0x7f4c58005090] [rhythmdb_read_leave] rhythmdb.c:1225: counter: 1
(19:55:44) [0x7f4c58005090] [rhythmdb_process_one_event] rhythmdb.c:2518: processing RHYTHMDB_EVENT_THREAD_EXITED
(19:55:44) [0x7f4c58005090] [rhythmdb_process_one_event] rhythmdb.c:2525: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(19:55:44) [0x7f4c58005090] [rhythmdb_read_leave] rhythmdb.c:1225: counter: 0
(19:55:44) [0x7f4c58005090] [rhythmdb_process_one_event] rhythmdb.c:2518: processing RHYTHMDB_EVENT_THREAD_EXITED
(19:55:45) [0x7f4c58005090] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:840: No username set
(19:55:50) [0x7f4c58005090] [rhythmdb_read_enter] rhythmdb.c:1211: counter: 1
(19:55:50) [0x7f4c58005090] [rhythmdb_read_enter] rhythmdb.c:1211: counter: 2
(19:55:50) [0x3e18c50] [query_thread_main] rhythmdb.c:4034: entering query thread
(19:55:50) [0x4d6acf0] [query_thread_main] rhythmdb.c:4034: entering query thread
(19:55:50) [0x3e18c50] [rhythmdb_query_internal] rhythmdb.c:4011: doing query
(19:55:50) [0x4d6acf0] [rhythmdb_query_internal] rhythmdb.c:4011: doing query
(19:55:50) [0x3e18c50] [do_query_recurse] rhythmdb-tree.c:2316: doing recursive query, 1 conjunctions
(19:55:50) [0x4d6acf0] [do_query_recurse] rhythmdb-tree.c:2316: doing recursive query, 1 conjunctions
(19:55:50) [0x3e18c50] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:2248: adding 1 entries
(19:55:50) [0x3e18c50] [rhythmdb_query_internal] rhythmdb.c:4017: completed
(19:55:50) [0x7f4c58005090] [idle_process_update] rhythmdb-query-model.c:1187: inserting 1 rows
(19:55:50) [0x7f4c58005090] [rhythmdb_process_one_event] rhythmdb.c:2525: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(19:55:50) [0x7f4c58005090] [rhythmdb_read_leave] rhythmdb.c:1225: counter: 1
(19:55:50) [0x7f4c58005090] [rhythmdb_process_one_event] rhythmdb.c:2518: processing RHYTHMDB_EVENT_THREAD_EXITED
(19:55:50) [0x4d6acf0] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:2248: adding 1 entries
(19:55:50) [0x4d6acf0] [rhythmdb_query_internal] rhythmdb.c:4017: completed
(19:55:50) [0x7f4c58005090] [idle_process_update] rhythmdb-query-model.c:1187: inserting 1 rows
(19:55:50) [0x7f4c58005090] [rhythmdb_process_one_event] rhythmdb.c:2525: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(19:55:50) [0x7f4c58005090] [rhythmdb_read_leave] rhythmdb.c:1225: counter: 0
(19:55:50) [0x7f4c58005090] [rhythmdb_process_one_event] rhythmdb.c:2518: processing RHYTHMDB_EVENT_THREAD_EXITED
(19:55:53) [0x7f4c58005090] [window_focus_cb] rb-mmkeys-plugin.c:169: window got focus, re-grabbing media keys
(19:55:53) [0x7f4c58005090] [window_focus_cb] rb-mmkeys-plugin.c:169: window got focus, re-grabbing media keys
(19:55:54) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2195: paned position 160
(19:55:54) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2202: right_paned position 400
(19:55:54) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2209: sidebar paned position 300
(19:55:55) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2195: paned position 160
(19:55:55) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2202: right_paned position 400
(19:55:55) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2209: sidebar paned position 300
(19:55:56) [0x7f4c58005090] [window_focus_cb] rb-mmkeys-plugin.c:169: window got focus, re-grabbing media keys
(19:55:56) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2195: paned position 160
(19:55:56) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2202: right_paned position 400
(19:55:56) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2209: sidebar paned position 300
(19:55:57) [0x7f4c58005090] [window_focus_cb] rb-mmkeys-plugin.c:169: window got focus, re-grabbing media keys
(19:55:58) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2195: paned position 160
(19:55:58) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2202: right_paned position 400
(19:55:58) [0x7f4c58005090] [sync_window_settings] rb-shell.c:2209: sidebar paned position 300
(19:56:00) [0x7f4c58005090] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:840: No username set

ProblemType: Bug
DistroRelease: Ubuntu 13.04
Package: rhythmbox-plugins 2.98-0ubuntu3
Uname: Linux 3.8.0-994-generic x86_64
ApportVersion: 2.8-0ubuntu2
Architecture: amd64
Date: Mon Jan 28 19:59:50 2013
InstallationDate: Installed on 2012-12-30 (29 days ago)
InstallationMedia: Ubuntu 12.10 "Quantal Quetzal" - Release amd64 (20121017.5)
MarkForUpload: True
SourcePackage: rhythmbox
UpgradeStatus: Upgraded to raring on 2013-01-12 (16 days ago)

[Launchpad Janitor (janitor)]

Status changed to 'Confirmed' because the bug affects multiple users.

[Philip Langdale (langdalepl)]

This has always been a problem. You can't have two MTP clients talking to one device at the same time. Rhythmbox tries to talk directly at the same time as gvfs and two stomp on each other. It was hard to notice before because gvfs would simply fail to connect at all using the gphoto2 backend, and rhythmbox will fail too as it doesn't use the mtp API in an android-safe way either.

[Stéphane Gourichon (stephane-gourichon-lpad)]

@langdalep wrote "You can't have two MTP clients talking to one device at the same time."

What should be done, then ?

If I understand correctly, gvfs can multiplex calls from different clients. So it might be a good idea to have it the only one to talk to the MTP server. If so, rhythmbox could/should be changed to connect to gvfs, right ? But rhythmbox would then talk gvfs, not MTP. Is that a good thing ?

Or is it a better idea to insert a MTP-specific multiplexer that is the only one available from clients (hiding the direct connection). That multiplexer would accepts connections from multiple clients. Each client would still talk MTP. Is that possible ?

In both setups, the MTP server talks to only one client, and several simultaneous clients can talk to the server.

Is any of those two paths a good path to go ? Or something different ? What are your thought ?

[Philip Langdale (langdalepl)]

The multiplexer daemon is generally considered the right approach. Media players use parts of mtp that don't apply to gvfs so it's not a candidate to serve as the multiplexer. But this is a bunch of work and isn't going to happen soon.

[vit1967 (vit1967)]

gnom good show Android-SD as any link, BUT its SD not mounting as simple mounted devise (like /media/Android..e.t.c)
Do not working rsync (to automatic backup) for example
How can i rsync Android external MicroSD with ubuntu dir. in Ubuntu 13.?

[CerebroJD (jdcerebro)]

So is this a problem within Rhythmbox then? It needs to use a different method to access an MTP devices, using GVFS?

[Walter Garcia-Fontes (walter-garcia)]

Thank you for taking the time to report this bug and helping to make Ubuntu better. We are sorry that we do not always have the capacity to look at all reported bugs in a timely manner. There have been many changes in Ubuntu since that time you reported the bug and your problem may have been fixed with some of the updates. It would help us a lot if you could test it on a currently supported Ubuntu version. When you test it and it is still an issue, kindly upload the updated logs by running apport-collect <bug #> and any other logs that are relevant for this particular issue.

[Launchpad Janitor (janitor)]

[Expired for rhythmbox (Ubuntu) because there has been no activity for 60 days.]