This happens frequently when playing. RB locks two seconds before the end of a track and stops playing. Chosing a different track crashes the application.

This is the output when running with -d right on the moment of the lock:



(16:45:03) [0x7f7a9c0838a0] [rb_shell_player_handle_eos] rb-shell-player.c:941: handling eos for file:///media/lacie/music/The%20Dave%20Brubeck%20Quartet-Time%20Out/-Everybody's%20Jumpin'.mp3
(16:45:03) [0x7f7a9c0838a0] [rb_shuffle_play_order_get_next] rb-play-order-shuffle.c:195: choosing next entry in shuffle
(16:45:03) [0x7f7a9c0838a0] [rb_shell_player_do_next_internal] rb-shell-player.c:2149: got new entry file:///home/claudio/Music/Pat%20Metheny%20-%20The%20Trios%201975%20-%202005%20(5%20albums%20+%201%20boot)/2005%20-%20Pat%20Metheny%20Trio%20+1%20-%20Live%20at%20Monterey%20Jazz%20Festival%20(320kbps)/04%20Question%20And%20Answer.mp3 from play order
(16:45:03) [0x7f7a9c0838a0] [rb_shell_player_do_next_internal] rb-shell-player.c:2178: didn't get a new entry from queue play order
(16:45:03) [0x7f7a9c0838a0] [impl_open] rb-player-gst.c:786: setting new uri to file:///home/claudio/Music/Pat%20Metheny%20-%20The%20Trios%201975%20-%202005%20(5%20albums%20+%201%20boot)/2005%20-%20Pat%20Metheny%20Trio%20+1%20-%20Live%20at%20Monterey%20Jazz%20Festival%20(320kbps)/04%20Question%20And%20Answer.mp3
(16:45:03) [0x7f7a9c0838a0] [impl_play] rb-player-gst.c:821: current track finishing -> just setting URI on playbin
(16:45:03) [0x7f7a9c0838a0] [track_change_done] rb-player-gst.c:337: track change finished
(16:45:03) [0x7f7a9c0838a0] [rb_shell_player_set_playing_entry] rb-shell-player.c:1773: Success!
(16:45:03) [0x7f7a9c0838a0] [rhythmdb_entry_set] rhythmdb.c:3245: queuing RHYTHMDB_ACTION_ENTRY_SET
(16:45:03) [0x7f7a9c0838a0] [rb_shell_player_handle_eos_unlocked] rb-shell-player.c:894: updating play statistics
(16:45:03) [0x7f7a9c0838a0] [rhythmdb_entry_set] rhythmdb.c:3245: queuing RHYTHMDB_ACTION_ENTRY_SET
(16:45:03) [0x7f7a9c0838a0] [rhythmdb_entry_set] rhythmdb.c:3245: queuing RHYTHMDB_ACTION_ENTRY_SET
(16:45:03) [0x1128bc0] [rhythmdb_process_one_event] rhythmdb.c:2512: processing RHYTHMDB_EVENT_ENTRY_SET
(16:45:03) [0x1128bc0] [rhythmdb_process_one_event] rhythmdb.c:2512: processing RHYTHMDB_EVENT_ENTRY_SET
(16:45:03) [0x1128bc0] [rhythmdb_process_one_event] rhythmdb.c:2512: processing RHYTHMDB_EVENT_ENTRY_SET
(16:45:03) [0x1128bc0] [bus_cb] rb-player-gst.c:495: got error from stream: Resource not found. (gstfilesrc.c(1036): gst_file_src_start (): /GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin4/GstFileSrc:source:
No such file "/home/claudio/Music/Pat Metheny - The Trios 1975 - 2005 (5 albums + 1 boot)/2005 - Pat Metheny Trio +1 - Live at Monterey Jazz Festival (320kbps)/04 Question And Answer.mp3")
(16:45:03) [0x1128bc0] [rb_shell_player_error] rb-shell-player.c:3432: playback error while playing: Resource not found.
(16:45:03) [0x1128bc0] [error_cb] rb-shell-player.c:3547: exiting error hander

