-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Mon 2025-11-03 16:40:40 GMT. -- Nov 03 16:39:09 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Nov 03 16:39:09 rivo volumio[3285]: info: browseTIDALUri took 409 milliseconds Nov 03 16:39:10 rivo volumio[3285]: info: Preload queue cleared Nov 03 16:39:10 rivo volumio[3285]: info: Preloading song: tidal://song/412952397 Nov 03 16:39:10 rivo volumio[3285]: info: Preloading song: tidal://song/412952399 Nov 03 16:39:10 rivo volumio[3285]: info: Preloading song: tidal://song/412952400 Nov 03 16:39:10 rivo volumio[3285]: info: Preloading song: tidal://song/412952401 Nov 03 16:39:10 rivo volumio[3285]: info: Preloading song: tidal://song/412952403 Nov 03 16:39:10 rivo volumio[3285]: info: Preloading song: tidal://song/412952404 Nov 03 16:39:10 rivo volumio[3285]: info: Preloading song: tidal://song/412952405 Nov 03 16:39:10 rivo volumio[3285]: info: Preloading song: tidal://song/412952406 Nov 03 16:39:10 rivo volumio[3285]: info: Preloading song: tidal://song/412952407 Nov 03 16:39:10 rivo volumio[3285]: info: Preloading song: tidal://song/412952408 Nov 03 16:39:10 rivo volumio[3285]: info: Preloading song: tidal://song/412952409 Nov 03 16:39:10 rivo volumio[3285]: info: Exploding uri tidal://song/412952397 in service tidal Nov 03 16:39:10 rivo volumio[3285]: info: Exploding uri tidal://song/412952399 in service tidal Nov 03 16:39:10 rivo volumio[3285]: info: Exploding uri tidal://song/412952400 in service tidal Nov 03 16:39:10 rivo volumio[3285]: info: Exploding uri tidal://song/412952401 in service tidal Nov 03 16:39:10 rivo volumio[3285]: info: No valid Plugin REST Endpoint: getSimilarAlbums Nov 03 16:39:10 rivo volumio[3285]: info: Exploding uri tidal://song/412952403 in service tidal Nov 03 16:39:10 rivo volumio[3285]: info: explodeTIDALUri took 208 milliseconds Nov 03 16:39:10 rivo volumio[3285]: info: explodeTIDALUri took 196 milliseconds Nov 03 16:39:10 rivo volumio[3285]: info: Exploding uri tidal://song/412952404 in service tidal Nov 03 16:39:10 rivo volumio[3285]: info: explodeTIDALUri took 193 milliseconds Nov 03 16:39:10 rivo volumio[3285]: info: Exploding uri tidal://song/412952405 in service tidal Nov 03 16:39:10 rivo volumio[3285]: info: Exploding uri tidal://song/412952406 in service tidal Nov 03 16:39:10 rivo volumio[3285]: info: explodeTIDALUri took 205 milliseconds Nov 03 16:39:10 rivo volumio[3285]: info: explodeTIDALUri took 192 milliseconds Nov 03 16:39:10 rivo volumio[3285]: info: Exploding uri tidal://song/412952407 in service tidal Nov 03 16:39:10 rivo volumio[3285]: info: Exploding uri tidal://song/412952408 in service tidal Nov 03 16:39:10 rivo volumio[3285]: info: explodeTIDALUri took 209 milliseconds Nov 03 16:39:10 rivo volumio[3285]: info: Exploding uri tidal://song/412952409 in service tidal Nov 03 16:39:10 rivo volumio[3285]: info: explodeTIDALUri took 212 milliseconds Nov 03 16:39:10 rivo volumio[3285]: info: explodeTIDALUri took 200 milliseconds Nov 03 16:39:10 rivo volumio[3285]: info: explodeTIDALUri took 181 milliseconds Nov 03 16:39:10 rivo volumio[3285]: info: explodeTIDALUri took 259 milliseconds Nov 03 16:39:10 rivo volumio[3285]: info: explodeTIDALUri took 218 milliseconds Nov 03 16:39:13 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 16:39:13 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 16:39:13 rivo volumio[3285]: info: Discovery: Getting this device information Nov 03 16:39:13 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Nov 03 16:39:13 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 16:39:13 rivo volumio[3285]: info: Preload queue cleared Nov 03 16:39:13 rivo volumio[3285]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 03 16:39:13 rivo volumio[3285]: info: CoreStateMachine::ClearQueue Nov 03 16:39:13 rivo volumio[3285]: info: CoreStateMachine::stop Nov 03 16:39:13 rivo volumio[3285]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 16:39:13 rivo volumio[3285]: info: CoreStateMachine::stPlaybackTimer Nov 03 16:39:13 rivo volumio[3285]: info: CoreStateMachine::updateTrackBlock Nov 03 16:39:13 rivo volumio[3285]: info: CorePlayQueue::getTrackBlock Nov 03 16:39:13 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:13 rivo volumio[3285]: info: CorePlayQueue::getTrack 0 Nov 03 16:39:13 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 16:39:13 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:13 rivo volumio[3285]: info: CoreStateMachine::serviceStop Nov 03 16:39:13 rivo volumio[3285]: info: CorePlayQueue::getTrack 0 Nov 03 16:39:13 rivo volumio[3285]: info: CoreCommandRouter::serviceStop Nov 03 16:39:13 rivo volumio[3285]: info: [1762187953888] ControllerTidal::stop Nov 03 16:39:13 rivo volumio[3285]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 03 16:39:13 rivo volumio[3285]: info: ControllerMpd::stop Nov 03 16:39:13 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand stop Nov 03 16:39:13 rivo volumio[3285]: info: CorePlayQueue::clearPlayQueue Nov 03 16:39:13 rivo volumio[3285]: info: CorePlayQueue::saveQueue Nov 03 16:39:13 rivo volumio[3285]: info: CoreCommandRouter::volumioPushQueue Nov 03 16:39:13 rivo volumio[3285]: info: CoreStateMachine::addQueueItems Nov 03 16:39:13 rivo volumio[3285]: info: CorePlayQueue::addQueueItems Nov 03 16:39:13 rivo volumio[3285]: info: Preload queue cleared Nov 03 16:39:13 rivo volumio[3285]: info: Adding Item to queue: tidal://album/412952396 Nov 03 16:39:13 rivo volumio[3285]: info: Exploding uri tidal://album/412952396 in service tidal Nov 03 16:39:13 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Nov 03 16:39:13 rivo kernel: spdif_a keep clk continuous Nov 03 16:39:13 rivo kernel: aml_spdif_close Nov 03 16:39:13 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Nov 03 16:39:13 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:13 rivo volumio[3285]: info: Nov 03 16:39:13 rivo volumio[3285]: ---------------------------- MPD announces state update: player Nov 03 16:39:13 rivo volumio[3285]: info: sendMpdCommand stop took 45 milliseconds Nov 03 16:39:13 rivo volumio[3285]: info: ControllerMpd::getState Nov 03 16:39:13 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand status Nov 03 16:39:13 rivo volumio[3285]: info: sendMpdCommand status took 2 milliseconds Nov 03 16:39:13 rivo volumio[3285]: verbose: ControllerMpd::parseState Nov 03 16:39:13 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 16:39:13 rivo volumio[3285]: info: Nov 03 16:39:13 rivo volumio[3285]: ---------------------------- MPD announces state update: player Nov 03 16:39:13 rivo volumio[3285]: info: ControllerMpd::getState Nov 03 16:39:13 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand status Nov 03 16:39:13 rivo volumio[3285]: info: Nov 03 16:39:13 rivo volumio[3285]: ---------------------------- MPD announces state update: player Nov 03 16:39:13 rivo volumio[3285]: info: ControllerMpd::getState Nov 03 16:39:13 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand status Nov 03 16:39:13 rivo volumio[3285]: info: sendMpdCommand playlistinfo took 8 milliseconds Nov 03 16:39:13 rivo volumio[3285]: verbose: ControllerMpd::parseTrackInfo Nov 03 16:39:13 rivo volumio[3285]: info: ControllerMpd::pushState Nov 03 16:39:13 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Nov 03 16:39:13 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:13 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 16:39:13 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:13 rivo volumio[3285]: info: CorePlayQueue::getTrack 0 Nov 03 16:39:13 rivo volumio[3285]: 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":"110958685&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/110958685&soundQuality=HI_RES","trackType":"tidal"} Nov 03 16:39:13 rivo volumio[3285]: verbose: CURRENT POSITION 0 Nov 03 16:39:13 rivo volumio[3285]: info: CoreStateMachine::syncState stateService stop Nov 03 16:39:13 rivo volumio[3285]: info: CoreStateMachine::syncState currentStatus stop Nov 03 16:39:13 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:13 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:13 rivo volumio[3285]: info: No code Nov 03 16:39:13 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:13 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:14 rivo volumio[3285]: info: ------------------------------ 102ms Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand status took 100 milliseconds Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand status took 100 milliseconds Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::parseState Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::parseState Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 16:39:14 rivo volumio[3285]: info: Signalling Playback active due to playback status change Nov 03 16:39:14 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:14 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:14 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand playlistinfo took 29 milliseconds Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand playlistinfo took 27 milliseconds Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::parseTrackInfo Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::parseTrackInfo Nov 03 16:39:14 rivo volumio[3285]: info: ControllerMpd::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:14 rivo volumio[3285]: info: CorePlayQueue::getTrack 0 Nov 03 16:39:14 rivo volumio[3285]: 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":"110958685&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/110958685&soundQuality=HI_RES","trackType":"tidal"} Nov 03 16:39:14 rivo volumio[3285]: verbose: CURRENT POSITION 0 Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::syncState stateService stop Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::syncState currentStatus stop Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:14 rivo volumio[3285]: info: No code Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:14 rivo volumio[3285]: info: ControllerMpd::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:14 rivo volumio[3285]: info: CorePlayQueue::getTrack 0 Nov 03 16:39:14 rivo volumio[3285]: 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":"110958685&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/110958685&soundQuality=HI_RES","trackType":"tidal"} Nov 03 16:39:14 rivo volumio[3285]: verbose: CURRENT POSITION 0 Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::syncState stateService stop Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::syncState currentStatus stop Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:14 rivo volumio[3285]: info: No code Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:14 rivo volumio[3285]: info: ------------------------------ 309ms Nov 03 16:39:14 rivo volumio[3285]: info: ------------------------------ 309ms Nov 03 16:39:14 rivo volumio[3285]: info: MCU Signalled Playback Inactive Nov 03 16:39:14 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:14 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:14 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:14 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:14 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:14 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:14 rivo volumio[3285]: info: MCU Signalled Playback Active Nov 03 16:39:14 rivo volumio[3285]: info: explodeTIDALUri took 463 milliseconds Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::volumioPushQueue Nov 03 16:39:14 rivo volumio[3285]: info: CorePlayQueue::saveQueue Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::updateTrackBlock Nov 03 16:39:14 rivo volumio[3285]: info: CorePlayQueue::getTrackBlock Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::volumioPlay Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::play index 0 Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::stop Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::play index undefined Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 16:39:14 rivo volumio[3285]: info: CorePlayQueue::getTrack 0 Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::startPlaybackTimer Nov 03 16:39:14 rivo volumio[3285]: info: CorePlayQueue::getTrack 0 Nov 03 16:39:14 rivo volumio[3285]: info: [1762187954378] ControllerTidal::clearAddPlayTrack Nov 03 16:39:14 rivo volumio[3285]: info: Getting stream with soundQuality HI_RES Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand stop Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand stop took 7 milliseconds Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand clear Nov 03 16:39:14 rivo volumio[3285]: info: Nov 03 16:39:14 rivo volumio[3285]: ---------------------------- MPD announces system playlist update Nov 03 16:39:14 rivo volumio[3285]: info: Ignoring MPD Status Update Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand clear took 1 milliseconds Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/412952397&soundQuality=HI_RES" Nov 03 16:39:14 rivo volumio[3285]: info: Nov 03 16:39:14 rivo volumio[3285]: ---------------------------- MPD announces system playlist update Nov 03 16:39:14 rivo volumio[3285]: info: Ignoring MPD Status Update Nov 03 16:39:14 rivo volumio[3285]: info: Nov 03 16:39:14 rivo volumio[3285]: ---------------------------- MPD announces system playlist update Nov 03 16:39:14 rivo volumio[3285]: info: Ignoring MPD Status Update Nov 03 16:39:14 rivo volumio[3285]: error: updateQueue error: null Nov 03 16:39:14 rivo volumio[3285]: info: Nov 03 16:39:14 rivo volumio[3285]: ---------------------------- MPD announces system playlist update Nov 03 16:39:14 rivo volumio[3285]: info: Ignoring MPD Status Update Nov 03 16:39:14 rivo volumio[3285]: info: ------------------------------ 6ms Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/412952397&soundQuality=HI_RES" took 6 milliseconds Nov 03 16:39:14 rivo volumio[3285]: info: ------------------------------ 5ms Nov 03 16:39:14 rivo volumio[3285]: info: ------------------------------ 4ms Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand play Nov 03 16:39:14 rivo volumio[3285]: info: Nov 03 16:39:14 rivo volumio[3285]: ---------------------------- MPD announces system playlist update Nov 03 16:39:14 rivo volumio[3285]: info: Ignoring MPD Status Update Nov 03 16:39:14 rivo volumio[3285]: info: Nov 03 16:39:14 rivo volumio[3285]: ---------------------------- MPD announces system playlist update Nov 03 16:39:14 rivo volumio[3285]: info: Ignoring MPD Status Update Nov 03 16:39:14 rivo volumio[3285]: STREAMING PROXY: Handling url /?data=tidal://song/412952397&soundQuality=HI_RES Nov 03 16:39:14 rivo volumio[3285]: info: ------------------------------ 8ms Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand play took 5 milliseconds Nov 03 16:39:14 rivo volumio[3285]: info: ------------------------------ 4ms Nov 03 16:39:14 rivo volumio[3285]: info: ------------------------------ 4ms Nov 03 16:39:14 rivo volumio[3285]: info: Executing endpoint getStreamUrltidal Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 03 16:39:14 rivo volumio[3285]: info: getStreamUrl took 232 milliseconds Nov 03 16:39:14 rivo volumio[3285]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1YWM1MTNiOTcwMzI1MDczOWY3YTYwZmNjYWMyMTQ1YS5tcDQ/0.flac?token=1762191554~NTk5YzFhMjAyN2YxZTVlMTY2M2Y1ZjBkYjliZTBhZTRiZDBhZWYwOA== Nov 03 16:39:14 rivo volumio[3285]: STREAMING PROXY: Response: 200, length: 51187305 Nov 03 16:39:14 rivo volumio[3285]: info: Nov 03 16:39:14 rivo volumio[3285]: ---------------------------- MPD announces state update: player Nov 03 16:39:14 rivo kernel: aml_spdif_open Nov 03 16:39:14 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Nov 03 16:39:14 rivo kernel: set normal 512 fs /4 fs Nov 03 16:39:14 rivo kernel: set spdifout clk:6144000, mpll:24576000 Nov 03 16:39:14 rivo kernel: get spdifout clk:6143997, mpll:24575987 Nov 03 16:39:14 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Nov 03 16:39:14 rivo kernel: set normal 512 fs /4 fs Nov 03 16:39:14 rivo kernel: set spdifout clk:6144000, mpll:24576000 Nov 03 16:39:14 rivo kernel: get spdifout clk:6143997, mpll:24575987 Nov 03 16:39:14 rivo kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Nov 03 16:39:14 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Nov 03 16:39:14 rivo volumio[3285]: info: ControllerMpd::getState Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand status Nov 03 16:39:14 rivo volumio[3285]: info: Nov 03 16:39:14 rivo volumio[3285]: ---------------------------- MPD announces state update: player Nov 03 16:39:14 rivo volumio[3285]: info: ControllerMpd::getState Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand status Nov 03 16:39:14 rivo volumio[3285]: info: Nov 03 16:39:14 rivo volumio[3285]: ---------------------------- MPD announces state update: player Nov 03 16:39:14 rivo volumio[3285]: info: ControllerMpd::getState Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand status Nov 03 16:39:14 rivo volumio[3285]: info: Nov 03 16:39:14 rivo volumio[3285]: ---------------------------- MPD announces state update: player Nov 03 16:39:14 rivo volumio[3285]: info: ControllerMpd::getState Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand status Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand status took 12 milliseconds Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand status took 10 milliseconds Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand status took 7 milliseconds Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::parseState Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::parseState Nov 03 16:39:14 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::parseState Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 16:39:14 rivo volumio[3285]: info: Nov 03 16:39:14 rivo volumio[3285]: ---------------------------- MPD announces state update: player Nov 03 16:39:14 rivo volumio[3285]: info: ControllerMpd::getState Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand status Nov 03 16:39:14 rivo volumio[3285]: info: Nov 03 16:39:14 rivo volumio[3285]: ---------------------------- MPD announces state update: player Nov 03 16:39:14 rivo volumio[3285]: info: ControllerMpd::getState Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand status Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand status took 28 milliseconds Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand playlistinfo took 25 milliseconds Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand playlistinfo took 24 milliseconds Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand status took 11 milliseconds Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand status took 10 milliseconds Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::parseState Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::parseTrackInfo Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::parseState Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::parseState Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 16:39:14 rivo volumio[3285]: info: ControllerMpd::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Nov 03 16:39:14 rivo volumio[3285]: info: CorePlayQueue::getTrack 0 Nov 03 16:39:14 rivo volumio[3285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"412952397&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/412952397&soundQuality=HI_RES","trackType":"tidal"} Nov 03 16:39:14 rivo volumio[3285]: verbose: CURRENT POSITION 0 Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::syncState stateService play Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::syncState currentStatus stop Nov 03 16:39:14 rivo volumio[3285]: info: ControllerMpd::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Nov 03 16:39:14 rivo volumio[3285]: info: CorePlayQueue::getTrack 0 Nov 03 16:39:14 rivo volumio[3285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"412952397&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/412952397&soundQuality=HI_RES","trackType":"tidal"} Nov 03 16:39:14 rivo volumio[3285]: verbose: CURRENT POSITION 0 Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::syncState stateService play Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::syncState currentStatus play Nov 03 16:39:14 rivo volumio[3285]: info: Received an update from plugin. extracting info from payload Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:14 rivo volumio[3285]: info: ControllerMpd::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Nov 03 16:39:14 rivo volumio[3285]: info: CorePlayQueue::getTrack 0 Nov 03 16:39:14 rivo volumio[3285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"412952397&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/412952397&soundQuality=HI_RES","trackType":"tidal"} Nov 03 16:39:14 rivo volumio[3285]: verbose: CURRENT POSITION 0 Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::syncState stateService play Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::syncState currentStatus play Nov 03 16:39:14 rivo volumio[3285]: info: Received an update from plugin. extracting info from payload Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:14 rivo volumio[3285]: info: ------------------------------ 92ms Nov 03 16:39:14 rivo volumio[3285]: info: ------------------------------ 173ms Nov 03 16:39:14 rivo volumio[3285]: info: ------------------------------ 172ms Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand playlistinfo took 132 milliseconds Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand playlistinfo took 132 milliseconds Nov 03 16:39:14 rivo volumio[3285]: info: sendMpdCommand playlistinfo took 132 milliseconds Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::parseTrackInfo Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::parseTrackInfo Nov 03 16:39:14 rivo volumio[3285]: verbose: ControllerMpd::parseTrackInfo Nov 03 16:39:14 rivo volumio[3285]: info: ControllerMpd::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Nov 03 16:39:14 rivo volumio[3285]: info: CorePlayQueue::getTrack 0 Nov 03 16:39:14 rivo volumio[3285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":264,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1016 Kbps","isStreaming":false,"title":"412952397&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/412952397&soundQuality=HI_RES","trackType":"tidal"} Nov 03 16:39:14 rivo volumio[3285]: verbose: CURRENT POSITION 0 Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::syncState stateService play Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::syncState currentStatus play Nov 03 16:39:14 rivo volumio[3285]: info: Received an update from plugin. extracting info from payload Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:14 rivo volumio[3285]: info: ControllerMpd::pushState Nov 03 16:39:14 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Nov 03 16:39:14 rivo volumio[3285]: info: CorePlayQueue::getTrack 0 Nov 03 16:39:14 rivo volumio[3285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":264,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1036 Kbps","isStreaming":false,"title":"412952397&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/412952397&soundQuality=HI_RES","trackType":"tidal"} Nov 03 16:39:14 rivo volumio[3285]: verbose: CURRENT POSITION 0 Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::syncState stateService play Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::syncState currentStatus play Nov 03 16:39:14 rivo volumio[3285]: info: Received an update from plugin. extracting info from payload Nov 03 16:39:14 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:15 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 16:39:15 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:15 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:15 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:15 rivo volumio[3285]: info: ControllerMpd::pushState Nov 03 16:39:15 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Nov 03 16:39:15 rivo volumio[3285]: info: CorePlayQueue::getTrack 0 Nov 03 16:39:15 rivo volumio[3285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":264,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1044 Kbps","isStreaming":false,"title":"412952397&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/412952397&soundQuality=HI_RES","trackType":"tidal"} Nov 03 16:39:15 rivo volumio[3285]: verbose: CURRENT POSITION 0 Nov 03 16:39:15 rivo volumio[3285]: info: CoreStateMachine::syncState stateService play Nov 03 16:39:15 rivo volumio[3285]: info: CoreStateMachine::syncState currentStatus play Nov 03 16:39:15 rivo volumio[3285]: info: Received an update from plugin. extracting info from payload Nov 03 16:39:15 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:15 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 16:39:15 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:15 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:39:15 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:39:15 rivo volumio[3285]: info: ------------------------------ 402ms Nov 03 16:39:15 rivo volumio[3285]: info: ------------------------------ 383ms Nov 03 16:39:15 rivo volumio[3285]: info: ------------------------------ 381ms Nov 03 16:39:15 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:15 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:15 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:15 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:15 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:15 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:15 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:15 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:15 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:15 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:39:19 rivo volumio[3285]: STREAMING PROXY: Client dropped request, destroying Nov 03 16:39:42 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 16:39:42 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 16:39:42 rivo volumio[3285]: info: Discovery: Getting this device information Nov 03 16:39:42 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Nov 03 16:39:42 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 16:40:13 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 16:40:13 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 16:40:13 rivo volumio[3285]: info: Discovery: Getting this device information Nov 03 16:40:13 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Nov 03 16:40:13 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 16:40:18 rivo volumio[3285]: info: Preload queue cleared Nov 03 16:40:18 rivo volumio[3285]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 03 16:40:18 rivo volumio[3285]: info: CoreStateMachine::ClearQueue Nov 03 16:40:18 rivo volumio[3285]: info: CoreStateMachine::stop Nov 03 16:40:18 rivo volumio[3285]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 16:40:18 rivo volumio[3285]: info: CoreStateMachine::stPlaybackTimer Nov 03 16:40:18 rivo volumio[3285]: info: CoreStateMachine::updateTrackBlock Nov 03 16:40:18 rivo volumio[3285]: info: CorePlayQueue::getTrackBlock Nov 03 16:40:18 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:40:18 rivo volumio[3285]: info: CorePlayQueue::getTrack 0 Nov 03 16:40:18 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 16:40:18 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::serviceStop Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::getTrack 0 Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::serviceStop Nov 03 16:40:19 rivo volumio[3285]: info: [1762188019011] ControllerTidal::stop Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 03 16:40:19 rivo volumio[3285]: info: ControllerMpd::stop Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand stop Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::clearPlayQueue Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::saveQueue Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::volumioPushQueue Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::addQueueItems Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::addQueueItems Nov 03 16:40:19 rivo volumio[3285]: info: Preload queue cleared Nov 03 16:40:19 rivo volumio[3285]: info: Adding Item to queue: tidal://song/412952397 Nov 03 16:40:19 rivo volumio[3285]: info: Using cached record of: tidal://song/412952397 Nov 03 16:40:19 rivo volumio[3285]: info: Adding Item to queue: tidal://song/412952399 Nov 03 16:40:19 rivo volumio[3285]: info: Using cached record of: tidal://song/412952399 Nov 03 16:40:19 rivo volumio[3285]: info: Adding Item to queue: tidal://song/412952400 Nov 03 16:40:19 rivo volumio[3285]: info: Using cached record of: tidal://song/412952400 Nov 03 16:40:19 rivo volumio[3285]: info: Adding Item to queue: tidal://song/412952401 Nov 03 16:40:19 rivo volumio[3285]: info: Using cached record of: tidal://song/412952401 Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::volumioPushQueue Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::saveQueue Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::updateTrackBlock Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::getTrackBlock Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::volumioPlay Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::play index 3 Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::addQueueItems Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::addQueueItems Nov 03 16:40:19 rivo volumio[3285]: info: Preload queue cleared Nov 03 16:40:19 rivo volumio[3285]: info: Adding Item to queue: tidal://song/412952403 Nov 03 16:40:19 rivo volumio[3285]: info: Using cached record of: tidal://song/412952403 Nov 03 16:40:19 rivo volumio[3285]: info: Adding Item to queue: tidal://song/412952404 Nov 03 16:40:19 rivo volumio[3285]: info: Using cached record of: tidal://song/412952404 Nov 03 16:40:19 rivo volumio[3285]: info: Adding Item to queue: tidal://song/412952405 Nov 03 16:40:19 rivo volumio[3285]: info: Using cached record of: tidal://song/412952405 Nov 03 16:40:19 rivo volumio[3285]: info: Adding Item to queue: tidal://song/412952406 Nov 03 16:40:19 rivo volumio[3285]: info: Using cached record of: tidal://song/412952406 Nov 03 16:40:19 rivo volumio[3285]: info: Adding Item to queue: tidal://song/412952407 Nov 03 16:40:19 rivo volumio[3285]: info: Using cached record of: tidal://song/412952407 Nov 03 16:40:19 rivo volumio[3285]: info: Adding Item to queue: tidal://song/412952408 Nov 03 16:40:19 rivo volumio[3285]: info: Using cached record of: tidal://song/412952408 Nov 03 16:40:19 rivo volumio[3285]: info: Adding Item to queue: tidal://song/412952409 Nov 03 16:40:19 rivo volumio[3285]: info: Using cached record of: tidal://song/412952409 Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::stop Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::volumioPushQueue Nov 03 16:40:19 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Nov 03 16:40:19 rivo kernel: spdif_a keep clk continuous Nov 03 16:40:19 rivo kernel: aml_spdif_close Nov 03 16:40:19 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::saveQueue Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::play index undefined Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::updateTrackBlock Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::getTrackBlock Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::getTrack 3 Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::startPlaybackTimer Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::getTrack 3 Nov 03 16:40:19 rivo volumio[3285]: info: [1762188019096] ControllerTidal::clearAddPlayTrack Nov 03 16:40:19 rivo volumio[3285]: info: Getting stream with soundQuality HI_RES Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand stop Nov 03 16:40:19 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:40:19 rivo volumio[3285]: info: Nov 03 16:40:19 rivo volumio[3285]: ---------------------------- MPD announces state update: player Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand stop took 112 milliseconds Nov 03 16:40:19 rivo volumio[3285]: info: ControllerMpd::getState Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand status Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand stop took 27 milliseconds Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand clear Nov 03 16:40:19 rivo volumio[3285]: info: Nov 03 16:40:19 rivo volumio[3285]: ---------------------------- MPD announces state update: player Nov 03 16:40:19 rivo volumio[3285]: info: ControllerMpd::getState Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand status Nov 03 16:40:19 rivo volumio[3285]: info: Nov 03 16:40:19 rivo volumio[3285]: ---------------------------- MPD announces state update: player Nov 03 16:40:19 rivo volumio[3285]: info: ControllerMpd::getState Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand status Nov 03 16:40:19 rivo volumio[3285]: info: Nov 03 16:40:19 rivo volumio[3285]: ---------------------------- MPD announces system playlist update Nov 03 16:40:19 rivo volumio[3285]: info: Ignoring MPD Status Update Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand status took 8 milliseconds Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand clear took 8 milliseconds Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand status took 7 milliseconds Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand status took 5 milliseconds Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::parseState Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/412952401&soundQuality=HI_RES" Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::parseState Nov 03 16:40:19 rivo volumio[3285]: info: ControllerMpd::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::getTrack 3 Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::getTrack 3 Nov 03 16:40:19 rivo volumio[3285]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Nov 03 16:40:19 rivo volumio[3285]: info: ControllerMpd::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::getTrack 3 Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::getTrack 3 Nov 03 16:40:19 rivo volumio[3285]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Nov 03 16:40:19 rivo volumio[3285]: info: ------------------------------ 69ms Nov 03 16:40:19 rivo volumio[3285]: info: ------------------------------ 69ms Nov 03 16:40:19 rivo volumio[3285]: info: Nov 03 16:40:19 rivo volumio[3285]: ---------------------------- MPD announces system playlist update Nov 03 16:40:19 rivo volumio[3285]: info: Ignoring MPD Status Update Nov 03 16:40:19 rivo volumio[3285]: info: Nov 03 16:40:19 rivo volumio[3285]: ---------------------------- MPD announces system playlist update Nov 03 16:40:19 rivo volumio[3285]: info: Ignoring MPD Status Update Nov 03 16:40:19 rivo volumio[3285]: error: updateQueue error: null Nov 03 16:40:19 rivo volumio[3285]: info: Nov 03 16:40:19 rivo volumio[3285]: ---------------------------- MPD announces system playlist update Nov 03 16:40:19 rivo volumio[3285]: info: Ignoring MPD Status Update Nov 03 16:40:19 rivo volumio[3285]: info: ------------------------------ 80ms Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand playlistinfo took 77 milliseconds Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/412952401&soundQuality=HI_RES" took 76 milliseconds Nov 03 16:40:19 rivo volumio[3285]: info: ------------------------------ 15ms Nov 03 16:40:19 rivo volumio[3285]: info: ------------------------------ 14ms Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::parseTrackInfo Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand play Nov 03 16:40:19 rivo volumio[3285]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 03 16:40:19 rivo volumio[3285]: info: ------------------------------ 106ms Nov 03 16:40:19 rivo volumio[3285]: info: Nov 03 16:40:19 rivo volumio[3285]: ---------------------------- MPD announces system playlist update Nov 03 16:40:19 rivo volumio[3285]: info: Ignoring MPD Status Update Nov 03 16:40:19 rivo volumio[3285]: info: Nov 03 16:40:19 rivo volumio[3285]: ---------------------------- MPD announces system playlist update Nov 03 16:40:19 rivo volumio[3285]: info: Ignoring MPD Status Update Nov 03 16:40:19 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:40:19 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:40:19 rivo volumio[3285]: STREAMING PROXY: Handling url /?data=tidal://song/412952401&soundQuality=HI_RES Nov 03 16:40:19 rivo volumio[3285]: info: ------------------------------ 38ms Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand play took 33 milliseconds Nov 03 16:40:19 rivo volumio[3285]: info: ------------------------------ 20ms Nov 03 16:40:19 rivo volumio[3285]: info: ------------------------------ 18ms Nov 03 16:40:19 rivo volumio[3285]: info: MCU Signalled Playback Inactive Nov 03 16:40:19 rivo volumio[3285]: info: Executing endpoint getStreamUrltidal Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 03 16:40:19 rivo volumio[3285]: info: getStreamUrl took 259 milliseconds Nov 03 16:40:19 rivo volumio[3285]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRlNjk0YWVlMzhhMDU5OGJlOTdhNmZjODgxNTI0NmFlMS5tcDQ/0.flac?token=1762191619~NmU2MTNlZTg2YWIzZWJhMmM4ZTEyY2Y0ZTUzZTE1Yjc0NWM3OGU4MQ== Nov 03 16:40:19 rivo volumio[3285]: STREAMING PROXY: Response: 200, length: 42152980 Nov 03 16:40:19 rivo kernel: aml_spdif_open Nov 03 16:40:19 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Nov 03 16:40:19 rivo kernel: set normal 512 fs /4 fs Nov 03 16:40:19 rivo kernel: set spdifout clk:6144000, mpll:24576000 Nov 03 16:40:19 rivo kernel: get spdifout clk:6143997, mpll:24575987 Nov 03 16:40:19 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Nov 03 16:40:19 rivo kernel: set normal 512 fs /4 fs Nov 03 16:40:19 rivo kernel: set spdifout clk:6144000, mpll:24576000 Nov 03 16:40:19 rivo kernel: get spdifout clk:6143997, mpll:24575987 Nov 03 16:40:19 rivo kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Nov 03 16:40:19 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Nov 03 16:40:19 rivo volumio[3285]: info: Nov 03 16:40:19 rivo volumio[3285]: ---------------------------- MPD announces state update: player Nov 03 16:40:19 rivo volumio[3285]: info: ControllerMpd::getState Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand status Nov 03 16:40:19 rivo volumio[3285]: info: Nov 03 16:40:19 rivo volumio[3285]: ---------------------------- MPD announces state update: player Nov 03 16:40:19 rivo volumio[3285]: info: ControllerMpd::getState Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand status Nov 03 16:40:19 rivo volumio[3285]: info: Nov 03 16:40:19 rivo volumio[3285]: ---------------------------- MPD announces state update: player Nov 03 16:40:19 rivo volumio[3285]: info: ControllerMpd::getState Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand status Nov 03 16:40:19 rivo volumio[3285]: info: Nov 03 16:40:19 rivo volumio[3285]: ---------------------------- MPD announces state update: player Nov 03 16:40:19 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Nov 03 16:40:19 rivo volumio[3285]: info: ControllerMpd::getState Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand status Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand status took 20 milliseconds Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand status took 15 milliseconds Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand status took 10 milliseconds Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::parseState Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::parseState Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::parseState Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 16:40:19 rivo volumio[3285]: info: Nov 03 16:40:19 rivo volumio[3285]: ---------------------------- MPD announces state update: player Nov 03 16:40:19 rivo volumio[3285]: info: ControllerMpd::getState Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand status Nov 03 16:40:19 rivo volumio[3285]: info: Nov 03 16:40:19 rivo volumio[3285]: ---------------------------- MPD announces state update: player Nov 03 16:40:19 rivo volumio[3285]: info: ControllerMpd::getState Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand status Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand status took 27 milliseconds Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand playlistinfo took 21 milliseconds Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand playlistinfo took 20 milliseconds Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand playlistinfo took 20 milliseconds Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand status took 15 milliseconds Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand status took 12 milliseconds Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::parseState Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::parseTrackInfo Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::parseTrackInfo Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::parseTrackInfo Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::parseState Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::parseState Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 16:40:19 rivo volumio[3285]: info: ControllerMpd::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::getTrack 3 Nov 03 16:40:19 rivo volumio[3285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"412952401&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/412952401&soundQuality=HI_RES","trackType":"tidal"} Nov 03 16:40:19 rivo volumio[3285]: verbose: CURRENT POSITION 3 Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::syncState stateService play Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::syncState currentStatus stop Nov 03 16:40:19 rivo volumio[3285]: info: ControllerMpd::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::getTrack 3 Nov 03 16:40:19 rivo volumio[3285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"412952401&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/412952401&soundQuality=HI_RES","trackType":"tidal"} Nov 03 16:40:19 rivo volumio[3285]: verbose: CURRENT POSITION 3 Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::syncState stateService play Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::syncState currentStatus play Nov 03 16:40:19 rivo volumio[3285]: info: Received an update from plugin. extracting info from payload Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:40:19 rivo volumio[3285]: info: ControllerMpd::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::getTrack 3 Nov 03 16:40:19 rivo volumio[3285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"412952401&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/412952401&soundQuality=HI_RES","trackType":"tidal"} Nov 03 16:40:19 rivo volumio[3285]: verbose: CURRENT POSITION 3 Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::syncState stateService play Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::syncState currentStatus play Nov 03 16:40:19 rivo volumio[3285]: info: Received an update from plugin. extracting info from payload Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:40:19 rivo volumio[3285]: info: ------------------------------ 91ms Nov 03 16:40:19 rivo volumio[3285]: info: ------------------------------ 185ms Nov 03 16:40:19 rivo volumio[3285]: info: ------------------------------ 180ms Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand playlistinfo took 141 milliseconds Nov 03 16:40:19 rivo volumio[3285]: info: sendMpdCommand playlistinfo took 140 milliseconds Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::parseTrackInfo Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::parseTrackInfo Nov 03 16:40:19 rivo volumio[3285]: verbose: ControllerMpd::parseTrackInfo Nov 03 16:40:19 rivo volumio[3285]: info: ControllerMpd::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::getTrack 3 Nov 03 16:40:19 rivo volumio[3285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":191,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1365 Kbps","isStreaming":false,"title":"412952401&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/412952401&soundQuality=HI_RES","trackType":"tidal"} Nov 03 16:40:19 rivo volumio[3285]: verbose: CURRENT POSITION 3 Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::syncState stateService play Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::syncState currentStatus play Nov 03 16:40:19 rivo volumio[3285]: info: Received an update from plugin. extracting info from payload Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:40:19 rivo volumio[3285]: info: ControllerMpd::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::getTrack 3 Nov 03 16:40:19 rivo volumio[3285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":191,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1524 Kbps","isStreaming":false,"title":"412952401&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/412952401&soundQuality=HI_RES","trackType":"tidal"} Nov 03 16:40:19 rivo volumio[3285]: verbose: CURRENT POSITION 3 Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::syncState stateService play Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::syncState currentStatus play Nov 03 16:40:19 rivo volumio[3285]: info: Received an update from plugin. extracting info from payload Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:40:19 rivo volumio[3285]: info: ControllerMpd::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Nov 03 16:40:19 rivo volumio[3285]: info: CorePlayQueue::getTrack 3 Nov 03 16:40:19 rivo volumio[3285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":191,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1969 Kbps","isStreaming":false,"title":"412952401&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/412952401&soundQuality=HI_RES","trackType":"tidal"} Nov 03 16:40:19 rivo volumio[3285]: verbose: CURRENT POSITION 3 Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::syncState stateService play Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::syncState currentStatus play Nov 03 16:40:19 rivo volumio[3285]: info: Received an update from plugin. extracting info from payload Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreStateMachine::pushState Nov 03 16:40:19 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Nov 03 16:40:20 rivo volumio[3285]: info: ------------------------------ 393ms Nov 03 16:40:20 rivo volumio[3285]: info: ------------------------------ 381ms Nov 03 16:40:20 rivo volumio[3285]: info: ------------------------------ 379ms Nov 03 16:40:20 rivo volumio[3285]: info: Signalling Playback active due to playback status change Nov 03 16:40:20 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:40:20 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:40:20 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:40:20 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:40:20 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:40:20 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:40:20 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:40:20 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:40:20 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:40:20 rivo volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 16:40:20 rivo volumio[3285]: info: MCU Signalled Playback Active Nov 03 16:40:24 rivo volumio[3285]: STREAMING PROXY: Client dropped request, destroying Nov 03 16:40:28 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Nov 03 16:40:28 rivo volumio[3285]: info: browseTIDALUri took 75 milliseconds Nov 03 16:40:28 rivo volumio[3285]: info: Preload queue cleared Nov 03 16:40:39 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Nov 03 16:40:39 rivo volumio[3285]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 16:40:39 rivo volumio[3285]: TypeError: Cannot read property 'then' of undefined Nov 03 16:40:39 rivo volumio[3285]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Nov 03 16:40:39 rivo volumio[3285]: at Socket.emit (events.js:400:28) Nov 03 16:40:39 rivo volumio[3285]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 03 16:40:39 rivo volumio[3285]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Nov 03 16:40:39 rivo volumio[3285]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 16:40:40 rivo sudo[14957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-03 16:39 Nov 03 16:40:40 rivo sudo[14957]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:43:09 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="fc7d09bc07ffd188bff4808d8f79c746"