-- Logs begin at Sun 2026-02-22 09:18:55 CET, end at Mon 2026-02-23 10:19:02 CET. -- Feb 23 10:18:38 rivoplus volumio[3106]: verbose: New Socket.io Connection to 192.168.0.220 from 192.168.0.39 UA: Mozilla/5.0 (Linux; Android 8.0.0; SM-A520F Build/R16NW; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.179 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 23 10:18:38 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 10:18:38 rivoplus volumio[3106]: info: CoreCommandRouter::volumioGetVisibleSources Feb 23 10:18:38 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 10:18:38 rivoplus volumio[3106]: info: CoreCommandRouter::volumioGetState Feb 23 10:18:38 rivoplus volumio[3106]: info: CorePlayQueue::getTrack 4 Feb 23 10:18:38 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 23 10:18:38 rivoplus volumio[3106]: info: CoreCommandRouter::volumioGetQueue Feb 23 10:18:38 rivoplus volumio[3106]: info: CoreStateMachine::getQueue Feb 23 10:18:38 rivoplus volumio[3106]: info: CorePlayQueue::getQueue Feb 23 10:18:38 rivoplus volumio[3106]: info: Listing playlists Feb 23 10:18:38 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 10:18:38 rivoplus volumio[3106]: info: Received Get System Info Feb 23 10:18:38 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 10:18:38 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 10:18:38 rivoplus volumio[3106]: info: Discovery: Getting this device information Feb 23 10:18:38 rivoplus volumio[3106]: info: CoreCommandRouter::volumioGetState Feb 23 10:18:38 rivoplus volumio[3106]: info: CorePlayQueue::getTrack 4 Feb 23 10:18:38 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 10:18:38 rivoplus volumio[3106]: info: CoreCommandRouter::volumioGetState Feb 23 10:18:38 rivoplus volumio[3106]: info: CorePlayQueue::getTrack 4 Feb 23 10:18:38 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 23 10:18:47 rivoplus volumio[3106]: info: Preload queue cleared Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::ClearQueue Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::stop Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::stPlaybackTimer Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::updateTrackBlock Feb 23 10:18:47 rivoplus volumio[3106]: info: CorePlayQueue::getTrackBlock Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CorePlayQueue::getTrack 4 Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::serviceStop Feb 23 10:18:47 rivoplus volumio[3106]: info: CorePlayQueue::getTrack 4 Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreCommandRouter::serviceStop Feb 23 10:18:47 rivoplus volumio[3106]: info: [1771838327716] ControllerTidal::stop Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 23 10:18:47 rivoplus volumio[3106]: info: ControllerMpd::stop Feb 23 10:18:47 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 10:18:47 rivoplus volumio[3106]: info: CorePlayQueue::clearPlayQueue Feb 23 10:18:47 rivoplus volumio[3106]: info: CorePlayQueue::saveQueue Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushQueue Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::addQueueItems Feb 23 10:18:47 rivoplus volumio[3106]: info: CorePlayQueue::addQueueItems Feb 23 10:18:47 rivoplus volumio[3106]: info: Preload queue cleared Feb 23 10:18:47 rivoplus volumio[3106]: info: Adding Item to queue: tidal://song/24752489 Feb 23 10:18:47 rivoplus volumio[3106]: info: Exploding uri tidal://song/24752489 in service tidal Feb 23 10:18:47 rivoplus volumio[3106]: info: Adding Item to queue: tidal://song/24752490 Feb 23 10:18:47 rivoplus volumio[3106]: info: Exploding uri tidal://song/24752490 in service tidal Feb 23 10:18:47 rivoplus volumio[3106]: info: Adding Item to queue: tidal://song/24752491 Feb 23 10:18:47 rivoplus volumio[3106]: info: Exploding uri tidal://song/24752491 in service tidal Feb 23 10:18:47 rivoplus volumio[3106]: info: Adding Item to queue: tidal://song/24752492 Feb 23 10:18:47 rivoplus volumio[3106]: info: Exploding uri tidal://song/24752492 in service tidal Feb 23 10:18:47 rivoplus volumio[3106]: info: Adding Item to queue: tidal://song/24752493 Feb 23 10:18:47 rivoplus volumio[3106]: info: Exploding uri tidal://song/24752493 in service tidal Feb 23 10:18:47 rivoplus volumio[3106]: info: Adding Item to queue: tidal://song/24752494 Feb 23 10:18:47 rivoplus volumio[3106]: info: Exploding uri tidal://song/24752494 in service tidal Feb 23 10:18:47 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:47.728+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" state=STATUS_STOPPED positionMs=0 volume=33 Feb 23 10:18:47 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:47.728+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" id=tidal://song/24752493 title="Long distance call" Feb 23 10:18:47 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:47 rivoplus volumio[3106]: info: Feb 23 10:18:47 rivoplus volumio[3106]: ---------------------------- MPD announces state update: player Feb 23 10:18:47 rivoplus volumio[3106]: info: sendMpdCommand stop took 76 milliseconds Feb 23 10:18:47 rivoplus volumio[3106]: info: ControllerMpd::getState Feb 23 10:18:47 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Feb 23 10:18:47 rivoplus volumio[3106]: info: Feb 23 10:18:47 rivoplus volumio[3106]: ---------------------------- MPD announces state update: player Feb 23 10:18:47 rivoplus volumio[3106]: info: ControllerMpd::getState Feb 23 10:18:47 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Feb 23 10:18:47 rivoplus volumio[3106]: info: Feb 23 10:18:47 rivoplus volumio[3106]: ---------------------------- MPD announces state update: player Feb 23 10:18:47 rivoplus volumio[3106]: info: ControllerMpd::getState Feb 23 10:18:47 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Feb 23 10:18:47 rivoplus volumio[3106]: info: sendMpdCommand status took 5 milliseconds Feb 23 10:18:47 rivoplus volumio[3106]: info: sendMpdCommand status took 3 milliseconds Feb 23 10:18:47 rivoplus volumio[3106]: info: sendMpdCommand status took 3 milliseconds Feb 23 10:18:47 rivoplus volumio[3106]: verbose: ControllerMpd::parseState Feb 23 10:18:47 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 10:18:47 rivoplus volumio[3106]: verbose: ControllerMpd::parseState Feb 23 10:18:47 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 10:18:47 rivoplus volumio[3106]: verbose: ControllerMpd::parseState Feb 23 10:18:47 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 10:18:47 rivoplus volumio[3106]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 10:18:47 rivoplus volumio[3106]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 10:18:47 rivoplus volumio[3106]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 10:18:47 rivoplus volumio[3106]: verbose: ControllerMpd::parseTrackInfo Feb 23 10:18:47 rivoplus volumio[3106]: verbose: ControllerMpd::parseTrackInfo Feb 23 10:18:47 rivoplus volumio[3106]: info: ControllerMpd::pushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreCommandRouter::servicePushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CorePlayQueue::getTrack 4 Feb 23 10:18:47 rivoplus volumio[3106]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24752493&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/24752493&soundQuality=LOSSLESS","trackType":"tidal"} Feb 23 10:18:47 rivoplus volumio[3106]: verbose: CURRENT POSITION 4 Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::syncState stateService stop Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::syncState currentStatus stop Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:47 rivoplus volumio[3106]: info: No code Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:47 rivoplus volumio[3106]: info: ControllerMpd::pushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreCommandRouter::servicePushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CorePlayQueue::getTrack 4 Feb 23 10:18:47 rivoplus volumio[3106]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24752493&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/24752493&soundQuality=LOSSLESS","trackType":"tidal"} Feb 23 10:18:47 rivoplus volumio[3106]: verbose: CURRENT POSITION 4 Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::syncState stateService stop Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::syncState currentStatus stop Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:47 rivoplus volumio[3106]: info: No code Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:47 rivoplus volumio[3106]: info: ControllerMpd::pushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreCommandRouter::servicePushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CorePlayQueue::getTrack 4 Feb 23 10:18:47 rivoplus volumio[3106]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24752493&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/24752493&soundQuality=LOSSLESS","trackType":"tidal"} Feb 23 10:18:47 rivoplus volumio[3106]: verbose: CURRENT POSITION 4 Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::syncState stateService stop Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::syncState currentStatus stop Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:47 rivoplus volumio[3106]: info: No code Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:47 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:47 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:47.831+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" state=STATUS_PLAYING positionMs=0 volume=33 Feb 23 10:18:47 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:47.832+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" id=tidal://song/24752491 title="Woman, you don't have to go" Feb 23 10:18:47 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:47.832+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" state=STATUS_PLAYING positionMs=0 volume=33 Feb 23 10:18:47 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:47.833+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" state=STATUS_PLAYING positionMs=0 volume=33 Feb 23 10:18:47 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:47.833+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" id=tidal://song/24752491 title="Woman, you don't have to go" Feb 23 10:18:47 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:47.833+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" state=STATUS_PLAYING positionMs=0 volume=33 Feb 23 10:18:47 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:47.834+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" state=STATUS_PLAYING positionMs=0 volume=33 Feb 23 10:18:47 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:47.834+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" state=STATUS_PLAYING positionMs=0 volume=33 Feb 23 10:18:47 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:47.834+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" id=tidal://song/24752491 title="Woman, you don't have to go" Feb 23 10:18:47 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:47.835+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" state=STATUS_PLAYING positionMs=0 volume=33 Feb 23 10:18:47 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:47.835+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" id=tidal://song/24752491 title="Woman, you don't have to go" Feb 23 10:18:47 rivoplus volumio[3106]: info: ------------------------------ 52ms Feb 23 10:18:47 rivoplus volumio[3106]: info: ------------------------------ 51ms Feb 23 10:18:47 rivoplus volumio[3106]: info: ------------------------------ 51ms Feb 23 10:18:47 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:47 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:47 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:47 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:47 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:47 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:47 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:47 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:47 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:47 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:47 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:47 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:47 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:47 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:47 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:47 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:47 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:47 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:47 rivoplus volumio[3106]: info: MCU Signalled Playback Inactive Feb 23 10:18:48 rivoplus volumio[3106]: info: MCU Signalled Playback Active Feb 23 10:18:48 rivoplus volumio[3106]: info: explodeTIDALUri took 317 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: explodeTIDALUri took 323 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: explodeTIDALUri took 353 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: explodeTIDALUri took 367 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: explodeTIDALUri took 392 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: explodeTIDALUri took 404 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushQueue Feb 23 10:18:48 rivoplus volumio[3106]: info: CorePlayQueue::saveQueue Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::updateTrackBlock Feb 23 10:18:48 rivoplus volumio[3106]: info: CorePlayQueue::getTrackBlock Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPlay Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::play index 5 Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::addQueueItems Feb 23 10:18:48 rivoplus volumio[3106]: info: CorePlayQueue::addQueueItems Feb 23 10:18:48 rivoplus volumio[3106]: info: Preload queue cleared Feb 23 10:18:48 rivoplus volumio[3106]: info: Adding Item to queue: tidal://song/24752495 Feb 23 10:18:48 rivoplus volumio[3106]: info: Exploding uri tidal://song/24752495 in service tidal Feb 23 10:18:48 rivoplus volumio[3106]: info: Adding Item to queue: tidal://song/24752496 Feb 23 10:18:48 rivoplus volumio[3106]: info: Exploding uri tidal://song/24752496 in service tidal Feb 23 10:18:48 rivoplus volumio[3106]: info: Adding Item to queue: tidal://song/24752497 Feb 23 10:18:48 rivoplus volumio[3106]: info: Exploding uri tidal://song/24752497 in service tidal Feb 23 10:18:48 rivoplus volumio[3106]: info: Adding Item to queue: tidal://song/24752498 Feb 23 10:18:48 rivoplus volumio[3106]: info: Exploding uri tidal://song/24752498 in service tidal Feb 23 10:18:48 rivoplus volumio[3106]: info: Adding Item to queue: tidal://song/24752499 Feb 23 10:18:48 rivoplus volumio[3106]: info: Exploding uri tidal://song/24752499 in service tidal Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::stop Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::play index undefined Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 10:18:48 rivoplus volumio[3106]: info: CorePlayQueue::getTrack 5 Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::startPlaybackTimer Feb 23 10:18:48 rivoplus volumio[3106]: info: CorePlayQueue::getTrack 5 Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::volumioGetVisibleSources Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 23 10:18:48 rivoplus volumio[3106]: info: [1771838328157] ControllerTidal::clearAddPlayTrack Feb 23 10:18:48 rivoplus volumio[3106]: info: Getting stream with soundQuality LOSSLESS Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 10:18:48 rivoplus volumio[3106]: info: sendMpdCommand stop took 25 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 10:18:48 rivoplus volumio[3106]: info: Feb 23 10:18:48 rivoplus volumio[3106]: ---------------------------- MPD announces system playlist update Feb 23 10:18:48 rivoplus volumio[3106]: info: Ignoring MPD Status Update Feb 23 10:18:48 rivoplus volumio[3106]: info: sendMpdCommand clear took 3 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24752494&soundQuality=LOSSLESS" Feb 23 10:18:48 rivoplus volumio[3106]: info: Feb 23 10:18:48 rivoplus volumio[3106]: ---------------------------- MPD announces system playlist update Feb 23 10:18:48 rivoplus volumio[3106]: info: Ignoring MPD Status Update Feb 23 10:18:48 rivoplus volumio[3106]: info: Feb 23 10:18:48 rivoplus volumio[3106]: ---------------------------- MPD announces system playlist update Feb 23 10:18:48 rivoplus volumio[3106]: info: Ignoring MPD Status Update Feb 23 10:18:48 rivoplus volumio[3106]: error: updateQueue error: null Feb 23 10:18:48 rivoplus volumio[3106]: info: Feb 23 10:18:48 rivoplus volumio[3106]: ---------------------------- MPD announces system playlist update Feb 23 10:18:48 rivoplus volumio[3106]: info: Ignoring MPD Status Update Feb 23 10:18:48 rivoplus volumio[3106]: info: ------------------------------ 6ms Feb 23 10:18:48 rivoplus volumio[3106]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24752494&soundQuality=LOSSLESS" took 5 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: ------------------------------ 4ms Feb 23 10:18:48 rivoplus volumio[3106]: info: ------------------------------ 3ms Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand play Feb 23 10:18:48 rivoplus volumio[3106]: info: Feb 23 10:18:48 rivoplus volumio[3106]: ---------------------------- MPD announces system playlist update Feb 23 10:18:48 rivoplus volumio[3106]: info: Ignoring MPD Status Update Feb 23 10:18:48 rivoplus volumio[3106]: info: Feb 23 10:18:48 rivoplus volumio[3106]: ---------------------------- MPD announces system playlist update Feb 23 10:18:48 rivoplus volumio[3106]: info: Ignoring MPD Status Update Feb 23 10:18:48 rivoplus volumio[3106]: STREAMING PROXY: Handling url /?data=tidal://song/24752494&soundQuality=LOSSLESS Feb 23 10:18:48 rivoplus volumio[3106]: info: ------------------------------ 6ms Feb 23 10:18:48 rivoplus volumio[3106]: info: sendMpdCommand play took 5 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: ------------------------------ 5ms Feb 23 10:18:48 rivoplus volumio[3106]: info: ------------------------------ 3ms Feb 23 10:18:48 rivoplus volumio[3106]: info: Executing endpoint getStreamUrltidal Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Feb 23 10:18:48 rivoplus volumio[3106]: info: explodeTIDALUri took 225 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: explodeTIDALUri took 255 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: explodeTIDALUri took 271 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: explodeTIDALUri took 299 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: explodeTIDALUri took 315 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushQueue Feb 23 10:18:48 rivoplus volumio[3106]: info: CorePlayQueue::saveQueue Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::updateTrackBlock Feb 23 10:18:48 rivoplus volumio[3106]: info: CorePlayQueue::getTrackBlock Feb 23 10:18:48 rivoplus volumio[3106]: info: getStreamUrl took 472 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicyNTMyNDFiMjE4N2I1NmNiNGVmYTZmOWE3MGRjNTg1NV82MS5tcDQ/0.flac?token=1771841928~ZDJkMGRhMzIyMjBmYTRiMWYyMzMyZDIyYzA1ZTg1NDU2NWU2ZTE5Yg== Feb 23 10:18:48 rivoplus volumio[3106]: STREAMING PROXY: Requesting upstream with range: none Feb 23 10:18:48 rivoplus volumio[3106]: STREAMING PROXY: Upstream status code: 200 Feb 23 10:18:48 rivoplus volumio[3106]: info: Feb 23 10:18:48 rivoplus volumio[3106]: ---------------------------- MPD announces state update: player Feb 23 10:18:48 rivoplus volumio[3106]: info: ControllerMpd::getState Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Feb 23 10:18:48 rivoplus volumio[3106]: info: Feb 23 10:18:48 rivoplus volumio[3106]: ---------------------------- MPD announces state update: player Feb 23 10:18:48 rivoplus volumio[3106]: info: ControllerMpd::getState Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Feb 23 10:18:48 rivoplus volumio[3106]: info: Feb 23 10:18:48 rivoplus volumio[3106]: ---------------------------- MPD announces state update: player Feb 23 10:18:48 rivoplus volumio[3106]: info: ControllerMpd::getState Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Feb 23 10:18:48 rivoplus volumio[3106]: info: sendMpdCommand status took 16 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::parseState Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 10:18:48 rivoplus volumio[3106]: info: Feb 23 10:18:48 rivoplus volumio[3106]: ---------------------------- MPD announces state update: player Feb 23 10:18:48 rivoplus volumio[3106]: info: ControllerMpd::getState Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Feb 23 10:18:48 rivoplus volumio[3106]: info: sendMpdCommand status took 17 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: sendMpdCommand status took 17 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::parseState Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::parseState Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::parseTrackInfo Feb 23 10:18:48 rivoplus volumio[3106]: info: ControllerMpd::pushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::servicePushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CorePlayQueue::getTrack 5 Feb 23 10:18:48 rivoplus volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24752494&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/24752494&soundQuality=LOSSLESS","trackType":"tidal"} Feb 23 10:18:48 rivoplus volumio[3106]: verbose: CURRENT POSITION 5 Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::syncState stateService play Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::syncState currentStatus stop Feb 23 10:18:48 rivoplus volumio[3106]: info: ------------------------------ 25ms Feb 23 10:18:48 rivoplus volumio[3106]: info: Feb 23 10:18:48 rivoplus volumio[3106]: ---------------------------- MPD announces state update: player Feb 23 10:18:48 rivoplus volumio[3106]: info: ControllerMpd::getState Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Feb 23 10:18:48 rivoplus volumio[3106]: info: Feb 23 10:18:48 rivoplus volumio[3106]: ---------------------------- MPD announces state update: player Feb 23 10:18:48 rivoplus volumio[3106]: info: ControllerMpd::getState Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Feb 23 10:18:48 rivoplus volumio[3106]: info: sendMpdCommand status took 10 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: sendMpdCommand status took 9 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::parseState Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::parseTrackInfo Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::parseTrackInfo Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::parseState Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 10:18:48 rivoplus volumio[3106]: info: ControllerMpd::pushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::servicePushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CorePlayQueue::getTrack 5 Feb 23 10:18:48 rivoplus volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24752494&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/24752494&soundQuality=LOSSLESS","trackType":"tidal"} Feb 23 10:18:48 rivoplus volumio[3106]: verbose: CURRENT POSITION 5 Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::syncState stateService play Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::syncState currentStatus play Feb 23 10:18:48 rivoplus volumio[3106]: info: Received an update from plugin. extracting info from payload Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:48 rivoplus volumio[3106]: info: ControllerMpd::pushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::servicePushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CorePlayQueue::getTrack 5 Feb 23 10:18:48 rivoplus volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24752494&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/24752494&soundQuality=LOSSLESS","trackType":"tidal"} Feb 23 10:18:48 rivoplus volumio[3106]: verbose: CURRENT POSITION 5 Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::syncState stateService play Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::syncState currentStatus play Feb 23 10:18:48 rivoplus volumio[3106]: info: Received an update from plugin. extracting info from payload Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:48 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:48.921+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" state=STATUS_PLAYING positionMs=0 volume=33 Feb 23 10:18:48 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:48.922+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" id=tidal://song/24752494 title="Rock mr baby" Feb 23 10:18:48 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:48.922+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" state=STATUS_PLAYING positionMs=0 volume=33 Feb 23 10:18:48 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:48.922+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" id=tidal://song/24752494 title="Rock mr baby" Feb 23 10:18:48 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:48.922+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" state=STATUS_PLAYING positionMs=0 volume=33 Feb 23 10:18:48 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:48.923+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" state=STATUS_PLAYING positionMs=0 volume=33 Feb 23 10:18:48 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:48.923+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" id=tidal://song/24752494 title="Rock mr baby" Feb 23 10:18:48 rivoplus volumio[3106]: info: ------------------------------ 62ms Feb 23 10:18:48 rivoplus volumio[3106]: info: sendMpdCommand status took 39 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: sendMpdCommand playlistinfo took 31 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: info: sendMpdCommand playlistinfo took 30 milliseconds Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::parseState Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::parseTrackInfo Feb 23 10:18:48 rivoplus volumio[3106]: verbose: ControllerMpd::parseTrackInfo Feb 23 10:18:48 rivoplus volumio[3106]: info: ControllerMpd::pushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::servicePushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CorePlayQueue::getTrack 5 Feb 23 10:18:48 rivoplus volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24752494&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/24752494&soundQuality=LOSSLESS","trackType":"tidal"} Feb 23 10:18:48 rivoplus volumio[3106]: verbose: CURRENT POSITION 5 Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::syncState stateService play Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::syncState currentStatus play Feb 23 10:18:48 rivoplus volumio[3106]: info: Received an update from plugin. extracting info from payload Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:48 rivoplus volumio[3106]: info: ControllerMpd::pushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::servicePushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CorePlayQueue::getTrack 5 Feb 23 10:18:48 rivoplus volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24752494&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/24752494&soundQuality=LOSSLESS","trackType":"tidal"} Feb 23 10:18:48 rivoplus volumio[3106]: verbose: CURRENT POSITION 5 Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::syncState stateService play Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::syncState currentStatus play Feb 23 10:18:48 rivoplus volumio[3106]: info: Received an update from plugin. extracting info from payload Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:48 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:48 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:48.955+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" state=STATUS_PLAYING positionMs=273 volume=33 Feb 23 10:18:48 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:48.955+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" id=tidal://song/24752494 title="Rock mr baby" Feb 23 10:18:48 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:48.956+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" state=STATUS_PLAYING positionMs=273 volume=33 Feb 23 10:18:48 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:48.957+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" id=tidal://song/24752494 title="Rock mr baby" Feb 23 10:18:48 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:48.958+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" state=STATUS_PLAYING positionMs=273 volume=33 Feb 23 10:18:48 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:48.958+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" id=tidal://song/24752494 title="Rock mr baby" Feb 23 10:18:48 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:48.959+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" state=STATUS_PLAYING positionMs=273 volume=33 Feb 23 10:18:48 rivoplus volumio[3106]: info: ------------------------------ 79ms Feb 23 10:18:48 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:48.961+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" id=tidal://song/24752494 title="Rock mr baby" Feb 23 10:18:48 rivoplus volumio[3106]: info: ------------------------------ 73ms Feb 23 10:18:48 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:48 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:48 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:48 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:48 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:48 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:48 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:48 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:48 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:48 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:48 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:48 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:48 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:48 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:48 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:48 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:49 rivoplus volumio[3106]: info: sendMpdCommand playlistinfo took 73 milliseconds Feb 23 10:18:49 rivoplus volumio[3106]: verbose: ControllerMpd::parseTrackInfo Feb 23 10:18:49 rivoplus volumio[3106]: info: ControllerMpd::pushState Feb 23 10:18:49 rivoplus volumio[3106]: info: CoreCommandRouter::servicePushState Feb 23 10:18:49 rivoplus volumio[3106]: info: CorePlayQueue::getTrack 5 Feb 23 10:18:49 rivoplus volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24752494&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/24752494&soundQuality=LOSSLESS","trackType":"tidal"} Feb 23 10:18:49 rivoplus volumio[3106]: verbose: CURRENT POSITION 5 Feb 23 10:18:49 rivoplus volumio[3106]: info: CoreStateMachine::syncState stateService play Feb 23 10:18:49 rivoplus volumio[3106]: info: CoreStateMachine::syncState currentStatus play Feb 23 10:18:49 rivoplus volumio[3106]: info: Received an update from plugin. extracting info from payload Feb 23 10:18:49 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:49 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:49 rivoplus volumio[3106]: info: CoreStateMachine::pushState Feb 23 10:18:49 rivoplus volumio[3106]: info: CoreCommandRouter::volumioPushState Feb 23 10:18:49 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:49.017+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" state=STATUS_PLAYING positionMs=273 volume=33 Feb 23 10:18:49 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:49.018+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" id=tidal://song/24752494 title="Rock mr baby" Feb 23 10:18:49 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:49.018+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" state=STATUS_PLAYING positionMs=273 volume=33 Feb 23 10:18:49 rivoplus volumio5-onboarding[3799]: time=2026-02-23T10:18:49.018+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.39:42685 @ 0x3094300" id=tidal://song/24752494 title="Rock mr baby" Feb 23 10:18:49 rivoplus volumio[3106]: info: ------------------------------ 132ms Feb 23 10:18:49 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:49 rivoplus volumio[3106]: info: Signalling Playback active due to playback status change Feb 23 10:18:49 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:49 rivoplus volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Feb 23 10:18:54 rivoplus volumio[3106]: STREAMING PROXY: Upstream closed. Received 30874138 bytes Feb 23 10:18:54 rivoplus volumio[3106]: STREAMING PROXY: Client closed connection Feb 23 10:18:59 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 23 10:18:59 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: tidal , search Feb 23 10:18:59 rivoplus volumio[3106]: info: searchTIDALUri took 269 milliseconds Feb 23 10:18:59 rivoplus volumio[3106]: info: search took 271 milliseconds Feb 23 10:18:59 rivoplus volumio[3106]: info: All search sources collected, pushing search results Feb 23 10:18:59 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Feb 23 10:18:59 rivoplus volumio[3106]: info: browseTIDALUri took 605 milliseconds Feb 23 10:18:59 rivoplus volumio[3106]: info: Preload queue cleared Feb 23 10:18:59 rivoplus volumio[3106]: info: Preload queue cleared Feb 23 10:18:59 rivoplus volumio[3106]: info: Preload queue cleared Feb 23 10:18:59 rivoplus volumio[3106]: info: Preloading song: tidal://song/24846322 Feb 23 10:18:59 rivoplus volumio[3106]: info: Preloading song: tidal://song/24846319 Feb 23 10:18:59 rivoplus volumio[3106]: info: Preloading song: tidal://song/24846318 Feb 23 10:18:59 rivoplus volumio[3106]: info: Preloading song: tidal://song/24846321 Feb 23 10:18:59 rivoplus volumio[3106]: info: Preloading song: tidal://song/24846320 Feb 23 10:18:59 rivoplus volumio[3106]: info: Preloading song: tidal://song/24846323 Feb 23 10:18:59 rivoplus volumio[3106]: info: Preloading song: tidal://song/24846324 Feb 23 10:18:59 rivoplus volumio[3106]: info: Preloading song: tidal://song/24846325 Feb 23 10:19:00 rivoplus volumio[3106]: info: Exploding uri tidal://song/24846322 in service tidal Feb 23 10:19:00 rivoplus volumio[3106]: info: Exploding uri tidal://song/24846319 in service tidal Feb 23 10:19:00 rivoplus volumio[3106]: info: Exploding uri tidal://song/24846318 in service tidal Feb 23 10:19:00 rivoplus volumio[3106]: info: Exploding uri tidal://song/24846321 in service tidal Feb 23 10:19:00 rivoplus volumio[3106]: info: Exploding uri tidal://song/24846320 in service tidal Feb 23 10:19:00 rivoplus volumio[3106]: info: explodeTIDALUri took 220 milliseconds Feb 23 10:19:00 rivoplus volumio[3106]: info: explodeTIDALUri took 199 milliseconds Feb 23 10:19:00 rivoplus volumio[3106]: info: Exploding uri tidal://song/24846323 in service tidal Feb 23 10:19:00 rivoplus volumio[3106]: info: explodeTIDALUri took 198 milliseconds Feb 23 10:19:00 rivoplus volumio[3106]: info: Exploding uri tidal://song/24846324 in service tidal Feb 23 10:19:00 rivoplus volumio[3106]: info: Exploding uri tidal://song/24846325 in service tidal Feb 23 10:19:00 rivoplus volumio[3106]: info: explodeTIDALUri took 209 milliseconds Feb 23 10:19:00 rivoplus volumio[3106]: info: explodeTIDALUri took 206 milliseconds Feb 23 10:19:00 rivoplus volumio[3106]: info: explodeTIDALUri took 198 milliseconds Feb 23 10:19:00 rivoplus volumio[3106]: info: explodeTIDALUri took 208 milliseconds Feb 23 10:19:00 rivoplus volumio[3106]: info: Executing endpoint getSimilarArtists Feb 23 10:19:00 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 23 10:19:00 rivoplus volumio[3106]: info: Executing endpoint getSimilarArtists Feb 23 10:19:00 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 23 10:19:00 rivoplus volumio[3106]: info: Executing endpoint metavolumio Feb 23 10:19:00 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 23 10:19:00 rivoplus volumio[3106]: info: Executing endpoint metavolumio Feb 23 10:19:00 rivoplus volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 23 10:19:00 rivoplus volumio[3106]: info: explodeTIDALUri took 227 milliseconds Feb 23 10:19:02 rivoplus volumio[3106]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 10:19:02 rivoplus volumio[3106]: TypeError: Cannot read property 'slice' of null Feb 23 10:19:02 rivoplus volumio[3106]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Feb 23 10:19:02 rivoplus volumio[3106]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Feb 23 10:19:02 rivoplus volumio[3106]: at Object.onceWrapper (events.js:520:26) Feb 23 10:19:02 rivoplus volumio[3106]: at ClientRequest.emit (events.js:400:28) Feb 23 10:19:02 rivoplus volumio[3106]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Feb 23 10:19:02 rivoplus volumio[3106]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Feb 23 10:19:02 rivoplus volumio[3106]: at TLSSocket.socketOnData (_http_client.js:515:22) Feb 23 10:19:02 rivoplus volumio[3106]: at TLSSocket.emit (events.js:400:28) Feb 23 10:19:02 rivoplus volumio[3106]: at addChunk (internal/streams/readable.js:293:12) Feb 23 10:19:02 rivoplus volumio[3106]: at readableAddChunk (internal/streams/readable.js:267:9) Feb 23 10:19:02 rivoplus volumio[3106]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Feb 23 10:19:02 rivoplus volumio[3106]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Feb 23 10:19:02 rivoplus volumio[3106]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 10:19:02 rivoplus sudo[7770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-23 10:18 Feb 23 10:19:02 rivoplus sudo[7770]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 02:06:11 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="6fcef4b36db553cbf88901935dbe83e3"