(rhythmbox:13394): GLib-CRITICAL **: g_str_has_prefix: assertion `str != NULL' failed

** (rhythmbox:13394): CRITICAL **: deactivate_group: assertion `group->active' failed
(16:45:03) [0x1128bc0] [start_state_change] rb-player-gst.c:384: state change succeeded synchronously
(16:45:03) [0x1128bc0] [rhythmdb_property_model_insert] rhythmdb-property-model.c:798: adding "Jazz": refcount 66
(16:45:03) [0x1128bc0] [rhythmdb_property_model_insert] rhythmdb-property-model.c:798: adding "The Dave Brubeck Quartet": refcount 9
(16:45:03) [0x1128bc0] [rhythmdb_property_model_insert] rhythmdb-property-model.c:798: adding "Time Out": refcount 3
(16:45:03) [0x1128bc0] [rb_entry_view_row_inserted_cb] rb-entry-view.c:2118: row added
(16:45:03) [0x1128bc0] [rb_shell_player_handle_eos] rb-shell-player.c:941: handling eos for file:///media/lacie/music/The%20Dave%20Brubeck%20Quartet-Time%20Out/-Everybody's%20Jumpin'.mp3
(16:45:03) [0x1128bc0] [rb_shell_player_handle_eos_unlocked] rb-shell-player.c:809: playing entry has already EOS'd
(16:45:03) [0x1128bc0] [sync_window_settings] rb-shell.c:1877: paned position 293
(16:45:03) [0x1128bc0] [sync_window_settings] rb-shell.c:1884: right_paned position 400
(16:45:03) [0x1128bc0] [sync_window_settings] rb-shell.c:1891: sidebar paned position 300
(16:45:28) [0x1128bc0] [rhythmdb_idle_save] rhythmdb.c:4685: database is dirty, doing regular save
(16:45:28) [0x1128bc0] [rhythmdb_save_async] rhythmdb.c:3165: saving the rhythmdb in the background
(16:45:28) [0x1128bc0] [rhythmdb_read_enter] rhythmdb.c:1229: counter: 1
(16:45:28) [0x1518c40] [rhythmdb_save_thread_main] rhythmdb.c:3113: entering save thread
(16:45:28) [0x1518c40] [rhythmdb_save_thread_main] rhythmdb.c:3131: saving rhythmdb
(16:45:28) [0x1518c40] [save_entry_type] rhythmdb-tree.c:1187: saving entries of type song
(16:45:28) [0x1518c40] [save_entry_type] rhythmdb-tree.c:1187: saving entries of type iradio
(16:45:28) [0x1518c40] [save_entry_type] rhythmdb-tree.c:1187: saving entries of type ignore
(16:45:28) [0x1518c40] [save_entry_type] rhythmdb-tree.c:1187: saving entries of type podcast-post
(16:45:28) [0x1518c40] [save_entry_type] rhythmdb-tree.c:1187: saving entries of type podcast-feed
(16:45:29) [0x1128bc0] [rhythmdb_process_one_event] rhythmdb.c:2533: processing RHYTHMDB_EVENT_DB_SAVED

