Dear Maintainer,



   When choosing a song to play from a playlist the song begins to play
but the entire gui interface freezes.  The text next to the start,
pause, back, and forward buttons says Not Playing.  The timer does not
advance and is greyed out.  This stays like this for about a minute and
then the interface works again until the end of that song or I attempt
to manually choose another song. Then the interface freezes again with
the timer showing the end of the previous song and all buttons become
frozen and the first song chosen is continued to be displayed, but the
next song on the playlist plays.  It will play all the songs on the
playlist chosen, but the interface remains frozen from that time on.

I don't know if this matters but I ripped all cd's using soundjuicer
and the .flac file type.  And I have disabled all plugins without it
making a difference.

Hi,

Can you reproduce the issue using official packages i.e. ones not postfixed
'dmo1'?

Regards

Phil

Hi there,
I experience the same problem when playing back streams.

$ rhythmbox --version
rhythmbox 3.4.2

$ uname -a
Linux technics1210 4.16.0-2-amd64 #1 SMP Debian 4.16.12-1 (2018-05-27)
x86_64 GNU/Linux

$ apt show rhythmbox
Package: rhythmbox
Version: 3.4.2-4
Priority: optional
Section: gnome
Maintainer: Debian GNOME Maintainers
<pkg-gnome-maintainers@lists.alioth.debian.org>
Installed-Size: 722 kB
Depends: libc6 (>= 2.4), libglib2.0-0 (>= 2.38.0),
libgstreamer-plugins-base1.0-0 (>= 1.4.0), libgstreamer1.0-0 (>= 1.4.0),
libgtk-3-0 (>= 3.0.0), libpeas-1.0-0 (>= 1.0.0), librhythmbox-core10 (=
3.4.2-4), libx11-6, rhythmbox-data (>= 3.4.2-4), dbus,
gstreamer1.0-plugins-base (>= 1.4.0), gstreamer1.0-plugins-good (>=
1.4.0), gstreamer1.0-x, media-player-info
Recommends: yelp, avahi-daemon, notification-daemon,
gstreamer1.0-pulseaudio, gstreamer1.0-plugins-ugly, gvfs-backends,
rhythmbox-plugins
Suggests: gstreamer1.0-plugins-bad, gnome-codec-install,
gnome-control-center, rhythmbox-plugin-cdrecorder
Homepage: https://wiki.gnome.org/Apps/Rhythmbox
Tag: hardware::storage, hardware::storage:cd, interface::graphical,
 interface::x11, role::program, scope::application, sound::player,
 suite::gnome, uitoolkit::gtk, use::organizing, use::playing,
 works-with-format::iso9660, works-with-format::mp3,
 works-with-format::oggvorbis, works-with::archive, works-with::audio,
 x11::application
Download-Size: 535 kB
APT-Manual-Installed: yes
APT-Sources: http://deb.debian.org/debian buster/main amd64 Packages
Description: music player and organizer for GNOME
 Rhythmbox is a very easy to use music playing and management program
 which supports a wide range of audio formats (including mp3 and ogg).
 Originally inspired by Apple's iTunes, the current version also supports
 Internet Radio, iPod integration and generic portable audio player
 support, Audio CD burning, Audio CD playback, music sharing, and
 Podcasts.


I also started rhythmbox with --debug and I could see that the GUI
freezes until the first log entry from [tick_cb] appears.
Here are the logs - maybe they are relevant:


(rhythmbox:1576): libnotify-WARNING **: 09:58:33.232: Failed to connect
to proxy
(09:58:33) [0x55a483877100] [do_notify] rb-notification-plugin.c:156:
notification server does not support actions
(09:58:33) [0x55a483877100] [do_notify] rb-notification-plugin.c:162:
notification server does not support persistence

