-- Logs begin at Thu 2025-10-23 11:34:58 CEST, end at Tue 2025-10-28 05:38:46 CET. -- Oct 28 05:37:04 cronkolorivo volumio[3445]: info: Preload queue cleared Oct 28 05:37:04 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 28 05:37:04 cronkolorivo volumio[3445]: info: CoreStateMachine::ClearQueue Oct 28 05:37:04 cronkolorivo volumio[3445]: info: CoreStateMachine::stop Oct 28 05:37:04 cronkolorivo volumio[3445]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 05:37:04 cronkolorivo volumio[3445]: info: CoreStateMachine::updateTrackBlock Oct 28 05:37:04 cronkolorivo volumio[3445]: info: CorePlayQueue::getTrackBlock Oct 28 05:37:04 cronkolorivo volumio[3445]: info: CoreStateMachine::stPlaybackTimer Oct 28 05:37:04 cronkolorivo volumio[3445]: info: CoreStateMachine::pushState Oct 28 05:37:04 cronkolorivo volumio[3445]: info: CorePlayQueue::getTrack 0 Oct 28 05:37:04 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 05:37:04 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioPushState Oct 28 05:37:04 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioGetState Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CorePlayQueue::getTrack 0 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: MRS: Pushing multiroomSync output update for this device Oct 28 05:37:05 cronkolorivo volumio[3445]: info: MRS: Pushing multiroomSync output Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreStateMachine::serviceStop Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CorePlayQueue::getTrack 0 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreCommandRouter::serviceStop Oct 28 05:37:05 cronkolorivo volumio[3445]: info: ControllerMpd::stop Oct 28 05:37:05 cronkolorivo volumio[3445]: verbose: ControllerMpd::sendMpdCommand stop Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CorePlayQueue::clearPlayQueue Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CorePlayQueue::saveQueue Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioPushQueue Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreStateMachine::addQueueItems Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CorePlayQueue::addQueueItems Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Preload queue cleared Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/51751544 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/51751544 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/44913861 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/44913861 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/188683379 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/188683379 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/17516430 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/17516430 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/44922603 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/44922603 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/44913867 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/44913867 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/44913863 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/44913863 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/44913862 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/44913862 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/138022765 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/138022765 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/44913865 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/44913865 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/44903871 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/44903871 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/89928381 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/89928381 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/44913866 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/44913866 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/44922605 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/44922605 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/44913869 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/44913869 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/44903868 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/44903868 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/51100099 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/51100099 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/44913873 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/44913873 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/44913868 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/44913868 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Oct 28 05:37:05 cronkolorivo volumio[3445]: ---------------------------- MPD announces state update: player Oct 28 05:37:05 cronkolorivo volumio[3445]: info: sendMpdCommand stop took 173 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: ControllerMpd::getState Oct 28 05:37:05 cronkolorivo volumio[3445]: verbose: ControllerMpd::sendMpdCommand status Oct 28 05:37:05 cronkolorivo volumio[3445]: info: sendMpdCommand status took 8 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: verbose: ControllerMpd::parseState Oct 28 05:37:05 cronkolorivo volumio[3445]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 05:37:05 cronkolorivo volumio[3445]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: verbose: ControllerMpd::parseTrackInfo Oct 28 05:37:05 cronkolorivo volumio[3445]: info: ControllerMpd::pushState Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreCommandRouter::servicePushState Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreStateMachine::pushState Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CorePlayQueue::getTrack 0 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioPushState Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioGetState Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CorePlayQueue::getTrack 0 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: MRS: Pushing multiroomSync output update for this device Oct 28 05:37:05 cronkolorivo volumio[3445]: info: MRS: Pushing multiroomSync output Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CorePlayQueue::getTrack 0 Oct 28 05:37:05 cronkolorivo volumio[3445]: 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":"Let’s Face the Music & Dance","artist":"Frank Sinatra","album":"My Way: The Best of Frank Sinatra (CD 2)","uri":"USB/Elements/Frank Sinatra - My Way- The Best of Frank Sinatra (CD 2)/01 - Let’s Face the Music & Dance.flac","trackType":"flac"} Oct 28 05:37:05 cronkolorivo volumio[3445]: verbose: CURRENT POSITION 0 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreStateMachine::syncState stateService stop Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreStateMachine::syncState currentStatus stop Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreStateMachine::pushState Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CorePlayQueue::getTrack 0 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioPushState Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioGetState Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CorePlayQueue::getTrack 0 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: MRS: Pushing multiroomSync output update for this device Oct 28 05:37:05 cronkolorivo volumio[3445]: info: MRS: Pushing multiroomSync output Oct 28 05:37:05 cronkolorivo volumio[3445]: info: No code Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreStateMachine::pushState Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CorePlayQueue::getTrack 0 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioPushState Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioGetState Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CorePlayQueue::getTrack 0 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: MRS: Pushing multiroomSync output update for this device Oct 28 05:37:05 cronkolorivo volumio[3445]: info: MRS: Pushing multiroomSync output Oct 28 05:37:05 cronkolorivo volumio[3445]: info: ------------------------------ 71ms Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 677 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 680 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 685 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 685 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 665 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 690 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 688 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 694 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 700 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 694 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 700 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 708 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 700 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 717 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 741 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 765 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 798 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 796 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: explodeUri took 831 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioPushQueue Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CorePlayQueue::saveQueue Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreStateMachine::updateTrackBlock Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CorePlayQueue::getTrackBlock Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioPlay Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreStateMachine::play index 18 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreStateMachine::addQueueItems Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CorePlayQueue::addQueueItems Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Preload queue cleared Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Adding Item to queue: qobuz://song/172821435 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Exploding uri qobuz://song/172821435 in service qobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreStateMachine::stop Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreStateMachine::play index undefined Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CorePlayQueue::getTrack 18 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreStateMachine::startPlaybackTimer Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CorePlayQueue::getTrack 18 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioGetVisibleSources Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 28 05:37:05 cronkolorivo volumio[3445]: info: [1761626225896] ControllerQobuz::clearAddPlayTrack Oct 28 05:37:05 cronkolorivo volumio[3445]: verbose: ControllerMpd::sendMpdCommand stop Oct 28 05:37:05 cronkolorivo volumio[3445]: info: sendMpdCommand stop took 12 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: verbose: ControllerMpd::sendMpdCommand clear Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Oct 28 05:37:05 cronkolorivo volumio[3445]: ---------------------------- MPD announces system playlist update Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Ignoring MPD Status Update Oct 28 05:37:05 cronkolorivo volumio[3445]: info: sendMpdCommand clear took 4 milliseconds Oct 28 05:37:05 cronkolorivo volumio[3445]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/44913868" Oct 28 05:37:05 cronkolorivo volumio[3445]: error: updateQueue error: null Oct 28 05:37:05 cronkolorivo volumio[3445]: STREAMING PROXY: Handling url /?data=qobuz://song/44913868 Oct 28 05:37:05 cronkolorivo volumio[3445]: info: ------------------------------ 13ms Oct 28 05:37:05 cronkolorivo volumio[3445]: info: Executing endpoint getStreamUrlqobuz Oct 28 05:37:05 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 28 05:37:06 cronkolorivo volumio[3445]: info: explodeUri took 301 milliseconds Oct 28 05:37:06 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioPushQueue Oct 28 05:37:06 cronkolorivo volumio[3445]: info: CorePlayQueue::saveQueue Oct 28 05:37:06 cronkolorivo volumio[3445]: info: CoreStateMachine::updateTrackBlock Oct 28 05:37:06 cronkolorivo volumio[3445]: info: CorePlayQueue::getTrackBlock Oct 28 05:37:06 cronkolorivo volumio[3445]: info: getStreamUrl took 254 milliseconds Oct 28 05:37:06 cronkolorivo volumio[3445]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3378361&eid=44913868&fmt=6&profile=raw&app_id=539451548&cid=2469382&etsp=1761629826&hmac=mNuW2cB3eiIdM7ziL2WVXMeqrmI Oct 28 05:37:06 cronkolorivo volumio[3445]: STREAMING PROXY: Response: 200, length: 8764940 Oct 28 05:37:06 cronkolorivo volumio[3445]: STREAMING PROXY: Client dropped request, destroying Oct 28 05:37:06 cronkolorivo volumio[3445]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/44913868" Oct 28 05:37:06 cronkolorivo volumio[3445]: info: Oct 28 05:37:06 cronkolorivo volumio[3445]: ---------------------------- MPD announces system playlist update Oct 28 05:37:06 cronkolorivo volumio[3445]: info: Ignoring MPD Status Update Oct 28 05:37:06 cronkolorivo volumio[3445]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/44913868" took 6 milliseconds Oct 28 05:37:06 cronkolorivo volumio[3445]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 28 05:37:06 cronkolorivo volumio[3445]: verbose: ControllerMpd::sendMpdCommand play Oct 28 05:37:06 cronkolorivo volumio[3445]: info: ------------------------------ 8ms Oct 28 05:37:06 cronkolorivo volumio[3445]: STREAMING PROXY: Handling url /?data=qobuz://song/44913868 Oct 28 05:37:06 cronkolorivo volumio[3445]: info: sendMpdCommand play took 2 milliseconds Oct 28 05:37:06 cronkolorivo volumio[3445]: info: Executing endpoint getStreamUrlqobuz Oct 28 05:37:06 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 28 05:37:06 cronkolorivo volumio[3445]: info: getStreamUrl took 191 milliseconds Oct 28 05:37:06 cronkolorivo volumio[3445]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3378361&eid=44913868&fmt=6&profile=raw&app_id=539451548&cid=2469382&etsp=1761629826&hmac=mNuW2cB3eiIdM7ziL2WVXMeqrmI Oct 28 05:37:06 cronkolorivo volumio[3445]: STREAMING PROXY: Response: 200, length: 8764940 Oct 28 05:37:06 cronkolorivo volumio[3445]: info: Oct 28 05:37:06 cronkolorivo volumio[3445]: ---------------------------- MPD announces state update: player Oct 28 05:37:06 cronkolorivo kernel: aml_spdif_open Oct 28 05:37:06 cronkolorivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 28 05:37:06 cronkolorivo kernel: set normal 512 fs /4 fs Oct 28 05:37:06 cronkolorivo kernel: set spdifout clk:5644800, mpll:22579200 Oct 28 05:37:06 cronkolorivo kernel: get spdifout clk:5644797, mpll:22579186 Oct 28 05:37:06 cronkolorivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Oct 28 05:37:06 cronkolorivo kernel: set normal 512 fs /4 fs Oct 28 05:37:06 cronkolorivo kernel: set spdifout clk:5644800, mpll:22579200 Oct 28 05:37:06 cronkolorivo kernel: get spdifout clk:5644797, mpll:22579186 Oct 28 05:37:06 cronkolorivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Oct 28 05:37:06 cronkolorivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Oct 28 05:37:06 cronkolorivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Oct 28 05:37:06 cronkolorivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Oct 28 05:37:06 cronkolorivo volumio[3445]: info: ControllerMpd::getState Oct 28 05:37:06 cronkolorivo volumio[3445]: verbose: ControllerMpd::sendMpdCommand status Oct 28 05:37:06 cronkolorivo volumio[3445]: info: Oct 28 05:37:06 cronkolorivo volumio[3445]: ---------------------------- MPD announces state update: player Oct 28 05:37:06 cronkolorivo volumio[3445]: info: ControllerMpd::getState Oct 28 05:37:06 cronkolorivo volumio[3445]: verbose: ControllerMpd::sendMpdCommand status Oct 28 05:37:06 cronkolorivo volumio[3445]: info: sendMpdCommand status took 3 milliseconds Oct 28 05:37:06 cronkolorivo volumio[3445]: verbose: ControllerMpd::parseState Oct 28 05:37:06 cronkolorivo volumio[3445]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 05:37:07 cronkolorivo volumio[3445]: info: sendMpdCommand status took 15 milliseconds Oct 28 05:37:07 cronkolorivo volumio[3445]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 28 05:37:07 cronkolorivo volumio[3445]: verbose: ControllerMpd::parseState Oct 28 05:37:07 cronkolorivo volumio[3445]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 05:37:07 cronkolorivo volumio[3445]: verbose: ControllerMpd::parseTrackInfo Oct 28 05:37:07 cronkolorivo volumio[3445]: info: ControllerMpd::pushState Oct 28 05:37:07 cronkolorivo volumio[3445]: info: CoreCommandRouter::servicePushState Oct 28 05:37:07 cronkolorivo volumio[3445]: info: CorePlayQueue::getTrack 18 Oct 28 05:37:07 cronkolorivo volumio[3445]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"44913868","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/44913868","trackType":"qobuz"} Oct 28 05:37:07 cronkolorivo volumio[3445]: verbose: CURRENT POSITION 18 Oct 28 05:37:07 cronkolorivo volumio[3445]: info: CoreStateMachine::syncState stateService play Oct 28 05:37:07 cronkolorivo volumio[3445]: info: CoreStateMachine::syncState currentStatus stop Oct 28 05:37:07 cronkolorivo volumio[3445]: info: ------------------------------ 32ms Oct 28 05:37:07 cronkolorivo volumio[3445]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 28 05:37:07 cronkolorivo volumio[3445]: verbose: ControllerMpd::parseTrackInfo Oct 28 05:37:07 cronkolorivo volumio[3445]: info: ControllerMpd::pushState Oct 28 05:37:07 cronkolorivo volumio[3445]: info: CoreCommandRouter::servicePushState Oct 28 05:37:07 cronkolorivo volumio[3445]: info: CorePlayQueue::getTrack 18 Oct 28 05:37:07 cronkolorivo volumio[3445]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"44913868","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/44913868","trackType":"qobuz"} Oct 28 05:37:07 cronkolorivo volumio[3445]: verbose: CURRENT POSITION 18 Oct 28 05:37:07 cronkolorivo volumio[3445]: info: CoreStateMachine::syncState stateService play Oct 28 05:37:07 cronkolorivo volumio[3445]: info: CoreStateMachine::syncState currentStatus play Oct 28 05:37:07 cronkolorivo volumio[3445]: info: Received an update from plugin. extracting info from payload Oct 28 05:37:07 cronkolorivo volumio[3445]: info: CoreStateMachine::pushState Oct 28 05:37:07 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 05:37:07 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioPushState Oct 28 05:37:07 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioGetState Oct 28 05:37:07 cronkolorivo volumio[3445]: info: MRS: Pushing multiroomSync output update for this device Oct 28 05:37:07 cronkolorivo volumio[3445]: info: MRS: Pushing multiroomSync output Oct 28 05:37:07 cronkolorivo volumio[3445]: info: CoreStateMachine::pushState Oct 28 05:37:07 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioPushState Oct 28 05:37:07 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioGetState Oct 28 05:37:07 cronkolorivo volumio[3445]: info: MRS: Pushing multiroomSync output update for this device Oct 28 05:37:07 cronkolorivo volumio[3445]: info: MRS: Pushing multiroomSync output Oct 28 05:37:07 cronkolorivo volumio[3445]: info: ------------------------------ 90ms Oct 28 05:37:07 cronkolorivo volumio[3445]: info: Signalling Playback active due to playback status change Oct 28 05:37:07 cronkolorivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Oct 28 05:37:07 cronkolorivo volumio[3445]: info: MCU Signalled Playback Active Oct 28 05:37:07 cronkolorivo volumio[3445]: info: MCU Signalled Sleep Mode Disabled Oct 28 05:37:07 cronkolorivo sudo[16520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Oct 28 05:37:07 cronkolorivo sudo[16520]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 05:37:07 cronkolorivo kernel: fb: osd[0] enable: 1 (Xorg) Oct 28 05:37:07 cronkolorivo kernel: fb: osd[0] enable: 1 (Xorg) Oct 28 05:37:07 cronkolorivo sudo[16520]: pam_unix(sudo:session): session closed for user root Oct 28 05:37:07 cronkolorivo sudo[16523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Oct 28 05:37:07 cronkolorivo sudo[16523]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 05:37:07 cronkolorivo sudo[16523]: pam_unix(sudo:session): session closed for user root Oct 28 05:37:08 cronkolorivo volumio[3445]: STREAMING PROXY: Client dropped request, destroying Oct 28 05:37:09 cronkolorivo volumio[3445]: info: Executing endpoint metavolumio Oct 28 05:37:09 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 28 05:37:09 cronkolorivo volumio[3445]: info: Executing endpoint metavolumio Oct 28 05:37:09 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 28 05:37:09 cronkolorivo volumio[3445]: info: Executing endpoint metavolumio Oct 28 05:37:09 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 28 05:37:25 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 05:37:25 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 05:37:25 cronkolorivo volumio[3445]: info: Discovery: Getting this device information Oct 28 05:37:25 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioGetState Oct 28 05:37:25 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 05:37:55 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 05:37:55 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 05:37:55 cronkolorivo volumio[3445]: info: Discovery: Getting this device information Oct 28 05:37:55 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioGetState Oct 28 05:37:55 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 05:38:20 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Oct 28 05:38:22 cronkolorivo volumio[3445]: info: handleBrowseUri took 1607 milliseconds Oct 28 05:38:22 cronkolorivo volumio[3445]: info: Preload queue cleared Oct 28 05:38:23 cronkolorivo volumio[3445]: info: Executing endpoint getSimilarArtists Oct 28 05:38:23 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Oct 28 05:38:23 cronkolorivo volumio[3445]: info: Executing endpoint metavolumio Oct 28 05:38:23 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 28 05:38:25 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 05:38:25 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 05:38:25 cronkolorivo volumio[3445]: info: Discovery: Getting this device information Oct 28 05:38:25 cronkolorivo volumio[3445]: info: CoreCommandRouter::volumioGetState Oct 28 05:38:25 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 05:38:41 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Oct 28 05:38:42 cronkolorivo volumio[3445]: info: handleBrowseUri took 1063 milliseconds Oct 28 05:38:42 cronkolorivo volumio[3445]: info: Preload queue cleared Oct 28 05:38:43 cronkolorivo volumio[3445]: info: Executing endpoint getSimilarArtists Oct 28 05:38:43 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Oct 28 05:38:43 cronkolorivo volumio[3445]: info: Executing endpoint metavolumio Oct 28 05:38:43 cronkolorivo volumio[3445]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 28 05:38:45 cronkolorivo volumio[3445]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 05:38:46 cronkolorivo volumio[3445]: TypeError: Cannot read property 'slice' of null Oct 28 05:38:46 cronkolorivo volumio[3445]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Oct 28 05:38:46 cronkolorivo volumio[3445]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Oct 28 05:38:46 cronkolorivo volumio[3445]: at Object.onceWrapper (events.js:520:26) Oct 28 05:38:46 cronkolorivo volumio[3445]: at ClientRequest.emit (events.js:400:28) Oct 28 05:38:46 cronkolorivo volumio[3445]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Oct 28 05:38:46 cronkolorivo volumio[3445]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Oct 28 05:38:46 cronkolorivo volumio[3445]: at TLSSocket.socketOnData (_http_client.js:515:22) Oct 28 05:38:46 cronkolorivo volumio[3445]: at TLSSocket.emit (events.js:400:28) Oct 28 05:38:46 cronkolorivo volumio[3445]: at addChunk (internal/streams/readable.js:293:12) Oct 28 05:38:46 cronkolorivo volumio[3445]: at readableAddChunk (internal/streams/readable.js:267:9) Oct 28 05:38:46 cronkolorivo volumio[3445]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Oct 28 05:38:46 cronkolorivo volumio[3445]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Oct 28 05:38:46 cronkolorivo volumio[3445]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 05:38:46 cronkolorivo sudo[16961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-28 05:37 Oct 28 05:38:46 cronkolorivo sudo[16961]: 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"