(16:16:30) [0x6c9500] [window_focus_cb] rb-mmkeys-plugin.c:117: window got focus, re-grabbing media keys
(16:16:30) [0x6c9500] [rb_entry_view_row_activated_cb] rb-entry-view.c:1713: row activated
(16:16:30) [0x6c9500] [rb_entry_view_row_activated_cb] rb-entry-view.c:1717: emitting entry activated
(16:16:30) [0x6c9500] [rb_shell_player_entry_activated_cb] rb-shell-player.c:2329: got entry 0x2aaab02bcdf0 activated
(16:16:30) [0x6c9500] [rb_shell_player_set_playing_source_internal] rb-shell-player.c:2732: setting playing source to 0x93f690
(16:16:30) [0x6c9500] [rb_shell_player_sync_with_source] rb-shell-player.c:2563: playing source: 0x93f690, active entry: (nil)
(16:16:30) [0x6c9500] [rb_shell_set_window_title] rb-shell.c:2083: clearing title
(16:16:30) [0x6c9500] [rb_shell_player_get_playing_song_duration] rb-shell-player.c:2926: Did not get playing entry : return -1 as length
(16:16:30) [0x6c9500] [rb_shell_player_get_playing_song_duration] rb-shell-player.c:2926: Did not get playing entry : return -1 as length
(16:16:30) [0x6c9500] [rb_header_sync] rb-header.c:355: syncing with entry = (nil)
(16:16:30) [0x6c9500] [rb_header_sync] rb-header.c:426: not playing
(16:16:30) [0x6c9500] [rb_shell_player_sync_buttons] rb-shell-player.c:2666: syncing with source 0x93f690
(16:16:30) [0x6c9500] [rb_shell_playing_source_changed_cb] rb-shell.c:1893: playing source changed
(16:16:30) [0x6c9500] [rb_player_gst_sync_pipeline] rb-player-gst.c:653: syncing pipeline
(16:16:30) [0x6c9500] [rb_player_gst_sync_pipeline] rb-player-gst.c:660: PAUSING pipeline
(16:16:30) [0x6c9500] [rb_player_gst_sync_pipeline] rb-player-gst.c:653: syncing pipeline
(16:16:30) [0x6c9500] [rb_player_gst_sync_pipeline] rb-player-gst.c:655: PLAYING pipeline
(16:16:30) [0x6c9500] [rb_shell_player_set_playing_entry] rb-shell-player.c:1509: Success!
(16:16:30) [0x6c9500] [rb_shell_player_error] rb-shell-player.c:2962: playback error while playing: Internal GStreamer error: state change failed.  Please file a bug at http://bugzilla.gnome.org/enter_bug.cgi?product=GStreamer.
(16:16:30) [0x6c9500] [rb_shell_player_set_playing_source_internal] rb-shell-player.c:2732: setting playing source to (nil)
(16:16:30) [0x6c9500] [rb_shell_player_set_playing_source_internal] rb-shell-player.c:2755: source is already playing, stopping it
(16:16:30) [0x6c9500] [rb_shell_player_stop] rb-shell-player.c:2800: stopping
(16:16:30) [0x6c9500] [rb_shell_player_sync_with_source] rb-shell-player.c:2563: playing source: (nil), active entry: (nil)
(16:16:30) [0x6c9500] [rb_shell_set_window_title] rb-shell.c:2083: clearing title
(16:16:30) [0x6c9500] [rb_shell_player_get_playing_song_duration] rb-shell-player.c:2926: Did not get playing entry : return -1 as length
(16:16:30) [0x6c9500] [rb_shell_player_get_playing_song_duration] rb-shell-player.c:2926: Did not get playing entry : return -1 as length
(16:16:30) [0x6c9500] [rb_header_sync] rb-header.c:355: syncing with entry = (nil)
(16:16:30) [0x6c9500] [rb_header_sync] rb-header.c:426: not playing
(16:16:30) [0x6c9500] [rb_shell_player_get_playing_song_duration] rb-shell-player.c:2926: Did not get playing entry : return -1 as length
(16:16:30) [0x6c9500] [rb_shell_player_sync_buttons] rb-shell-player.c:2666: syncing with source 0x93f690
(16:16:30) [0x6c9500] [rb_shell_player_sync_with_source] rb-shell-player.c:2563: playing source: (nil), active entry: (nil)
(16:16:30) [0x6c9500] [rb_shell_set_window_title] rb-shell.c:2083: clearing title
(16:16:30) [0x6c9500] [rb_shell_player_get_playing_song_duration] rb-shell-player.c:2926: Did not get playing entry : return -1 as length
(16:16:30) [0x6c9500] [rb_shell_player_get_playing_song_duration] rb-shell-player.c:2926: Did not get playing entry : return -1 as length
(16:16:30) [0x6c9500] [rb_header_sync] rb-header.c:355: syncing with entry = (nil)
(16:16:30) [0x6c9500] [rb_header_sync] rb-header.c:426: not playing
(16:16:30) [0x6c9500] [rb_shell_player_sync_buttons] rb-shell-player.c:2666: syncing with source 0x93f690
(16:16:30) [0x6c9500] [rb_shell_playing_source_changed_cb] rb-shell.c:1893: playing source changed
(16:16:30) [0x6c9500] [error_cb] rb-shell-player.c:3109: exiting error hander
(16:16:30) [0x6c9500] [rb_shell_clipboard_entryview_changed_cb] rb-shell-clipboard.c:743: entryview changed

(rhythmbox:18802): RhythmDB-CRITICAL **: rhythmdb_entry_get_string: assertion `entry != NULL' failed
(16:16:30) [0x6c9500] [new_playing_stream_idle_cb] rb-shell-player.c:3013: new playing stream: (null)
(16:16:30) [0x6c9500] [rb_shell_player_get_playing_song_duration] rb-shell-player.c:2926: Did not get playing entry : return -1 as length
(16:16:30) [0x6c9500] [rb_shell_player_sync_with_source] rb-shell-player.c:2563: playing source: (nil), active entry: (nil)
(16:16:30) [0x6c9500] [rb_shell_set_window_title] rb-shell.c:2083: clearing title
(16:16:30) [0x6c9500] [rb_shell_player_get_playing_song_duration] rb-shell-player.c:2926: Did not get playing entry : return -1 as length
(16:16:30) [0x6c9500] [rb_shell_player_get_playing_song_duration] rb-shell-player.c:2926: Did not get playing entry : return -1 as length
(16:16:30) [0x6c9500] [rb_header_sync] rb-header.c:355: syncing with entry = (nil)
(16:16:30) [0x6c9500] [rb_header_sync] rb-header.c:426: not playing
(16:16:30) [0x6c9500] [rb_shell_player_sync_buttons] rb-shell-player.c:2666: syncing with source 0x93f690
(16:16:30) [0x6c9500] [rb_shell_clipboard_sync] rb-shell-clipboard.c:543: syncing clipboard
(16:16:31) [0x6c9500] [uninhibit] rb-power-manager-plugin.c:221: Was going to uninhibit power manager, but we haven't inhibited it