(rhythmbox:1576): Rhythmbox-WARNING **: 09:58:58.256: Failed to send
notification (Sub.FM): Error calling StartServiceByName for
org.freedesktop.Notifications: Timeout was reached
(09:58:58) [0x55a483877100] [playing_entry_changed_cb]
rb-mpris-plugin.c:1226: emitting Metadata and CanSeek changed
(09:58:58) [0x55a483877100] [add_string_property] rb-mpris-plugin.c:390:
adding xesam:url = http://subfm.radioca.st/Sub.FM
(09:58:58) [0x55a483877100] [add_string_property_2]
rb-mpris-plugin.c:415: adding xesam:title = Sub.FM
(09:58:58) [0x55a483877100] [add_string_property] rb-mpris-plugin.c:390:
adding xesam:genre = Dubstep
(09:58:58) [0x55a483877100] [add_ulong_property] rb-mpris-plugin.c:436:
adding xesam:audioBitrate = 191
(09:58:58) [0x55a483877100] [add_ulong_property_as_int64]
rb-mpris-plugin.c:453: adding mpris:length = 0
(09:58:58) [0x55a483877100] [add_ulong_property] rb-mpris-plugin.c:436:
adding xesam:trackNumber = 0
(09:58:58) [0x55a483877100] [add_ulong_property] rb-mpris-plugin.c:436:
adding xesam:useCount = 247
(09:58:58) [0x55a483877100] [add_double_property] rb-mpris-plugin.c:469:
adding xesam:userRating = 0.000000
(09:58:58) [0x55a483877100] [lookup_cb] rb-ext-db.c:551: lookup failed
(09:58:58) [0x55a483877100] [rb_shell_player_sync_with_source]
rb-shell-player.c:1879: playing source: 0x55a483de1e70, active entry:
0x7fcdf4029d80
(09:58:58) [0x55a483877100] [rb_shell_set_window_title] rb-shell.c:2424:
setting title to "Sub.FM"
(09:58:58) [0x55a483877100] [rb_shell_player_sync_buttons]
rb-shell-player.c:1971: syncing with source 0x55a483de1e70