(16:45:29) [0x1128bc0] [rhythmdb_read_leave] rhythmdb.c:1243: counter: 0
(16:45:29) [0x1128bc0] [rhythmdb_process_one_event] rhythmdb.c:2530: processing RHYTHMDB_EVENT_THREAD_EXITED
(16:45:52) [0x1128bc0] [rhythmdb_read_enter] rhythmdb.c:1229: counter: 1
(16:45:52) [0x1128bc0] [rhythmdb_read_enter] rhythmdb.c:1229: counter: 2
(16:45:52) [0x1128bc0] [rhythmdb_read_enter] rhythmdb.c:1229: counter: 3
(16:45:52) [0x3d82ef0] [query_thread_main] rhythmdb.c:4039: entering query thread
(16:45:52) [0x1128bc0] [rhythmdb_read_enter] rhythmdb.c:1229: counter: 4
(16:45:52) [0x3d82ef0] [rhythmdb_query_internal] rhythmdb.c:4016: doing query
(16:45:52) [0x3d82ef0] [do_query_recurse] rhythmdb-tree.c:2304: doing recursive query, 1 conjunctions
(16:45:52) [0x3d82ef0] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:2248: adding 0 entries
(16:45:52) [0x3d82ef0] [rhythmdb_query_internal] rhythmdb.c:4022: completed
(16:45:52) [0x3d828a0] [query_thread_main] rhythmdb.c:4039: entering query thread
(16:45:52) [0x3d828a0] [rhythmdb_query_internal] rhythmdb.c:4016: doing query
(16:45:52) [0x3d828a0] [do_query_recurse] rhythmdb-tree.c:2304: doing recursive query, 1 conjunctions
(16:45:52) [0x3d828a0] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:2248: adding 0 entries
(16:45:52) [0x3d828a0] [rhythmdb_query_internal] rhythmdb.c:4022: completed
(16:45:52) [0x3d828a0] [query_thread_main] rhythmdb.c:4039: entering query thread
(16:45:52) [0x3d828a0] [rhythmdb_query_internal] rhythmdb.c:4016: doing query
(16:45:52) [0x3d828a0] [do_query_recurse] rhythmdb-tree.c:2304: doing recursive query, 1 conjunctions
(16:45:52) [0x3d828a0] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:2248: adding 0 entries
(16:45:52) [0x3d828a0] [rhythmdb_query_internal] rhythmdb.c:4022: completed
(16:45:52) [0x1518c40] [query_thread_main] rhythmdb.c:4039: entering query thread
(16:45:52) [0x1128bc0] [rhythmdb_read_enter] rhythmdb.c:1229: counter: 5
(16:45:52) [0x1518c40] [rhythmdb_query_internal] rhythmdb.c:4016: doing query
(16:45:52) [0x1518c40] [do_query_recurse] rhythmdb-tree.c:2304: doing recursive query, 1 conjunctions
(16:45:52) [0x3d82ef0] [query_thread_main] rhythmdb.c:4039: entering query thread
(16:45:52) [0x1518c40] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:2248: adding 0 entries
(16:45:52) [0x3d82ef0] [rhythmdb_query_internal] rhythmdb.c:4016: doing query
(16:45:52) [0x1518c40] [rhythmdb_query_internal] rhythmdb.c:4022: completed
(16:45:52) [0x3d82ef0] [do_query_recurse] rhythmdb-tree.c:2304: doing recursive query, 1 conjunctions
(16:45:52) [0x1128bc0] [rhythmdb_read_enter] rhythmdb.c:1229: counter: 6
(16:45:52) [0x3d828a0] [query_thread_main] rhythmdb.c:4039: entering query thread
(16:45:52) [0x3d828a0] [rhythmdb_query_internal] rhythmdb.c:4016: doing query
(16:45:52) [0x1128bc0] [idle_process_update] rhythmdb-query-model.c:1187: inserting 0 rows
(16:45:52) [0x3d828a0] [do_query_recurse] rhythmdb-tree.c:2304: doing recursive query, 1 conjunctions
(16:45:52) [0x1128bc0] [idle_process_update] rhythmdb-query-model.c:1187: inserting 0 rows
(16:45:52) [0x1128bc0] [idle_process_update] rhythmdb-query-model.c:1187: inserting 0 rows
(16:45:52) [0x1128bc0] [idle_process_update] rhythmdb-query-model.c:1187: inserting 0 rows
(16:45:52) [0x1128bc0] [rhythmdb_process_one_event] rhythmdb.c:2537: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(16:45:52) [0x1128bc0] [rhythmdb_read_leave] rhythmdb.c:1243: counter: 5
(16:45:52) [0x1128bc0] [rhythmdb_process_one_event] rhythmdb.c:2530: processing RHYTHMDB_EVENT_THREAD_EXITED
(16:45:52) [0x1128bc0] [rhythmdb_process_one_event] rhythmdb.c:2537: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(16:45:52) [0x1128bc0] [rhythmdb_read_leave] rhythmdb.c:1243: counter: 4
(16:45:52) [0x1128bc0] [rhythmdb_process_one_event] rhythmdb.c:2530: processing RHYTHMDB_EVENT_THREAD_EXITED
(16:45:52) [0x1128bc0] [rhythmdb_process_one_event] rhythmdb.c:2537: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(16:45:52) [0x1128bc0] [rhythmdb_read_leave] rhythmdb.c:1243: counter: 3
(16:45:52) [0x1128bc0] [rhythmdb_process_one_event] rhythmdb.c:2530: processing RHYTHMDB_EVENT_THREAD_EXITED
(16:45:52) [0x1128bc0] [rhythmdb_process_one_event] rhythmdb.c:2537: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(16:45:52) [0x1128bc0] [rhythmdb_read_leave] rhythmdb.c:1243: counter: 2
(16:45:52) [0x1128bc0] [rhythmdb_process_one_event] rhythmdb.c:2530: processing RHYTHMDB_EVENT_THREAD_EXITED
(16:45:52) [0x3d82ef0] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:2248: adding 12 entries
(16:45:52) [0x3d82ef0] [rhythmdb_query_internal] rhythmdb.c:4022: completed
(16:45:52) [0x1128bc0] [idle_process_update] rhythmdb-query-model.c:1187: inserting 12 rows
(16:45:52) [0x1128bc0] [rhythmdb_process_one_event] rhythmdb.c:2537: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(16:45:52) [0x1128bc0] [rhythmdb_read_leave] rhythmdb.c:1243: counter: 1
(16:45:52) [0x1128bc0] [rhythmdb_process_one_event] rhythmdb.c:2530: processing RHYTHMDB_EVENT_THREAD_EXITED
(16:45:52) [0x3d828a0] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:2248: adding 106 entries
(16:45:52) [0x3d828a0] [rhythmdb_query_internal] rhythmdb.c:4022: completed
(16:45:52) [0x1128bc0] [idle_process_update] rhythmdb-query-model.c:1187: inserting 106 rows
(16:45:52) [0x1128bc0] [rhythmdb_process_one_event] rhythmdb.c:2537: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(16:45:52) [0x1128bc0] [rhythmdb_read_leave] rhythmdb.c:1243: counter: 0
(16:45:52) [0x1128bc0] [rhythmdb_process_one_event] rhythmdb.c:2530: processing RHYTHMDB_EVENT_THREAD_EXITED
(16:46:02) [0x1128bc0] [window_focus_cb] rb-mmkeys-plugin.c:169: window got focus, re-grabbing media keys
(16:46:02) [0x1128bc0] [sync_window_settings] rb-shell.c:1877: paned position 293
(16:46:02) [0x1128bc0] [sync_window_settings] rb-shell.c:1884: right_paned position 400
(16:46:02) [0x1128bc0] [sync_window_settings] rb-shell.c:1891: sidebar paned position 300
(16:46:03) [0x1128bc0] [sync_window_settings] rb-shell.c:1877: paned position 293
(16:46:03) [0x1128bc0] [sync_window_settings] rb-shell.c:1884: right_paned position 400
(16:46:03) [0x1128bc0] [sync_window_settings] rb-shell.c:1891: sidebar paned position 300

