(20:12:39) [0x6c5040] [rb_debug_init_match] rb-debug.c:153: Debugging enabled
(20:12:39) [0x6c5040] [main] main.c:171: initializing Rhythmbox 0.11.2
(20:12:39) [0x6c5040] [rb_threads_init] rb-util.c:460: GMutex isn't recursive
(20:12:39) [0x6c5040] [main] main.c:179: going to create DBus object
(20:12:39) [0x6c5040] [main] main.c:246: Going to create a new shell
(20:12:39) [0x6c5040] [rb_dot_dir] rb-file-helpers.c:88: unable to create Rhythmbox's dot dir
(20:12:39) [0x6c5040] [rb_shell_constructor] rb-shell.c:1281: Constructing shell
(20:12:39) [0x6c5040] [construct_db] rb-shell.c:967: creating database object
(20:12:39) [0x6c5040] [construct_widgets] rb-shell.c:1020: shell: initializing shell services
(20:12:39) [0x6c5040] [gconf_network_buffer_size_changed] rb-shell-player.c:3327: network buffer size changed
(20:12:39) [0x6c5040] [rb_shell_player_set_playing_source_internal] rb-shell-player.c:2732: setting playing source to (nil)
(20:12:39) [0x6c5040] [rb_shell_player_stop] rb-shell-player.c:2800: stopping
(20:12:39) [0x6c5040] [rb_shell_player_sync_with_source] rb-shell-player.c:2563: playing source: (nil), active entry: (nil)
(20:12:39) [0x6c5040] [rb_header_sync] rb-header.c:355: syncing with entry = (nil)
(20:12:39) [0x6c5040] [rb_header_sync] rb-header.c:426: not playing
(20:12:39) [0x6c5040] [rb_shell_player_sync_buttons] rb-shell-player.c:2666: syncing with source (nil)
(20:12:39) [0x6c5040] [rb_shell_player_sync_with_source] rb-shell-player.c:2563: playing source: (nil), active entry: (nil)
(20:12:39) [0x6c5040] [rb_header_sync] rb-header.c:355: syncing with entry = (nil)
(20:12:39) [0x6c5040] [rb_header_sync] rb-header.c:426: not playing
(20:12:39) [0x6c5040] [rb_shell_player_sync_control_state] rb-shell-player.c:2128: syncing control state
(20:12:39) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x6df4b0 chaining to base model (nil)
(20:12:39) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x6df570 chaining to base model (nil)
(20:12:39) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0x6df4b0
(20:12:39) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0x6df4b0
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x6df4b0 ()
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x6df630 (Track)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x6df6f0 (Title)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb83800 (Genre)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb838c0 (Artist)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb83980 (Album)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb83a40 (Year)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb83b00 (Time)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb83bc0 (Quality)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb83c80 (Rating)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb83d40 (Play Count)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb83e00 (Last Played)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb83ec0 (Date Added)
(20:12:39) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:39) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 0 - reusing parent model
(20:12:39) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 1 - reusing parent model
(20:12:39) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 2 - reusing parent model
(20:12:39) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:39) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:39) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:39) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0xb8d280 chaining to base model (nil)
(20:12:39) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0xb8d280 chaining to base model 0x6df570
(20:12:39) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 0 - reusing parent model
(20:12:39) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 1 - reusing parent model
(20:12:39) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 2 - reusing parent model
(20:12:39) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:39) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:39) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:39) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0xb8d400 chaining to base model (nil)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb8d4c0 (Play Queue)
(20:12:39) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0xb8d400
(20:12:39) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0xb8d400
(20:12:39) [0x6c5040] [rb_sourcelist_append] rb-sourcelist.c:1053: inserting source 0xadb1c0 to group library
(20:12:39) [0x6c5040] [rb_proxy_config_init] rb-proxy-config.c:90: watching HTTP proxy configuration
(20:12:39) [0x6c5040] [get_proxy_config] rb-proxy-config.c:221: HTTP proxy is enabled, but no proxy host is specified
(20:12:39) [0x6c5040] [rb_shell_constructor] rb-shell.c:1301: shell: setting up tray icon
(20:12:39) [0x6c5040] [tray_destroy_cb] rb-shell.c:2879: creating new tray icon
(20:12:39) [0x6c5040] [rb_tray_icon_init] rb-tray-icon.c:187: setting up tray icon
(20:12:39) [0x6c5040] [rb_tray_icon_show_notifications_changed_cb] rb-tray-icon.c:578: show notifications clicked for 0x8482a0
(20:12:39) [0x6c5040] [tray_destroy_cb] rb-shell.c:2888: done creating new tray icon 0x8482a0
(20:12:39) [0x6c5040] [rb_shell_constructor] rb-shell.c:1304: shell: adding gconf notification
(20:12:39) [0x6c5040] [rb_shell_constructor] rb-shell.c:1336: shell: syncing with gconf
(20:12:39) [0x6c5040] [rb_source_set_property] rb-source.c:471: Setting Play Queue visibility to 1
(20:12:39) [0x6c5040] [visibility_notify_cb] rb-sourcelist.c:969: Source visibility changed: Play Queue
(20:12:39) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:39) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0xb9e8c0 chaining to base model (nil)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb8d400 (Track)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb9e980 (Title)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb9ea40 (Genre)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb9eb00 (Artist)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Artist:0
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb9ebc0 (Album)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Artist:0
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb9ec80 (Year)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Artist:0
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb9ed40 (Time)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Artist:0
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb9ee00 (Quality)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Artist:0
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb9eec0 (Play Count)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Artist:0
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [rb_browser_source_state_prefs_sync] rb-browser-source.c:745: syncing state
(20:12:39) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0xbd3830 chaining to base model (nil)
(20:12:39) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 0 - reusing parent model
(20:12:39) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 1 - reusing parent model
(20:12:39) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 2 - reusing parent model
(20:12:39) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0xb9e8c0
(20:12:39) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0xb9e8c0
(20:12:39) [0x6c5040] [rhythmdb_read_enter] rhythmdb.c:907: counter: 1
(20:12:39) [0xbd41c0] [query_thread_main] rhythmdb.c:3414: entering query thread
(20:12:39) [0xbd41c0] [rhythmdb_query_internal] rhythmdb.c:3391: doing query
(20:12:39) [0xbd41c0] [do_query_recurse] rhythmdb-tree.c:2164: doing recursive query, 1 conjunctions
(20:12:39) [0xbd41c0] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:1918: adding 0 entries
(20:12:39) [0xbd41c0] [rhythmdb_query_internal] rhythmdb.c:3397: completed
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbd38f0 (Rating)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Artist:0
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [songs_view_sort_order_changed_cb] rb-browser-source.c:598: sort order changed
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbd39b0 (Last Played)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Artist:0
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [songs_view_sort_order_changed_cb] rb-browser-source.c:598: sort order changed
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbd3a70 (Date Added)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Artist:0
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [songs_view_sort_order_changed_cb] rb-browser-source.c:598: sort order changed
(20:12:39) [0x6c5040] [rb_sourcelist_append] rb-sourcelist.c:1053: inserting source 0xbbe000 to group library
(20:12:39) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0xbd3bf0 chaining to base model (nil)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbd3cb0 (Date)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Date:1
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbd3d70 (Title)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Date:1
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xb9e8c0 (Feed)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Date:1
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbd3e30 (Time)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Date:1
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbd3ef0 (Rating)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Date:1
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbe5000 (Play Count)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Date:1
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbe50c0 (Last Played)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Date:1
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbe5180 (Status)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Date:1
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0xbe5300 chaining to base model (nil)
(20:12:39) [0x6c5040] [rhythmdb_read_enter] rhythmdb.c:907: counter: 2
(20:12:39) [0x6c5040] [rhythmdb_query_internal] rhythmdb.c:3391: doing query
(20:12:39) [0x6c5040] [do_query_recurse] rhythmdb-tree.c:2164: doing recursive query, 1 conjunctions
(20:12:39) [0x6c5040] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:1918: adding 0 entries
(20:12:39) [0x6c5040] [idle_process_update] rhythmdb-query-model.c:1039: inserting 0 rows
(20:12:39) [0x6c5040] [rhythmdb_query_internal] rhythmdb.c:3397: completed
(20:12:39) [0x6c5040] [rb_podcast_source_state_prefs_sync] rb-podcast-source.c:958: syncing state
(20:12:39) [0x6c5040] [rb_podcast_source_do_query] rb-podcast-source.c:1180: select entry filter
(20:12:39) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0xbe5480 chaining to base model (nil)
(20:12:39) [0x6c5040] [rb_podcast_source_do_query] rb-podcast-source.c:1193: caching new query
(20:12:39) [0x6c5040] [rb_podcast_source_do_query] rb-podcast-source.c:1197: setting empty model
(20:12:39) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0xbd3bf0
(20:12:39) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0xbd3bf0
(20:12:39) [0x6c5040] [rb_podcast_source_do_query] rb-podcast-source.c:1201: doing query
(20:12:39) [0x6c5040] [rhythmdb_read_enter] rhythmdb.c:907: counter: 3
(20:12:39) [0xbd41c0] [query_thread_main] rhythmdb.c:3414: entering query thread
(20:12:39) [0xbd41c0] [rhythmdb_query_internal] rhythmdb.c:3391: doing query
(20:12:39) [0xbd41c0] [do_query_recurse] rhythmdb-tree.c:2164: doing recursive query, 1 conjunctions
(20:12:39) [0xbd41c0] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:1918: adding 0 entries
(20:12:39) [0xbd41c0] [rhythmdb_query_internal] rhythmdb.c:3397: completed
(20:12:39) [0x6c5040] [rb_sourcelist_append] rb-sourcelist.c:1053: inserting source 0xbd9030 to group library
(20:12:39) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0xbe5540 chaining to base model (nil)
(20:12:39) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0xbe56c0 chaining to base model (nil)
(20:12:39) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0xbe56c0
(20:12:39) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0xbe56c0
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbeb820 (Track)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbeb8e0 (Title)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbeb9a0 (Artist)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbeba60 (Album)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbe56c0 (Location)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbd3bf0 (Last Seen)
(20:12:39) [0x6c5040] [rb_source_set_property] rb-source.c:471: Setting Missing Files visibility to 0
(20:12:39) [0x6c5040] [rb_sourcelist_append] rb-sourcelist.c:1053: inserting source 0xbc3120 to group library
(20:12:39) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0xbebb20 chaining to base model (nil)
(20:12:39) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0xbebca0 chaining to base model (nil)
(20:12:39) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0xbebca0
(20:12:39) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0xbebca0
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbebd60 (Location)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbebe20 (Error)
(20:12:39) [0x6c5040] [rb_source_set_property] rb-source.c:471: Setting Import Errors visibility to 0
(20:12:39) [0x6c5040] [rb_sourcelist_append] rb-sourcelist.c:1053: inserting source 0xbc3210 to group library
(20:12:39) [0x6c5040] [construct_sources] rb-shell.c:1169: shell: creating playlist manager
(20:12:39) [0x6c5040] [rebuild_playlist_menu] rb-shell-clipboard.c:964: rebuilding add-to-playlist menu
(20:12:39) [0x6c5040] [construct_sources] rb-shell.c:1181: shell: creating removable media manager
(20:12:39) [0x6c5040] [construct_load_ui] rb-shell.c:1201: shell: loading ui
(20:12:39) [0x6c5040] [rb_shell_select_source] rb-shell.c:1975: selecting source 0xbbe000
(20:12:39) [0x6c5040] [rb_shell_clipboard_set_source_internal] rb-shell-clipboard.c:344: selected source 0xbbe000
(20:12:39) [0x6c5040] [rb_shell_clipboard_sync] rb-shell-clipboard.c:543: syncing clipboard
(20:12:39) [0x6c5040] [rebuild_playlist_menu] rb-shell-clipboard.c:964: rebuilding add-to-playlist menu
(20:12:39) [0x6c5040] [rb_shell_player_set_source_internal] rb-shell-player.c:901: selected source 0xbbe000
(20:12:39) [0x6c5040] [rb_shell_player_sync_with_selected_source] rb-shell-player.c:2940: syncing with selected source: 0xbbe000
(20:12:39) [0x6c5040] [rb_shell_player_sync_with_selected_source] rb-shell-player.c:2943: no playing source, new source is 0xbbe000
(20:12:39) [0x6c5040] [rb_shell_player_sync_with_source] rb-shell-player.c:2563: playing source: (nil), active entry: (nil)
(20:12:39) [0x6c5040] [rb_shell_set_window_title] rb-shell.c:2079: clearing title
(20:12:39) [0x6c5040] [rb_shell_player_get_playing_song_duration] rb-shell-player.c:2926: Did not get playing entry : return -1 as length
(20:12:39) [0x6c5040] [rb_shell_player_get_playing_song_duration] rb-shell-player.c:2926: Did not get playing entry : return -1 as length
(20:12:39) [0x6c5040] [rb_header_sync] rb-header.c:355: syncing with entry = (nil)
(20:12:39) [0x6c5040] [rb_header_sync] rb-header.c:426: not playing
(20:12:39) [0x6c5040] [rb_shell_player_sync_buttons] rb-shell-player.c:2666: syncing with source 0xbbe000
(20:12:39) [0x6c5040] [rb_source_header_set_source_internal] rb-source-header.c:318: selected source 0xbbe000
(20:12:39) [0x6c5040] [rb_source_header_view_browser_changed_cb] rb-source-header.c:541: got view browser toggle
(20:12:39) [0x6c5040] [rb_source_header_view_browser_changed_cb] rb-source-header.c:551: got view browser toggle
(20:12:39) [0x6c5040] [rb_statusbar_set_property] rb-statusbar.c:313: selected source 0xbbe000
(20:12:39) [0x6c5040] [rb_statusbar_sync_status] rb-statusbar.c:417: updating status with: '0 songs', '', -1.000000
(20:12:39) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:108: Loading plugin: /usr/lib/rhythmbox/plugins/magnatune/magnatune.rb-plugin
(20:12:39) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:197: Could not find 'Icon' in /usr/lib/rhythmbox/plugins/magnatune/magnatune.rb-plugin
(20:12:39) [0x6c5040] [rb_plugins_engine_load_cb] rb-plugins-engine.c:271: Plugin Magnatune Store loaded
(20:12:39) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:108: Loading plugin: /usr/lib/rhythmbox/plugins/python-console/pythonconsole.rb-plugin
(20:12:39) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:197: Could not find 'Icon' in /usr/lib/rhythmbox/plugins/python-console/pythonconsole.rb-plugin
(20:12:39) [0x6c5040] [rb_plugins_engine_load_cb] rb-plugins-engine.c:271: Plugin Python Console loaded
(20:12:39) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:108: Loading plugin: /usr/lib/rhythmbox/plugins/lyrics/lyrics.rb-plugin
(20:12:39) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:197: Could not find 'Icon' in /usr/lib/rhythmbox/plugins/lyrics/lyrics.rb-plugin
(20:12:39) [0x6c5040] [rb_plugins_engine_load_cb] rb-plugins-engine.c:271: Plugin Song Lyrics loaded
(20:12:39) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:108: Loading plugin: /usr/lib/rhythmbox/plugins/daap/daap.rb-plugin
(20:12:39) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:197: Could not find 'Icon' in /usr/lib/rhythmbox/plugins/daap/daap.rb-plugin
(20:12:39) [0x6c5040] [rb_plugins_engine_load_cb] rb-plugins-engine.c:271: Plugin DAAP Music Sharing loaded
(20:12:39) [0x6c5040] [rb_module_init] rb-module.c:132: RBModule 0xc135e0 initialising
(20:12:39) [0x6c5040] [rb_module_load] rb-module.c:64: Loading /usr/lib/rhythmbox/plugins/daap/libdaap.so
(20:12:39) [0x6c5040] [register_rb_plugin] rb-daap-plugin.c:100: Registering plugin RBDaapPlugin
(20:12:39) [0x6c5040] [rb_module_new_object] rb-module.c:117: Creating object of type RBDaapPlugin
(20:12:39) [0x6c5040] [rb_daap_plugin_init] rb-daap-plugin.c:159: RBDaapPlugin initialising
(20:12:39) [0x6c5040] [rb_plugin_find_file] rb-plugin.c:255: found '/usr/lib/rhythmbox/plugins/daap/daap-ui.xml' when searching for file 'daap-ui.xml' for plugin 'daap'
(20:12:39) [0x6c5040] [create_share] rb-daap-sharing.c:67: initialize daap sharing
(20:12:39) [0x6c5040] [rb_daap_share_server_start] rb-daap-share.c:1663: Started DAAP server on port 3689
(20:12:39) [0x6c5040] [rb_daap_share_publish_start] rb-daap-share.c:1787: Published DAAP server information to mdns
(20:12:39) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:108: Loading plugin: /usr/lib/rhythmbox/plugins/iradio/iradio.rb-plugin
(20:12:39) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:197: Could not find 'Icon' in /usr/lib/rhythmbox/plugins/iradio/iradio.rb-plugin
(20:12:39) [0x6c5040] [rb_plugins_engine_load_cb] rb-plugins-engine.c:271: Plugin Internet Radio loaded
(20:12:39) [0x6c5040] [rb_module_init] rb-module.c:132: RBModule 0xccb400 initialising
(20:12:39) [0x6c5040] [rb_module_load] rb-module.c:64: Loading /usr/lib/rhythmbox/plugins/iradio/libiradio.so
(20:12:39) [0x6c5040] [register_rb_plugin] rb-iradio-plugin.c:68: Registering plugin RBIRadioPlugin
(20:12:39) [0x6c5040] [rb_module_new_object] rb-module.c:117: Creating object of type RBIRadioPlugin
(20:12:39) [0x6c5040] [rb_iradio_plugin_init] rb-iradio-plugin.c:73: RBIRadioPlugin initialising
(20:12:39) [0x6c5040] [rhythmdb_tree_entry_type_registered] rhythmdb-tree.c:2603: no entries of newly registered type iradio loaded from db
(20:12:39) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0xbf83d0 chaining to base model (nil)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbf8490 (Title)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbf8550 (Genre)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Genre:0
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbf8610 (Rating)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Genre:0
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbf86d0 (Last Played)
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Genre:0
(20:12:39) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:39) [0x6c5040] [rb_iradio_source_state_prefs_sync] rb-iradio-source.c:661: syncing state
(20:12:39) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0xcd80d0 chaining to base model (nil)
(20:12:39) [0x6c5040] [rhythmdb_read_enter] rhythmdb.c:907: counter: 4
(20:12:39) [0x6c5040] [rhythmdb_query_internal] rhythmdb.c:3391: doing query
(20:12:39) [0x6c5040] [do_query_recurse] rhythmdb-tree.c:2164: doing recursive query, 1 conjunctions
(20:12:39) [0x6c5040] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:1918: adding 0 entries
(20:12:39) [0x6c5040] [idle_process_update] rhythmdb-query-model.c:1039: inserting 0 rows
(20:12:39) [0x6c5040] [rhythmdb_query_internal] rhythmdb.c:3397: completed
(20:12:39) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0xbf83d0
(20:12:39) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0xbf83d0
(20:12:39) [0x6c5040] [rb_sourcelist_append] rb-sourcelist.c:1053: inserting source 0xc5cc00 to group library
(20:12:39) [0x6c5040] [rb_plugin_find_file] rb-plugin.c:255: found '/usr/lib/rhythmbox/plugins/iradio/iradio-ui.xml' when searching for file 'iradio-ui.xml' for plugin 'iradio'
(20:12:39) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:108: Loading plugin: /usr/lib/rhythmbox/plugins/cd-recorder/cd-recorder.rb-plugin
(20:12:39) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:197: Could not find 'Icon' in /usr/lib/rhythmbox/plugins/cd-recorder/cd-recorder.rb-plugin
(20:12:39) [0x6c5040] [rb_plugins_engine_load_cb] rb-plugins-engine.c:271: Plugin Audio CD Recorder loaded
(20:12:39) [0x6c5040] [rb_module_init] rb-module.c:132: RBModule 0xccb4c0 initialising
(20:12:39) [0x6c5040] [rb_module_load] rb-module.c:64: Loading /usr/lib/rhythmbox/plugins/cd-recorder/libcd-recorder.so
(20:12:39) [0x6c5040] [register_rb_plugin] rb-cd-recorder-plugin.c:91: Registering plugin RBCdRecorderPlugin
(20:12:39) [0x6c5040] [rb_module_new_object] rb-module.c:117: Creating object of type RBCdRecorderPlugin
(20:12:39) [0x6c5040] [rb_cd_recorder_plugin_init] rb-cd-recorder-plugin.c:108: RBCdRecorderPlugin initializing
(20:12:39) [0x6c5040] [impl_activate] rb-cd-recorder-plugin.c:325: RBCdRecorderPlugin activating
(20:12:39) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:108: Loading plugin: /usr/lib/rhythmbox/plugins/audioscrobbler/audioscrobbler.rb-plugin
(20:12:39) [0x6c5040] [rb_plugins_engine_load_cb] rb-plugins-engine.c:271: Plugin Last.fm loaded
(20:12:39) [0x6c5040] [rb_module_init] rb-module.c:132: RBModule 0xccb520 initialising
(20:12:39) [0x6c5040] [rb_module_load] rb-module.c:64: Loading /usr/lib/rhythmbox/plugins/audioscrobbler/libaudioscrobbler.so
(20:12:39) [0x6c5040] [register_rb_plugin] rb-audioscrobbler-plugin.c:74: Registering plugin RBAudioscrobblerPlugin
(20:12:39) [0x6c5040] [rb_module_new_object] rb-module.c:117: Creating object of type RBAudioscrobblerPlugin
(20:12:39) [0x6c5040] [rb_audioscrobbler_plugin_init] rb-audioscrobbler-plugin.c:92: RBAudioscrobblerPlugin initialising
(20:12:39) [0x6c5040] [rb_audioscrobbler_init] rb-audioscrobbler.c:303: Initialising Audioscrobbler
(20:12:39) [0x6c5040] [rb_audioscrobbler_init] rb-audioscrobbler.c:305: Plugin ID: rbx, Version 0.11.2 (Protocol 1.1)
(20:12:39) [0x6c5040] [rb_audioscrobbler_load_queue] rb-audioscrobbler.c:1468: Loading Audioscrobbler queue from "file:///home/thoemy/.gnome2/rhythmbox/audioscrobbler.queue"
(20:12:39) [0x6c5040] [rb_audioscrobbler_add_timeout] rb-audioscrobbler.c:482: Adding Audioscrobbler timer (15 seconds)
(20:12:39) [0x6c5040] [rb_plugin_find_file] rb-plugin.c:255: found '/usr/lib/rhythmbox/plugins/audioscrobbler/audioscrobbler-ui.xml' when searching for file 'audioscrobbler-ui.xml' for plugin 'audioscrobbler'
(20:12:39) [0x6c5040] [rhythmdb_tree_entry_type_registered] rhythmdb-tree.c:2603: no entries of newly registered type lastfm-station loaded from db
(20:12:39) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0xcd83d0 chaining to base model (nil)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xcd8490 (Title)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xcd8550 (Rating)
(20:12:39) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xbf83d0 (Last Played)
(20:12:39) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0xcd8610 chaining to base model (nil)
(20:12:39) [0x6c5040] [rhythmdb_read_enter] rhythmdb.c:907: counter: 5
(20:12:39) [0x6c5040] [rhythmdb_query_internal] rhythmdb.c:3391: doing query
(20:12:39) [0x6c5040] [do_query_recurse] rhythmdb-tree.c:2164: doing recursive query, 1 conjunctions
(20:12:39) [0x6c5040] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:1918: adding 0 entries
(20:12:39) [0x6c5040] [idle_process_update] rhythmdb-query-model.c:1039: inserting 0 rows
(20:12:39) [0x6c5040] [rhythmdb_query_internal] rhythmdb.c:3397: completed
(20:12:39) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0xcd83d0
(20:12:39) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0xcd83d0
(20:12:39) [0x6c5040] [rb_lastfm_source_new_station] rb-lastfm-source.c:919: adding lastfm: lastfm://user//neighbours, Neighbour Radio
(20:12:39) [0x6c5040] [rhythmdb_entry_new] rhythmdb.c:1277: emitting entry added
(20:12:39) [0x6c5040] [rb_plugin_find_file] rb-plugin.c:255: found '/usr/lib/rhythmbox/plugins/audioscrobbler/as-icon.png' when searching for file 'as-icon.png' for plugin 'audioscrobbler'
(20:12:39) [0x6c5040] [rb_sourcelist_append] rb-sourcelist.c:1053: inserting source 0xce6000 to group library
(20:12:39) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:108: Loading plugin: /usr/lib/rhythmbox/plugins/visualizer/visualizer.rb-plugin
(20:12:39) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:197: Could not find 'Icon' in /usr/lib/rhythmbox/plugins/visualizer/visualizer.rb-plugin
(20:12:39) [0x6c5040] [rb_plugins_engine_load_cb] rb-plugins-engine.c:271: Plugin Visualization loaded
(20:12:39) [0x6c5040] [rb_module_init] rb-module.c:132: RBModule 0xccb920 initialising
(20:12:39) [0x6c5040] [rb_module_load] rb-module.c:64: Loading /usr/lib/rhythmbox/plugins/visualizer/libvisualizer.so
(20:12:39) [0x6c5040] [register_rb_plugin] rb-visualizer-plugin.c:249: Registering plugin RBVisualizerPlugin
(20:12:39) [0x6c5040] [rb_module_new_object] rb-module.c:117: Creating object of type RBVisualizerPlugin
(20:12:39) [0x6c5040] [rb_visualizer_plugin_init] rb-visualizer-plugin.c:257: RBVisualizerPlugin initialising
(20:12:39) [0x6c5040] [impl_activate] rb-visualizer-plugin.c:1605: using tee-based visualization
(20:12:40) [0x6c5040] [rb_plugin_find_file] rb-plugin.c:255: found '/usr/lib/rhythmbox/plugins/visualizer/visualizer-ui.xml' when searching for file 'visualizer-ui.xml' for plugin 'visualizer'
(20:12:40) [0x6c5040] [rb_plugin_find_file] rb-plugin.c:255: found '/usr/lib/rhythmbox/plugins/visualizer/visualizer-controls.glade' when searching for file 'visualizer-controls.glade' for plugin 'visualizer'
(20:12:40) [0x6c5040] [get_vis_plugin_list] rb-visualizer-plugin.c:1880: building vis plugin list
(20:12:40) [0x6c5040] [get_vis_plugin_list] rb-visualizer-plugin.c:1893: adding visualizer element: Monoscope (monoscope)
(20:12:40) [0x6c5040] [get_vis_plugin_list] rb-visualizer-plugin.c:1893: adding visualizer element: GOOM: what a GOOM! (goom)
(20:12:40) [0x6c5040] [get_vis_plugin_list] rb-visualizer-plugin.c:1893: adding visualizer element: libvisual oinksie plugin plugin v.0.1 (libvisual_oinksie)
(20:12:40) [0x6c5040] [get_vis_plugin_list] rb-visualizer-plugin.c:1893: adding visualizer element: libvisual libvisual scope plugin v.0.1 (libvisual_lv_scope)
(20:12:40) [0x6c5040] [get_vis_plugin_list] rb-visualizer-plugin.c:1893: adding visualizer element: libvisual libvisual analyzer plugin v.1.0 (libvisual_lv_analyzer)
(20:12:40) [0x6c5040] [get_vis_plugin_list] rb-visualizer-plugin.c:1893: adding visualizer element: libvisual jess plugin plugin v.0.1 (libvisual_jess)
(20:12:40) [0x6c5040] [get_vis_plugin_list] rb-visualizer-plugin.c:1893: adding visualizer element: libvisual Jakdaw plugin plugin v.0.0.1 (libvisual_jakdaw)
(20:12:40) [0x6c5040] [get_vis_plugin_list] rb-visualizer-plugin.c:1893: adding visualizer element: libvisual infinite plugin plugin v.0.1 (libvisual_infinite)
(20:12:40) [0x6c5040] [get_vis_plugin_list] rb-visualizer-plugin.c:1893: adding visualizer element: libvisual libvisual corona plugin plugin v.0.1 (libvisual_corona)
(20:12:40) [0x6c5040] [get_vis_plugin_list] rb-visualizer-plugin.c:1893: adding visualizer element: libvisual Bumpscope plugin plugin v.0.0.1 (libvisual_bumpscope)
(20:12:40) [0x6c5040] [populate_combo_boxes] rb-visualizer-plugin.c:2031: populating screen selection combo box with 1 screens
(20:12:40) [0x6c5040] [populate_combo_boxes] rb-visualizer-plugin.c:2052: populating screen selection combo box with 1 monitors from screen 0
(20:12:40) [0x6c5040] [populate_combo_boxes] rb-visualizer-plugin.c:2059: appending <0,0> to store
(20:12:40) [0x6c5040] [populate_combo_boxes] rb-visualizer-plugin.c:2068: current output is on 0.0, id 0
(20:12:40) [0x6c5040] [screen_list_cell_data] rb-visualizer-plugin.c:1942: displaying 0.0 (0x7fff7bb45cf0)
(20:12:40) [0x6c5040] [can_draw_on_desktop] rb-visualizer-plugin.c:352: desktop manager exists: probably can't draw on desktop
(20:12:40) [0x6c5040] [show_controls] rb-visualizer-plugin.c:826: showing controls
(20:12:40) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:108: Loading plugin: /usr/lib/rhythmbox/plugins/upnp_coherence/coherence.rb-plugin
(20:12:40) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:197: Could not find 'Icon' in /usr/lib/rhythmbox/plugins/upnp_coherence/coherence.rb-plugin
(20:12:40) [0x6c5040] [rb_plugins_engine_load_cb] rb-plugins-engine.c:271: Plugin UPnP sharing support loaded
(20:12:40) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:108: Loading plugin: /usr/lib/rhythmbox/plugins/power-manager/power-manager.rb-plugin
(20:12:40) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:197: Could not find 'Icon' in /usr/lib/rhythmbox/plugins/power-manager/power-manager.rb-plugin
(20:12:40) [0x6c5040] [rb_plugins_engine_load_cb] rb-plugins-engine.c:271: Plugin Power Manager loaded
(20:12:40) [0x6c5040] [rb_module_init] rb-module.c:132: RBModule 0xd461e0 initialising
(20:12:40) [0x6c5040] [rb_module_load] rb-module.c:64: Loading /usr/lib/rhythmbox/plugins/power-manager/libpower-manager.so
(20:12:40) [0x6c5040] [register_rb_plugin] rb-power-manager-plugin.c:64: Registering plugin RBGPMPlugin
(20:12:40) [0x6c5040] [rb_module_new_object] rb-module.c:117: Creating object of type RBGPMPlugin
(20:12:40) [0x6c5040] [rb_gpm_plugin_init] rb-power-manager-plugin.c:77: RBGPMPlugin initialising
(20:12:40) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:108: Loading plugin: /usr/lib/rhythmbox/plugins/mmkeys/mmkeys.rb-plugin
(20:12:40) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:197: Could not find 'Icon' in /usr/lib/rhythmbox/plugins/mmkeys/mmkeys.rb-plugin
(20:12:40) [0x6c5040] [rb_plugins_engine_load_cb] rb-plugins-engine.c:271: Plugin Media Player Keys loaded
(20:12:40) [0x6c5040] [rb_module_init] rb-module.c:132: RBModule 0xd46240 initialising
(20:12:40) [0x6c5040] [rb_module_load] rb-module.c:64: Loading /usr/lib/rhythmbox/plugins/mmkeys/libmmkeys.so
(20:12:40) [0x6c5040] [register_rb_plugin] rb-mmkeys-plugin.c:78: Registering plugin RBMMKeysPlugin
(20:12:40) [0x6c5040] [rb_module_new_object] rb-module.c:117: Creating object of type RBMMKeysPlugin
(20:12:40) [0x6c5040] [rb_mmkeys_plugin_init] rb-mmkeys-plugin.c:85: RBMMKeysPlugin initialising
(20:12:40) [0x6c5040] [impl_activate] rb-mmkeys-plugin.c:285: activating media player keys plugin
(20:12:40) [0x6c5040] [impl_activate] rb-mmkeys-plugin.c:310: created dbus proxy for org.gnome.SettingsDaemon; grabbing keys
(20:12:40) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:108: Loading plugin: /usr/lib/rhythmbox/plugins/generic-player/generic-player.rb-plugin
(20:12:40) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:197: Could not find 'Icon' in /usr/lib/rhythmbox/plugins/generic-player/generic-player.rb-plugin
(20:12:40) [0x6c5040] [rb_plugins_engine_load_cb] rb-plugins-engine.c:271: Plugin Portable Players loaded
(20:12:40) [0x6c5040] [rb_module_init] rb-module.c:132: RBModule 0xd46360 initialising
(20:12:40) [0x6c5040] [rb_module_load] rb-module.c:64: Loading /usr/lib/rhythmbox/plugins/generic-player/libgeneric-player.so
(20:12:40) [0x6c5040] [register_rb_plugin] rb-generic-player-plugin.c:78: Registering plugin RBGenericPlayerPlugin
(20:12:40) [0x6c5040] [rb_module_new_object] rb-module.c:117: Creating object of type RBGenericPlayerPlugin
(20:12:40) [0x6c5040] [rb_generic_player_plugin_init] rb-generic-player-plugin.c:101: RBGenericPlayerPlugin initialising
(20:12:40) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:108: Loading plugin: /usr/lib/rhythmbox/plugins/artdisplay/artdisplay.rb-plugin
(20:12:40) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:197: Could not find 'Icon' in /usr/lib/rhythmbox/plugins/artdisplay/artdisplay.rb-plugin
(20:12:40) [0x6c5040] [rb_plugins_engine_load_cb] rb-plugins-engine.c:271: Plugin Cover art loaded
(20:12:40) [0x6c5040] [rb_python_module_init] rb-python-module.c:368: Init of python module
(20:12:40) [0x6c5040] [rb_python_object_get_type] rb-python-plugin.c:254: Registering python plugin instance: ArtDisplayPlugin+RBPythonPlugin
(20:12:40) [0x6c5040] [rb_python_module_new_object] rb-python-module.c:343: Creating object of type ArtDisplayPlugin+RBPythonPlugin
(20:12:40) [0x6c5040] [rb_python_object_init] rb-python-plugin.c:190: Creating python plugin instance
(20:12:40) [0x6c5040] [rb_plugin_find_file] rb-plugin.c:255: found '/usr/lib/rhythmbox/plugins/artdisplay/rhythmbox-missing-artwork.svg' when searching for file 'rhythmbox-missing-artwork.svg' for plugin 'artdisplay'
(20:12:40) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:108: Loading plugin: /usr/lib/rhythmbox/plugins/audiocd/audiocd.rb-plugin
(20:12:40) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:197: Could not find 'Icon' in /usr/lib/rhythmbox/plugins/audiocd/audiocd.rb-plugin
(20:12:40) [0x6c5040] [rb_plugins_engine_load_cb] rb-plugins-engine.c:271: Plugin Audio CD Player loaded
(20:12:40) [0x6c5040] [rb_module_init] rb-module.c:132: RBModule 0xd85520 initialising
(20:12:40) [0x6c5040] [rb_module_load] rb-module.c:64: Loading /usr/lib/rhythmbox/plugins/audiocd/libaudiocd.so
(20:12:40) [0x6c5040] [register_rb_plugin] rb-audiocd-plugin.c:101: Registering plugin RBAudioCdPlugin
(20:12:40) [0x6c5040] [rb_module_new_object] rb-module.c:117: Creating object of type RBAudioCdPlugin
(20:12:40) [0x6c5040] [rb_audiocd_plugin_init] rb-audiocd-plugin.c:120: RBAudioCdPlugin initialising
(20:12:40) [0x6c5040] [rb_plugin_find_file] rb-plugin.c:255: found '/usr/lib/rhythmbox/plugins/audiocd/audiocd-ui.xml' when searching for file 'audiocd-ui.xml' for plugin 'audiocd'
(20:12:40) [0x6c5040] [impl_activate] rb-audiocd-plugin.c:567: found volume for /dev/hdd
(20:12:40) [0x6c5040] [rb_audiocd_plugin_mount_volume] rb-audiocd-plugin.c:155: checking audiocd for udev
(20:12:40) [0x6c5040] [impl_activate] rb-audiocd-plugin.c:567: found volume for /dev/hdc
(20:12:40) [0x6c5040] [rb_audiocd_plugin_mount_volume] rb-audiocd-plugin.c:155: checking audiocd for udev
(20:12:40) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:108: Loading plugin: /usr/lib/rhythmbox/plugins/mtpdevice/mtpdevice.rb-plugin
(20:12:40) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:197: Could not find 'Icon' in /usr/lib/rhythmbox/plugins/mtpdevice/mtpdevice.rb-plugin
(20:12:40) [0x6c5040] [rb_plugins_engine_load_cb] rb-plugins-engine.c:271: Plugin Portable Players - MTP loaded
(20:12:40) [0x6c5040] [rb_module_init] rb-module.c:132: RBModule 0xd85580 initialising
(20:12:40) [0x6c5040] [rb_module_load] rb-module.c:64: Loading /usr/lib/rhythmbox/plugins/mtpdevice/libmtpdevice.so
(20:12:40) [0x6c5040] [register_rb_plugin] rb-mtp-plugin.c:95: Registering plugin RBMtpPlugin
(20:12:40) [0x6c5040] [rb_module_new_object] rb-module.c:117: Creating object of type RBMtpPlugin
(20:12:40) [0x6c5040] [rb_mtp_plugin_init] rb-mtp-plugin.c:125: RBMtpPlugin initialising
(20:12:40) [0x6c5040] [rb_plugin_find_file] rb-plugin.c:255: found '/usr/lib/rhythmbox/plugins/mtpdevice/mtp-ui.xml' when searching for file 'mtp-ui.xml' for plugin 'mtpdevice'
(20:12:40) [0x6c5040] [rb_mtp_plugin_setup_dbus_hal_connection] rb-mtp-plugin.c:379: connected to: :1.118
(20:12:46) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:108: Loading plugin: /usr/lib/rhythmbox/plugins/jamendo/jamendo.rb-plugin
(20:12:46) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:197: Could not find 'Icon' in /usr/lib/rhythmbox/plugins/jamendo/jamendo.rb-plugin
(20:12:46) [0x6c5040] [rb_plugins_engine_load_cb] rb-plugins-engine.c:271: Plugin Jamendo loaded
(20:12:46) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:108: Loading plugin: /usr/lib/rhythmbox/plugins/lirc/lirc.rb-plugin
(20:12:46) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:197: Could not find 'Icon' in /usr/lib/rhythmbox/plugins/lirc/lirc.rb-plugin
(20:12:46) [0x6c5040] [rb_plugins_engine_load_cb] rb-plugins-engine.c:271: Plugin LIRC  loaded
(20:12:46) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:108: Loading plugin: /usr/lib/rhythmbox/plugins/ipod/ipod.rb-plugin
(20:12:46) [0x6c5040] [rb_plugins_engine_load] rb-plugins-engine.c:197: Could not find 'Icon' in /usr/lib/rhythmbox/plugins/ipod/ipod.rb-plugin
(20:12:46) [0x6c5040] [rb_plugins_engine_load_cb] rb-plugins-engine.c:271: Plugin Portable Players - iPod loaded
(20:12:46) [0x6c5040] [rb_shell_constructor] rb-shell.c:1365: loading database
(20:12:46) [0x6c5040] [rb_shell_constructor] rb-shell.c:1368: shell: syncing window state
(20:12:46) [0xc7c0e0] [rhythmdb_tree_parser_end_element] rhythmdb-tree.c:501: found entry with duplicate location lastfm://user//neighbours. merging metadata
(20:12:46) [0xc7c0e0] [queue_stat_uri] rhythmdb.c:2118: queueing stat for "file:///tmp/file2.mp3"
(20:12:46) [0xc7c0e0] [queue_stat_uri] rhythmdb.c:2118: queueing stat for "file:///tmp/file3.mp3"
(20:12:46) [0xc7c0e0] [queue_stat_uri] rhythmdb.c:2118: queueing stat for "file:///tmp/file1.mp3"
(20:12:46) [0xc7c0e0] [rhythmdb_load_thread_main] rhythmdb.c:2484: queuing db load complete signal
(20:12:46) [0xc7c0e0] [rhythmdb_load_thread_main] rhythmdb.c:2489: exiting
(20:12:46) [0x6c5040] [rb_shell_set_visibility] rb-shell.c:1511: showing main window
(20:12:46) [0x6c5040] [paned_size_allocate_cb] rb-shell.c:2742: paned position 239
(20:12:46) [0x6c5040] [sidebar_paned_size_allocate_cb] rb-shell.c:2752: sidebar paned position 300
(20:12:46) [0x6c5040] [paned_size_allocate_cb] rb-browser-source.c:723: paned size allocate
(20:12:46) [0x6c5040] [paned_size_allocate_cb] rb-podcast-source.c:950: paned size allocate
(20:12:46) [0x6c5040] [paned_size_allocate_cb] rb-iradio-source.c:653: paned size allocate
(20:12:46) [0x6c5040] [paned_size_allocate_cb] rb-shell.c:2742: paned position 239
(20:12:46) [0x6c5040] [sidebar_paned_size_allocate_cb] rb-shell.c:2752: sidebar paned position 300
(20:12:46) [0x6c5040] [paned_size_allocate_cb] rb-browser-source.c:723: paned size allocate
(20:12:46) [0x6c5040] [paned_size_allocate_cb] rb-podcast-source.c:950: paned size allocate
(20:12:46) [0x6c5040] [paned_size_allocate_cb] rb-iradio-source.c:653: paned size allocate
(20:12:46) [0x6c5040] [rb_shell_present] rb-shell.c:3378: presenting with timestamp 0
(20:12:46) [0x6c5040] [rb_tray_icon_show_window_changed_cb] rb-tray-icon.c:568: show window clicked for 0x8482a0
(20:12:46) [0x6c5040] [shell_selected_source_notify_cb] rb-cd-recorder-plugin.c:302: RBCdRecorderPlugin selected source changed
(20:12:46) [0x6c5040] [actually_hide_controls] rb-visualizer-plugin.c:786: hiding controls
(20:12:46) [0x6c5040] [published_cb] rb-daap-share.c:210: mDNS publish successful
(20:12:46) [0x6c5040] [browse_cb] rb-daap-mdns-browser-avahi.c:278: Ignoring local service Musik von Thoemy
(20:12:46) [0x6c5040] [browse_cb] rb-daap-mdns-browser-avahi.c:278: Ignoring local service Musik von Thoemy
(20:12:46) [0x6c5040] [rb_shell_window_configure_cb] rb-shell.c:1564: storing window size of 1028:684
(20:12:46) [0x6c5040] [rb_shell_window_configure_cb] rb-shell.c:1564: storing window size of 1028:684
(20:12:46) [0x6c5040] [window_focus_cb] rb-mmkeys-plugin.c:117: window got focus, re-grabbing media keys
(20:12:46) [0x6c5040] [paned_size_allocate_cb] rb-shell.c:2742: paned position 239
(20:12:46) [0x6c5040] [sidebar_paned_size_allocate_cb] rb-shell.c:2752: sidebar paned position 300
(20:12:46) [0x6c5040] [paned_size_allocate_cb] rb-browser-source.c:723: paned size allocate
(20:12:46) [0x6c5040] [paned_size_allocate_cb] rb-podcast-source.c:950: paned size allocate
(20:12:46) [0x6c5040] [paned_size_allocate_cb] rb-iradio-source.c:653: paned size allocate
(20:12:46) [0x6c5040] [rb_browser_source_state_pref_changed] rb-browser-source.c:735: state prefs changed
(20:12:46) [0x6c5040] [rb_browser_source_state_prefs_sync] rb-browser-source.c:745: syncing state
(20:12:46) [0x6c5040] [rb_browser_source_state_pref_changed] rb-browser-source.c:735: state prefs changed
(20:12:46) [0x6c5040] [rb_browser_source_state_prefs_sync] rb-browser-source.c:745: syncing state
(20:12:46) [0x6c5040] [rb_statusbar_sync_status] rb-statusbar.c:417: updating status with: '0 songs', '', -1.000000
(20:12:46) [0x6c5040] [rb_shell_clipboard_entryview_changed_cb] rb-shell-clipboard.c:743: entryview changed
(20:12:46) [0x6c5040] [rb_shell_clipboard_entryview_changed_cb] rb-shell-clipboard.c:743: entryview changed
(20:12:46) [0x6c5040] [idle_process_update] rhythmdb-query-model.c:1039: inserting 0 rows
(20:12:46) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:46) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:46) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:46) [0x6c5040] [idle_process_update] rhythmdb-query-model.c:1039: inserting 0 rows
(20:12:46) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:609: adding new property "Unknown"
(20:12:46) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:609: adding new property "Unknown"
(20:12:46) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:609: adding new property "Unknown"
(20:12:46) [0x6c5040] [rb_entry_view_row_inserted_cb] rb-entry-view.c:1731: row added
(20:12:46) [0x6c5040] [rb_shell_clipboard_entries_changed_cb] rb-shell-clipboard.c:751: entryview changed
(20:12:46) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 2
(20:12:46) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 2
(20:12:46) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 2
(20:12:46) [0x6c5040] [rb_entry_view_row_inserted_cb] rb-entry-view.c:1731: row added
(20:12:46) [0x6c5040] [rb_shell_clipboard_entries_changed_cb] rb-shell-clipboard.c:751: entryview changed
(20:12:46) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:46) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:46) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:46) [0x6c5040] [rb_entry_view_row_inserted_cb] rb-entry-view.c:1731: row added
(20:12:46) [0x6c5040] [rb_shell_clipboard_entries_changed_cb] rb-shell-clipboard.c:751: entryview changed
(20:12:46) [0x6c5040] [rb_entry_view_row_inserted_cb] rb-entry-view.c:1731: row added
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=ext3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=/dev/sda1
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=/boot (972,7 MB)
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=/org/freedesktop/Hal/devices/volume_uuid_2681e68b_abbb_42a8_b624_9e243252a629
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:169: Nokia detection: info.parent=/org/freedesktop/Hal/devices/storage_serial_SS0VVJ1NP300446
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:174: Nokia detection: info.vendor=ATA
(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1065: searching for player udi from /org/freedesktop/Hal/devices/volume_uuid_2681e68b_abbb_42a8_b624_9e243252a629
(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1074: parent of udi /org/freedesktop/Hal/devices/volume_uuid_2681e68b_abbb_42a8_b624_9e243252a629: /org/freedesktop/Hal/devices/storage_serial_SS0VVJ1NP300446
(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1074: parent of udi /org/freedesktop/Hal/devices/storage_serial_SS0VVJ1NP300446: /org/freedesktop/Hal/devices/pci_197b_2360_scsi_host_scsi_device_lun0
(20:12:46) [0x6c5040] [free_dbus_error] rb-generic-player-source.c:1103: finding audio player udi: dbus error: No property info.capabilities on device with id /org/freedesktop/Hal/devices/pci_197b_2360_scsi_host_scsi_device_lun0
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=rpc_pipefs
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=rpc_pipefs
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=rpc_pipefs
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=(null)
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=fusectl
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=fusectl
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=connections
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=(null)
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=sysfs
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=none
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=sys
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=(null)
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=binfmt_misc
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=binfmt_misc
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=binfmt_misc
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=(null)
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=usbfs
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=usbfs
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=usb
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=(null)
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=proc
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=none
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=proc
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=(null)
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=tmpfs
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=tmpfs
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=rw
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=(null)
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=ext3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=/dev/mapper/thoemy--vg-home
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=home
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=(null)
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=tmpfs
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=tmpfs
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=shm
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=(null)
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=devpts
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=devpts
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=pts
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=(null)
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=tmpfs
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=udev
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=dev
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=(null)
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=rootfs
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=rootfs
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=Root-Datentr?ger
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=(null)
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=ext3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=/dev/mapper/thoemy--vg-root
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=Root-Datentr?ger
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=(null)
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=vfat
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=/dev/hda8
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=PLATZ
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=/org/freedesktop/Hal/devices/volume_uuid_4338_3C25
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:169: Nokia detection: info.parent=/org/freedesktop/Hal/devices/storage_serial_VNR43EC4GYH14R
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:174: Nokia detection: info.vendor=(null)
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:201: Error: No property info.vendor on device with id /org/freedesktop/Hal/devices/storage_serial_VNR43EC4GYH14R

(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1065: searching for player udi from /org/freedesktop/Hal/devices/volume_uuid_4338_3C25
(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1074: parent of udi /org/freedesktop/Hal/devices/volume_uuid_4338_3C25: /org/freedesktop/Hal/devices/storage_serial_VNR43EC4GYH14R
(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1074: parent of udi /org/freedesktop/Hal/devices/storage_serial_VNR43EC4GYH14R: /org/freedesktop/Hal/devices/pci_10b9_5229_ide_0_0
(20:12:46) [0x6c5040] [free_dbus_error] rb-generic-player-source.c:1103: finding audio player udi: dbus error: No property info.capabilities on device with id /org/freedesktop/Hal/devices/pci_10b9_5229_ide_0_0
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=ntfs
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=/dev/hdb1
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=Zeigs
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=/org/freedesktop/Hal/devices/volume_uuid_5A6C196D6C1944E3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:169: Nokia detection: info.parent=/org/freedesktop/Hal/devices/storage_serial_Y3K2M5SE
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:174: Nokia detection: info.vendor=(null)
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:201: Error: No property info.vendor on device with id /org/freedesktop/Hal/devices/storage_serial_Y3K2M5SE

(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1065: searching for player udi from /org/freedesktop/Hal/devices/volume_uuid_5A6C196D6C1944E3
(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1074: parent of udi /org/freedesktop/Hal/devices/volume_uuid_5A6C196D6C1944E3: /org/freedesktop/Hal/devices/storage_serial_Y3K2M5SE
(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1074: parent of udi /org/freedesktop/Hal/devices/storage_serial_Y3K2M5SE: /org/freedesktop/Hal/devices/pci_10b9_5229_ide_0_1
(20:12:46) [0x6c5040] [free_dbus_error] rb-generic-player-source.c:1103: finding audio player udi: dbus error: No property info.capabilities on device with id /org/freedesktop/Hal/devices/pci_10b9_5229_ide_0_1
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=ext3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=/dev/hda5
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=/
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=/org/freedesktop/Hal/devices/volume_uuid_3df180f0_9868_4ff4_807a_28fa34952b37
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:169: Nokia detection: info.parent=/org/freedesktop/Hal/devices/storage_serial_VNR43EC4GYH14R
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:174: Nokia detection: info.vendor=(null)
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:201: Error: No property info.vendor on device with id /org/freedesktop/Hal/devices/storage_serial_VNR43EC4GYH14R

(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1065: searching for player udi from /org/freedesktop/Hal/devices/volume_uuid_3df180f0_9868_4ff4_807a_28fa34952b37
(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1074: parent of udi /org/freedesktop/Hal/devices/volume_uuid_3df180f0_9868_4ff4_807a_28fa34952b37: /org/freedesktop/Hal/devices/storage_serial_VNR43EC4GYH14R
(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1074: parent of udi /org/freedesktop/Hal/devices/storage_serial_VNR43EC4GYH14R: /org/freedesktop/Hal/devices/pci_10b9_5229_ide_0_0
(20:12:46) [0x6c5040] [free_dbus_error] rb-generic-player-source.c:1103: finding audio player udi: dbus error: No property info.capabilities on device with id /org/freedesktop/Hal/devices/pci_10b9_5229_ide_0_0
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=ntfs
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=/dev/sda5
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=Pool
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=/org/freedesktop/Hal/devices/volume_uuid_240C68CA0C689896
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:169: Nokia detection: info.parent=/org/freedesktop/Hal/devices/storage_serial_SS0VVJ1NP300446
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:174: Nokia detection: info.vendor=ATA
(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1065: searching for player udi from /org/freedesktop/Hal/devices/volume_uuid_240C68CA0C689896
(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1074: parent of udi /org/freedesktop/Hal/devices/volume_uuid_240C68CA0C689896: /org/freedesktop/Hal/devices/storage_serial_SS0VVJ1NP300446
(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1074: parent of udi /org/freedesktop/Hal/devices/storage_serial_SS0VVJ1NP300446: /org/freedesktop/Hal/devices/pci_197b_2360_scsi_host_scsi_device_lun0
(20:12:46) [0x6c5040] [free_dbus_error] rb-generic-player-source.c:1103: finding audio player udi: dbus error: No property info.capabilities on device with id /org/freedesktop/Hal/devices/pci_197b_2360_scsi_host_scsi_device_lun0
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=ext3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=/dev/hda6
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=/home
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=/org/freedesktop/Hal/devices/volume_uuid_2eee32f3_e405_4e7f_91cb_d005955fd1bb
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:169: Nokia detection: info.parent=/org/freedesktop/Hal/devices/storage_serial_VNR43EC4GYH14R
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:174: Nokia detection: info.vendor=(null)
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:201: Error: No property info.vendor on device with id /org/freedesktop/Hal/devices/storage_serial_VNR43EC4GYH14R

(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1065: searching for player udi from /org/freedesktop/Hal/devices/volume_uuid_2eee32f3_e405_4e7f_91cb_d005955fd1bb
(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1074: parent of udi /org/freedesktop/Hal/devices/volume_uuid_2eee32f3_e405_4e7f_91cb_d005955fd1bb: /org/freedesktop/Hal/devices/storage_serial_VNR43EC4GYH14R
(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1074: parent of udi /org/freedesktop/Hal/devices/storage_serial_VNR43EC4GYH14R: /org/freedesktop/Hal/devices/pci_10b9_5229_ide_0_0
(20:12:46) [0x6c5040] [free_dbus_error] rb-generic-player-source.c:1103: finding audio player udi: dbus error: No property info.capabilities on device with id /org/freedesktop/Hal/devices/pci_10b9_5229_ide_0_0
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:424: detecting new media - device type=3
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:425: detecting new media - volume type=0
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:426: detecting new media - fs type=ntfs
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:427: detecting new media - device path=/dev/hda1
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:428: detecting new media - display name=Windows
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:429: detecting new media - hal udi=/org/freedesktop/Hal/devices/volume_uuid_12D48489D48470B1
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:430: detecting new media - icon=gnome-dev-harddisk
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:169: Nokia detection: info.parent=/org/freedesktop/Hal/devices/storage_serial_VNR43EC4GYH14R
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:174: Nokia detection: info.vendor=(null)
(20:12:46) [0x6c5040] [hal_udi_is_nokia770] rb-nokia770-source.c:201: Error: No property info.vendor on device with id /org/freedesktop/Hal/devices/storage_serial_VNR43EC4GYH14R

(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1065: searching for player udi from /org/freedesktop/Hal/devices/volume_uuid_12D48489D48470B1
(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1074: parent of udi /org/freedesktop/Hal/devices/volume_uuid_12D48489D48470B1: /org/freedesktop/Hal/devices/storage_serial_VNR43EC4GYH14R
(20:12:46) [0x6c5040] [get_hal_udi_for_player] rb-generic-player-source.c:1074: parent of udi /org/freedesktop/Hal/devices/storage_serial_VNR43EC4GYH14R: /org/freedesktop/Hal/devices/pci_10b9_5229_ide_0_0
(20:12:46) [0x6c5040] [free_dbus_error] rb-generic-player-source.c:1103: finding audio player udi: dbus error: No property info.capabilities on device with id /org/freedesktop/Hal/devices/pci_10b9_5229_ide_0_0
(20:12:46) [0x6c5040] [rb_generic_player_is_volume_player] rb-generic-player-source.c:615: device is not an audio player
(20:12:46) [0x6c5040] [rb_removable_media_manager_mount_volume] rb-removable-media-manager.c:445: Unhandled media
(20:12:46) [0x6c5040] [rb_shell_clipboard_sync] rb-shell-clipboard.c:543: syncing clipboard
(20:12:46) [0x6c5040] [rb_statusbar_source_status_changed_cb] rb-statusbar.c:505: source status changed
(20:12:46) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2039: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(20:12:46) [0x6c5040] [rhythmdb_read_leave] rhythmdb.c:921: counter: 4
(20:12:46) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2032: processing RHYTHMDB_EVENT_THREAD_EXITED
(20:12:46) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2039: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(20:12:46) [0x6c5040] [rhythmdb_read_leave] rhythmdb.c:921: counter: 3
(20:12:46) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2039: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(20:12:46) [0x6c5040] [rhythmdb_read_leave] rhythmdb.c:921: counter: 2
(20:12:46) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2032: processing RHYTHMDB_EVENT_THREAD_EXITED
(20:12:46) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2039: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(20:12:46) [0x6c5040] [rhythmdb_read_leave] rhythmdb.c:921: counter: 1
(20:12:46) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2039: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(20:12:46) [0x6c5040] [rhythmdb_read_leave] rhythmdb.c:921: counter: 0
(20:12:46) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2018: processing RHYTHMDB_EVENT_DB_LOAD
(20:12:46) [0x6c5040] [idle_handle_load_complete] rb-shell.c:2479: load complete
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0xd83a40 chaining to base model (nil)
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0xd83b00 chaining to base model (nil)
(20:12:46) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0xd83a40
(20:12:46) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0xd83a40
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xd83bc0 ()
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xd83c80 (Track)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xd83d40 (Title)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xd83e00 (Genre)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xd83ec0 (Artist)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x10da000 (Album)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x10da0c0 (Year)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xd83a40 (Time)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x10da180 (Quality)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x10da240 (Rating)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x10da300 (Play Count)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x10da3c0 (Last Played)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x10da480 (Date Added)
(20:12:46) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x112a800 chaining to base model (nil)
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 0 - reusing parent model
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 1 - reusing parent model
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 2 - reusing parent model
(20:12:46) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0xd83b00
(20:12:46) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0xd83b00
(20:12:46) [0x6c5040] [rhythmdb_read_enter] rhythmdb.c:907: counter: 1
(20:12:46) [0x112bfc0] [query_thread_main] rhythmdb.c:3414: entering query thread
(20:12:46) [0x112bfc0] [rhythmdb_query_internal] rhythmdb.c:3391: doing query
(20:12:46) [0x112bfc0] [do_query_recurse] rhythmdb-tree.c:2164: doing recursive query, 1 conjunctions
(20:12:46) [0x112bfc0] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:1918: adding 0 entries
(20:12:46) [0x112bfc0] [rhythmdb_query_internal] rhythmdb.c:3397: completed
(20:12:46) [0x6c5040] [rb_sourcelist_append] rb-sourcelist.c:1053: inserting source 0xadb4c0 to group playlists
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x112a980 chaining to base model (nil)
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x112aa40 chaining to base model (nil)
(20:12:46) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0x112a980
(20:12:46) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0x112a980
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x112ab00 ()
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x112abc0 (Track)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x112ac80 (Title)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x112ad40 (Genre)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x112a980 (Artist)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0xd83b00 (Album)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x112ae00 (Year)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x112aec0 (Time)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x113b830 (Quality)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x113b8f0 (Rating)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x113b9b0 (Play Count)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x113ba70 (Last Played)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x113bb30 (Date Added)
(20:12:46) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x113be30 chaining to base model (nil)
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 0 - reusing parent model
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 1 - reusing parent model
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 2 - reusing parent model
(20:12:46) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0x112aa40
(20:12:46) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0x112aa40
(20:12:46) [0x6c5040] [rhythmdb_read_enter] rhythmdb.c:907: counter: 2
(20:12:46) [0x112bfc0] [query_thread_main] rhythmdb.c:3414: entering query thread
(20:12:46) [0x112bfc0] [rhythmdb_query_internal] rhythmdb.c:3391: doing query
(20:12:46) [0x112bfc0] [do_query_recurse] rhythmdb-tree.c:2164: doing recursive query, 1 conjunctions
(20:12:46) [0x112bfc0] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:1918: adding 0 entries
(20:12:46) [0x112bfc0] [rhythmdb_query_internal] rhythmdb.c:3397: completed
(20:12:46) [0x6c5040] [rb_sourcelist_append] rb-sourcelist.c:1053: inserting source 0xadb640 to group playlists
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x1144830 chaining to base model (nil)
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x11448f0 chaining to base model (nil)
(20:12:46) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0x1144830
(20:12:46) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0x1144830
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11449b0 ()
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1144a70 (Track)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1144830 (Title)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x112aa40 (Genre)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1144b30 (Artist)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1144bf0 (Album)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1144cb0 (Year)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1144d70 (Time)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1144e30 (Quality)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1144ef0 (Rating)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1155840 (Play Count)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1155900 (Last Played)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11559c0 (Date Added)
(20:12:46) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:46) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Artist:0
(20:12:46) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x1155cc0 chaining to base model (nil)
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 0 - reusing parent model
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 1 - reusing parent model
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 2 - reusing parent model
(20:12:46) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0x11448f0
(20:12:46) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0x11448f0
(20:12:46) [0x6c5040] [rhythmdb_read_enter] rhythmdb.c:907: counter: 3
(20:12:46) [0x112bfc0] [query_thread_main] rhythmdb.c:3414: entering query thread
(20:12:46) [0x112bfc0] [rhythmdb_query_internal] rhythmdb.c:3391: doing query
(20:12:46) [0x112bfc0] [do_query_recurse] rhythmdb-tree.c:2164: doing recursive query, 1 conjunctions
(20:12:46) [0x112bfc0] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:1918: adding 0 entries
(20:12:46) [0x112bfc0] [rhythmdb_query_internal] rhythmdb.c:3397: completed
(20:12:46) [0x6c5040] [rb_sourcelist_append] rb-sourcelist.c:1053: inserting source 0xadb7c0 to group playlists
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x1155e40 chaining to base model (nil)
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x1155f00 chaining to base model (nil)
(20:12:46) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0x1155e40
(20:12:46) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0x1155e40
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1155e40 ()
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11448f0 (Track)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1172030 (Title)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11720f0 (Genre)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11721b0 (Artist)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1172270 (Album)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1172330 (Year)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11723f0 (Time)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11724b0 (Quality)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1172570 (Rating)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1172630 (Play Count)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11726f0 (Last Played)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1179030 (Date Added)
(20:12:46) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:46) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Artist:0
(20:12:46) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x1179330 chaining to base model (nil)
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 0 - reusing parent model
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 1 - reusing parent model
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 2 - reusing parent model
(20:12:46) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0x1155f00
(20:12:46) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0x1155f00
(20:12:46) [0x6c5040] [rhythmdb_read_enter] rhythmdb.c:907: counter: 4
(20:12:46) [0x112bfc0] [query_thread_main] rhythmdb.c:3414: entering query thread
(20:12:46) [0x112bfc0] [rhythmdb_query_internal] rhythmdb.c:3391: doing query
(20:12:46) [0x112bfc0] [do_query_recurse] rhythmdb-tree.c:2164: doing recursive query, 1 conjunctions
(20:12:46) [0x112bfc0] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:1918: adding 0 entries
(20:12:46) [0x112bfc0] [rhythmdb_query_internal] rhythmdb.c:3397: completed
(20:12:46) [0x6c5040] [rb_sourcelist_append] rb-sourcelist.c:1053: inserting source 0xadb940 to group playlists
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x1155f00 chaining to base model (nil)
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x11794b0 chaining to base model (nil)
(20:12:46) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0x1155f00
(20:12:46) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0x1155f00
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1179570 ()
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1179630 (Track)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11796f0 (Title)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x118d800 (Genre)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x118d8c0 (Artist)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x118d980 (Album)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x118da40 (Year)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1155f00 (Time)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x118db00 (Quality)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x118dbc0 (Rating)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x118dc80 (Play Count)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x118dd40 (Last Played)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x118de00 (Date Added)
(20:12:46) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:46) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Artist:0
(20:12:46) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x1196190 chaining to base model (nil)
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 0 - reusing parent model
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 1 - reusing parent model
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 2 - reusing parent model
(20:12:46) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0x11794b0
(20:12:46) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0x11794b0
(20:12:46) [0x6c5040] [rhythmdb_read_enter] rhythmdb.c:907: counter: 5
(20:12:46) [0x112bfc0] [query_thread_main] rhythmdb.c:3414: entering query thread
(20:12:46) [0x112bfc0] [rhythmdb_query_internal] rhythmdb.c:3391: doing query
(20:12:46) [0x112bfc0] [do_query_recurse] rhythmdb-tree.c:2164: doing recursive query, 1 conjunctions
(20:12:46) [0x112bfc0] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:1918: adding 0 entries
(20:12:46) [0x112bfc0] [rhythmdb_query_internal] rhythmdb.c:3397: completed
(20:12:46) [0x6c5040] [rb_sourcelist_append] rb-sourcelist.c:1053: inserting source 0xadbac0 to group playlists
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x1196310 chaining to base model (nil)
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x11963d0 chaining to base model (nil)
(20:12:46) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0x1196310
(20:12:46) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0x1196310
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1196490 ()
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1196550 (Track)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1196610 (Title)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11966d0 (Genre)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x1196310 (Artist)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11794b0 (Album)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11b4030 (Year)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11b40f0 (Time)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11b41b0 (Quality)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11b4270 (Rating)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11b4330 (Play Count)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11b43f0 (Last Played)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11b44b0 (Date Added)
(20:12:46) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:46) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Artist:0
(20:12:46) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x11c0010 chaining to base model (nil)
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 0 - reusing parent model
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 1 - reusing parent model
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 2 - reusing parent model
(20:12:46) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0x11963d0
(20:12:46) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0x11963d0
(20:12:46) [0x6c5040] [rhythmdb_read_enter] rhythmdb.c:907: counter: 6
(20:12:46) [0x112bfc0] [query_thread_main] rhythmdb.c:3414: entering query thread
(20:12:46) [0x112bfc0] [rhythmdb_query_internal] rhythmdb.c:3391: doing query
(20:12:46) [0x112bfc0] [do_query_recurse] rhythmdb-tree.c:2164: doing recursive query, 1 conjunctions
(20:12:46) [0x112bfc0] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:1918: adding 3 entries
(20:12:46) [0x112bfc0] [rhythmdb_query_internal] rhythmdb.c:3397: completed
(20:12:46) [0x6c5040] [rb_sourcelist_append] rb-sourcelist.c:1053: inserting source 0xadbc40 to group playlists
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x11c0190 chaining to base model (nil)
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x11c0250 chaining to base model (nil)
(20:12:46) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0x11c0190
(20:12:46) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0x11c0190
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11c0310 ()
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11c03d0 (Track)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11c0190 (Title)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11963d0 (Genre)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11c0490 (Artist)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11c0550 (Album)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11c0610 (Year)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11c06d0 (Time)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11d5820 (Quality)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11d58e0 (Rating)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11d59a0 (Play Count)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11d5a60 (Last Played)
(20:12:46) [0x6c5040] [rb_entry_view_insert_column_custom] rb-entry-view.c:1341: appending column: 0x11d5b20 (Date Added)
(20:12:46) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:46) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:931: Updating EntryView sort order to Artist:0
(20:12:46) [0x6c5040] [rb_entry_view_sync_sorting] rb-entry-view.c:942: emitting sort order changed
(20:12:46) [0x6c5040] [rhythmdb_query_model_chain] rhythmdb-query-model.c:758: query model 0x11d5e20 chaining to base model (nil)
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 0 - reusing parent model
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 1 - reusing parent model
(20:12:46) [0x6c5040] [rebuild_child_model] rb-library-browser.c:576: no selection for browser 2 - reusing parent model
(20:12:46) [0x6c5040] [rhythmdb_query_model_dispose] rhythmdb-query-model.c:633: disposing query model 0x11c0250
(20:12:46) [0x6c5040] [rhythmdb_query_model_finalize] rhythmdb-query-model.c:681: finalizing query model 0x11c0250
(20:12:46) [0x6c5040] [rhythmdb_read_enter] rhythmdb.c:907: counter: 7
(20:12:46) [0x112bfc0] [query_thread_main] rhythmdb.c:3414: entering query thread
(20:12:46) [0x112bfc0] [rhythmdb_query_internal] rhythmdb.c:3391: doing query
(20:12:46) [0x112bfc0] [do_query_recurse] rhythmdb-tree.c:2164: doing recursive query, 1 conjunctions
(20:12:46) [0x112bfc0] [rhythmdb_query_model_add_results] rhythmdb-query-model.c:1918: adding 3 entries
(20:12:46) [0x112bfc0] [rhythmdb_query_internal] rhythmdb.c:3397: completed
(20:12:46) [0x6c5040] [rb_sourcelist_append] rb-sourcelist.c:1053: inserting source 0xadbdc0 to group playlists
(20:12:46) [0x6c5040] [paned_size_allocate_cb] rb-shell.c:2742: paned position 239
(20:12:46) [0x6c5040] [sidebar_paned_size_allocate_cb] rb-shell.c:2752: sidebar paned position 300
(20:12:46) [0x6c5040] [paned_size_allocate_cb] rb-browser-source.c:723: paned size allocate
(20:12:46) [0x6c5040] [paned_size_allocate_cb] rb-podcast-source.c:950: paned size allocate
(20:12:46) [0x6c5040] [paned_size_allocate_cb] rb-iradio-source.c:653: paned size allocate
(20:12:47) [0x6c5040] [rb_statusbar_sync_status] rb-statusbar.c:417: updating status with: '3 songs, 11 minutes, 8.9 MB', '', 999.000000
(20:12:47) [0x6c5040] [idle_process_update] rhythmdb-query-model.c:1039: inserting 0 rows
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [idle_process_update] rhythmdb-query-model.c:1039: inserting 0 rows
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [idle_process_update] rhythmdb-query-model.c:1039: inserting 0 rows
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [idle_process_update] rhythmdb-query-model.c:1039: inserting 0 rows
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [idle_process_update] rhythmdb-query-model.c:1039: inserting 0 rows
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [idle_process_update] rhythmdb-query-model.c:1039: inserting 3 rows
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:609: adding new property "Unknown"
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:609: adding new property "Unknown"
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:609: adding new property "Unknown"
(20:12:47) [0x6c5040] [rb_entry_view_row_inserted_cb] rb-entry-view.c:1731: row added
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 2
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 2
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 2
(20:12:47) [0x6c5040] [rb_entry_view_row_inserted_cb] rb-entry-view.c:1731: row added
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rb_entry_view_row_inserted_cb] rb-entry-view.c:1731: row added
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [idle_process_update] rhythmdb-query-model.c:1039: inserting 3 rows
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:609: adding new property "Unknown"
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:609: adding new property "Unknown"
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:609: adding new property "Unknown"
(20:12:47) [0x6c5040] [rb_entry_view_row_inserted_cb] rb-entry-view.c:1731: row added
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 2
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 2
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 2
(20:12:47) [0x6c5040] [rb_entry_view_row_inserted_cb] rb-entry-view.c:1731: row added
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rb_entry_view_row_inserted_cb] rb-entry-view.c:1731: row added
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [rb_property_view_selection_changed_cb] rb-property-view.c:696: selection changed
(20:12:47) [0x6c5040] [rb_browser_source_state_pref_changed] rb-browser-source.c:735: state prefs changed
(20:12:47) [0x6c5040] [rb_browser_source_state_prefs_sync] rb-browser-source.c:745: syncing state
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2032: processing RHYTHMDB_EVENT_THREAD_EXITED
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2039: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(20:12:47) [0x6c5040] [rhythmdb_read_leave] rhythmdb.c:921: counter: 6
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2032: processing RHYTHMDB_EVENT_THREAD_EXITED
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2039: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(20:12:47) [0x6c5040] [rhythmdb_read_leave] rhythmdb.c:921: counter: 5
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2032: processing RHYTHMDB_EVENT_THREAD_EXITED
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2039: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(20:12:47) [0x6c5040] [rhythmdb_read_leave] rhythmdb.c:921: counter: 4
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2032: processing RHYTHMDB_EVENT_THREAD_EXITED
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2039: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(20:12:47) [0x6c5040] [rhythmdb_read_leave] rhythmdb.c:921: counter: 3
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2032: processing RHYTHMDB_EVENT_THREAD_EXITED
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2039: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(20:12:47) [0x6c5040] [rhythmdb_read_leave] rhythmdb.c:921: counter: 2
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2032: processing RHYTHMDB_EVENT_THREAD_EXITED
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2039: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(20:12:47) [0x6c5040] [rhythmdb_read_leave] rhythmdb.c:921: counter: 1
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2032: processing RHYTHMDB_EVENT_THREAD_EXITED
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2039: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(20:12:47) [0x6c5040] [rhythmdb_read_leave] rhythmdb.c:921: counter: 0
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2032: processing RHYTHMDB_EVENT_THREAD_EXITED
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2006: processing RHYTHMDB_EVENT_STAT
(20:12:47) [0x6c5040] [rhythmdb_process_stat_event] rhythmdb.c:1727: changed: file:///tmp/file1.mp3
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2006: processing RHYTHMDB_EVENT_STAT
(20:12:47) [0x6c5040] [rhythmdb_process_stat_event] rhythmdb.c:1727: changed: file:///tmp/file3.mp3
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2006: processing RHYTHMDB_EVENT_STAT
(20:12:47) [0x6c5040] [rhythmdb_process_stat_event] rhythmdb.c:1727: changed: file:///tmp/file2.mp3
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2043: processing RHYTHMDB_EVENT_FILE_CREATED_OR_MODIFIED
(20:12:47) [0x10f68d0] [action_thread_main] rhythmdb.c:2329: executing RHYTHMDB_ACTION_LOAD for "file:///tmp/file1.mp3"
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2043: processing RHYTHMDB_EVENT_FILE_CREATED_OR_MODIFIED
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2043: processing RHYTHMDB_EVENT_FILE_CREATED_OR_MODIFIED
(20:12:47) [0x61dc00] [rb_debug_init_match] rb-debug.c:153: Debugging enabled
(20:12:47) [0x61dc00] [main] rb-metadata-dbus-service.c:469: initializing metadata service; pid = 8293; address = unix:tmpdir=/tmp
(20:12:47) [0x61dc00] [main] rb-metadata-dbus-service.c:488: D-BUS server listening on address unix:abstract=/tmp/dbus-sSZJaV56fZ,guid=bc94eabe105a2b3a1fc5368847a4c0af
(20:12:47) [0x10f68d0] [start_metadata_service] rb-metadata-dbus-client.c:261: Got metadata helper D-BUS address unix:abstract=/tmp/dbus-sSZJaV56fZ,guid=bc94eabe105a2b3a1fc5368847a4c0af
(20:12:47) [0x10f68d0] [start_metadata_service] rb-metadata-dbus-client.c:277: Metadata process 8293 started
(20:12:47) [0x10f68d0] [rb_metadata_load] rb-metadata-dbus-client.c:373: sending metadata load request
(20:12:47) [0x61dc00] [_new_connection] rb-metadata-dbus-service.c:350: new connection to metadata service
(20:12:47) [0x61dc00] [_handle_message] rb-metadata-dbus-service.c:322: handling metadata service message
(20:12:47) [0x61dc00] [rb_metadata_dbus_load] rb-metadata-dbus-service.c:115: loading metadata from file:///tmp/file1.mp3
(20:12:47) [0x61dc00] [rb_metadata_load] rb-metadata-gst.c:928: loading metadata for uri: file:///tmp/file1.mp3
(20:12:47) [0x61dc00] [rb_metadata_load] rb-metadata-gst.c:985: going to PAUSED for metadata, uri: file:///tmp/file1.mp3
(20:12:47) [0x61dc00] [rb_metadata_gst_typefind_cb] rb-metadata-gst.c:697: found type application/x-id3
(20:12:47) [0x784490] [rb_metadata_gst_new_decoded_pad_cb] rb-metadata-gst.c:729: got decoded audio pad of type audio/x-raw-int
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file1.mp3 tag: title 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file1.mp3 tag: artist 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file1.mp3 tag: album 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file1.mp3 tag: genre 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file1.mp3 tag: private-id3v2-frame 
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file1.mp3 tag: audio-codec 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file1.mp3 tag: bitrate 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file1.mp3 tag: layer 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file1.mp3 tag: mode 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file1.mp3 tag: emphasis 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file1.mp3 tag: bitrate 
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 2097152
(20:12:47) [0x61dc00] [rb_metadata_load] rb-metadata-gst.c:1018: gone to PAUSED for file:///tmp/file1.mp3
(20:12:47) [0x61dc00] [rb_metadata_load] rb-metadata-gst.c:1035: duration query succeeded
(20:12:47) [0x61dc00] [rb_metadata_load] rb-metadata-gst.c:1122: successfully read metadata for file:///tmp/file1.mp3
(20:12:47) [0x61dc00] [rb_metadata_dbus_load] rb-metadata-dbus-service.c:127: metadata load finished; mimetype = application/x-id3
(20:12:47) [0x61dc00] [rb_metadata_dbus_add_to_message] rb-metadata-dbus.c:75: opening container type {uv}
(20:12:47) [0x10f68d0] [rb_metadata_load] rb-metadata-dbus-client.c:409: got mimetype: application/x-id3
(20:12:47) [0x10f68d0] [action_thread_main] rhythmdb.c:2329: executing RHYTHMDB_ACTION_LOAD for "file:///tmp/file3.mp3"
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2010: processing RHYTHMDB_EVENT_METADATA_LOAD
(20:12:47) [0x61dc00] [_handle_message] rb-metadata-dbus-service.c:322: handling metadata service message
(20:12:47) [0x61dc00] [rb_metadata_dbus_ping] rb-metadata-dbus-service.c:279: ping
(20:12:47) [0x10f68d0] [rb_metadata_load] rb-metadata-dbus-client.c:373: sending metadata load request
(20:12:47) [0x61dc00] [_handle_message] rb-metadata-dbus-service.c:322: handling metadata service message
(20:12:47) [0x61dc00] [rb_metadata_dbus_load] rb-metadata-dbus-service.c:115: loading metadata from file:///tmp/file3.mp3
(20:12:47) [0x61dc00] [rb_metadata_load] rb-metadata-gst.c:928: loading metadata for uri: file:///tmp/file3.mp3
(20:12:47) [0x61dc00] [rb_metadata_load] rb-metadata-gst.c:985: going to PAUSED for metadata, uri: file:///tmp/file3.mp3
(20:12:47) [0x61dc00] [rb_metadata_gst_typefind_cb] rb-metadata-gst.c:697: found type application/x-id3
(20:12:47) [0x784490] [rb_metadata_gst_new_decoded_pad_cb] rb-metadata-gst.c:729: got decoded audio pad of type audio/x-raw-int
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file3.mp3 tag: title 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file3.mp3 tag: artist 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file3.mp3 tag: album 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file3.mp3 tag: genre 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file3.mp3 tag: private-id3v2-frame 
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file3.mp3 tag: audio-codec 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file3.mp3 tag: bitrate 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file3.mp3 tag: layer 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file3.mp3 tag: mode 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file3.mp3 tag: emphasis 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file3.mp3 tag: bitrate 
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 2097152
(20:12:47) [0x61dc00] [rb_metadata_load] rb-metadata-gst.c:1018: gone to PAUSED for file:///tmp/file3.mp3
(20:12:47) [0x61dc00] [rb_metadata_load] rb-metadata-gst.c:1035: duration query succeeded
(20:12:47) [0x61dc00] [rb_metadata_load] rb-metadata-gst.c:1122: successfully read metadata for file:///tmp/file3.mp3
(20:12:47) [0x61dc00] [rb_metadata_dbus_load] rb-metadata-dbus-service.c:127: metadata load finished; mimetype = application/x-id3
(20:12:47) [0x61dc00] [rb_metadata_dbus_add_to_message] rb-metadata-dbus.c:75: opening container type {uv}
(20:12:47) [0x10f68d0] [rb_metadata_load] rb-metadata-dbus-client.c:409: got mimetype: application/x-id3
(20:12:47) [0x10f68d0] [action_thread_main] rhythmdb.c:2329: executing RHYTHMDB_ACTION_LOAD for "file:///tmp/file2.mp3"
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2010: processing RHYTHMDB_EVENT_METADATA_LOAD
(20:12:47) [0x61dc00] [_handle_message] rb-metadata-dbus-service.c:322: handling metadata service message
(20:12:47) [0x61dc00] [rb_metadata_dbus_ping] rb-metadata-dbus-service.c:279: ping
(20:12:47) [0x10f68d0] [rb_metadata_load] rb-metadata-dbus-client.c:373: sending metadata load request
(20:12:47) [0x61dc00] [_handle_message] rb-metadata-dbus-service.c:322: handling metadata service message
(20:12:47) [0x61dc00] [rb_metadata_dbus_load] rb-metadata-dbus-service.c:115: loading metadata from file:///tmp/file2.mp3
(20:12:47) [0x61dc00] [rb_metadata_load] rb-metadata-gst.c:928: loading metadata for uri: file:///tmp/file2.mp3
(20:12:47) [0x61dc00] [rb_metadata_load] rb-metadata-gst.c:985: going to PAUSED for metadata, uri: file:///tmp/file2.mp3
(20:12:47) [0x61dc00] [rb_metadata_gst_typefind_cb] rb-metadata-gst.c:697: found type application/x-id3
(20:12:47) [0x784490] [rb_metadata_gst_new_decoded_pad_cb] rb-metadata-gst.c:729: got decoded audio pad of type audio/x-raw-int
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file2.mp3 tag: title 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file2.mp3 tag: artist 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file2.mp3 tag: album 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file2.mp3 tag: genre 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file2.mp3 tag: private-id3v2-frame 
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file2.mp3 tag: audio-codec 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file2.mp3 tag: bitrate 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file2.mp3 tag: layer 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file2.mp3 tag: mode 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file2.mp3 tag: emphasis 
(20:12:47) [0x61dc00] [rb_metadata_gst_load_tag] rb-metadata-gst.c:595: uri: file:///tmp/file2.mp3 tag: bitrate 
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 64
(20:12:47) [0x61dc00] [rb_metadata_bus_handler] rb-metadata-gst.c:860: message of type 2097152
(20:12:47) [0x61dc00] [rb_metadata_load] rb-metadata-gst.c:1018: gone to PAUSED for file:///tmp/file2.mp3
(20:12:47) [0x61dc00] [rb_metadata_load] rb-metadata-gst.c:1035: duration query succeeded
(20:12:47) [0x61dc00] [rb_metadata_load] rb-metadata-gst.c:1122: successfully read metadata for file:///tmp/file2.mp3
(20:12:47) [0x61dc00] [rb_metadata_dbus_load] rb-metadata-dbus-service.c:127: metadata load finished; mimetype = application/x-id3
(20:12:47) [0x61dc00] [rb_metadata_dbus_add_to_message] rb-metadata-dbus.c:75: opening container type {uv}
(20:12:47) [0x10f68d0] [rb_metadata_load] rb-metadata-dbus-client.c:409: got mimetype: application/x-id3
(20:12:47) [0x6c5040] [rhythmdb_process_one_event] rhythmdb.c:2010: processing RHYTHMDB_EVENT_METADATA_LOAD
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_delete_prop] rhythmdb-property-model.c:661: deleting "Unknown": refcount: 3
(20:12:47) [0x6c5040] [rhythmdb_property_model_insert] rhythmdb-property-model.c:600: adding "Unknown": refcount 3
(20:12:47) [0x6c5040] [rb_statusbar_sync_status] rb-statusbar.c:417: updating status with: '3 songs, 11 minutes, 8.9 MB', '', 999.000000
(20:12:49) [0x6c5040] [rb_shell_clipboard_entryview_changed_cb] rb-shell-clipboard.c:743: entryview changed
(20:12:49) [0x6c5040] [rb_shell_clipboard_sync] rb-shell-clipboard.c:543: syncing clipboard
(20:12:49) [0x6c5040] [rb_entry_view_row_activated_cb] rb-entry-view.c:1713: row activated
(20:12:49) [0x6c5040] [rb_entry_view_row_activated_cb] rb-entry-view.c:1717: emitting entry activated
(20:12:49) [0x6c5040] [rb_shell_player_entry_activated_cb] rb-shell-player.c:2329: got entry 0x10b5ba0 activated
(20:12:49) [0x6c5040] [rb_shell_player_set_playing_source_internal] rb-shell-player.c:2732: setting playing source to 0xbbe000
(20:12:49) [0x6c5040] [rb_shell_player_sync_with_source] rb-shell-player.c:2563: playing source: 0xbbe000, active entry: (nil)
(20:12:49) [0x6c5040] [rb_shell_set_window_title] rb-shell.c:2079: clearing title
(20:12:49) [0x6c5040] [rb_shell_player_get_playing_song_duration] rb-shell-player.c:2926: Did not get playing entry : return -1 as length
(20:12:49) [0x6c5040] [show_controls] rb-visualizer-plugin.c:826: showing controls
(20:12:49) [0x6c5040] [rb_shell_player_get_playing_song_duration] rb-shell-player.c:2926: Did not get playing entry : return -1 as length
(20:12:49) [0x6c5040] [rb_header_sync] rb-header.c:355: syncing with entry = (nil)
(20:12:49) [0x6c5040] [rb_header_sync] rb-header.c:426: not playing
(20:12:49) [0x6c5040] [rb_shell_player_sync_buttons] rb-shell-player.c:2666: syncing with source 0xbbe000
(20:12:49) [0x6c5040] [rb_shell_playing_source_changed_cb] rb-shell.c:1889: playing source changed
(20:12:49) [0x6c5040] [create_stream] rb-player-gst-xfade.c:1732: creating new stream for file:///tmp/file1.mp3 (stream data 0x10b5ba0)
(20:12:49) [0x6c5040] [dump_stream_list] rb-player-gst-xfade.c:473: current stream list:
(20:12:49) [0x6c5040] [dump_stream_list] rb-player-gst-xfade.c:494: [waiting] file:///tmp/file1.mp3
(20:12:49) [0x6c5040] [start_sink] rb-player-gst-xfade.c:2302: starting sink
(20:12:49) [0x122f790] [stream_new_decoded_pad_cb] rb-player-gst-xfade.c:1650: got decoded audio pad for stream file:///tmp/file1.mp3
(20:12:49) [0x6c5040] [start_sink] rb-player-gst-xfade.c:2332: silence bin is now in state PAUSED
(20:12:49) [0x6c5040] [start_sink] rb-player-gst-xfade.c:2339: adder is now in state PAUSED
(20:12:50) [0x6c5040] [start_sink] rb-player-gst-xfade.c:2346: output bin is now in state PAUSED
(20:12:50) [0x6c5040] [start_sink] rb-player-gst-xfade.c:2351: unpausing sink
(20:12:50) [0x6c5040] [start_sink] rb-player-gst-xfade.c:2391: sink playing
(20:12:50) [0x6c5040] [rb_player_gst_xfade_play] rb-player-gst-xfade.c:2956: playing stream file:///tmp/file1.mp3, crossfade -1
(20:12:50) [0x6c5040] [rb_player_gst_xfade_play] rb-player-gst-xfade.c:2970: stream file:///tmp/file1.mp3 is prerolling; will start playback once prerolling is complete -> PREROLL_PLAY
(20:12:50) [0x6c5040] [rb_shell_player_set_playing_entry] rb-shell-player.c:1509: Success!
(20:12:50) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag title for stream file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1354: emitting info field 0 for uri file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag artist for stream file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag album for stream file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag genre for stream file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1354: emitting info field 4 for uri file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag private-id3v2-frame for stream file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag audio-codec for stream file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag bitrate for stream file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1354: emitting info field 20 for uri file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag layer for stream file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag mode for stream file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag emphasis for stream file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag bitrate for stream file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1354: emitting info field 20 for uri file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [rb_shell_clipboard_entryview_changed_cb] rb-shell-clipboard.c:743: entryview changed
(20:12:50) [0x6c5040] [rb_shell_clipboard_sync] rb-shell-clipboard.c:543: syncing clipboard
(20:12:50) [0x6c5040] [get_times_and_stream] rb-player-gst-xfade.c:2251: not playing
(20:12:50) [0x1231c50] [stream_src_blocked_cb] rb-player-gst-xfade.c:2112: stream file:///tmp/file1.mp3 is prerolled, need to start it
(20:12:50) [0x1231c50] [actually_start_stream] rb-player-gst-xfade.c:1976: going to start playback for stream file:///tmp/file1.mp3 (crossfade -1) -> FADING_IN | PLAYING
(20:12:50) [0x1231c50] [link_and_unblock_stream] rb-player-gst-xfade.c:1038: linking stream file:///tmp/file1.mp3
(20:12:50) [0x1231c50] [link_unblocked_cb] rb-player-gst-xfade.c:1001: stream file:///tmp/file1.mp3 is unblocked -> FADING_IN | PLAYING
(20:12:50) [0x1231c50] [adjust_stream_base_time] rb-player-gst-xfade.c:840: adjusting base time: 789478458 - 26485250 => 762993208
(20:12:50) [0x1231c50] [post_stream_playing_message] rb-player-gst-xfade.c:796: posting rb-stream-playing message for stream file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [rb_player_gst_xfade_bus_cb] rb-player-gst-xfade.c:1462: got stream playing message for file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [new_playing_stream_idle_cb] rb-shell-player.c:3013: new playing stream: file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [rb_shell_hidden_notify_markup] rb-shell.c:3038: shell is visible, not notifying
(20:12:50) [0x6c5040] [rb_lastfm_song_changed_cb] rb-lastfm-source.c:1320: non-lastfm entry being played
(20:12:50) [0x1231c50] [stream_src_event_cb] rb-player-gst-xfade.c:1703: got new segment for stream file:///tmp/file1.mp3
(20:12:50) [0x1231c50] [adjust_stream_base_time] rb-player-gst-xfade.c:840: adjusting base time: 789478458 - 26485250 => 762993208
(20:12:50) [0x6c5040] [LocalCoverArtSearch.search] /usr/lib/rhythmbox/plugins/artdisplay/LocalCoverArtSearch.py:108: searching for art local to
(20:12:50) [0x6c5040] [LocalCoverArtSearch.search] /usr/lib/rhythmbox/plugins/artdisplay/LocalCoverArtSearch.py:108:  
(20:12:50) [0x6c5040] [LocalCoverArtSearch.search] /usr/lib/rhythmbox/plugins/artdisplay/LocalCoverArtSearch.py:108: file:///tmp/file1.mp3
(20:12:50) [0x6c5040] [rb_shell_player_sync_with_source] rb-shell-player.c:2563: playing source: 0xbbe000, active entry: 0x10b5ba0
(20:12:50) [0x6c5040] [rb_shell_set_window_title] rb-shell.c:2102: setting title to "Unknown - file1"
(20:12:50) [0x6c5040] [show_controls] rb-visualizer-plugin.c:826: showing controls
(20:12:50) [0x6c5040] [rb_header_sync] rb-header.c:355: syncing with entry = 0x10b5ba0
(20:12:50) [0x6c5040] [rb_shell_player_sync_buttons] rb-shell-player.c:2666: syncing with source 0xbbe000
(20:12:50) [0x6c5040] [show_controls] rb-visualizer-plugin.c:826: showing controls
(20:12:50) [0x6c5040] [paned_size_allocate_cb] rb-shell.c:2742: paned position 239
(20:12:50) [0x6c5040] [sidebar_paned_size_allocate_cb] rb-shell.c:2752: sidebar paned position 300
(20:12:50) [0x6c5040] [paned_size_allocate_cb] rb-shell.c:2742: paned position 239
(20:12:50) [0x6c5040] [sidebar_paned_size_allocate_cb] rb-shell.c:2752: sidebar paned position 300
(20:12:50) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 0:223(1)]
(20:12:50) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 0:223(1)]
(20:12:50) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 0:223(1)]
(20:12:51) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 0:223(1)]
(20:12:51) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 0:223(1)]
(20:12:51) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 0:223(1)]
(20:12:51) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 1:223(1)]
(20:12:51) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 1:223(1)]
(20:12:52) [0x6c5040] [AmazonCoverArtSearch.__valid_match] /usr/lib/rhythmbox/plugins/artdisplay/AmazonCoverArtSearch.py:444: http://www.amazon.com/gp/product/B000UMUY86%3ftag=webservices-20%26link_code=xm2%26camp=2025%26dev-t=18C3VZN9HCECM5G3HQG2 doesn't have image URLs; ignoring
(20:12:52) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 1:223(1)]
(20:12:52) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 1:223(1)]
(20:12:52) [0x6c5040] [AmazonCoverArtSearch.__valid_match] /usr/lib/rhythmbox/plugins/artdisplay/AmazonCoverArtSearch.py:444: http://www.amazon.com/gp/product/B000UMUY86%3ftag=webservices-20%26link_code=xm2%26camp=2025%26dev-t=18C3VZN9HCECM5G3HQG2 doesn't have image URLs; ignoring
(20:12:52) [0x6c5040] [AmazonCoverArtSearch.__valid_match] /usr/lib/rhythmbox/plugins/artdisplay/AmazonCoverArtSearch.py:444: http://www.amazon.com/gp/product/B000T8Y7QG%3ftag=webservices-20%26link_code=xm2%26camp=2025%26dev-t=18C3VZN9HCECM5G3HQG2 doesn't have image URLs; ignoring
(20:12:52) [0x6c5040] [AmazonCoverArtSearch.__valid_match] /usr/lib/rhythmbox/plugins/artdisplay/AmazonCoverArtSearch.py:444: http://www.amazon.com/gp/product/B000XGBRIU%3ftag=webservices-20%26link_code=xm2%26camp=2025%26dev-t=18C3VZN9HCECM5G3HQG2 doesn't have image URLs; ignoring
(20:12:52) [0x6c5040] [AmazonCoverArtSearch.__valid_match] /usr/lib/rhythmbox/plugins/artdisplay/AmazonCoverArtSearch.py:444: http://www.amazon.com/gp/product/B000CAAAYE%3ftag=webservices-20%26link_code=xm2%26camp=2025%26dev-t=18C3VZN9HCECM5G3HQG2 doesn't have image URLs; ignoring
(20:12:52) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 1:223(1)]
(20:12:52) [0x6c5040] [slider_moved_callback] rb-header.c:548: slider is not dragging
(20:12:52) [0x6c5040] [slider_moved_callback] rb-header.c:548: slider is not dragging
(20:12:52) [0x6c5040] [slider_moved_callback] rb-header.c:548: slider is not dragging
(20:12:52) [0x6c5040] [slider_moved_callback] rb-header.c:548: slider is not dragging
(20:12:52) [0x6c5040] [slider_moved_callback] rb-header.c:548: slider is not dragging
(20:12:52) [0x6c5040] [AmazonCoverArtSearch.__valid_match] /usr/lib/rhythmbox/plugins/artdisplay/AmazonCoverArtSearch.py:444: http://www.amazon.com/gp/product/B0009IW9B6%3ftag=webservices-20%26link_code=xm2%26camp=2025%26dev-t=18C3VZN9HCECM5G3HQG2 doesn't have image URLs; ignoring
(20:12:52) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 2:223(1)]
(20:12:52) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 2:223(1)]
(20:12:53) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 2:223(1)]
(20:12:53) [0x6c5040] [AmazonCoverArtSearch.__valid_match] /usr/lib/rhythmbox/plugins/artdisplay/AmazonCoverArtSearch.py:444: http://www.amazon.com/gp/product/B00005YJKH%3ftag=webservices-20%26link_code=xm2%26camp=2025%26dev-t=18C3VZN9HCECM5G3HQG2 doesn't have image URLs; ignoring
(20:12:53) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 2:223(1)]
(20:12:53) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 2:223(1)]
(20:12:53) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 3:223(1)]
(20:12:53) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 3:223(1)]
(20:12:54) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 3:223(1)]
(20:12:54) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file1.mp3, 3:223(1)]
(20:12:54) [0x6c5040] [create_stream] rb-player-gst-xfade.c:1732: creating new stream for file:///tmp/file2.mp3 (stream data 0x10aefa0)
(20:12:54) [0x6c5040] [dump_stream_list] rb-player-gst-xfade.c:473: current stream list:
(20:12:54) [0x6c5040] [dump_stream_list] rb-player-gst-xfade.c:494: [waiting] file:///tmp/file2.mp3
(20:12:54) [0x6c5040] [dump_stream_list] rb-player-gst-xfade.c:494: [playing] file:///tmp/file1.mp3
(20:12:54) [0x6c5040] [rb_player_gst_xfade_play] rb-player-gst-xfade.c:2956: playing stream file:///tmp/file2.mp3, crossfade 2
(20:12:54) [0x6c5040] [rb_player_gst_xfade_play] rb-player-gst-xfade.c:2970: stream file:///tmp/file2.mp3 is prerolling; will start playback once prerolling is complete -> PREROLL_PLAY
(20:12:54) [0x6c5040] [rb_shell_player_set_playing_entry] rb-shell-player.c:1509: Success!
(20:12:54) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag title for stream file:///tmp/file2.mp3
(20:12:54) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1354: emitting info field 0 for uri file:///tmp/file2.mp3
(20:12:54) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag artist for stream file:///tmp/file2.mp3
(20:12:54) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag album for stream file:///tmp/file2.mp3
(20:12:54) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag genre for stream file:///tmp/file2.mp3
(20:12:54) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1354: emitting info field 4 for uri file:///tmp/file2.mp3
(20:12:54) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag private-id3v2-frame for stream file:///tmp/file2.mp3
(20:12:54) [0x14ee700] [stream_new_decoded_pad_cb] rb-player-gst-xfade.c:1650: got decoded audio pad for stream file:///tmp/file2.mp3
(20:12:54) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag audio-codec for stream file:///tmp/file2.mp3
(20:12:54) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag bitrate for stream file:///tmp/file2.mp3
(20:12:54) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1354: emitting info field 20 for uri file:///tmp/file2.mp3
(20:12:54) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag layer for stream file:///tmp/file2.mp3
(20:12:54) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag mode for stream file:///tmp/file2.mp3
(20:12:54) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag emphasis for stream file:///tmp/file2.mp3
(20:12:54) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag bitrate for stream file:///tmp/file2.mp3
(20:12:54) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1354: emitting info field 20 for uri file:///tmp/file2.mp3
(20:12:54) [0x12e83b0] [stream_src_blocked_cb] rb-player-gst-xfade.c:2112: stream file:///tmp/file2.mp3 is prerolled, need to start it
(20:12:54) [0x12e83b0] [actually_start_stream] rb-player-gst-xfade.c:1976: going to start playback for stream file:///tmp/file2.mp3 (crossfade 2) -> FADING_IN | PLAYING
(20:12:54) [0x12e83b0] [actually_start_stream] rb-player-gst-xfade.c:1996: stream file:///tmp/file1.mp3 is playing; crossfading -> FADING_OUT
(20:12:54) [0x12e83b0] [start_stream_fade] rb-player-gst-xfade.c:947: fading stream file:///tmp/file1.mp3: [1.000000, 4022856962] to [0.000000, 6022856962]
(20:12:54) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file1.mp3 to 1.000000 (not fading)
(20:12:54) [0x12e83b0] [start_stream_fade] rb-player-gst-xfade.c:947: fading stream file:///tmp/file2.mp3: [0.000000, 26122439] to [1.000000, 2026122439]
(20:12:54) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 0.000000 (not fading)
(20:12:54) [0x12e83b0] [link_and_unblock_stream] rb-player-gst-xfade.c:1038: linking stream file:///tmp/file2.mp3
(20:12:54) [0x12e83b0] [link_unblocked_cb] rb-player-gst-xfade.c:1001: stream file:///tmp/file2.mp3 is unblocked -> FADING_IN | PLAYING
(20:12:54) [0x12e83b0] [adjust_stream_base_time] rb-player-gst-xfade.c:840: adjusting base time: 4759727891 - 26122439 => 4733605452
(20:12:54) [0x12e83b0] [post_stream_playing_message] rb-player-gst-xfade.c:796: posting rb-stream-playing message for stream file:///tmp/file2.mp3
(20:12:54) [0x6c5040] [rb_player_gst_xfade_bus_cb] rb-player-gst-xfade.c:1462: got stream playing message for file:///tmp/file2.mp3
(20:12:54) [0x6c5040] [new_playing_stream_idle_cb] rb-shell-player.c:3013: new playing stream: file:///tmp/file2.mp3
(20:12:54) [0x6c5040] [rb_shell_hidden_notify_markup] rb-shell.c:3038: shell is visible, not notifying
(20:12:54) [0x6c5040] [rb_lastfm_song_changed_cb] rb-lastfm-source.c:1320: non-lastfm entry being played
(20:12:54) [0x6c5040] [LocalCoverArtSearch.search] /usr/lib/rhythmbox/plugins/artdisplay/LocalCoverArtSearch.py:108: searching for art local to
(20:12:54) [0x6c5040] [LocalCoverArtSearch.search] /usr/lib/rhythmbox/plugins/artdisplay/LocalCoverArtSearch.py:108:  
(20:12:54) [0x6c5040] [LocalCoverArtSearch.search] /usr/lib/rhythmbox/plugins/artdisplay/LocalCoverArtSearch.py:108: file:///tmp/file2.mp3
(20:12:54) [0x12e83b0] [stream_src_event_cb] rb-player-gst-xfade.c:1703: got new segment for stream file:///tmp/file2.mp3
(20:12:54) [0x12e83b0] [adjust_stream_base_time] rb-player-gst-xfade.c:840: adjusting base time: 4759727891 - 52244873 => 4707483018
(20:12:54) [0x6c5040] [rb_shell_player_sync_with_source] rb-shell-player.c:2563: playing source: 0xbbe000, active entry: 0x10aefa0
(20:12:54) [0x6c5040] [rb_shell_set_window_title] rb-shell.c:2102: setting title to "Unknown - file2"
(20:12:54) [0x6c5040] [show_controls] rb-visualizer-plugin.c:826: showing controls
(20:12:54) [0x6c5040] [rb_header_sync] rb-header.c:355: syncing with entry = 0x10aefa0
(20:12:54) [0x6c5040] [rb_shell_player_sync_buttons] rb-shell-player.c:2666: syncing with source 0xbbe000
(20:12:54) [0x6c5040] [show_controls] rb-visualizer-plugin.c:826: showing controls
(20:12:54) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 0:261(1)]
(20:12:54) [0x6c5040] [rb_shell_clipboard_entryview_changed_cb] rb-shell-clipboard.c:743: entryview changed
(20:12:54) [0x6c5040] [rb_shell_clipboard_sync] rb-shell-clipboard.c:543: syncing clipboard
(20:12:54) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 0:261(1)]
(20:12:54) [0x6c5040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:830: No username set
(20:12:54) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 0:261(1)]
(20:12:55) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 0:261(1)]
(20:12:55) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 0:261(1)]
(20:12:55) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 1:261(1)]
(20:12:55) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 1:261(1)]
(20:12:55) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 1:261(1)]
(20:12:56) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 1:261(1)]
(20:12:56) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 1:261(1)]
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:871: stream file:///tmp/file2.mp3 fully faded in -> PLAYING state
(20:12:56) [0x1231c50] [volume_changed_cb] rb-player-gst-xfade.c:881: stream file:///tmp/file1.mp3 fully faded out
(20:12:56) [0x1231c50] [volume_changed_cb] rb-player-gst-xfade.c:902: posting rb-fade-out-done message for stream file:///tmp/file1.mp3
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x6c5040] [rb_player_gst_xfade_bus_cb] rb-player-gst-xfade.c:1468: got fade-out-done for stream file:///tmp/file1.mp3 -> PENDING_REMOVE
(20:12:56) [0x6c5040] [dump_stream_list] rb-player-gst-xfade.c:473: current stream list:
(20:12:56) [0x6c5040] [dump_stream_list] rb-player-gst-xfade.c:494: [playing] file:///tmp/file2.mp3
(20:12:56) [0x6c5040] [dump_stream_list] rb-player-gst-xfade.c:494: [pending remove] file:///tmp/file1.mp3
(20:12:56) [0x6c5040] [dump_stream_list] rb-player-gst-xfade.c:473: current stream list:
(20:12:56) [0x6c5040] [dump_stream_list] rb-player-gst-xfade.c:494: [playing] file:///tmp/file2.mp3
(20:12:56) [0x6c5040] [dump_stream_list] rb-player-gst-xfade.c:494: [pending remove] file:///tmp/file1.mp3
(20:12:56) [0x6c5040] [reap_streams] rb-player-gst-xfade.c:1283: reaping stream file:///tmp/file1.mp3
(20:12:56) [0x6c5040] [unlink_and_dispose_stream] rb-player-gst-xfade.c:1233: stopping stream file:///tmp/file1.mp3
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x1231c50] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file1.mp3 to 0.000000 (not fading)
(20:12:56) [0x6c5040] [unlink_and_dispose_stream] rb-player-gst-xfade.c:1242: unlinking stream file:///tmp/file1.mp3
(20:12:56) [0x6c5040] [dump_stream_list] rb-player-gst-xfade.c:473: current stream list:
(20:12:56) [0x6c5040] [dump_stream_list] rb-player-gst-xfade.c:494: [playing] file:///tmp/file2.mp3
(20:12:56) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 2:261(1)]
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 2:261(1)]
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 2:261(1)]
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:56) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 2:261(1)]
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 2:261(1)]
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 3:261(1)]
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 3:261(1)]
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 3:261(1)]
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:57) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 3:261(1)]
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 3:261(1)]
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 4:261(1)]
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 4:261(1)]
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 4:261(1)]
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:58) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 4:261(1)]
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 4:261(1)]
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x6c5040] [actually_hide_controls] rb-visualizer-plugin.c:786: hiding controls
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 5:261(1)]
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 5:261(1)]
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 5:261(1)]
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:12:59) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 5:261(1)]
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 5:261(1)]
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 6:261(1)]
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 6:261(1)]
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:00) [0x6c5040] [rb_shell_player_cmd_play] rb-shell-player.c:2004: play!
(20:13:00) [0x6c5040] [rb_shell_player_playpause] rb-shell-player.c:2021: doing playpause
(20:13:00) [0x6c5040] [rb_shell_player_playpause] rb-shell-player.c:2031: pausing mm player
(20:13:00) [0x6c5040] [pause_sink] rb-player-gst-xfade.c:2462: pausing sink
(20:13:00) [0x6c5040] [show_controls] rb-visualizer-plugin.c:826: showing controls
(20:13:00) [0x6c5040] [rb_shell_player_sync_with_source] rb-shell-player.c:2563: playing source: 0xbbe000, active entry: 0x10aefa0
(20:13:00) [0x6c5040] [rb_shell_set_window_title] rb-shell.c:2102: setting title to "Unknown - file2"
(20:13:00) [0x6c5040] [show_controls] rb-visualizer-plugin.c:826: showing controls
(20:13:00) [0x6c5040] [rb_header_sync] rb-header.c:355: syncing with entry = 0x10aefa0
(20:13:00) [0x6c5040] [rb_shell_player_sync_buttons] rb-shell-player.c:2666: syncing with source 0xbbe000
(20:13:00) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 6:261(1)]
(20:13:01) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 6:261(1)]
(20:13:01) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 6:261(1)]
(20:13:01) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 6:261(1)]
(20:13:01) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 6:261(1)]
(20:13:01) [0x6c5040] [uninhibit] rb-power-manager-plugin.c:221: Was going to uninhibit power manager, but we haven't inhibited it
(20:13:01) [0x6c5040] [tick_cb] rb-shell-player.c:3148: tick: [file:///tmp/file2.mp3, 6:261(1)]
(20:13:02) [0x6c5040] [create_stream] rb-player-gst-xfade.c:1732: creating new stream for file:///tmp/file3.mp3 (stream data 0x10b5a40)
(20:13:02) [0x6c5040] [dump_stream_list] rb-player-gst-xfade.c:473: current stream list:
(20:13:02) [0x6c5040] [dump_stream_list] rb-player-gst-xfade.c:494: [waiting] file:///tmp/file3.mp3
(20:13:02) [0x6c5040] [dump_stream_list] rb-player-gst-xfade.c:494: [playing] file:///tmp/file2.mp3
(20:13:02) [0x6c5040] [start_sink] rb-player-gst-xfade.c:2351: unpausing sink
(20:13:02) [0x122fca0] [stream_new_decoded_pad_cb] rb-player-gst-xfade.c:1650: got decoded audio pad for stream file:///tmp/file3.mp3
(20:13:02) [0x6c5040] [start_sink] rb-player-gst-xfade.c:2391: sink playing
(20:13:02) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:02) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:02) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:02) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:02) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:02) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:02) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:02) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:02) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:02) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:02) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:02) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:02) [0x12e83b0] [volume_changed_cb] rb-player-gst-xfade.c:894: unexpectedly got a volume change for stream file:///tmp/file2.mp3 to 1.000000 (not fading)
(20:13:02) [0x6c5040] [rb_player_gst_xfade_play] rb-player-gst-xfade.c:2956: playing stream file:///tmp/file3.mp3, crossfade -1
(20:13:02) [0x6c5040] [rb_player_gst_xfade_play] rb-player-gst-xfade.c:2970: stream file:///tmp/file3.mp3 is prerolling; will start playback once prerolling is complete -> PREROLL_PLAY
(20:13:02) [0x6c5040] [rb_shell_player_set_playing_entry] rb-shell-player.c:1509: Success!
(20:13:02) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag title for stream file:///tmp/file3.mp3
(20:13:02) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1354: emitting info field 0 for uri file:///tmp/file3.mp3
(20:13:02) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag artist for stream file:///tmp/file3.mp3
(20:13:02) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag album for stream file:///tmp/file3.mp3
(20:13:02) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag genre for stream file:///tmp/file3.mp3
(20:13:02) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1354: emitting info field 4 for uri file:///tmp/file3.mp3
(20:13:02) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag private-id3v2-frame for stream file:///tmp/file3.mp3
(20:13:02) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag audio-codec for stream file:///tmp/file3.mp3
(20:13:02) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag bitrate for stream file:///tmp/file3.mp3
(20:13:02) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1354: emitting info field 20 for uri file:///tmp/file3.mp3
(20:13:02) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag layer for stream file:///tmp/file3.mp3
(20:13:02) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag mode for stream file:///tmp/file3.mp3
(20:13:02) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag emphasis for stream file:///tmp/file3.mp3
(20:13:02) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1315: got tag bitrate for stream file:///tmp/file3.mp3
(20:13:02) [0x6c5040] [process_tag] rb-player-gst-xfade.c:1354: emitting info field 20 for uri file:///tmp/file3.mp3
(20:13:02) [0x14ec930] [stream_src_blocked_cb] rb-player-gst-xfade.c:2112: stream file:///tmp/file3.mp3 is prerolled, need to start it
(20:13:02) [0x14ec930] [actually_start_stream] rb-player-gst-xfade.c:1976: going to start playback for stream file:///tmp/file3.mp3 (crossfade -1) -> FADING_IN | PLAYING
(20:13:02) [0x14ec930] [actually_start_stream] rb-player-gst-xfade.c:2073: stopping stream file:///tmp/file2.mp3 (replaced by new stream)
(20:13:02) [0x14ec930] [unlink_and_dispose_stream] rb-player-gst-xfade.c:1233: stopping stream file:///tmp/file2.mp3
(20:13:32) [0x61dc00] [electromagnetic_shotgun] rb-metadata-dbus-service.c:305: shutting down (45s idle)