(rhythmbox:1576): Rhythmbox-WARNING **: 09:59:23.283: Failed to send
notification (Sub.FM): Error calling StartServiceByName for
org.freedesktop.Notifications: Timeout was reached
(09:59:23) [0x55a483877100] [playing_changed_cb] rb-mpris-plugin.c:1206:
emitting PlaybackStatus change
(09:59:23) [0x55a483877100] [actually_emit_stream_and_tags]
rb-player-gst.c:251: processing buffered taglist
(09:59:23) [0x55a483877100] [process_tag] rb-player-gst.c:230: emitting
info field 13
(09:59:23) [0x55a483877100] [info_available_cb] rb-iradio-source.c:1076:
iradio station already has title: Sub.FM; ignoring Sub FM
(09:59:23) [0x55a483877100] [process_tag] rb-player-gst.c:230: emitting
info field 4
(09:59:23) [0x55a483877100] [info_available_cb] rb-iradio-source.c:1051:
iradio station already has genre: Dubstep; ignoring Dubstep
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag location doesn't correspond to a
metadata field we're interested in
(09:59:23) [0x55a483877100] [actually_emit_stream_and_tags]
rb-player-gst.c:251: processing buffered taglist
(09:59:23) [0x55a483877100] [process_tag] rb-player-gst.c:230: emitting
info field 13
(09:59:23) [0x55a483877100] [info_available_cb] rb-iradio-source.c:1076:
iradio station already has title: Sub.FM; ignoring Sub FM
(09:59:23) [0x55a483877100] [process_tag] rb-player-gst.c:230: emitting
info field 4
(09:59:23) [0x55a483877100] [info_available_cb] rb-iradio-source.c:1051:
iradio station already has genre: Dubstep; ignoring Dubstep
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag location doesn't correspond to a
metadata field we're interested in
(09:59:23) [0x55a483877100] [actually_emit_stream_and_tags]
rb-player-gst.c:251: processing buffered taglist
(09:59:23) [0x55a483877100] [process_tag] rb-player-gst.c:230: emitting
info field 13
(09:59:23) [0x55a483877100] [info_available_cb] rb-iradio-source.c:1076:
iradio station already has title: Sub.FM; ignoring Sub FM
(09:59:23) [0x55a483877100] [process_tag] rb-player-gst.c:230: emitting
info field 4
(09:59:23) [0x55a483877100] [info_available_cb] rb-iradio-source.c:1051:
iradio station already has genre: Dubstep; ignoring Dubstep
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag location doesn't correspond to a
metadata field we're interested in
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag has-crc doesn't correspond to a metadata
field we're interested in
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag channel-mode doesn't correspond to a
metadata field we're interested in
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag audio-codec doesn't correspond to a
metadata field we're interested in
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag nominal-bitrate doesn't correspond to a
metadata field we're interested in
(09:59:23) [0x55a483877100] [rb_player_gst_find_element_with_property]
rb-player-gst-helper.c:151: iterating bin looking for property volume
(09:59:23) [0x55a483877100] [find_property_element]
rb-player-gst-helper.c:117: found property "volume" on element
autoaudiosink0-actual-sink-pulse
(09:59:23) [0x55a483877100] [rhythmdb_read_enter] rhythmdb.c:1249:
counter: 1
(09:59:23) [0x55a483877100] [rhythmdb_read_enter] rhythmdb.c:1249:
counter: 2
(09:59:23) [0x55a483877100] [rhythmdb_read_enter] rhythmdb.c:1249:
counter: 3
(09:59:23) [0x7fcdc00026d0] [query_thread_main] rhythmdb.c:4235:
entering query thread
(09:59:23) [0x7fcdc00026d0] [rhythmdb_query_internal] rhythmdb.c:4212:
doing query
(09:59:23) [0x7fcdc00026d0] [do_query_recurse] rhythmdb-tree.c:2339:
doing recursive query, 1 conjunctions
(09:59:23) [0x55a483877100] [rhythmdb_read_enter] rhythmdb.c:1249:
counter: 4
(09:59:23) [0x7fcdc00026d0] [rhythmdb_query_model_add_results]
rhythmdb-query-model.c:2269: adding 0 entries
(09:59:23) [0x7fcdc00026d0] [rhythmdb_query_internal] rhythmdb.c:4218:
completed
(09:59:23) [0x7fcdc00026d0] [query_thread_main] rhythmdb.c:4235:
entering query thread
(09:59:23) [0x7fcdc00026d0] [rhythmdb_query_internal] rhythmdb.c:4212:
doing query
(09:59:23) [0x7fcdc00026d0] [do_query_recurse] rhythmdb-tree.c:2339:
doing recursive query, 1 conjunctions
(09:59:23) [0x7fcdc00026d0] [rhythmdb_query_model_add_results]
rhythmdb-query-model.c:2269: adding 0 entries
(09:59:23) [0x7fcdc00026d0] [rhythmdb_query_internal] rhythmdb.c:4218:
completed
(09:59:23) [0x7fcdc00026d0] [query_thread_main] rhythmdb.c:4235:
entering query thread
(09:59:23) [0x7fcdc00026d0] [rhythmdb_query_internal] rhythmdb.c:4212:
doing query
(09:59:23) [0x7fcdc00026d0] [do_query_recurse] rhythmdb-tree.c:2339:
doing recursive query, 1 conjunctions
(09:59:23) [0x7fcdc00026d0] [rhythmdb_query_model_add_results]
rhythmdb-query-model.c:2269: adding 0 entries
(09:59:23) [0x7fcdc00026d0] [rhythmdb_query_internal] rhythmdb.c:4218:
completed
(09:59:23) [0x7fcdc00026d0] [query_thread_main] rhythmdb.c:4235:
entering query thread
(09:59:23) [0x7fcdc00026d0] [rhythmdb_query_internal] rhythmdb.c:4212:
doing query
(09:59:23) [0x7fcdc00026d0] [do_query_recurse] rhythmdb-tree.c:2339:
doing recursive query, 1 conjunctions
(09:59:23) [0x7fcdc00026d0] [rhythmdb_query_model_add_results]
rhythmdb-query-model.c:2269: adding 0 entries
(09:59:23) [0x7fcdc00026d0] [rhythmdb_query_internal] rhythmdb.c:4218:
completed
(09:59:23) [0x55a483877100] [process_tag] rb-player-gst.c:230: emitting
info field 13
(09:59:23) [0x55a483877100] [info_available_cb] rb-iradio-source.c:1076:
iradio station already has title: Sub.FM; ignoring Sub FM
(09:59:23) [0x55a483877100] [process_tag] rb-player-gst.c:230: emitting
info field 4
(09:59:23) [0x55a483877100] [info_available_cb] rb-iradio-source.c:1051:
iradio station already has genre: Dubstep; ignoring Dubstep
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag location doesn't correspond to a
metadata field we're interested in
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag has-crc doesn't correspond to a metadata
field we're interested in
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag channel-mode doesn't correspond to a
metadata field we're interested in
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag audio-codec doesn't correspond to a
metadata field we're interested in
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag nominal-bitrate doesn't correspond to a
metadata field we're interested in
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag minimum-bitrate doesn't correspond to a
metadata field we're interested in
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag maximum-bitrate doesn't correspond to a
metadata field we're interested in
(09:59:23) [0x55a483877100] [process_tag] rb-player-gst.c:230: emitting
info field 20
(09:59:23) [0x55a483877100] [inhibit] rb-power-manager-plugin.c:88:
inhibiting
(09:59:23) [0x55a483877100] [process_tag] rb-player-gst.c:230: emitting
info field 0
(09:59:23) [0x55a483877100] [rb_streaming_source_set_streaming_title]
rb-streaming-source.c:403: streaming title: "archives subfm"
(09:59:23) [0x55a483877100] [rb_shell_player_sync_with_source]
rb-shell-player.c:1879: playing source: 0x55a483de1e70, active entry:
0x7fcdf4029d80
(09:59:23) [0x55a483877100] [streaming_title_request_cb]
rb-streaming-source.c:262: returning streaming title "archives subfm" to
extra metadata request
(09:59:23) [0x55a483877100] [rb_shell_player_sync_with_source]
rb-shell-player.c:1895: got streaming title "archives subfm"
(09:59:23) [0x55a483877100] [rb_shell_set_window_title] rb-shell.c:2424:
setting title to "archives subfm (Sub.FM)"
(09:59:23) [0x55a483877100] [rb_header_sync] rb-header.c:854: syncing
with http://subfm.radioca.st/Sub.FM
(09:59:23) [0x55a483877100] [streaming_title_request_cb]
rb-streaming-source.c:262: returning streaming title "archives subfm" to
extra metadata request
(09:59:23) [0x55a483877100] [rb_ext_db_request] rb-ext-db.c:707:
starting metadata request
(09:59:23) [0x55a483877100] [lookup_cb] rb-ext-db.c:551: lookup failed
(09:59:23) [0x55a483877100] [rb_ext_db_request] rb-ext-db.c:762: found
existing equivalent request
(09:59:23) [0x55a483877100] [rb_ext_db_request] rb-ext-db.c:757: found
matching existing request
(09:59:23) [0x55a483877100] [streaming_title_request_cb]
rb-streaming-source.c:262: returning streaming title "archives subfm" to
extra metadata request
(09:59:23) [0x55a483877100] [entry_extra_metadata_notify_cb]
rb-mpris-plugin.c:1237: emitting Metadata change due to extra metadata
field rb:stream-song-title
(09:59:23) [0x55a483877100] [add_string_property] rb-mpris-plugin.c:390:
adding xesam:url = http://subfm.radioca.st/Sub.FM
(09:59:23) [0x55a483877100] [streaming_title_request_cb]
rb-streaming-source.c:262: returning streaming title "archives subfm" to
extra metadata request
(09:59:23) [0x55a483877100] [add_string_property_2]
rb-mpris-plugin.c:415: adding xesam:title = archives subfm
(09:59:23) [0x55a483877100] [add_string_property] rb-mpris-plugin.c:390:
adding xesam:genre = Dubstep
(09:59:23) [0x55a483877100] [streaming_title_request_cb]
rb-streaming-source.c:262: returning streaming title "archives subfm" to
extra metadata request
(09:59:23) [0x55a483877100] [add_string_property] rb-mpris-plugin.c:390:
adding rhythmbox:streamTitle = Sub.FM
(09:59:23) [0x55a483877100] [add_ulong_property] rb-mpris-plugin.c:436:
adding xesam:audioBitrate = 191
(09:59:23) [0x55a483877100] [add_ulong_property_as_int64]
rb-mpris-plugin.c:453: adding mpris:length = 0
(09:59:23) [0x55a483877100] [add_ulong_property] rb-mpris-plugin.c:436:
adding xesam:trackNumber = 0
(09:59:23) [0x55a483877100] [add_ulong_property] rb-mpris-plugin.c:436:
adding xesam:useCount = 247
(09:59:23) [0x55a483877100] [add_double_property] rb-mpris-plugin.c:469:
adding xesam:userRating = 0.000000
(09:59:23) [0x55a483877100] [lookup_cb] rb-ext-db.c:551: lookup failed
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag has-crc doesn't correspond to a metadata
field we're interested in
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag channel-mode doesn't correspond to a
metadata field we're interested in
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag audio-codec doesn't correspond to a
metadata field we're interested in
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag nominal-bitrate doesn't correspond to a
metadata field we're interested in
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag minimum-bitrate doesn't correspond to a
metadata field we're interested in
(09:59:23) [0x55a483877100] [rb_gst_process_tag_string]
rb-player-gst-helper.c:305: tag maximum-bitrate doesn't correspond to a
metadata field we're interested in
(09:59:23) [0x55a483877100] [process_tag] rb-player-gst.c:230: emitting
info field 20
(09:59:23) [0x55a483877100] [bus_cb] rb-player-gst.c:610: buffering -
temporarily pausing playback
(09:59:23) [0x55a483877100] [rb_header_sync] rb-header.c:854: syncing
with http://subfm.radioca.st/Sub.FM
(09:59:23) [0x55a483877100] [streaming_title_request_cb]
rb-streaming-source.c:262: returning streaming title "archives subfm" to
extra metadata request
(09:59:23) [0x55a483877100] [rb_header_sync] rb-header.c:854: syncing
with http://subfm.radioca.st/Sub.FM
(09:59:23) [0x55a483877100] [streaming_title_request_cb]
rb-streaming-source.c:262: returning streaming title "archives subfm" to
extra metadata request
(09:59:23) [0x55a483877100] [rb_header_sync] rb-header.c:854: syncing
with http://subfm.radioca.st/Sub.FM
(09:59:23) [0x55a483877100] [streaming_title_request_cb]
rb-streaming-source.c:262: returning streaming title "archives subfm" to
extra metadata request
(09:59:23) [0x55a483877100] [rb_header_sync] rb-header.c:854: syncing
with http://subfm.radioca.st/Sub.FM
(09:59:23) [0x55a483877100] [streaming_title_request_cb]
rb-streaming-source.c:262: returning streaming title "archives subfm" to
extra metadata request
(09:59:23) [0x55a483877100] [rb_header_sync] rb-header.c:854: syncing
with http://subfm.radioca.st/Sub.FM
(09:59:23) [0x55a483877100] [streaming_title_request_cb]
rb-streaming-source.c:262: returning streaming title "archives subfm" to
extra metadata request
(09:59:23) [0x55a483877100] [rb_header_sync] rb-header.c:854: syncing
with http://subfm.radioca.st/Sub.FM
(09:59:23) [0x55a483877100] [streaming_title_request_cb]
rb-streaming-source.c:262: returning streaming title "archives subfm" to
extra metadata request
(09:59:23) [0x55a483877100] [rb_header_sync] rb-header.c:854: syncing
with http://subfm.radioca.st/Sub.FM
(09:59:23) [0x55a483877100] [streaming_title_request_cb]
rb-streaming-source.c:262: returning streaming title "archives subfm" to
extra metadata request
(09:59:23) [0x55a483877100] [rb_header_sync] rb-header.c:854: syncing
with http://subfm.radioca.st/Sub.FM
(09:59:23) [0x55a483877100] [streaming_title_request_cb]
rb-streaming-source.c:262: returning streaming title "archives subfm" to
extra metadata request
(09:59:23) [0x55a483877100] [bus_cb] rb-player-gst.c:603: buffering
done, setting pipeline back to PLAYING
(09:59:23) [0x55a483877100] [rb_header_sync] rb-header.c:854: syncing
with http://subfm.radioca.st/Sub.FM
(09:59:23) [0x55a483877100] [streaming_title_request_cb]
rb-streaming-source.c:262: returning streaming title "archives subfm" to
extra metadata request
(09:59:23) [0x55a483877100] [bus_cb] rb-player-gst.c:564: playbin
reached state PAUSED
(09:59:23) [0x55a483877100] [bus_cb] rb-player-gst.c:564: playbin
reached state PLAYING
(09:59:23) [0x55a483877100] [volume_changed_cb] rb-mpris-plugin.c:1199:
emitting Volume change
(09:59:23) [0x55a483877100] [volume_changed_cb] rb-mpris-plugin.c:1199:
emitting Volume change
(09:59:23) [0x55a483877100] [volume_changed_cb] rb-mpris-plugin.c:1199:
emitting Volume change
(09:59:23) [0x55a483877100] [volume_changed_cb] rb-mpris-plugin.c:1199:
emitting Volume change
(09:59:23) [0x55a483877100] [rb_statusbar_sync_status]
rb-statusbar.c:297: updating status with: '6 stations', '', 999.000000
(09:59:23) [0x55a483877100] [volume_changed_cb] rb-mpris-plugin.c:1199:
emitting Volume change
(09:59:23) [0x55a483877100] [volume_changed_cb] rb-mpris-plugin.c:1199:
emitting Volume change
(09:59:23) [0x55a483877100] [idle_process_update]
rhythmdb-query-model.c:1208: inserting 0 rows
(09:59:23) [0x55a483877100] [idle_process_update]
rhythmdb-query-model.c:1208: inserting 0 rows
(09:59:23) [0x55a483877100] [idle_process_update]
rhythmdb-query-model.c:1208: inserting 0 rows
(09:59:23) [0x55a483877100] [idle_process_update]
rhythmdb-query-model.c:1208: inserting 0 rows
(09:59:23) [0x55a483877100] [volume_changed_cb] rb-mpris-plugin.c:1199:
emitting Volume change
(09:59:23) [0x55a483877100] [volume_changed_cb] rb-mpris-plugin.c:1199:
emitting Volume change
(09:59:23) [0x55a483877100] [rhythmdb_process_one_event]
rhythmdb.c:2672: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(09:59:23) [0x55a483877100] [rhythmdb_read_leave] rhythmdb.c:1263:
counter: 3
(09:59:23) [0x55a483877100] [rhythmdb_process_one_event]
rhythmdb.c:2665: processing RHYTHMDB_EVENT_THREAD_EXITED
(09:59:23) [0x55a483877100] [rhythmdb_process_one_event]
rhythmdb.c:2672: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(09:59:23) [0x55a483877100] [rhythmdb_read_leave] rhythmdb.c:1263:
counter: 2
(09:59:23) [0x55a483877100] [rhythmdb_process_one_event]
rhythmdb.c:2665: processing RHYTHMDB_EVENT_THREAD_EXITED
(09:59:23) [0x55a483877100] [rhythmdb_process_one_event]
rhythmdb.c:2672: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(09:59:23) [0x55a483877100] [rhythmdb_read_leave] rhythmdb.c:1263:
counter: 1
(09:59:23) [0x55a483877100] [rhythmdb_process_one_event]
rhythmdb.c:2665: processing RHYTHMDB_EVENT_THREAD_EXITED
(09:59:23) [0x55a483877100] [rhythmdb_process_one_event]
rhythmdb.c:2672: processing RHYTHMDB_EVENT_QUERY_COMPLETE
(09:59:23) [0x55a483877100] [rhythmdb_read_leave] rhythmdb.c:1263:
counter: 0
(09:59:23) [0x55a483877100] [rhythmdb_process_one_event]
rhythmdb.c:2665: processing RHYTHMDB_EVENT_THREAD_EXITED
(09:59:23) [0x55a483877100] [volume_changed_cb] rb-mpris-plugin.c:1199:
emitting Volume change
(09:59:23) [0x55a483877100] [volume_changed_cb] rb-mpris-plugin.c:1199:
emitting Volume change
(09:59:23) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 75263198000:0(0)]
(09:59:23) [0x55a483877100] [emit_properties_idle]
rb-mpris-plugin.c:168: emitting Seeked; new time 75263198

