-- Logs begin at Fri 2025-07-04 04:15:07 CEST, end at Wed 2025-07-09 21:05:35 CEST. -- Jul 09 21:04:00 rivo volumio[3110]: info: Preload queue cleared Jul 09 21:04:00 rivo volumio[3110]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 09 21:04:00 rivo volumio[3110]: info: CoreStateMachine::ClearQueue Jul 09 21:04:00 rivo volumio[3110]: info: CoreStateMachine::stop Jul 09 21:04:00 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 21:04:00 rivo volumio[3110]: info: CoreStateMachine::stPlaybackTimer Jul 09 21:04:00 rivo volumio[3110]: info: CoreStateMachine::updateTrackBlock Jul 09 21:04:00 rivo volumio[3110]: info: CorePlayQueue::getTrackBlock Jul 09 21:04:00 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:00 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:00 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 21:04:00 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:00 rivo volumio[3110]: info: CoreStateMachine::serviceStop Jul 09 21:04:00 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:00 rivo volumio[3110]: info: CoreCommandRouter::serviceStop Jul 09 21:04:00 rivo volumio[3110]: info: [1752087840326] ControllerTidal::stop Jul 09 21:04:00 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 21:04:00 rivo volumio[3110]: info: ControllerMpd::stop Jul 09 21:04:00 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 21:04:00 rivo volumio[3110]: info: CorePlayQueue::clearPlayQueue Jul 09 21:04:00 rivo volumio[3110]: info: CorePlayQueue::saveQueue Jul 09 21:04:00 rivo volumio[3110]: info: CoreCommandRouter::volumioPushQueue Jul 09 21:04:00 rivo volumio[3110]: info: CoreStateMachine::addQueueItems Jul 09 21:04:00 rivo volumio[3110]: info: CorePlayQueue::addQueueItems Jul 09 21:04:00 rivo volumio[3110]: info: Preload queue cleared Jul 09 21:04:00 rivo volumio[3110]: info: Adding Item to queue: tidal://album/347405478 Jul 09 21:04:00 rivo volumio[3110]: info: Exploding uri tidal://album/347405478 in service tidal Jul 09 21:04:00 rivo volumio[3110]: STREAMING PROXY: Client dropped request, destroying Jul 09 21:04:00 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:00 rivo volumio[3110]: info: sendMpdCommand stop took 57 milliseconds Jul 09 21:04:00 rivo volumio[3110]: info: Jul 09 21:04:00 rivo volumio[3110]: ---------------------------- MPD announces state update: player Jul 09 21:04:00 rivo volumio[3110]: info: ControllerMpd::getState Jul 09 21:04:00 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Jul 09 21:04:00 rivo volumio[3110]: info: sendMpdCommand status took 1 milliseconds Jul 09 21:04:00 rivo volumio[3110]: verbose: ControllerMpd::parseState Jul 09 21:04:00 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 21:04:00 rivo volumio[3110]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 09 21:04:00 rivo volumio[3110]: verbose: ControllerMpd::parseTrackInfo Jul 09 21:04:00 rivo volumio[3110]: info: ControllerMpd::pushState Jul 09 21:04:00 rivo volumio[3110]: info: CoreCommandRouter::servicePushState Jul 09 21:04:00 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:00 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 21:04:00 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:00 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:00 rivo volumio[3110]: 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":"207383958&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/207383958&soundQuality=LOSSLESS","trackType":"tidal"} Jul 09 21:04:00 rivo volumio[3110]: verbose: CURRENT POSITION 0 Jul 09 21:04:00 rivo volumio[3110]: info: CoreStateMachine::syncState stateService stop Jul 09 21:04:00 rivo volumio[3110]: info: CoreStateMachine::syncState currentStatus stop Jul 09 21:04:00 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:00 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:00 rivo volumio[3110]: info: No code Jul 09 21:04:00 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:00 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:00 rivo volumio[3110]: info: ------------------------------ 25ms Jul 09 21:04:00 rivo volumio[3110]: info: Signalling Playback active due to playback status change Jul 09 21:04:00 rivo volumio[3110]: info: Signalling Playback active due to playback status change Jul 09 21:04:00 rivo volumio[3110]: info: Signalling Playback active due to playback status change Jul 09 21:04:00 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:00 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:00 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:00 rivo volumio[3110]: info: MCU Signalled Playback Inactive Jul 09 21:04:00 rivo volumio[3110]: info: MCU Signalled Playback Active Jul 09 21:04:01 rivo volumio[3110]: info: explodeTIDALUri took 903 milliseconds Jul 09 21:04:01 rivo volumio[3110]: info: CoreCommandRouter::volumioPushQueue Jul 09 21:04:01 rivo volumio[3110]: info: CorePlayQueue::saveQueue Jul 09 21:04:01 rivo volumio[3110]: info: CoreStateMachine::updateTrackBlock Jul 09 21:04:01 rivo volumio[3110]: info: CorePlayQueue::getTrackBlock Jul 09 21:04:01 rivo volumio[3110]: info: CoreCommandRouter::volumioPlay Jul 09 21:04:01 rivo volumio[3110]: info: CoreStateMachine::play index 0 Jul 09 21:04:01 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 21:04:01 rivo volumio[3110]: info: CoreStateMachine::stop Jul 09 21:04:01 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 21:04:01 rivo volumio[3110]: info: CoreStateMachine::play index undefined Jul 09 21:04:01 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 21:04:01 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:01 rivo volumio[3110]: info: CoreStateMachine::startPlaybackTimer Jul 09 21:04:01 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:01 rivo volumio[3110]: info: [1752087841254] ControllerTidal::clearAddPlayTrack Jul 09 21:04:01 rivo volumio[3110]: info: Getting stream with soundQuality LOSSLESS Jul 09 21:04:01 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 21:04:01 rivo volumio[3110]: info: sendMpdCommand stop took 11 milliseconds Jul 09 21:04:01 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand clear Jul 09 21:04:01 rivo volumio[3110]: info: Jul 09 21:04:01 rivo volumio[3110]: ---------------------------- MPD announces system playlist update Jul 09 21:04:01 rivo volumio[3110]: info: Ignoring MPD Status Update Jul 09 21:04:01 rivo volumio[3110]: info: sendMpdCommand clear took 1 milliseconds Jul 09 21:04:01 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/347405479&soundQuality=LOSSLESS" Jul 09 21:04:01 rivo volumio[3110]: error: updateQueue error: null Jul 09 21:04:01 rivo volumio[3110]: info: Jul 09 21:04:01 rivo volumio[3110]: ---------------------------- MPD announces system playlist update Jul 09 21:04:01 rivo volumio[3110]: info: Ignoring MPD Status Update Jul 09 21:04:01 rivo volumio[3110]: info: ------------------------------ 4ms Jul 09 21:04:01 rivo volumio[3110]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/347405479&soundQuality=LOSSLESS" took 3 milliseconds Jul 09 21:04:01 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 21:04:01 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand play Jul 09 21:04:01 rivo volumio[3110]: info: ------------------------------ 3ms Jul 09 21:04:01 rivo volumio[3110]: info: sendMpdCommand play took 2 milliseconds Jul 09 21:04:01 rivo volumio[3110]: STREAMING PROXY: Handling url /?data=tidal://song/347405479&soundQuality=LOSSLESS Jul 09 21:04:01 rivo volumio[3110]: info: Executing endpoint getStreamUrltidal Jul 09 21:04:01 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jul 09 21:04:01 rivo volumio[3110]: info: getStreamUrl took 335 milliseconds Jul 09 21:04:01 rivo volumio[3110]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1ZGI2OTFiNDhiMTNjMGU2MjFhMzNiNjQzOWZhYjE2Yi5tcDQ/0.flac?token=1752091441~OTk2MGMzM2MyNDMxODU2ZGI3OTE2MmRlMzIwMzkwZGRhNjA1NGE0MA== Jul 09 21:04:01 rivo volumio[3110]: STREAMING PROXY: Response: 200, length: 62905087 Jul 09 21:04:01 rivo volumio[3110]: info: Jul 09 21:04:01 rivo volumio[3110]: ---------------------------- MPD announces state update: player Jul 09 21:04:01 rivo volumio[3110]: info: ControllerMpd::getState Jul 09 21:04:01 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Jul 09 21:04:01 rivo volumio[3110]: info: sendMpdCommand status took 22 milliseconds Jul 09 21:04:01 rivo volumio[3110]: verbose: ControllerMpd::parseState Jul 09 21:04:01 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 21:04:01 rivo volumio[3110]: info: Jul 09 21:04:01 rivo volumio[3110]: ---------------------------- MPD announces state update: player Jul 09 21:04:01 rivo volumio[3110]: info: ControllerMpd::getState Jul 09 21:04:01 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Jul 09 21:04:01 rivo volumio[3110]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 09 21:04:01 rivo volumio[3110]: verbose: ControllerMpd::parseTrackInfo Jul 09 21:04:01 rivo volumio[3110]: info: ControllerMpd::pushState Jul 09 21:04:01 rivo volumio[3110]: info: CoreCommandRouter::servicePushState Jul 09 21:04:01 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:01 rivo volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":796,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"347405479&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/347405479&soundQuality=LOSSLESS","trackType":"tidal"} Jul 09 21:04:01 rivo volumio[3110]: verbose: CURRENT POSITION 0 Jul 09 21:04:01 rivo volumio[3110]: info: CoreStateMachine::syncState stateService play Jul 09 21:04:01 rivo volumio[3110]: info: CoreStateMachine::syncState currentStatus stop Jul 09 21:04:01 rivo volumio[3110]: info: ------------------------------ 31ms Jul 09 21:04:01 rivo volumio[3110]: info: sendMpdCommand status took 5 milliseconds Jul 09 21:04:01 rivo volumio[3110]: verbose: ControllerMpd::parseState Jul 09 21:04:01 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 21:04:01 rivo volumio[3110]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 09 21:04:01 rivo volumio[3110]: verbose: ControllerMpd::parseTrackInfo Jul 09 21:04:01 rivo volumio[3110]: info: ControllerMpd::pushState Jul 09 21:04:01 rivo volumio[3110]: info: CoreCommandRouter::servicePushState Jul 09 21:04:01 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:01 rivo volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":796,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"598 Kbps","isStreaming":false,"title":"347405479&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/347405479&soundQuality=LOSSLESS","trackType":"tidal"} Jul 09 21:04:01 rivo volumio[3110]: verbose: CURRENT POSITION 0 Jul 09 21:04:01 rivo volumio[3110]: info: CoreStateMachine::syncState stateService play Jul 09 21:04:01 rivo volumio[3110]: info: CoreStateMachine::syncState currentStatus play Jul 09 21:04:01 rivo volumio[3110]: info: Received an update from plugin. extracting info from payload Jul 09 21:04:01 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:01 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 21:04:01 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:01 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:01 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:01 rivo volumio[3110]: info: ------------------------------ 26ms Jul 09 21:04:01 rivo volumio[3110]: info: Signalling Playback active due to playback status change Jul 09 21:04:01 rivo volumio[3110]: info: Signalling Playback active due to playback status change Jul 09 21:04:01 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:01 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:04 rivo volumio[3110]: info: Preload queue cleared Jul 09 21:04:04 rivo volumio[3110]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 09 21:04:04 rivo volumio[3110]: info: CoreStateMachine::ClearQueue Jul 09 21:04:04 rivo volumio[3110]: info: CoreStateMachine::stop Jul 09 21:04:04 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 21:04:04 rivo volumio[3110]: info: CoreStateMachine::stPlaybackTimer Jul 09 21:04:04 rivo volumio[3110]: info: CoreStateMachine::updateTrackBlock Jul 09 21:04:04 rivo volumio[3110]: info: CorePlayQueue::getTrackBlock Jul 09 21:04:04 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:04 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:04 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 21:04:04 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:04 rivo volumio[3110]: info: CoreStateMachine::serviceStop Jul 09 21:04:04 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:04 rivo volumio[3110]: info: CoreCommandRouter::serviceStop Jul 09 21:04:04 rivo volumio[3110]: info: [1752087844767] ControllerTidal::stop Jul 09 21:04:04 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 21:04:04 rivo volumio[3110]: info: ControllerMpd::stop Jul 09 21:04:04 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 21:04:04 rivo volumio[3110]: info: CorePlayQueue::clearPlayQueue Jul 09 21:04:04 rivo volumio[3110]: info: CorePlayQueue::saveQueue Jul 09 21:04:04 rivo volumio[3110]: info: CoreCommandRouter::volumioPushQueue Jul 09 21:04:04 rivo volumio[3110]: info: CoreStateMachine::addQueueItems Jul 09 21:04:04 rivo volumio[3110]: info: CorePlayQueue::addQueueItems Jul 09 21:04:04 rivo volumio[3110]: info: Preload queue cleared Jul 09 21:04:04 rivo volumio[3110]: info: Adding Item to queue: tidal://album/201311223 Jul 09 21:04:04 rivo volumio[3110]: info: Exploding uri tidal://album/201311223 in service tidal Jul 09 21:04:04 rivo volumio[3110]: STREAMING PROXY: Client dropped request, destroying Jul 09 21:04:04 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:04 rivo volumio[3110]: info: sendMpdCommand stop took 28 milliseconds Jul 09 21:04:04 rivo volumio[3110]: info: Jul 09 21:04:04 rivo volumio[3110]: ---------------------------- MPD announces state update: player Jul 09 21:04:04 rivo volumio[3110]: info: ControllerMpd::getState Jul 09 21:04:04 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Jul 09 21:04:04 rivo volumio[3110]: info: sendMpdCommand status took 2 milliseconds Jul 09 21:04:04 rivo volumio[3110]: verbose: ControllerMpd::parseState Jul 09 21:04:04 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 21:04:04 rivo volumio[3110]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 09 21:04:04 rivo volumio[3110]: verbose: ControllerMpd::parseTrackInfo Jul 09 21:04:04 rivo volumio[3110]: info: ControllerMpd::pushState Jul 09 21:04:04 rivo volumio[3110]: info: CoreCommandRouter::servicePushState Jul 09 21:04:04 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:04 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 21:04:04 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:04 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:04 rivo volumio[3110]: 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":"347405479&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/347405479&soundQuality=LOSSLESS","trackType":"tidal"} Jul 09 21:04:04 rivo volumio[3110]: verbose: CURRENT POSITION 0 Jul 09 21:04:04 rivo volumio[3110]: info: CoreStateMachine::syncState stateService stop Jul 09 21:04:04 rivo volumio[3110]: info: CoreStateMachine::syncState currentStatus stop Jul 09 21:04:04 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:04 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:04 rivo volumio[3110]: info: No code Jul 09 21:04:04 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:04 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:04 rivo volumio[3110]: info: ------------------------------ 25ms Jul 09 21:04:04 rivo volumio[3110]: info: Signalling Playback active due to playback status change Jul 09 21:04:04 rivo volumio[3110]: info: Signalling Playback active due to playback status change Jul 09 21:04:04 rivo volumio[3110]: info: Signalling Playback active due to playback status change Jul 09 21:04:04 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:04 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:04 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:04 rivo volumio[3110]: info: MCU Signalled Playback Inactive Jul 09 21:04:04 rivo volumio[3110]: info: MCU Signalled Playback Active Jul 09 21:04:05 rivo volumio[3110]: info: explodeTIDALUri took 430 milliseconds Jul 09 21:04:05 rivo volumio[3110]: info: CoreCommandRouter::volumioPushQueue Jul 09 21:04:05 rivo volumio[3110]: info: CorePlayQueue::saveQueue Jul 09 21:04:05 rivo volumio[3110]: info: CoreStateMachine::updateTrackBlock Jul 09 21:04:05 rivo volumio[3110]: info: CorePlayQueue::getTrackBlock Jul 09 21:04:05 rivo volumio[3110]: info: CoreCommandRouter::volumioPlay Jul 09 21:04:05 rivo volumio[3110]: info: CoreStateMachine::play index 0 Jul 09 21:04:05 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 21:04:05 rivo volumio[3110]: info: CoreStateMachine::stop Jul 09 21:04:05 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 21:04:05 rivo volumio[3110]: info: CoreStateMachine::play index undefined Jul 09 21:04:05 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 21:04:05 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:05 rivo volumio[3110]: info: CoreStateMachine::startPlaybackTimer Jul 09 21:04:05 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:05 rivo volumio[3110]: info: [1752087845217] ControllerTidal::clearAddPlayTrack Jul 09 21:04:05 rivo volumio[3110]: info: Getting stream with soundQuality LOSSLESS Jul 09 21:04:05 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 21:04:05 rivo volumio[3110]: info: sendMpdCommand stop took 4 milliseconds Jul 09 21:04:05 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand clear Jul 09 21:04:05 rivo volumio[3110]: info: Jul 09 21:04:05 rivo volumio[3110]: ---------------------------- MPD announces system playlist update Jul 09 21:04:05 rivo volumio[3110]: info: Ignoring MPD Status Update Jul 09 21:04:05 rivo volumio[3110]: info: sendMpdCommand clear took 2 milliseconds Jul 09 21:04:05 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/201311224&soundQuality=LOSSLESS" Jul 09 21:04:05 rivo volumio[3110]: error: updateQueue error: null Jul 09 21:04:05 rivo volumio[3110]: info: Jul 09 21:04:05 rivo volumio[3110]: ---------------------------- MPD announces system playlist update Jul 09 21:04:05 rivo volumio[3110]: info: Ignoring MPD Status Update Jul 09 21:04:05 rivo volumio[3110]: info: ------------------------------ 10ms Jul 09 21:04:05 rivo volumio[3110]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/201311224&soundQuality=LOSSLESS" took 3 milliseconds Jul 09 21:04:05 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 21:04:05 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand play Jul 09 21:04:05 rivo volumio[3110]: info: ------------------------------ 4ms Jul 09 21:04:05 rivo volumio[3110]: info: sendMpdCommand play took 2 milliseconds Jul 09 21:04:05 rivo volumio[3110]: STREAMING PROXY: Handling url /?data=tidal://song/201311224&soundQuality=LOSSLESS Jul 09 21:04:05 rivo volumio[3110]: info: Executing endpoint getStreamUrltidal Jul 09 21:04:05 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jul 09 21:04:05 rivo volumio[3110]: info: getStreamUrl took 559 milliseconds Jul 09 21:04:05 rivo volumio[3110]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicyMDg5MmJkOTc0ZWRhOWU5Mzk4YjQ2OTIyYjYwZTM1Y182MS5tcDQ/0.flac?token=1752091445~MmJhNWNjNWYzY2Y1MmFkMjcxZTQyN2Q5ZDI5ZGYxYTY5MzdkOTljOQ== Jul 09 21:04:06 rivo volumio[3110]: STREAMING PROXY: Response: 200, length: 136167513 Jul 09 21:04:06 rivo volumio[3110]: info: Jul 09 21:04:06 rivo volumio[3110]: ---------------------------- MPD announces state update: player Jul 09 21:04:06 rivo volumio[3110]: info: ControllerMpd::getState Jul 09 21:04:06 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Jul 09 21:04:06 rivo volumio[3110]: info: sendMpdCommand status took 29 milliseconds Jul 09 21:04:06 rivo volumio[3110]: verbose: ControllerMpd::parseState Jul 09 21:04:06 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 21:04:06 rivo volumio[3110]: info: Jul 09 21:04:06 rivo volumio[3110]: ---------------------------- MPD announces state update: player Jul 09 21:04:06 rivo volumio[3110]: info: ControllerMpd::getState Jul 09 21:04:06 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Jul 09 21:04:06 rivo volumio[3110]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 09 21:04:06 rivo volumio[3110]: verbose: ControllerMpd::parseTrackInfo Jul 09 21:04:06 rivo volumio[3110]: info: ControllerMpd::pushState Jul 09 21:04:06 rivo volumio[3110]: info: CoreCommandRouter::servicePushState Jul 09 21:04:06 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:06 rivo volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"201311224&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201311224&soundQuality=LOSSLESS","trackType":"tidal"} Jul 09 21:04:06 rivo volumio[3110]: verbose: CURRENT POSITION 0 Jul 09 21:04:06 rivo volumio[3110]: info: CoreStateMachine::syncState stateService play Jul 09 21:04:06 rivo volumio[3110]: info: CoreStateMachine::syncState currentStatus stop Jul 09 21:04:06 rivo volumio[3110]: info: ------------------------------ 41ms Jul 09 21:04:06 rivo volumio[3110]: info: sendMpdCommand status took 9 milliseconds Jul 09 21:04:06 rivo volumio[3110]: verbose: ControllerMpd::parseState Jul 09 21:04:06 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 21:04:06 rivo volumio[3110]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 09 21:04:06 rivo volumio[3110]: verbose: ControllerMpd::parseTrackInfo Jul 09 21:04:06 rivo volumio[3110]: info: ControllerMpd::pushState Jul 09 21:04:06 rivo volumio[3110]: info: CoreCommandRouter::servicePushState Jul 09 21:04:06 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:06 rivo volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"201311224&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201311224&soundQuality=LOSSLESS","trackType":"tidal"} Jul 09 21:04:06 rivo volumio[3110]: verbose: CURRENT POSITION 0 Jul 09 21:04:06 rivo volumio[3110]: info: CoreStateMachine::syncState stateService play Jul 09 21:04:06 rivo volumio[3110]: info: CoreStateMachine::syncState currentStatus play Jul 09 21:04:06 rivo volumio[3110]: info: Received an update from plugin. extracting info from payload Jul 09 21:04:06 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:06 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 21:04:06 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:06 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:06 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:06 rivo volumio[3110]: info: ------------------------------ 38ms Jul 09 21:04:06 rivo volumio[3110]: info: Signalling Playback active due to playback status change Jul 09 21:04:06 rivo volumio[3110]: info: Signalling Playback active due to playback status change Jul 09 21:04:06 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:06 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:08 rivo volumio[3110]: info: Preload queue cleared Jul 09 21:04:08 rivo volumio[3110]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::ClearQueue Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::stop Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::stPlaybackTimer Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::updateTrackBlock Jul 09 21:04:08 rivo volumio[3110]: info: CorePlayQueue::getTrackBlock Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:08 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:08 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 21:04:08 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::serviceStop Jul 09 21:04:08 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:08 rivo volumio[3110]: info: CoreCommandRouter::serviceStop Jul 09 21:04:08 rivo volumio[3110]: info: [1752087848396] ControllerTidal::stop Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 21:04:08 rivo volumio[3110]: info: ControllerMpd::stop Jul 09 21:04:08 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 21:04:08 rivo volumio[3110]: info: CorePlayQueue::clearPlayQueue Jul 09 21:04:08 rivo volumio[3110]: info: CorePlayQueue::saveQueue Jul 09 21:04:08 rivo volumio[3110]: info: CoreCommandRouter::volumioPushQueue Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::addQueueItems Jul 09 21:04:08 rivo volumio[3110]: info: CorePlayQueue::addQueueItems Jul 09 21:04:08 rivo volumio[3110]: info: Preload queue cleared Jul 09 21:04:08 rivo volumio[3110]: info: Adding Item to queue: tidal://album/303656220 Jul 09 21:04:08 rivo volumio[3110]: info: Exploding uri tidal://album/303656220 in service tidal Jul 09 21:04:08 rivo volumio[3110]: STREAMING PROXY: Client dropped request, destroying Jul 09 21:04:08 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:08 rivo volumio[3110]: info: sendMpdCommand stop took 45 milliseconds Jul 09 21:04:08 rivo volumio[3110]: info: Jul 09 21:04:08 rivo volumio[3110]: ---------------------------- MPD announces state update: player Jul 09 21:04:08 rivo volumio[3110]: info: ControllerMpd::getState Jul 09 21:04:08 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Jul 09 21:04:08 rivo volumio[3110]: info: sendMpdCommand status took 1 milliseconds Jul 09 21:04:08 rivo volumio[3110]: verbose: ControllerMpd::parseState Jul 09 21:04:08 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 21:04:08 rivo volumio[3110]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 09 21:04:08 rivo volumio[3110]: verbose: ControllerMpd::parseTrackInfo Jul 09 21:04:08 rivo volumio[3110]: info: ControllerMpd::pushState Jul 09 21:04:08 rivo volumio[3110]: info: CoreCommandRouter::servicePushState Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:08 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 21:04:08 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:08 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:08 rivo volumio[3110]: 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":"201311224&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201311224&soundQuality=LOSSLESS","trackType":"tidal"} Jul 09 21:04:08 rivo volumio[3110]: verbose: CURRENT POSITION 0 Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::syncState stateService stop Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::syncState currentStatus stop Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:08 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:08 rivo volumio[3110]: info: No code Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:08 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:08 rivo volumio[3110]: info: ------------------------------ 32ms Jul 09 21:04:08 rivo volumio[3110]: info: Signalling Playback active due to playback status change Jul 09 21:04:08 rivo volumio[3110]: info: Signalling Playback active due to playback status change Jul 09 21:04:08 rivo volumio[3110]: info: Signalling Playback active due to playback status change Jul 09 21:04:08 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:08 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:08 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:08 rivo volumio[3110]: info: MCU Signalled Playback Inactive Jul 09 21:04:08 rivo volumio[3110]: info: MCU Signalled Playback Active Jul 09 21:04:08 rivo volumio[3110]: info: explodeTIDALUri took 518 milliseconds Jul 09 21:04:08 rivo volumio[3110]: info: CoreCommandRouter::volumioPushQueue Jul 09 21:04:08 rivo volumio[3110]: info: CorePlayQueue::saveQueue Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::updateTrackBlock Jul 09 21:04:08 rivo volumio[3110]: info: CorePlayQueue::getTrackBlock Jul 09 21:04:08 rivo volumio[3110]: info: CoreCommandRouter::volumioPlay Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::play index 0 Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::stop Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::play index undefined Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 21:04:08 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::startPlaybackTimer Jul 09 21:04:08 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:08 rivo volumio[3110]: info: [1752087848931] ControllerTidal::clearAddPlayTrack Jul 09 21:04:08 rivo volumio[3110]: info: Getting stream with soundQuality LOSSLESS Jul 09 21:04:08 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 21:04:08 rivo volumio[3110]: info: sendMpdCommand stop took 7 milliseconds Jul 09 21:04:08 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand clear Jul 09 21:04:08 rivo volumio[3110]: info: Jul 09 21:04:08 rivo volumio[3110]: ---------------------------- MPD announces system playlist update Jul 09 21:04:08 rivo volumio[3110]: info: Ignoring MPD Status Update Jul 09 21:04:08 rivo volumio[3110]: info: sendMpdCommand clear took 1 milliseconds Jul 09 21:04:08 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/303656221&soundQuality=LOSSLESS" Jul 09 21:04:08 rivo volumio[3110]: error: updateQueue error: null Jul 09 21:04:08 rivo volumio[3110]: info: Jul 09 21:04:08 rivo volumio[3110]: ---------------------------- MPD announces system playlist update Jul 09 21:04:08 rivo volumio[3110]: info: Ignoring MPD Status Update Jul 09 21:04:08 rivo volumio[3110]: info: ------------------------------ 3ms Jul 09 21:04:08 rivo volumio[3110]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/303656221&soundQuality=LOSSLESS" took 2 milliseconds Jul 09 21:04:08 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 21:04:08 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand play Jul 09 21:04:08 rivo volumio[3110]: info: ------------------------------ 4ms Jul 09 21:04:08 rivo volumio[3110]: info: sendMpdCommand play took 2 milliseconds Jul 09 21:04:08 rivo volumio[3110]: STREAMING PROXY: Handling url /?data=tidal://song/303656221&soundQuality=LOSSLESS Jul 09 21:04:08 rivo volumio[3110]: info: Executing endpoint getStreamUrltidal Jul 09 21:04:08 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jul 09 21:04:09 rivo volumio[3110]: info: getStreamUrl took 349 milliseconds Jul 09 21:04:09 rivo volumio[3110]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidiMmVmMGQwNTFjYTcwNzIzOGU2MzE5MjY3ZjM3NzMwZF82MS5tcDQ/0.flac?token=1752091449~MjJhYjFiMzVhMGE2NzcyZTUyYjJhMjk3YmZkNzM3YTg5YzkwNDgyZQ== Jul 09 21:04:09 rivo volumio[3110]: STREAMING PROXY: Response: 200, length: 63774520 Jul 09 21:04:09 rivo volumio[3110]: info: Jul 09 21:04:09 rivo volumio[3110]: ---------------------------- MPD announces state update: player Jul 09 21:04:09 rivo volumio[3110]: info: ControllerMpd::getState Jul 09 21:04:09 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Jul 09 21:04:09 rivo volumio[3110]: info: sendMpdCommand status took 35 milliseconds Jul 09 21:04:09 rivo volumio[3110]: verbose: ControllerMpd::parseState Jul 09 21:04:09 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 21:04:09 rivo volumio[3110]: info: Jul 09 21:04:09 rivo volumio[3110]: ---------------------------- MPD announces state update: player Jul 09 21:04:09 rivo volumio[3110]: info: ControllerMpd::getState Jul 09 21:04:09 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Jul 09 21:04:09 rivo volumio[3110]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 09 21:04:09 rivo volumio[3110]: verbose: ControllerMpd::parseTrackInfo Jul 09 21:04:09 rivo volumio[3110]: info: ControllerMpd::pushState Jul 09 21:04:09 rivo volumio[3110]: info: CoreCommandRouter::servicePushState Jul 09 21:04:09 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:09 rivo volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":954,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"303656221&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/303656221&soundQuality=LOSSLESS","trackType":"tidal"} Jul 09 21:04:09 rivo volumio[3110]: verbose: CURRENT POSITION 0 Jul 09 21:04:09 rivo volumio[3110]: info: CoreStateMachine::syncState stateService play Jul 09 21:04:09 rivo volumio[3110]: info: CoreStateMachine::syncState currentStatus stop Jul 09 21:04:09 rivo volumio[3110]: info: ------------------------------ 42ms Jul 09 21:04:09 rivo volumio[3110]: info: sendMpdCommand status took 4 milliseconds Jul 09 21:04:09 rivo volumio[3110]: verbose: ControllerMpd::parseState Jul 09 21:04:09 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 21:04:09 rivo volumio[3110]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 09 21:04:09 rivo volumio[3110]: verbose: ControllerMpd::parseTrackInfo Jul 09 21:04:09 rivo volumio[3110]: info: ControllerMpd::pushState Jul 09 21:04:09 rivo volumio[3110]: info: CoreCommandRouter::servicePushState Jul 09 21:04:09 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:09 rivo volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":954,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"303656221&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/303656221&soundQuality=LOSSLESS","trackType":"tidal"} Jul 09 21:04:09 rivo volumio[3110]: verbose: CURRENT POSITION 0 Jul 09 21:04:09 rivo volumio[3110]: info: CoreStateMachine::syncState stateService play Jul 09 21:04:09 rivo volumio[3110]: info: CoreStateMachine::syncState currentStatus play Jul 09 21:04:09 rivo volumio[3110]: info: Received an update from plugin. extracting info from payload Jul 09 21:04:09 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:09 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 21:04:09 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:09 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:09 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:09 rivo volumio[3110]: info: ------------------------------ 31ms Jul 09 21:04:09 rivo volumio[3110]: info: Signalling Playback active due to playback status change Jul 09 21:04:09 rivo volumio[3110]: info: Signalling Playback active due to playback status change Jul 09 21:04:09 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:09 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:18 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jul 09 21:04:18 rivo volumio[3110]: info: browseTIDALUri took 267 milliseconds Jul 09 21:04:18 rivo volumio[3110]: info: Preload queue cleared Jul 09 21:04:28 rivo volumio[3110]: info: CoreCommandRouter::volumioPause Jul 09 21:04:28 rivo volumio[3110]: info: CoreStateMachine::pause Jul 09 21:04:28 rivo volumio[3110]: info: CoreStateMachine::stPlaybackTimer Jul 09 21:04:28 rivo volumio[3110]: info: CoreStateMachine::servicePause Jul 09 21:04:28 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:28 rivo volumio[3110]: info: CoreCommandRouter::servicePause Jul 09 21:04:28 rivo volumio[3110]: info: [1752087868629] ControllerTidal::pause Jul 09 21:04:28 rivo volumio[3110]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 21:04:28 rivo volumio[3110]: info: ControllerMpd::pause Jul 09 21:04:28 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand pause Jul 09 21:04:28 rivo volumio[3110]: info: Jul 09 21:04:28 rivo volumio[3110]: ---------------------------- MPD announces state update: player Jul 09 21:04:28 rivo volumio[3110]: info: sendMpdCommand pause took 5 milliseconds Jul 09 21:04:28 rivo volumio[3110]: info: ControllerMpd::getState Jul 09 21:04:28 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Jul 09 21:04:28 rivo volumio[3110]: info: sendMpdCommand status took 1 milliseconds Jul 09 21:04:28 rivo volumio[3110]: verbose: ControllerMpd::parseState Jul 09 21:04:28 rivo volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 21:04:28 rivo volumio[3110]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 09 21:04:28 rivo volumio[3110]: verbose: ControllerMpd::parseTrackInfo Jul 09 21:04:28 rivo volumio[3110]: info: ControllerMpd::pushState Jul 09 21:04:28 rivo volumio[3110]: info: CoreCommandRouter::servicePushState Jul 09 21:04:28 rivo volumio[3110]: info: CorePlayQueue::getTrack 0 Jul 09 21:04:28 rivo volumio[3110]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":19585,"duration":954,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"526 Kbps","isStreaming":false,"title":"303656221&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/303656221&soundQuality=LOSSLESS","trackType":"tidal"} Jul 09 21:04:28 rivo volumio[3110]: verbose: CURRENT POSITION 0 Jul 09 21:04:28 rivo volumio[3110]: info: CoreStateMachine::syncState stateService pause Jul 09 21:04:28 rivo volumio[3110]: info: CoreStateMachine::syncState currentStatus pause Jul 09 21:04:28 rivo volumio[3110]: info: CoreStateMachine::pushState Jul 09 21:04:28 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 21:04:28 rivo volumio[3110]: info: CoreCommandRouter::volumioPushState Jul 09 21:04:28 rivo volumio[3110]: info: CoreStateMachine::stPlaybackTimer Jul 09 21:04:28 rivo volumio[3110]: info: ------------------------------ 20ms Jul 09 21:04:28 rivo volumio[3110]: info: Updating RAAT Signal Path Jul 09 21:04:28 rivo volumio[3110]: info: MCU Signalled Playback Inactive Jul 09 21:04:30 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:33 rivo sudo[23040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 09 21:04:33 rivo sudo[23040]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 21:04:33 rivo sudo[23040]: pam_unix(sudo:session): session closed for user root Jul 09 21:04:33 rivo sudo[23042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 09 21:04:33 rivo sudo[23042]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 21:04:33 rivo sudo[23042]: pam_unix(sudo:session): session closed for user root Jul 09 21:04:33 rivo volumio[3110]: verbose: New Socket.io Connection to 192.168.178.113 from 192.168.178.95 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7 Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:33 rivo volumio[3110]: info: Received Get System Info Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 21:04:33 rivo volumio[3110]: info: Discovery: Getting this device information Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::volumioGetVisibleSources Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 09 21:04:33 rivo volumio[3110]: info: Received Get System Info Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 21:04:33 rivo volumio[3110]: info: Discovery: Getting this device information Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:33 rivo volumio[3110]: info: Listing playlists Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::volumioGetQueue Jul 09 21:04:33 rivo volumio[3110]: info: CoreStateMachine::getQueue Jul 09 21:04:33 rivo volumio[3110]: info: CorePlayQueue::getQueue Jul 09 21:04:33 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 09 21:04:35 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 09 21:04:35 rivo volumio[3110]: info: Received Get System Info Jul 09 21:04:35 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 21:04:35 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 21:04:35 rivo volumio[3110]: info: Discovery: Getting this device information Jul 09 21:04:35 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:35 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 21:04:37 rivo sudo[23048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 09 21:04:37 rivo sudo[23048]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 21:04:37 rivo sudo[23050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 09 21:04:37 rivo sudo[23050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 21:04:37 rivo sudo[23048]: pam_unix(sudo:session): session closed for user root Jul 09 21:04:37 rivo sudo[23050]: pam_unix(sudo:session): session closed for user root Jul 09 21:04:37 rivo volumio[3110]: verbose: New Socket.io Connection to 192.168.178.113 from 192.168.178.95 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7 Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:37 rivo volumio[3110]: info: Received Get System Info Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 21:04:37 rivo volumio[3110]: info: Discovery: Getting this device information Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::volumioGetVisibleSources Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 09 21:04:37 rivo volumio[3110]: info: Received Get System Info Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 21:04:37 rivo volumio[3110]: info: Discovery: Getting this device information Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:37 rivo volumio[3110]: info: Listing playlists Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::volumioGetQueue Jul 09 21:04:37 rivo volumio[3110]: info: CoreStateMachine::getQueue Jul 09 21:04:37 rivo volumio[3110]: info: CorePlayQueue::getQueue Jul 09 21:04:37 rivo volumio[3110]: info: Received Get System Info Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 21:04:37 rivo volumio[3110]: info: Discovery: Getting this device information Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 21:04:37 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:38 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 09 21:04:38 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 21:04:43 rivo volumio[3110]: info: Retrieving Cloud Streaming UI Jul 09 21:04:43 rivo volumio[3110]: info: Getting Tidal Cloud Configuration Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 21:04:43 rivo volumio[3110]: info: Getting Qobuz Cloud Configuration Jul 09 21:04:43 rivo volumio[3110]: info: Asking plugin for UI Config Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 21:04:43 rivo volumio[3110]: info: Getting Spotify Cloud Configuration Jul 09 21:04:43 rivo volumio[3110]: info: Asking plugin for UI Config Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 21:04:43 rivo volumio[3110]: info: Saving Spotify Acccount Jul 09 21:04:43 rivo volumio[3110]: info: Got it Jul 09 21:04:43 rivo volumio[3110]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 09 21:04:43 rivo volumio[3110]: info: Got Tidal Cloud Configuration Jul 09 21:04:43 rivo volumio[3110]: info: Got it Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::volumioGetBrowseSources Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::volumioGetBrowseSources Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::volumioGetBrowseSources Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 21:04:43 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 09 21:04:47 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 21:04:47 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 09 21:04:51 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 21:04:54 rivo volumio[3110]: STREAMING PROXY: Client dropped request, destroying Jul 09 21:04:55 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 21:04:59 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 21:05:03 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 21:05:07 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 21:05:07 rivo volumio[3110]: info: CALLMETHOD: miscellanea albumart clearAlbumartCache Jul 09 21:05:07 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: albumart , clearAlbumartCache Jul 09 21:05:07 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: mpd , rebuildAlbumCache Jul 09 21:05:07 rivo volumio[3110]: info: Rebuild Album cache Jul 09 21:05:11 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 21:05:15 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 21:05:16 rivo volumio[3110]: info: CALLMETHOD: miscellanea my_music updateMusicLibrarySupersearchSources [object Object] Jul 09 21:05:16 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibrarySupersearchSources Jul 09 21:05:17 rivo volumio[3110]: info: CoreCommandRouter::volumioGetBrowseSources Jul 09 21:05:17 rivo volumio[3110]: info: CoreCommandRouter::volumioGetBrowseSources Jul 09 21:05:17 rivo volumio[3110]: info: CoreCommandRouter::updateGlobalSearchableBrowseSources Jul 09 21:05:20 rivo volumio[3110]: info: CoreCommandRouter::volumioGetState Jul 09 21:05:27 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jul 09 21:05:27 rivo volumio[3110]: info: browseTIDALUri took 195 milliseconds Jul 09 21:05:27 rivo volumio[3110]: info: Preload queue cleared Jul 09 21:05:27 rivo volumio[3110]: info: Preload queue cleared Jul 09 21:05:34 rivo volumio[3110]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Jul 09 21:05:34 rivo volumio[3110]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 21:05:34 rivo volumio[3110]: TypeError: Cannot read property 'then' of undefined Jul 09 21:05:34 rivo volumio[3110]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Jul 09 21:05:34 rivo volumio[3110]: at Socket.emit (events.js:400:28) Jul 09 21:05:34 rivo volumio[3110]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 09 21:05:34 rivo volumio[3110]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jul 09 21:05:34 rivo volumio[3110]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 21:05:35 rivo sudo[23218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-09 21:04 Jul 09 21:05:35 rivo sudo[23218]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 05:15:38 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="13b9c0f9785de91e97db9573691d3efe"