-- Logs begin at Wed 2026-03-04 19:24:16 EET, end at Wed 2026-03-04 19:41:12 EET. -- Mar 04 19:40:10 rivoplus volumio[3166]: Searching plugin music_service/tidal Mar 04 19:40:10 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 04 19:40:11 rivoplus volumio[3166]: info: searchTIDALUri took 352 milliseconds Mar 04 19:40:11 rivoplus volumio[3166]: info: search took 353 milliseconds Mar 04 19:40:11 rivoplus volumio[3166]: info: All search sources collected, pushing search results Mar 04 19:40:12 rivoplus volumio[3166]: Searching plugin music_service/tidal Mar 04 19:40:12 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 04 19:40:12 rivoplus volumio[3166]: info: searchTIDALUri took 367 milliseconds Mar 04 19:40:12 rivoplus volumio[3166]: info: search took 368 milliseconds Mar 04 19:40:12 rivoplus volumio[3166]: info: All search sources collected, pushing search results Mar 04 19:40:20 rivoplus volumio[3166]: info: Preload queue cleared Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::ClearQueue Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::stop Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::stPlaybackTimer Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::updateTrackBlock Mar 04 19:40:20 rivoplus volumio[3166]: info: CorePlayQueue::getTrackBlock Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CorePlayQueue::getTrack 18 Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:20 rivoplus volumio[3166]: info: CorePlayQueue::getTrack 18 Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::serviceStop Mar 04 19:40:20 rivoplus volumio[3166]: info: CorePlayQueue::getTrack 18 Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::serviceStop Mar 04 19:40:20 rivoplus volumio[3166]: info: [1772646020151] ControllerTidal::stop Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 19:40:20 rivoplus volumio[3166]: info: ControllerMpd::stop Mar 04 19:40:20 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 19:40:20 rivoplus volumio[3166]: info: CorePlayQueue::clearPlayQueue Mar 04 19:40:20 rivoplus volumio[3166]: info: CorePlayQueue::saveQueue Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushQueue Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::addQueueItems Mar 04 19:40:20 rivoplus volumio[3166]: info: CorePlayQueue::addQueueItems Mar 04 19:40:20 rivoplus volumio[3166]: info: Preload queue cleared Mar 04 19:40:20 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/188511714 Mar 04 19:40:20 rivoplus volumio[3166]: info: Exploding uri tidal://song/188511714 in service tidal Mar 04 19:40:20 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/203335196 Mar 04 19:40:20 rivoplus volumio[3166]: info: Exploding uri tidal://song/203335196 in service tidal Mar 04 19:40:20 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/170892199 Mar 04 19:40:20 rivoplus volumio[3166]: info: Exploding uri tidal://song/170892199 in service tidal Mar 04 19:40:20 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/207868468 Mar 04 19:40:20 rivoplus volumio[3166]: info: Exploding uri tidal://song/207868468 in service tidal Mar 04 19:40:20 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/277370883 Mar 04 19:40:20 rivoplus volumio[3166]: info: Exploding uri tidal://song/277370883 in service tidal Mar 04 19:40:20 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/91329355 Mar 04 19:40:20 rivoplus volumio[3166]: info: Exploding uri tidal://song/91329355 in service tidal Mar 04 19:40:20 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/124446835 Mar 04 19:40:20 rivoplus volumio[3166]: info: Exploding uri tidal://song/124446835 in service tidal Mar 04 19:40:20 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/234454858 Mar 04 19:40:20 rivoplus volumio[3166]: info: Exploding uri tidal://song/234454858 in service tidal Mar 04 19:40:20 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/295698238 Mar 04 19:40:20 rivoplus volumio[3166]: info: Exploding uri tidal://song/295698238 in service tidal Mar 04 19:40:20 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/282299312 Mar 04 19:40:20 rivoplus volumio[3166]: info: Exploding uri tidal://song/282299312 in service tidal Mar 04 19:40:20 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/182433137 Mar 04 19:40:20 rivoplus volumio[3166]: info: Exploding uri tidal://song/182433137 in service tidal Mar 04 19:40:20 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/264614293 Mar 04 19:40:20 rivoplus volumio[3166]: info: Exploding uri tidal://song/264614293 in service tidal Mar 04 19:40:20 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/203337618 Mar 04 19:40:20 rivoplus volumio[3166]: info: Exploding uri tidal://song/203337618 in service tidal Mar 04 19:40:20 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/203336653 Mar 04 19:40:20 rivoplus volumio[3166]: info: Exploding uri tidal://song/203336653 in service tidal Mar 04 19:40:20 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/153563935 Mar 04 19:40:20 rivoplus volumio[3166]: info: Exploding uri tidal://song/153563935 in service tidal Mar 04 19:40:20 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/48289389 Mar 04 19:40:20 rivoplus volumio[3166]: info: Exploding uri tidal://song/48289389 in service tidal Mar 04 19:40:20 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/226569447 Mar 04 19:40:20 rivoplus volumio[3166]: info: Exploding uri tidal://song/226569447 in service tidal Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.173+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_STOPPED positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.173+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_STOPPED positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.174+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.174+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio[3166]: STREAMING PROXY: Client closed connection Mar 04 19:40:20 rivoplus volumio[3166]: STREAMING PROXY: Upstream closed. Received 17972753 bytes Mar 04 19:40:20 rivoplus volumio[3166]: STREAMING PROXY: Incomplete transfer. Expected 25879484, got 17972753 Mar 04 19:40:20 rivoplus volumio[3166]: STREAMING PROXY: Retrying with range: bytes=17972753- (attempt 1) Mar 04 19:40:20 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:20 rivoplus volumio[3166]: info: Mar 04 19:40:20 rivoplus volumio[3166]: ---------------------------- MPD announces state update: player Mar 04 19:40:20 rivoplus volumio[3166]: info: sendMpdCommand stop took 139 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: ControllerMpd::getState Mar 04 19:40:20 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:40:20 rivoplus volumio[3166]: info: Mar 04 19:40:20 rivoplus volumio[3166]: ---------------------------- MPD announces state update: player Mar 04 19:40:20 rivoplus volumio[3166]: info: ControllerMpd::getState Mar 04 19:40:20 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:40:20 rivoplus volumio[3166]: info: Mar 04 19:40:20 rivoplus volumio[3166]: ---------------------------- MPD announces state update: player Mar 04 19:40:20 rivoplus volumio[3166]: info: ControllerMpd::getState Mar 04 19:40:20 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:40:20 rivoplus volumio[3166]: info: Mar 04 19:40:20 rivoplus volumio[3166]: ---------------------------- MPD announces state update: player Mar 04 19:40:20 rivoplus volumio[3166]: info: ControllerMpd::getState Mar 04 19:40:20 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:40:20 rivoplus volumio[3166]: info: sendMpdCommand status took 14 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: sendMpdCommand status took 13 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: sendMpdCommand status took 11 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: sendMpdCommand status took 10 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: verbose: ControllerMpd::parseState Mar 04 19:40:20 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:40:20 rivoplus volumio[3166]: verbose: ControllerMpd::parseState Mar 04 19:40:20 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:40:20 rivoplus volumio[3166]: verbose: ControllerMpd::parseState Mar 04 19:40:20 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:40:20 rivoplus volumio[3166]: verbose: ControllerMpd::parseState Mar 04 19:40:20 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:40:20 rivoplus volumio[3166]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:40:20 rivoplus volumio[3166]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:40:20 rivoplus volumio[3166]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:40:20 rivoplus volumio[3166]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:40:20 rivoplus volumio[3166]: info: ControllerMpd::pushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::servicePushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:20 rivoplus volumio[3166]: info: CorePlayQueue::getTrack 18 Mar 04 19:40:20 rivoplus volumio[3166]: 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":"227800338&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/227800338&soundQuality=HI_RES","trackType":"tidal"} Mar 04 19:40:20 rivoplus volumio[3166]: verbose: CURRENT POSITION 18 Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::syncState stateService stop Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::syncState currentStatus stop Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:20 rivoplus volumio[3166]: info: No code Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:20 rivoplus volumio[3166]: info: ControllerMpd::pushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::servicePushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:20 rivoplus volumio[3166]: info: CorePlayQueue::getTrack 18 Mar 04 19:40:20 rivoplus volumio[3166]: 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":"227800338&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/227800338&soundQuality=HI_RES","trackType":"tidal"} Mar 04 19:40:20 rivoplus volumio[3166]: verbose: CURRENT POSITION 18 Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::syncState stateService stop Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::syncState currentStatus stop Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:20 rivoplus volumio[3166]: info: No code Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:20 rivoplus volumio[3166]: info: ControllerMpd::pushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::servicePushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:20 rivoplus volumio[3166]: info: CorePlayQueue::getTrack 18 Mar 04 19:40:20 rivoplus volumio[3166]: 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":"227800338&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/227800338&soundQuality=HI_RES","trackType":"tidal"} Mar 04 19:40:20 rivoplus volumio[3166]: verbose: CURRENT POSITION 18 Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::syncState stateService stop Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::syncState currentStatus stop Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:20 rivoplus volumio[3166]: info: No code Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:20 rivoplus volumio[3166]: info: ControllerMpd::pushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::servicePushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:20 rivoplus volumio[3166]: info: CorePlayQueue::getTrack 18 Mar 04 19:40:20 rivoplus volumio[3166]: 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":"227800338&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/227800338&soundQuality=HI_RES","trackType":"tidal"} Mar 04 19:40:20 rivoplus volumio[3166]: verbose: CURRENT POSITION 18 Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::syncState stateService stop Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::syncState currentStatus stop Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:20 rivoplus volumio[3166]: info: No code Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:20 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:20 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.378+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.379+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.379+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.379+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.379+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.380+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.380+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.380+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.381+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.381+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.381+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.382+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.383+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.383+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.383+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.384+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.384+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.383+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.384+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.384+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.385+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.385+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.385+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.385+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.386+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.386+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.386+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.385+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.387+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.387+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.388+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.388+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.388+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.388+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.387+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.389+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.389+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.389+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.389+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.389+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.389+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.390+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:20.390+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/227800338 title="Ultima dată" Mar 04 19:40:20 rivoplus volumio[3166]: info: ------------------------------ 127ms Mar 04 19:40:20 rivoplus volumio[3166]: info: ------------------------------ 125ms Mar 04 19:40:20 rivoplus volumio[3166]: info: ------------------------------ 125ms Mar 04 19:40:20 rivoplus volumio[3166]: info: ------------------------------ 125ms Mar 04 19:40:20 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:20 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:20 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:20 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:20 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:20 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:20 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:20 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:20 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:20 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:20 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:20 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:20 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:20 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:20 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:20 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:20 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:20 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:20 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:20 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:20 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:20 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:20 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:20 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:20 rivoplus volumio[3166]: info: MCU Signalled Playback Inactive Mar 04 19:40:20 rivoplus volumio[3166]: info: explodeTIDALUri took 642 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: explodeTIDALUri took 653 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: explodeTIDALUri took 638 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: explodeTIDALUri took 655 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: explodeTIDALUri took 655 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: explodeTIDALUri took 652 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: explodeTIDALUri took 666 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: explodeTIDALUri took 700 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: explodeTIDALUri took 700 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: MCU Signalled Playback Active Mar 04 19:40:20 rivoplus volumio[3166]: info: explodeTIDALUri took 693 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: explodeTIDALUri took 737 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: explodeTIDALUri took 722 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: explodeTIDALUri took 756 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: explodeTIDALUri took 782 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: explodeTIDALUri took 786 milliseconds Mar 04 19:40:20 rivoplus volumio[3166]: info: explodeTIDALUri took 802 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: explodeTIDALUri took 837 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushQueue Mar 04 19:40:21 rivoplus volumio[3166]: info: CorePlayQueue::saveQueue Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::updateTrackBlock Mar 04 19:40:21 rivoplus volumio[3166]: info: CorePlayQueue::getTrackBlock Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPlay Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::play index 16 Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::addQueueItems Mar 04 19:40:21 rivoplus volumio[3166]: info: CorePlayQueue::addQueueItems Mar 04 19:40:21 rivoplus volumio[3166]: info: Preload queue cleared Mar 04 19:40:21 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/384376337 Mar 04 19:40:21 rivoplus volumio[3166]: info: Exploding uri tidal://song/384376337 in service tidal Mar 04 19:40:21 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/207190752 Mar 04 19:40:21 rivoplus volumio[3166]: info: Exploding uri tidal://song/207190752 in service tidal Mar 04 19:40:21 rivoplus volumio[3166]: info: Adding Item to queue: tidal://song/277370888 Mar 04 19:40:21 rivoplus volumio[3166]: info: Exploding uri tidal://song/277370888 in service tidal Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::stop Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::play index undefined Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 19:40:21 rivoplus volumio[3166]: info: CorePlayQueue::getTrack 16 Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::startPlaybackTimer Mar 04 19:40:21 rivoplus volumio[3166]: info: CorePlayQueue::getTrack 16 Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetVisibleSources Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 04 19:40:21 rivoplus volumio[3166]: info: [1772646021059] ControllerTidal::clearAddPlayTrack Mar 04 19:40:21 rivoplus volumio[3166]: info: Getting stream with soundQuality HI_RES Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand stop took 23 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 19:40:21 rivoplus volumio[3166]: info: Mar 04 19:40:21 rivoplus volumio[3166]: ---------------------------- MPD announces system playlist update Mar 04 19:40:21 rivoplus volumio[3166]: info: Ignoring MPD Status Update Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand clear took 2 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/226569447&soundQuality=HI_RES" Mar 04 19:40:21 rivoplus volumio[3166]: info: Mar 04 19:40:21 rivoplus volumio[3166]: ---------------------------- MPD announces system playlist update Mar 04 19:40:21 rivoplus volumio[3166]: info: Ignoring MPD Status Update Mar 04 19:40:21 rivoplus volumio[3166]: info: Mar 04 19:40:21 rivoplus volumio[3166]: ---------------------------- MPD announces system playlist update Mar 04 19:40:21 rivoplus volumio[3166]: info: Ignoring MPD Status Update Mar 04 19:40:21 rivoplus volumio[3166]: info: Mar 04 19:40:21 rivoplus volumio[3166]: ---------------------------- MPD announces system playlist update Mar 04 19:40:21 rivoplus volumio[3166]: info: Ignoring MPD Status Update Mar 04 19:40:21 rivoplus volumio[3166]: error: updateQueue error: null Mar 04 19:40:21 rivoplus volumio[3166]: info: Mar 04 19:40:21 rivoplus volumio[3166]: ---------------------------- MPD announces system playlist update Mar 04 19:40:21 rivoplus volumio[3166]: info: Ignoring MPD Status Update Mar 04 19:40:21 rivoplus volumio[3166]: info: ------------------------------ 8ms Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/226569447&soundQuality=HI_RES" took 8 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: ------------------------------ 7ms Mar 04 19:40:21 rivoplus volumio[3166]: info: ------------------------------ 6ms Mar 04 19:40:21 rivoplus volumio[3166]: info: ------------------------------ 4ms Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand play Mar 04 19:40:21 rivoplus volumio[3166]: info: Mar 04 19:40:21 rivoplus volumio[3166]: ---------------------------- MPD announces system playlist update Mar 04 19:40:21 rivoplus volumio[3166]: info: Ignoring MPD Status Update Mar 04 19:40:21 rivoplus volumio[3166]: info: Mar 04 19:40:21 rivoplus volumio[3166]: ---------------------------- MPD announces system playlist update Mar 04 19:40:21 rivoplus volumio[3166]: info: Ignoring MPD Status Update Mar 04 19:40:21 rivoplus volumio[3166]: info: Mar 04 19:40:21 rivoplus volumio[3166]: ---------------------------- MPD announces system playlist update Mar 04 19:40:21 rivoplus volumio[3166]: STREAMING PROXY: Handling url /?data=tidal://song/226569447&soundQuality=HI_RES Mar 04 19:40:21 rivoplus volumio[3166]: info: Ignoring MPD Status Update Mar 04 19:40:21 rivoplus volumio[3166]: info: ------------------------------ 10ms Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand play took 7 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: Executing endpoint getStreamUrltidal Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Mar 04 19:40:21 rivoplus volumio[3166]: info: ------------------------------ 18ms Mar 04 19:40:21 rivoplus volumio[3166]: info: ------------------------------ 17ms Mar 04 19:40:21 rivoplus volumio[3166]: STREAMING PROXY: Resuming with range: bytes=17972753- Mar 04 19:40:21 rivoplus volumio[3166]: STREAMING PROXY: Resume upstream status code: 206 Mar 04 19:40:21 rivoplus volumio[3166]: info: explodeTIDALUri took 254 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: explodeTIDALUri took 292 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: explodeTIDALUri took 310 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushQueue Mar 04 19:40:21 rivoplus volumio[3166]: info: CorePlayQueue::saveQueue Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::updateTrackBlock Mar 04 19:40:21 rivoplus volumio[3166]: info: CorePlayQueue::getTrackBlock Mar 04 19:40:21 rivoplus volumio[3166]: info: getStreamUrl took 485 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic4MDNiYWNjNjU2MjhiOTE5ZWRhNDIwOWE4NGU1MThiZl82Mi5tcDQ/0.flac?token=1772649621~ZDBkN2Q5MDdiMzlmNzdmMDZkMDZhMDhiODU2ZGMwYzIzNzBjMTYxZA== Mar 04 19:40:21 rivoplus volumio[3166]: STREAMING PROXY: Requesting upstream with range: none Mar 04 19:40:21 rivoplus volumio[3166]: STREAMING PROXY: Upstream status code: 200 Mar 04 19:40:21 rivoplus volumio[3166]: info: Mar 04 19:40:21 rivoplus volumio[3166]: ---------------------------- MPD announces state update: player Mar 04 19:40:21 rivoplus volumio[3166]: info: ControllerMpd::getState Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:40:21 rivoplus volumio[3166]: info: Mar 04 19:40:21 rivoplus volumio[3166]: ---------------------------- MPD announces state update: player Mar 04 19:40:21 rivoplus volumio[3166]: info: ControllerMpd::getState Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:40:21 rivoplus volumio[3166]: info: Mar 04 19:40:21 rivoplus volumio[3166]: ---------------------------- MPD announces state update: player Mar 04 19:40:21 rivoplus volumio[3166]: info: ControllerMpd::getState Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:40:21 rivoplus volumio[3166]: info: Mar 04 19:40:21 rivoplus volumio[3166]: ---------------------------- MPD announces state update: player Mar 04 19:40:21 rivoplus volumio[3166]: info: ControllerMpd::getState Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:40:21 rivoplus volumio[3166]: info: Mar 04 19:40:21 rivoplus volumio[3166]: ---------------------------- MPD announces state update: player Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand status took 15 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: ControllerMpd::getState Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::parseState Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:40:21 rivoplus volumio[3166]: info: Mar 04 19:40:21 rivoplus volumio[3166]: ---------------------------- MPD announces state update: player Mar 04 19:40:21 rivoplus volumio[3166]: info: ControllerMpd::getState Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand status took 19 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand status took 17 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand status took 16 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand status took 5 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand status took 4 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::parseState Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::parseState Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::parseState Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::parseState Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::parseState Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:40:21 rivoplus volumio[3166]: info: ControllerMpd::pushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::servicePushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CorePlayQueue::getTrack 16 Mar 04 19:40:21 rivoplus volumio[3166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":167,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"226569447&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/226569447&soundQuality=HI_RES","trackType":"tidal"} Mar 04 19:40:21 rivoplus volumio[3166]: verbose: CURRENT POSITION 16 Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::syncState stateService play Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::syncState currentStatus stop Mar 04 19:40:21 rivoplus volumio[3166]: info: ------------------------------ 28ms Mar 04 19:40:21 rivoplus volumio[3166]: info: Mar 04 19:40:21 rivoplus volumio[3166]: ---------------------------- MPD announces state update: player Mar 04 19:40:21 rivoplus volumio[3166]: info: ControllerMpd::getState Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:40:21 rivoplus volumio[3166]: info: Mar 04 19:40:21 rivoplus volumio[3166]: ---------------------------- MPD announces state update: player Mar 04 19:40:21 rivoplus volumio[3166]: info: ControllerMpd::getState Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand status took 6 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand status took 6 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::parseState Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::parseState Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:40:21 rivoplus volumio[3166]: info: ControllerMpd::pushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::servicePushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CorePlayQueue::getTrack 16 Mar 04 19:40:21 rivoplus volumio[3166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":167,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"226569447&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/226569447&soundQuality=HI_RES","trackType":"tidal"} Mar 04 19:40:21 rivoplus volumio[3166]: verbose: CURRENT POSITION 16 Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::syncState stateService play Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:40:21 rivoplus volumio[3166]: info: Received an update from plugin. extracting info from payload Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:21 rivoplus volumio[3166]: info: ControllerMpd::pushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::servicePushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CorePlayQueue::getTrack 16 Mar 04 19:40:21 rivoplus volumio[3166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":167,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"226569447&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/226569447&soundQuality=HI_RES","trackType":"tidal"} Mar 04 19:40:21 rivoplus volumio[3166]: verbose: CURRENT POSITION 16 Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::syncState stateService play Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:40:21 rivoplus volumio[3166]: info: Received an update from plugin. extracting info from payload Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:21 rivoplus volumio[3166]: info: ControllerMpd::pushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::servicePushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CorePlayQueue::getTrack 16 Mar 04 19:40:21 rivoplus volumio[3166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":167,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"226569447&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/226569447&soundQuality=HI_RES","trackType":"tidal"} Mar 04 19:40:21 rivoplus volumio[3166]: verbose: CURRENT POSITION 16 Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::syncState stateService play Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:40:21 rivoplus volumio[3166]: info: Received an update from plugin. extracting info from payload Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:21 rivoplus volumio[3166]: info: ControllerMpd::pushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::servicePushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CorePlayQueue::getTrack 16 Mar 04 19:40:21 rivoplus volumio[3166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":167,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"226569447&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/226569447&soundQuality=HI_RES","trackType":"tidal"} Mar 04 19:40:21 rivoplus volumio[3166]: verbose: CURRENT POSITION 16 Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::syncState stateService play Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:40:21 rivoplus volumio[3166]: info: Received an update from plugin. extracting info from payload Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:21 rivoplus volumio[3166]: info: ControllerMpd::pushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::servicePushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CorePlayQueue::getTrack 16 Mar 04 19:40:21 rivoplus volumio[3166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":167,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"226569447&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/226569447&soundQuality=HI_RES","trackType":"tidal"} Mar 04 19:40:21 rivoplus volumio[3166]: verbose: CURRENT POSITION 16 Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::syncState stateService play Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:40:21 rivoplus volumio[3166]: info: Received an update from plugin. extracting info from payload Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:21 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.948+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.948+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.949+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.949+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.950+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.951+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.951+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.951+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.951+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.952+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.952+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.951+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.952+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.952+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.953+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.953+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.954+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.954+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.954+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.955+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.955+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.955+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.955+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.955+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.956+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.956+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.957+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.957+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.957+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.958+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.958+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.964+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.965+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.968+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.969+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.973+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:21 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:21.974+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:21 rivoplus volumio[3166]: info: ------------------------------ 157ms Mar 04 19:40:21 rivoplus volumio[3166]: info: ------------------------------ 156ms Mar 04 19:40:21 rivoplus volumio[3166]: info: ------------------------------ 155ms Mar 04 19:40:21 rivoplus volumio[3166]: info: ------------------------------ 146ms Mar 04 19:40:21 rivoplus volumio[3166]: info: ------------------------------ 143ms Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand playlistinfo took 125 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: info: sendMpdCommand playlistinfo took 124 milliseconds Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:40:21 rivoplus volumio[3166]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:40:21 rivoplus volumio[3166]: info: ControllerMpd::pushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::servicePushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CorePlayQueue::getTrack 16 Mar 04 19:40:21 rivoplus volumio[3166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":167,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"226569447&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/226569447&soundQuality=HI_RES","trackType":"tidal"} Mar 04 19:40:21 rivoplus volumio[3166]: verbose: CURRENT POSITION 16 Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::syncState stateService play Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:40:21 rivoplus volumio[3166]: info: Received an update from plugin. extracting info from payload Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:21 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:22 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:22 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:22 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:22 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:22 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:22 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:22 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:22 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:22 rivoplus volumio[3166]: info: ControllerMpd::pushState Mar 04 19:40:22 rivoplus volumio[3166]: info: CoreCommandRouter::servicePushState Mar 04 19:40:22 rivoplus volumio[3166]: info: CorePlayQueue::getTrack 16 Mar 04 19:40:22 rivoplus volumio[3166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":167,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"226569447&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/226569447&soundQuality=HI_RES","trackType":"tidal"} Mar 04 19:40:22 rivoplus volumio[3166]: verbose: CURRENT POSITION 16 Mar 04 19:40:22 rivoplus volumio[3166]: info: CoreStateMachine::syncState stateService play Mar 04 19:40:22 rivoplus volumio[3166]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:40:22 rivoplus volumio[3166]: info: Received an update from plugin. extracting info from payload Mar 04 19:40:22 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:22 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:22 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:22 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:22 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:22 rivoplus volumio[3166]: info: CoreStateMachine::pushState Mar 04 19:40:22 rivoplus volumio[3166]: info: CoreCommandRouter::volumioPushState Mar 04 19:40:22 rivoplus volumio[3166]: info: CoreCommandRouter::volumioGetState Mar 04 19:40:22 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:40:22 rivoplus volumio[3166]: info: MRS: Pushing multiroomSync output Mar 04 19:40:22 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:22.020+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:22 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:22.020+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:22 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:22.021+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:22 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:22.021+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:22 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:22.022+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:22 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:22.022+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:22 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:22.023+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:22 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:22.023+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:22 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:22.024+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:22 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:22.024+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" state=STATUS_PLAYING positionMs=0 volume=41 Mar 04 19:40:22 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:22.025+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.213:49813 @ 0x2401590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:22 rivoplus volumio5-onboarding[3857]: time=2026-03-04T19:40:22.025+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%23,192.168.100.213:49813 @ 0x2959590" id=tidal://song/226569447 title="Inima Ta" Mar 04 19:40:22 rivoplus volumio[3166]: info: ------------------------------ 174ms Mar 04 19:40:22 rivoplus volumio[3166]: info: ------------------------------ 174ms Mar 04 19:40:22 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:22 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:22 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:22 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:22 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:22 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:22 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:22 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:22 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:22 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:22 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:22 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:22 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:22 rivoplus volumio[3166]: info: Signalling Playback active due to playback status change Mar 04 19:40:22 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:22 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:22 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:22 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:22 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:22 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:22 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:22 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:22 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:22 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:22 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:22 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:22 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:22 rivoplus volumio[3166]: info: Updating RAAT Signal Path Mar 04 19:40:27 rivoplus volumio[3166]: STREAMING PROXY: Upstream closed. Received 33046037 bytes Mar 04 19:40:27 rivoplus volumio[3166]: STREAMING PROXY: Client closed connection Mar 04 19:41:01 rivoplus volumio[3166]: Searching plugin music_service/tidal Mar 04 19:41:01 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 04 19:41:01 rivoplus volumio[3166]: info: searchTIDALUri took 371 milliseconds Mar 04 19:41:01 rivoplus volumio[3166]: info: search took 373 milliseconds Mar 04 19:41:01 rivoplus volumio[3166]: info: All search sources collected, pushing search results Mar 04 19:41:02 rivoplus volumio[3166]: Searching plugin music_service/tidal Mar 04 19:41:02 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 04 19:41:02 rivoplus volumio[3166]: info: searchTIDALUri took 399 milliseconds Mar 04 19:41:02 rivoplus volumio[3166]: info: search took 400 milliseconds Mar 04 19:41:02 rivoplus volumio[3166]: info: All search sources collected, pushing search results Mar 04 19:41:04 rivoplus volumio[3166]: Searching plugin music_service/tidal Mar 04 19:41:04 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 04 19:41:04 rivoplus volumio[3166]: info: searchTIDALUri took 376 milliseconds Mar 04 19:41:04 rivoplus volumio[3166]: info: search took 377 milliseconds Mar 04 19:41:04 rivoplus volumio[3166]: info: All search sources collected, pushing search results Mar 04 19:41:07 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Mar 04 19:41:08 rivoplus volumio[3166]: info: browseTIDALUri took 845 milliseconds Mar 04 19:41:08 rivoplus volumio[3166]: info: Preload queue cleared Mar 04 19:41:08 rivoplus volumio[3166]: info: Preload queue cleared Mar 04 19:41:08 rivoplus volumio[3166]: info: Preload queue cleared Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/465784600 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/284040117 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/228608515 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/366327090 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/351886776 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/491733995 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/216065561 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/288716882 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/322850416 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/448530708 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/215768516 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/262762440 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/216137638 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/314440880 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/480105888 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/462351906 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/292536069 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/243563823 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/216065596 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/220116646 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/459705647 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/489002743 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/233445498 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/216065592 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/216137653 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/216065563 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/294072751 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/240480259 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/216065557 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/459734877 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/311695131 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/216136021 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/459749644 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/459749171 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/459747804 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/459747734 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/459743020 Mar 04 19:41:08 rivoplus volumio[3166]: info: Preloading song: tidal://song/459734848 Mar 04 19:41:08 rivoplus volumio[3166]: info: Exploding uri tidal://song/465784600 in service tidal Mar 04 19:41:08 rivoplus volumio[3166]: info: Exploding uri tidal://song/284040117 in service tidal Mar 04 19:41:08 rivoplus volumio[3166]: info: Executing endpoint getSimilarArtists Mar 04 19:41:08 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Mar 04 19:41:08 rivoplus volumio[3166]: info: Exploding uri tidal://song/228608515 in service tidal Mar 04 19:41:08 rivoplus volumio[3166]: info: Executing endpoint getSimilarArtists Mar 04 19:41:08 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Mar 04 19:41:08 rivoplus volumio[3166]: info: Exploding uri tidal://song/366327090 in service tidal Mar 04 19:41:08 rivoplus volumio[3166]: info: Executing endpoint metavolumio Mar 04 19:41:08 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 19:41:08 rivoplus volumio[3166]: info: Executing endpoint metavolumio Mar 04 19:41:08 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 19:41:08 rivoplus volumio[3166]: info: Exploding uri tidal://song/351886776 in service tidal Mar 04 19:41:08 rivoplus volumio[3166]: info: Exploding uri tidal://song/491733995 in service tidal Mar 04 19:41:08 rivoplus volumio[3166]: info: explodeTIDALUri took 272 milliseconds Mar 04 19:41:08 rivoplus volumio[3166]: info: Exploding uri tidal://song/216065561 in service tidal Mar 04 19:41:08 rivoplus volumio[3166]: info: explodeTIDALUri took 271 milliseconds Mar 04 19:41:08 rivoplus volumio[3166]: info: Exploding uri tidal://song/288716882 in service tidal Mar 04 19:41:08 rivoplus volumio[3166]: info: explodeTIDALUri took 253 milliseconds Mar 04 19:41:08 rivoplus volumio[3166]: info: Exploding uri tidal://song/322850416 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 246 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/448530708 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 266 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/215768516 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 279 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/262762440 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 282 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/216137638 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 266 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/314440880 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 281 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/480105888 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 267 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/462351906 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 257 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/292536069 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 283 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/243563823 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 290 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/216065596 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/220116646 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 327 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 282 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/459705647 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 300 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/489002743 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 270 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/233445498 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 267 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/216065592 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/216137653 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 333 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 276 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/216065563 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 295 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/294072751 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 266 milliseconds Mar 04 19:41:09 rivoplus volumio[3166]: info: Exploding uri tidal://song/240480259 in service tidal Mar 04 19:41:09 rivoplus volumio[3166]: info: explodeTIDALUri took 289 milliseconds Mar 04 19:41:10 rivoplus volumio[3166]: info: Exploding uri tidal://song/216065557 in service tidal Mar 04 19:41:10 rivoplus volumio[3166]: info: explodeTIDALUri took 273 milliseconds Mar 04 19:41:10 rivoplus volumio[3166]: info: Exploding uri tidal://song/459734877 in service tidal Mar 04 19:41:10 rivoplus volumio[3166]: info: Exploding uri tidal://song/311695131 in service tidal Mar 04 19:41:10 rivoplus volumio[3166]: info: explodeTIDALUri took 266 milliseconds Mar 04 19:41:10 rivoplus volumio[3166]: info: explodeTIDALUri took 337 milliseconds Mar 04 19:41:10 rivoplus volumio[3166]: info: Exploding uri tidal://song/216136021 in service tidal Mar 04 19:41:10 rivoplus volumio[3166]: info: explodeTIDALUri took 295 milliseconds Mar 04 19:41:10 rivoplus volumio[3166]: info: Exploding uri tidal://song/459749644 in service tidal Mar 04 19:41:10 rivoplus volumio[3166]: info: Exploding uri tidal://song/459749171 in service tidal Mar 04 19:41:10 rivoplus volumio[3166]: info: explodeTIDALUri took 298 milliseconds Mar 04 19:41:10 rivoplus volumio[3166]: info: explodeTIDALUri took 283 milliseconds Mar 04 19:41:10 rivoplus volumio[3166]: info: Exploding uri tidal://song/459747804 in service tidal Mar 04 19:41:10 rivoplus volumio[3166]: info: explodeTIDALUri took 254 milliseconds Mar 04 19:41:10 rivoplus volumio[3166]: info: Exploding uri tidal://song/459747734 in service tidal Mar 04 19:41:10 rivoplus volumio[3166]: info: explodeTIDALUri took 263 milliseconds Mar 04 19:41:10 rivoplus volumio[3166]: info: Exploding uri tidal://song/459743020 in service tidal Mar 04 19:41:10 rivoplus volumio[3166]: info: explodeTIDALUri took 274 milliseconds Mar 04 19:41:10 rivoplus volumio[3166]: info: explodeTIDALUri took 247 milliseconds Mar 04 19:41:10 rivoplus volumio[3166]: info: Exploding uri tidal://song/459734848 in service tidal Mar 04 19:41:10 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Mar 04 19:41:10 rivoplus volumio[3166]: info: explodeTIDALUri took 265 milliseconds Mar 04 19:41:10 rivoplus volumio[3166]: info: explodeTIDALUri took 287 milliseconds Mar 04 19:41:10 rivoplus volumio[3166]: info: explodeTIDALUri took 299 milliseconds Mar 04 19:41:10 rivoplus volumio[3166]: info: explodeTIDALUri took 260 milliseconds Mar 04 19:41:10 rivoplus volumio[3166]: info: explodeTIDALUri took 262 milliseconds Mar 04 19:41:10 rivoplus volumio[3166]: info: browseTIDALUri took 380 milliseconds Mar 04 19:41:10 rivoplus volumio[3166]: info: Preload queue cleared Mar 04 19:41:11 rivoplus volumio[3166]: info: Executing endpoint getSimilarAlbums Mar 04 19:41:11 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 04 19:41:11 rivoplus volumio[3166]: info: Executing endpoint getSimilarAlbums Mar 04 19:41:11 rivoplus volumio[3166]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 04 19:41:11 rivoplus volumio[3166]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 19:41:11 rivoplus volumio[3166]: TypeError: Cannot read property 'slice' of null Mar 04 19:41:11 rivoplus volumio[3166]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 04 19:41:11 rivoplus volumio[3166]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 04 19:41:11 rivoplus volumio[3166]: at Object.onceWrapper (events.js:520:26) Mar 04 19:41:11 rivoplus volumio[3166]: at ClientRequest.emit (events.js:400:28) Mar 04 19:41:11 rivoplus volumio[3166]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Mar 04 19:41:11 rivoplus volumio[3166]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Mar 04 19:41:11 rivoplus volumio[3166]: at TLSSocket.socketOnData (_http_client.js:515:22) Mar 04 19:41:11 rivoplus volumio[3166]: at TLSSocket.emit (events.js:400:28) Mar 04 19:41:11 rivoplus volumio[3166]: at addChunk (internal/streams/readable.js:293:12) Mar 04 19:41:11 rivoplus volumio[3166]: at readableAddChunk (internal/streams/readable.js:267:9) Mar 04 19:41:11 rivoplus volumio[3166]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Mar 04 19:41:11 rivoplus volumio[3166]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Mar 04 19:41:11 rivoplus volumio[3166]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 19:41:12 rivoplus sudo[7409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-04 19:40 Mar 04 19:41:12 rivoplus sudo[7409]: 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="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 11:19:27 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="a7285124ffc88c43265bb2db03526f6a"