When picking a different track:


(16:50:58) [0x1128bc0] [rb_shell_clipboard_entryview_changed_cb] rb-shell-clipboard.c:809: entryview changed
(16:50:58) [0x1128bc0] [rb_shell_clipboard_sync] rb-shell-clipboard.c:600: syncing clipboard
(16:50:58) [0x1128bc0] [rb_entry_view_row_activated_cb] rb-entry-view.c:2100: row activated
(16:50:58) [0x1128bc0] [rb_entry_view_row_activated_cb] rb-entry-view.c:2104: emitting entry activated
(16:50:58) [0x1128bc0] [rb_shell_player_entry_activated_cb] rb-shell-player.c:2669: got entry 0x7f7ac00963d0 activated
(16:50:58) [0x1128bc0] [rb_shell_player_set_playing_source_internal] rb-shell-player.c:3088: setting playing source to 0x1616010
(16:50:58) [0x1128bc0] [rb_shell_player_set_playing_source_internal] rb-shell-player.c:3111: source is already playing, stopping it
(16:50:58) [0x1128bc0] [prev_action_sensitive_cb] rb-mpris-plugin.c:1285: emitting CanGoPrevious change
(16:50:58) [0x1128bc0] [next_action_sensitive_cb] rb-mpris-plugin.c:1276: emitting CanGoNext change
(16:50:58) [0x1128bc0] [rb_shell_player_sync_with_source] rb-shell-player.c:2910: playing source: 0x1616010, active entry: (nil)
(16:50:58) [0x1128bc0] [rb_shell_set_window_title] rb-shell.c:2329: clearing title
(16:50:58) [0x1128bc0] [rb_shell_player_sync_buttons] rb-shell-player.c:3002: syncing with source 0x1616010
(16:50:58) [0x1128bc0] [rb_shell_playing_source_changed_cb] rb-shell.c:2195: playing source changed
(16:50:58) [0x1128bc0] [playing_source_changed_cb] rb-mpris-plugin.c:1265: emitting CanPause change
(16:50:58) [0x1128bc0] [playing_source_changed_cb] rb-mpris-plugin.c:1268: emitting ActivePlaylist change
(16:50:58) [0x1128bc0] [impl_open] rb-player-gst.c:786: setting new uri to file:///media/lacie/music/3%20x%20Luka%20Jazz%20Band%20-%20De%20Ac%C3%A1%20Somos/01%20-%20Pantomima.mp3
(16:50:58) [0x1128bc0] [impl_play] rb-player-gst.c:848: not in transition, stopping current track to start the new one
(16:50:58) [0x1128bc0] [start_state_change] rb-player-gst.c:384: state change succeeded synchronously
(16:50:58) [0x1128bc0] [state_change_finished] rb-player-gst.c:409: setting new playback URI file:///media/lacie/music/3%20x%20Luka%20Jazz%20Band%20-%20De%20Ac%C3%A1%20Somos/01%20-%20Pantomima.mp3

(rhythmbox:13394): GLib-GObject-CRITICAL **: g_object_get: assertion `G_IS_OBJECT (object)' failed


Crossfading is disabled.