(rhythmbox:1576): Rhythmbox-WARNING **: 09:59:48.667: Failed to send
notification (archives subfm): Error calling StartServiceByName for
org.freedesktop.Notifications: Timeout was reached
(09:59:48) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 100448852000:0(0)]
(09:59:48) [0x55a483877100] [emit_properties_idle]
rb-mpris-plugin.c:168: emitting Seeked; new time 100448852
(09:59:48) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 100647971000:0(0)]
(09:59:49) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 100848432000:0(0)]
(09:59:49) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 101049681000:0(0)]
(09:59:49) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 101250227000:0(0)]
(09:59:49) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 101450611000:0(0)]
(09:59:49) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 101650993000:0(0)]
(09:59:50) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 101851811000:0(0)]
(09:59:50) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 102052189000:0(0)]
(09:59:50) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 102252664000:0(0)]
(09:59:50) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 102453092000:0(0)]
(09:59:50) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 102654158000:0(0)]
(09:59:51) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 102854919000:0(0)]
(09:59:51) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 103055717000:0(0)]
(09:59:51) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 103256363000:0(0)]
(09:59:51) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 103456871000:0(0)]
(09:59:51) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 103657918000:0(0)]
(09:59:52) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 103858827000:0(0)]
(09:59:52) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 104059342000:0(0)]
(09:59:52) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 104259842000:0(0)]
(09:59:52) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 104460092000:0(0)]
(09:59:52) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 104660450000:0(0)]
(09:59:53) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 104860865000:0(0)]
(09:59:53) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 105060976000:0(0)]
(09:59:53) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 105261759000:0(0)]
(09:59:53) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 105462109000:0(0)]
(09:59:53) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 105662403000:0(0)]
(09:59:54) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 105863458000:0(0)]
(09:59:54) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 106063855000:0(0)]
(09:59:54) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 106264310000:0(0)]
(09:59:54) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 106464733000:0(0)]
(09:59:54) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 106665681000:0(0)]
(09:59:55) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 106866092000:0(0)]
(09:59:55) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 107066326000:0(0)]
(09:59:55) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 107267096000:0(0)]
(09:59:55) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 107467481000:0(0)]
(09:59:55) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 107668128000:0(0)]
(09:59:56) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 107868823000:0(0)]
(09:59:56) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 108069311000:0(0)]
(09:59:56) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 108269899000:0(0)]
(09:59:56) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 108470567000:0(0)]
(09:59:56) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 108671009000:0(0)]
(09:59:57) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 108872016000:0(0)]
(09:59:57) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 109072470000:0(0)]
(09:59:57) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 109272984000:0(0)]
(09:59:57) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 109473735000:0(0)]
(09:59:57) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 109674239000:0(0)]
(09:59:58) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 109874611000:0(0)]
(09:59:58) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 110075851000:0(0)]
(09:59:58) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 110276956000:0(0)]
(09:59:58) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 110477372000:0(0)]
(09:59:58) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 110678648000:0(0)]
(09:59:59) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 110879593000:0(0)]
(09:59:59) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 111080026000:0(0)]
(09:59:59) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 111281193000:0(0)]
(09:59:59) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 111481480000:0(0)]
(09:59:59) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 111682027000:0(0)]
(10:00:00) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 111882596000:0(0)]
(10:00:00) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 112084246000:0(0)]
(10:00:00) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 112284545000:0(0)]
(10:00:00) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 112485361000:0(0)]
(10:00:00) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 112686090000:0(0)]
(10:00:01) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 112886507000:0(0)]
(10:00:01) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 113086908000:0(0)]
(10:00:01) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 113287246000:0(0)]
(10:00:01) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 113488434000:0(0)]
(10:00:01) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 113689204000:0(0)]
(10:00:02) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 113889917000:0(0)]
(10:00:02) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 114090504000:0(0)]
(10:00:02) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 114291153000:0(0)]
(10:00:02) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 114491451000:0(0)]
(10:00:02) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 114692308000:0(0)]
(10:00:03) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 114892735000:0(0)]
(10:00:03) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 115093071000:0(0)]
(10:00:03) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 115293755000:0(0)]
(10:00:03) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 115494138000:0(0)]
(10:00:03) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 115694704000:0(0)]
(10:00:04) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 115895841000:0(0)]
(10:00:04) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 116096768000:0(0)]
(10:00:04) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 116297229000:0(0)]
(10:00:04) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 116497678000:0(0)]
(10:00:04) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 116697969000:0(0)]
(10:00:05) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 116898412000:0(0)]
(10:00:05) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 117098599000:0(0)]
(10:00:05) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 117299182000:0(0)]
(10:00:05) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 117499576000:0(0)]
(10:00:05) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 117700037000:0(0)]
(10:00:06) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 117901152000:0(0)]
(10:00:06) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 118102113000:0(0)]
(10:00:06) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 118303060000:0(0)]
(10:00:06) [0x55a483877100] [tick_cb] rb-shell-player.c:2579: tick:
[http://subfm.radioca.st/Sub.FM, 118503755000:0(0)]
(10:00:06) [0x55a483877100] [play_action_cb] rb-shell-player.c:2773: play!
(10:00:06) [0x55a483877100] [rb_shell_player_playpause]
rb-shell-player.c:1349: doing playpause
(10:00:06) [0x55a483877100] [rb_shell_player_playpause]
rb-shell-player.c:1373: stopping playback


On Sun, 31 Dec 2017 22:50:17 +0000 Phil Wyett <philwyett@kathenas.org> wrote:

This is a bug in the notification server used by your desktop environment,
which doesn't respond to the D-Bus notification submission. Hence the
following lines in rhythmbox log:

(rhythmbox:1576): Rhythmbox-WARNING **: 09:58:58.256: Failed to send
notification (Sub.FM): Error calling StartServiceByName for
org.freedesktop.Notifications: Timeout was reached

Disabling the rhythmbox notification plugin should fix the issue.

This issue is handled in
https://gitlab.gnome.org/GNOME/rhythmbox/-/merge_requests/100