-- Logs begin at Mon 2025-12-22 10:53:13 CET, end at Mon 2025-12-22 14:33:25 CET. -- Dec 22 14:32:13 rivoplus volumio[3099]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 22 14:32:13 rivoplus volumio[3099]: info: CURURI: playlists Dec 22 14:32:13 rivoplus volumio[3099]: info: Listing playlists Dec 22 14:32:13 rivoplus volumio[3099]: info: Preload queue cleared Dec 22 14:32:14 rivoplus volumio[3099]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 22 14:32:14 rivoplus volumio[3099]: info: CURURI: playlists/Christmas Dec 22 14:32:14 rivoplus volumio[3099]: info: Preload queue cleared Dec 22 14:32:14 rivoplus volumio[3099]: info: Preloading song: qobuz://album/gckstngpromsc Dec 22 14:32:14 rivoplus volumio[3099]: info: Preloading song: qobuz://album/0093624942788 Dec 22 14:32:14 rivoplus volumio[3099]: info: Exploding uri qobuz://album/gckstngpromsc in service qobuz Dec 22 14:32:14 rivoplus volumio[3099]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 22 14:32:14 rivoplus volumio[3099]: info: Exploding uri qobuz://album/0093624942788 in service qobuz Dec 22 14:32:14 rivoplus volumio[3099]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 22 14:32:15 rivoplus volumio[3099]: info: explodeUri took 307 milliseconds Dec 22 14:32:15 rivoplus volumio[3099]: info: explodeUri took 336 milliseconds Dec 22 14:32:26 rivoplus volumio[3099]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 22 14:32:26 rivoplus volumio[3099]: info: handleBrowseUri took 77 milliseconds Dec 22 14:32:26 rivoplus volumio[3099]: info: Preload queue cleared Dec 22 14:32:26 rivoplus volumio[3099]: info: Preload queue cleared Dec 22 14:32:35 rivoplus volumio[3099]: info: Dec 22 14:32:35 rivoplus volumio[3099]: ---------------------------- MPD announces system playlist update Dec 22 14:32:35 rivoplus volumio[3099]: info: Ignoring MPD Status Update Dec 22 14:32:35 rivoplus volumio[3099]: info: Dec 22 14:32:35 rivoplus volumio[3099]: ---------------------------- MPD announces state update: player Dec 22 14:32:35 rivoplus volumio[3099]: info: ControllerMpd::getState Dec 22 14:32:35 rivoplus volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:32:35 rivoplus volumio[3099]: info: Dec 22 14:32:35 rivoplus volumio[3099]: ---------------------------- MPD announces system playlist update Dec 22 14:32:35 rivoplus volumio[3099]: info: Ignoring MPD Status Update Dec 22 14:32:35 rivoplus volumio[3099]: info: Dec 22 14:32:35 rivoplus volumio[3099]: ---------------------------- MPD announces state update: player Dec 22 14:32:35 rivoplus volumio[3099]: info: ControllerMpd::getState Dec 22 14:32:35 rivoplus volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:32:35 rivoplus volumio[3099]: info: Dec 22 14:32:35 rivoplus volumio[3099]: ---------------------------- MPD announces system playlist update Dec 22 14:32:35 rivoplus volumio[3099]: info: Ignoring MPD Status Update Dec 22 14:32:35 rivoplus volumio[3099]: info: Dec 22 14:32:35 rivoplus volumio[3099]: ---------------------------- MPD announces state update: player Dec 22 14:32:35 rivoplus volumio[3099]: info: ControllerMpd::getState Dec 22 14:32:35 rivoplus volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:32:35 rivoplus volumio[3099]: info: ------------------------------ 8ms Dec 22 14:32:35 rivoplus volumio[3099]: info: sendMpdCommand status took 8 milliseconds Dec 22 14:32:35 rivoplus volumio[3099]: info: ------------------------------ 6ms Dec 22 14:32:35 rivoplus volumio[3099]: info: sendMpdCommand status took 5 milliseconds Dec 22 14:32:35 rivoplus volumio[3099]: info: ------------------------------ 5ms Dec 22 14:32:35 rivoplus volumio[3099]: info: sendMpdCommand status took 4 milliseconds Dec 22 14:32:35 rivoplus volumio[3099]: verbose: ControllerMpd::parseState Dec 22 14:32:35 rivoplus volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:32:35 rivoplus volumio[3099]: verbose: ControllerMpd::parseState Dec 22 14:32:35 rivoplus volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:32:35 rivoplus volumio[3099]: verbose: ControllerMpd::parseState Dec 22 14:32:35 rivoplus volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:32:35 rivoplus volumio[3099]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 22 14:32:35 rivoplus volumio[3099]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 22 14:32:35 rivoplus volumio[3099]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 22 14:32:35 rivoplus volumio[3099]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:32:35 rivoplus volumio[3099]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:32:35 rivoplus volumio[3099]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:32:35 rivoplus volumio[3099]: info: ControllerMpd::pushState Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreCommandRouter::servicePushState Dec 22 14:32:35 rivoplus volumio[3099]: info: CorePlayQueue::getTrack 0 Dec 22 14:32:35 rivoplus volumio[3099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14249484,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"PUSH mit Sebastian Müller","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Dec 22 14:32:35 rivoplus volumio[3099]: verbose: CURRENT POSITION 0 Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreStateMachine::syncState stateService play Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreStateMachine::syncState currentStatus play Dec 22 14:32:35 rivoplus volumio[3099]: info: Received an update from plugin. extracting info from payload Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreStateMachine::pushState Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreCommandRouter::volumioPushState Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreStateMachine::pushState Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreCommandRouter::volumioPushState Dec 22 14:32:35 rivoplus volumio[3099]: info: ControllerMpd::pushState Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreCommandRouter::servicePushState Dec 22 14:32:35 rivoplus volumio[3099]: info: CorePlayQueue::getTrack 0 Dec 22 14:32:35 rivoplus volumio[3099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14249484,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"PUSH mit Sebastian Müller","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Dec 22 14:32:35 rivoplus volumio[3099]: verbose: CURRENT POSITION 0 Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreStateMachine::syncState stateService play Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreStateMachine::syncState currentStatus play Dec 22 14:32:35 rivoplus volumio[3099]: info: Received an update from plugin. extracting info from payload Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreStateMachine::pushState Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreCommandRouter::volumioPushState Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreStateMachine::pushState Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreCommandRouter::volumioPushState Dec 22 14:32:35 rivoplus volumio[3099]: info: ControllerMpd::pushState Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreCommandRouter::servicePushState Dec 22 14:32:35 rivoplus volumio[3099]: info: CorePlayQueue::getTrack 0 Dec 22 14:32:35 rivoplus volumio[3099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14249484,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"PUSH mit Sebastian Müller","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Dec 22 14:32:35 rivoplus volumio[3099]: verbose: CURRENT POSITION 0 Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreStateMachine::syncState stateService play Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreStateMachine::syncState currentStatus play Dec 22 14:32:35 rivoplus volumio[3099]: info: Received an update from plugin. extracting info from payload Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreStateMachine::pushState Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreCommandRouter::volumioPushState Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreStateMachine::pushState Dec 22 14:32:35 rivoplus volumio[3099]: info: CoreCommandRouter::volumioPushState Dec 22 14:32:35 rivoplus volumio[3099]: info: ------------------------------ 50ms Dec 22 14:32:35 rivoplus volumio[3099]: info: ------------------------------ 48ms Dec 22 14:32:35 rivoplus volumio[3099]: info: ------------------------------ 47ms Dec 22 14:32:35 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:35 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:35 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:35 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:35 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:35 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:35 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:35 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:35 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:35 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:35 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:35 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:40 rivoplus volumio[3099]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 22 14:32:40 rivoplus volumio[3099]: info: Preload queue cleared Dec 22 14:32:44 rivoplus volumio[3099]: info: Dec 22 14:32:44 rivoplus volumio[3099]: ---------------------------- MPD announces system playlist update Dec 22 14:32:44 rivoplus volumio[3099]: info: Ignoring MPD Status Update Dec 22 14:32:44 rivoplus volumio[3099]: info: Dec 22 14:32:44 rivoplus volumio[3099]: ---------------------------- MPD announces state update: player Dec 22 14:32:44 rivoplus volumio[3099]: info: ControllerMpd::getState Dec 22 14:32:44 rivoplus volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:32:44 rivoplus volumio[3099]: info: Dec 22 14:32:44 rivoplus volumio[3099]: ---------------------------- MPD announces system playlist update Dec 22 14:32:44 rivoplus volumio[3099]: info: Ignoring MPD Status Update Dec 22 14:32:44 rivoplus volumio[3099]: info: Dec 22 14:32:44 rivoplus volumio[3099]: ---------------------------- MPD announces state update: player Dec 22 14:32:44 rivoplus volumio[3099]: info: ControllerMpd::getState Dec 22 14:32:44 rivoplus volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:32:44 rivoplus volumio[3099]: info: Dec 22 14:32:44 rivoplus volumio[3099]: ---------------------------- MPD announces system playlist update Dec 22 14:32:44 rivoplus volumio[3099]: info: Ignoring MPD Status Update Dec 22 14:32:44 rivoplus volumio[3099]: info: Dec 22 14:32:44 rivoplus volumio[3099]: ---------------------------- MPD announces state update: player Dec 22 14:32:44 rivoplus volumio[3099]: info: ControllerMpd::getState Dec 22 14:32:44 rivoplus volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:32:44 rivoplus volumio[3099]: info: ------------------------------ 8ms Dec 22 14:32:44 rivoplus volumio[3099]: info: sendMpdCommand status took 8 milliseconds Dec 22 14:32:44 rivoplus volumio[3099]: info: ------------------------------ 7ms Dec 22 14:32:44 rivoplus volumio[3099]: info: sendMpdCommand status took 5 milliseconds Dec 22 14:32:44 rivoplus volumio[3099]: info: ------------------------------ 5ms Dec 22 14:32:44 rivoplus volumio[3099]: info: sendMpdCommand status took 4 milliseconds Dec 22 14:32:44 rivoplus volumio[3099]: verbose: ControllerMpd::parseState Dec 22 14:32:44 rivoplus volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:32:44 rivoplus volumio[3099]: verbose: ControllerMpd::parseState Dec 22 14:32:44 rivoplus volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:32:44 rivoplus volumio[3099]: verbose: ControllerMpd::parseState Dec 22 14:32:44 rivoplus volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:32:44 rivoplus volumio[3099]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 22 14:32:44 rivoplus volumio[3099]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 22 14:32:44 rivoplus volumio[3099]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 22 14:32:44 rivoplus volumio[3099]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:32:44 rivoplus volumio[3099]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:32:44 rivoplus volumio[3099]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:32:44 rivoplus volumio[3099]: info: ControllerMpd::pushState Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreCommandRouter::servicePushState Dec 22 14:32:44 rivoplus volumio[3099]: info: CorePlayQueue::getTrack 0 Dec 22 14:32:44 rivoplus volumio[3099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14258485,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Too sweet / Hozier","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Dec 22 14:32:44 rivoplus volumio[3099]: verbose: CURRENT POSITION 0 Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreStateMachine::syncState stateService play Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreStateMachine::syncState currentStatus play Dec 22 14:32:44 rivoplus volumio[3099]: info: Received an update from plugin. extracting info from payload Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreStateMachine::pushState Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreCommandRouter::volumioPushState Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreStateMachine::pushState Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreCommandRouter::volumioPushState Dec 22 14:32:44 rivoplus volumio[3099]: info: ControllerMpd::pushState Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreCommandRouter::servicePushState Dec 22 14:32:44 rivoplus volumio[3099]: info: CorePlayQueue::getTrack 0 Dec 22 14:32:44 rivoplus volumio[3099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14258485,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Too sweet / Hozier","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Dec 22 14:32:44 rivoplus volumio[3099]: verbose: CURRENT POSITION 0 Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreStateMachine::syncState stateService play Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreStateMachine::syncState currentStatus play Dec 22 14:32:44 rivoplus volumio[3099]: info: Received an update from plugin. extracting info from payload Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreStateMachine::pushState Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreCommandRouter::volumioPushState Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreStateMachine::pushState Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreCommandRouter::volumioPushState Dec 22 14:32:44 rivoplus volumio[3099]: info: ControllerMpd::pushState Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreCommandRouter::servicePushState Dec 22 14:32:44 rivoplus volumio[3099]: info: CorePlayQueue::getTrack 0 Dec 22 14:32:44 rivoplus volumio[3099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14258485,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Too sweet / Hozier","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Dec 22 14:32:44 rivoplus volumio[3099]: verbose: CURRENT POSITION 0 Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreStateMachine::syncState stateService play Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreStateMachine::syncState currentStatus play Dec 22 14:32:44 rivoplus volumio[3099]: info: Received an update from plugin. extracting info from payload Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreStateMachine::pushState Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreCommandRouter::volumioPushState Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreStateMachine::pushState Dec 22 14:32:44 rivoplus volumio[3099]: info: CoreCommandRouter::volumioPushState Dec 22 14:32:44 rivoplus volumio[3099]: info: ------------------------------ 50ms Dec 22 14:32:44 rivoplus volumio[3099]: info: ------------------------------ 48ms Dec 22 14:32:44 rivoplus volumio[3099]: info: ------------------------------ 46ms Dec 22 14:32:44 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:44 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:44 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:44 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:44 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:44 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:44 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:44 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:44 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:44 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:44 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:44 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:32:45 rivoplus volumio[3099]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 22 14:32:45 rivoplus volumio[3099]: info: Preload queue cleared Dec 22 14:32:54 rivoplus volumio[3099]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 22 14:32:54 rivoplus volumio[3099]: info: handleBrowseUri took 78 milliseconds Dec 22 14:32:54 rivoplus volumio[3099]: info: Preload queue cleared Dec 22 14:32:54 rivoplus volumio[3099]: info: Preload queue cleared Dec 22 14:32:57 rivoplus volumio[3099]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 22 14:32:57 rivoplus volumio[3099]: info: handleBrowseUri took 197 milliseconds Dec 22 14:32:57 rivoplus volumio[3099]: info: Preload queue cleared Dec 22 14:33:23 rivoplus volumio[3099]: info: Dec 22 14:33:23 rivoplus volumio[3099]: ---------------------------- MPD announces system playlist update Dec 22 14:33:23 rivoplus volumio[3099]: info: Ignoring MPD Status Update Dec 22 14:33:23 rivoplus volumio[3099]: info: Dec 22 14:33:23 rivoplus volumio[3099]: ---------------------------- MPD announces state update: player Dec 22 14:33:23 rivoplus volumio[3099]: info: ControllerMpd::getState Dec 22 14:33:23 rivoplus volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:33:23 rivoplus volumio[3099]: info: Dec 22 14:33:23 rivoplus volumio[3099]: ---------------------------- MPD announces system playlist update Dec 22 14:33:23 rivoplus volumio[3099]: info: Ignoring MPD Status Update Dec 22 14:33:23 rivoplus volumio[3099]: info: Dec 22 14:33:23 rivoplus volumio[3099]: ---------------------------- MPD announces state update: player Dec 22 14:33:23 rivoplus volumio[3099]: info: ControllerMpd::getState Dec 22 14:33:23 rivoplus volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:33:23 rivoplus volumio[3099]: info: Dec 22 14:33:23 rivoplus volumio[3099]: ---------------------------- MPD announces system playlist update Dec 22 14:33:23 rivoplus volumio[3099]: info: Ignoring MPD Status Update Dec 22 14:33:23 rivoplus volumio[3099]: info: Dec 22 14:33:23 rivoplus volumio[3099]: ---------------------------- MPD announces state update: player Dec 22 14:33:23 rivoplus volumio[3099]: info: ControllerMpd::getState Dec 22 14:33:23 rivoplus volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:33:23 rivoplus volumio[3099]: info: ------------------------------ 9ms Dec 22 14:33:23 rivoplus volumio[3099]: info: sendMpdCommand status took 7 milliseconds Dec 22 14:33:23 rivoplus volumio[3099]: info: ------------------------------ 7ms Dec 22 14:33:23 rivoplus volumio[3099]: info: sendMpdCommand status took 5 milliseconds Dec 22 14:33:23 rivoplus volumio[3099]: info: ------------------------------ 5ms Dec 22 14:33:23 rivoplus volumio[3099]: info: sendMpdCommand status took 3 milliseconds Dec 22 14:33:23 rivoplus volumio[3099]: verbose: ControllerMpd::parseState Dec 22 14:33:23 rivoplus volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:33:23 rivoplus volumio[3099]: verbose: ControllerMpd::parseState Dec 22 14:33:23 rivoplus volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:33:23 rivoplus volumio[3099]: verbose: ControllerMpd::parseState Dec 22 14:33:23 rivoplus volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:33:23 rivoplus volumio[3099]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 22 14:33:23 rivoplus volumio[3099]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 22 14:33:23 rivoplus volumio[3099]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 22 14:33:23 rivoplus volumio[3099]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:33:23 rivoplus volumio[3099]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:33:23 rivoplus volumio[3099]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:33:23 rivoplus volumio[3099]: info: ControllerMpd::pushState Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreCommandRouter::servicePushState Dec 22 14:33:23 rivoplus volumio[3099]: info: CorePlayQueue::getTrack 0 Dec 22 14:33:23 rivoplus volumio[3099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14297731,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"SWR3 PUSH Die Nachmittagsshow","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Dec 22 14:33:23 rivoplus volumio[3099]: verbose: CURRENT POSITION 0 Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreStateMachine::syncState stateService play Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreStateMachine::syncState currentStatus play Dec 22 14:33:23 rivoplus volumio[3099]: info: Received an update from plugin. extracting info from payload Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreStateMachine::pushState Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreCommandRouter::volumioPushState Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreStateMachine::pushState Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreCommandRouter::volumioPushState Dec 22 14:33:23 rivoplus volumio[3099]: info: ControllerMpd::pushState Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreCommandRouter::servicePushState Dec 22 14:33:23 rivoplus volumio[3099]: info: CorePlayQueue::getTrack 0 Dec 22 14:33:23 rivoplus volumio[3099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14297731,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"SWR3 PUSH Die Nachmittagsshow","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Dec 22 14:33:23 rivoplus volumio[3099]: verbose: CURRENT POSITION 0 Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreStateMachine::syncState stateService play Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreStateMachine::syncState currentStatus play Dec 22 14:33:23 rivoplus volumio[3099]: info: Received an update from plugin. extracting info from payload Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreStateMachine::pushState Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreCommandRouter::volumioPushState Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreStateMachine::pushState Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreCommandRouter::volumioPushState Dec 22 14:33:23 rivoplus volumio[3099]: info: ControllerMpd::pushState Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreCommandRouter::servicePushState Dec 22 14:33:23 rivoplus volumio[3099]: info: CorePlayQueue::getTrack 0 Dec 22 14:33:23 rivoplus volumio[3099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14297731,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"SWR3 PUSH Die Nachmittagsshow","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Dec 22 14:33:23 rivoplus volumio[3099]: verbose: CURRENT POSITION 0 Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreStateMachine::syncState stateService play Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreStateMachine::syncState currentStatus play Dec 22 14:33:23 rivoplus volumio[3099]: info: Received an update from plugin. extracting info from payload Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreStateMachine::pushState Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreCommandRouter::volumioPushState Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreStateMachine::pushState Dec 22 14:33:23 rivoplus volumio[3099]: info: CoreCommandRouter::volumioPushState Dec 22 14:33:23 rivoplus volumio[3099]: info: ------------------------------ 51ms Dec 22 14:33:23 rivoplus volumio[3099]: info: ------------------------------ 49ms Dec 22 14:33:23 rivoplus volumio[3099]: info: ------------------------------ 48ms Dec 22 14:33:23 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:33:23 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:33:23 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:33:23 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:33:23 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:33:23 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:33:23 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:33:23 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:33:23 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:33:23 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:33:23 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:33:23 rivoplus volumio[3099]: info: Signalling Playback active due to playback status change Dec 22 14:33:24 rivoplus volumio[3099]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Dec 22 14:33:24 rivoplus volumio[3099]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 14:33:24 rivoplus volumio[3099]: TypeError: Cannot read property 'then' of undefined Dec 22 14:33:24 rivoplus volumio[3099]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Dec 22 14:33:24 rivoplus volumio[3099]: at Socket.emit (events.js:400:28) Dec 22 14:33:24 rivoplus volumio[3099]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 22 14:33:24 rivoplus volumio[3099]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Dec 22 14:33:24 rivoplus volumio[3099]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 14:33:25 rivoplus sudo[10178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-22 14:32 Dec 22 14:33:25 rivoplus sudo[10178]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:47:33 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="9e8aa5d6afb5e5d87a87bafae7aa5647"