-- Logs begin at Fri 2025-12-12 13:59:02 UTC, end at Fri 2025-12-12 16:38:00 UTC. -- Dec 12 16:37:02 volumio volumio[1272]: STREAMING PROXY: Client dropped request, destroying Dec 12 16:37:07 volumio volumio[1272]: info: Preload queue cleared Dec 12 16:37:07 volumio volumio[1272]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 12 16:37:07 volumio volumio[1272]: info: CoreStateMachine::ClearQueue Dec 12 16:37:07 volumio volumio[1272]: info: CoreStateMachine::stop Dec 12 16:37:07 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 16:37:07 volumio volumio[1272]: info: CoreStateMachine::stPlaybackTimer Dec 12 16:37:07 volumio volumio[1272]: info: CoreStateMachine::updateTrackBlock Dec 12 16:37:07 volumio volumio[1272]: info: CorePlayQueue::getTrackBlock Dec 12 16:37:07 volumio volumio[1272]: info: CoreStateMachine::pushState Dec 12 16:37:07 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 12 16:37:07 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 16:37:07 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Dec 12 16:37:07 volumio volumio[1272]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:37:07 volumio volumio[1272]: info: MRS: Pushing multiroomSync output Dec 12 16:37:07 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 12 16:37:07 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 12 16:37:07 volumio volumio[1272]: info: CoreStateMachine::serviceStop Dec 12 16:37:07 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 12 16:37:07 volumio volumio[1272]: info: CoreCommandRouter::serviceStop Dec 12 16:37:07 volumio volumio[1272]: info: [1765557427828] ControllerQobuz::stop Dec 12 16:37:07 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 12 16:37:07 volumio volumio[1272]: info: ControllerMpd::stop Dec 12 16:37:07 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 16:37:07 volumio volumio[1272]: info: CorePlayQueue::clearPlayQueue Dec 12 16:37:07 volumio volumio[1272]: info: CorePlayQueue::saveQueue Dec 12 16:37:07 volumio volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 12 16:37:07 volumio volumio[1272]: info: CoreStateMachine::addQueueItems Dec 12 16:37:07 volumio volumio[1272]: info: CorePlayQueue::addQueueItems Dec 12 16:37:07 volumio volumio[1272]: info: Preload queue cleared Dec 12 16:37:07 volumio volumio[1272]: info: Adding Item to queue: qobuz://album/qt107639mw7qc Dec 12 16:37:07 volumio volumio[1272]: info: Exploding uri qobuz://album/qt107639mw7qc in service qobuz Dec 12 16:37:07 volumio volumio[1272]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 12 16:37:07 volumio volumio[1272]: info: Dec 12 16:37:07 volumio volumio[1272]: ---------------------------- MPD announces state update: player Dec 12 16:37:07 volumio volumio[1272]: info: sendMpdCommand stop took 17 milliseconds Dec 12 16:37:07 volumio volumio[1272]: info: ControllerMpd::getState Dec 12 16:37:07 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Dec 12 16:37:07 volumio volumio[1272]: info: sendMpdCommand status took 1 milliseconds Dec 12 16:37:07 volumio volumio[1272]: verbose: ControllerMpd::parseState Dec 12 16:37:07 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 16:37:07 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 12 16:37:07 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Dec 12 16:37:07 volumio volumio[1272]: info: ControllerMpd::pushState Dec 12 16:37:07 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Dec 12 16:37:07 volumio volumio[1272]: info: CoreStateMachine::pushState Dec 12 16:37:07 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 16:37:07 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Dec 12 16:37:07 volumio volumio[1272]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:37:07 volumio volumio[1272]: info: MRS: Pushing multiroomSync output Dec 12 16:37:07 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 12 16:37:07 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 12 16:37:07 volumio volumio[1272]: 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":"57230154","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/57230154","trackType":"qobuz"} Dec 12 16:37:07 volumio volumio[1272]: verbose: CURRENT POSITION 0 Dec 12 16:37:07 volumio volumio[1272]: info: CoreStateMachine::syncState stateService stop Dec 12 16:37:07 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus stop Dec 12 16:37:07 volumio volumio[1272]: info: CoreStateMachine::pushState Dec 12 16:37:07 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Dec 12 16:37:07 volumio volumio[1272]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:37:07 volumio volumio[1272]: info: MRS: Pushing multiroomSync output Dec 12 16:37:07 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 12 16:37:07 volumio volumio[1272]: info: No code Dec 12 16:37:07 volumio volumio[1272]: info: CoreStateMachine::pushState Dec 12 16:37:07 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Dec 12 16:37:07 volumio volumio[1272]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:37:07 volumio volumio[1272]: info: MRS: Pushing multiroomSync output Dec 12 16:37:07 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 12 16:37:07 volumio volumio[1272]: info: ------------------------------ 20ms Dec 12 16:37:08 volumio volumio[1272]: info: explodeUri took 390 milliseconds Dec 12 16:37:08 volumio volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 12 16:37:08 volumio volumio[1272]: info: CorePlayQueue::saveQueue Dec 12 16:37:08 volumio volumio[1272]: info: CoreStateMachine::updateTrackBlock Dec 12 16:37:08 volumio volumio[1272]: info: CorePlayQueue::getTrackBlock Dec 12 16:37:08 volumio volumio[1272]: info: CoreCommandRouter::volumioPlay Dec 12 16:37:08 volumio volumio[1272]: info: CoreStateMachine::play index 0 Dec 12 16:37:08 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 16:37:08 volumio volumio[1272]: info: CoreStateMachine::stop Dec 12 16:37:08 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 16:37:08 volumio volumio[1272]: info: CoreStateMachine::play index undefined Dec 12 16:37:08 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 16:37:08 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 12 16:37:08 volumio volumio[1272]: info: CoreStateMachine::startPlaybackTimer Dec 12 16:37:08 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 12 16:37:08 volumio volumio[1272]: info: [1765557428226] ControllerQobuz::clearAddPlayTrack Dec 12 16:37:08 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 16:37:08 volumio volumio[1272]: info: sendMpdCommand stop took 2 milliseconds Dec 12 16:37:08 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand clear Dec 12 16:37:08 volumio volumio[1272]: info: Dec 12 16:37:08 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Dec 12 16:37:08 volumio volumio[1272]: info: Ignoring MPD Status Update Dec 12 16:37:08 volumio volumio[1272]: info: sendMpdCommand clear took 1 milliseconds Dec 12 16:37:08 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/56781767" Dec 12 16:37:08 volumio volumio[1272]: STREAMING PROXY: Handling url /?data=qobuz://song/56781767 Dec 12 16:37:08 volumio volumio[1272]: info: Executing endpoint getStreamUrlqobuz Dec 12 16:37:08 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Dec 12 16:37:08 volumio volumio[1272]: info: getStreamUrl took 357 milliseconds Dec 12 16:37:08 volumio volumio[1272]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3762914&eid=56781767&fmt=6&profile=raw&app_id=539451548&cid=2739436&etsp=1765561028&hmac=QZKEK95d5RCkUaR6WM-sDh5XDqw Dec 12 16:37:09 volumio volumio[1272]: STREAMING PROXY: Response: 200, length: 17382825 Dec 12 16:37:09 volumio volumio[1272]: STREAMING PROXY: Client dropped request, destroying Dec 12 16:37:09 volumio volumio[1272]: error: updateQueue error: null Dec 12 16:37:09 volumio volumio[1272]: info: ------------------------------ 919ms Dec 12 16:37:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/56781767" Dec 12 16:37:09 volumio volumio[1272]: info: Dec 12 16:37:09 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Dec 12 16:37:09 volumio volumio[1272]: info: Ignoring MPD Status Update Dec 12 16:37:09 volumio volumio[1272]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/56781767" took 1 milliseconds Dec 12 16:37:09 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 12 16:37:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand play Dec 12 16:37:09 volumio volumio[1272]: info: ------------------------------ 3ms Dec 12 16:37:09 volumio volumio[1272]: info: sendMpdCommand play took 1 milliseconds Dec 12 16:37:09 volumio volumio[1272]: STREAMING PROXY: Handling url /?data=qobuz://song/56781767 Dec 12 16:37:09 volumio volumio[1272]: info: Executing endpoint getStreamUrlqobuz Dec 12 16:37:09 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Dec 12 16:37:09 volumio volumio[1272]: info: getStreamUrl took 232 milliseconds Dec 12 16:37:09 volumio volumio[1272]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3762914&eid=56781767&fmt=6&profile=raw&app_id=539451548&cid=2739436&etsp=1765561029&hmac=gEDPmYd09UCOR0sLLtCPq9XEyhw Dec 12 16:37:09 volumio volumio[1272]: STREAMING PROXY: Response: 200, length: 17382825 Dec 12 16:37:09 volumio volumio[1272]: info: Dec 12 16:37:09 volumio volumio[1272]: ---------------------------- MPD announces state update: player Dec 12 16:37:09 volumio volumio[1272]: info: ControllerMpd::getState Dec 12 16:37:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Dec 12 16:37:09 volumio volumio[1272]: info: Dec 12 16:37:09 volumio volumio[1272]: ---------------------------- MPD announces state update: player Dec 12 16:37:09 volumio volumio[1272]: info: sendMpdCommand status took 1 milliseconds Dec 12 16:37:09 volumio volumio[1272]: info: ControllerMpd::getState Dec 12 16:37:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Dec 12 16:37:09 volumio volumio[1272]: verbose: ControllerMpd::parseState Dec 12 16:37:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 16:37:09 volumio volumio[1272]: info: sendMpdCommand status took 1 milliseconds Dec 12 16:37:09 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 12 16:37:09 volumio volumio[1272]: verbose: ControllerMpd::parseState Dec 12 16:37:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 16:37:09 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Dec 12 16:37:09 volumio volumio[1272]: info: ControllerMpd::pushState Dec 12 16:37:09 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Dec 12 16:37:09 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 12 16:37:09 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":163,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"56781767","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/56781767","trackType":"qobuz"} Dec 12 16:37:09 volumio volumio[1272]: verbose: CURRENT POSITION 0 Dec 12 16:37:09 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Dec 12 16:37:09 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus stop Dec 12 16:37:09 volumio volumio[1272]: info: ------------------------------ 5ms Dec 12 16:37:09 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 12 16:37:09 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Dec 12 16:37:09 volumio volumio[1272]: info: ControllerMpd::pushState Dec 12 16:37:09 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Dec 12 16:37:09 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 12 16:37:09 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":163,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"56781767","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/56781767","trackType":"qobuz"} Dec 12 16:37:09 volumio volumio[1272]: verbose: CURRENT POSITION 0 Dec 12 16:37:09 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Dec 12 16:37:09 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Dec 12 16:37:09 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Dec 12 16:37:09 volumio volumio[1272]: info: CoreStateMachine::pushState Dec 12 16:37:09 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 16:37:09 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Dec 12 16:37:09 volumio volumio[1272]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:37:09 volumio volumio[1272]: info: MRS: Pushing multiroomSync output Dec 12 16:37:09 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 12 16:37:09 volumio volumio[1272]: info: CoreStateMachine::pushState Dec 12 16:37:09 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Dec 12 16:37:09 volumio volumio[1272]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:37:09 volumio volumio[1272]: info: MRS: Pushing multiroomSync output Dec 12 16:37:09 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 12 16:37:09 volumio volumio[1272]: info: ------------------------------ 17ms Dec 12 16:37:15 volumio volumio[1272]: STREAMING PROXY: Client dropped request, destroying Dec 12 16:37:34 volumio volumio[1272]: Searching plugin music_service/qobuz Dec 12 16:37:34 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 12 16:37:36 volumio volumio[1272]: info: searchQOBUZUri took 1676 milliseconds Dec 12 16:37:36 volumio volumio[1272]: info: All search sources collected, pushing search results Dec 12 16:37:46 volumio volumio[1272]: Searching plugin music_service/qobuz Dec 12 16:37:46 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 12 16:37:47 volumio volumio[1272]: Searching plugin music_service/qobuz Dec 12 16:37:47 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 12 16:37:48 volumio volumio[1272]: info: searchQOBUZUri took 956 milliseconds Dec 12 16:37:48 volumio volumio[1272]: info: All search sources collected, pushing search results Dec 12 16:37:48 volumio volumio[1272]: info: searchQOBUZUri took 2452 milliseconds Dec 12 16:37:48 volumio volumio[1272]: info: All search sources collected, pushing search results Dec 12 16:37:48 volumio volumio[1272]: Searching plugin music_service/qobuz Dec 12 16:37:48 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 12 16:37:50 volumio volumio[1272]: info: searchQOBUZUri took 1086 milliseconds Dec 12 16:37:50 volumio volumio[1272]: info: All search sources collected, pushing search results Dec 12 16:37:51 volumio volumio[1272]: Searching plugin music_service/qobuz Dec 12 16:37:51 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 12 16:37:53 volumio volumio[1272]: info: searchQOBUZUri took 2331 milliseconds Dec 12 16:37:53 volumio volumio[1272]: info: All search sources collected, pushing search results Dec 12 16:37:56 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 12 16:37:56 volumio volumio[1272]: info: handleBrowseUri took 839 milliseconds Dec 12 16:37:56 volumio volumio[1272]: info: Preload queue cleared Dec 12 16:37:57 volumio volumio[1272]: info: Executing endpoint getSimilarArtists Dec 12 16:37:57 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Dec 12 16:37:57 volumio volumio[1272]: info: Executing endpoint metavolumio Dec 12 16:37:57 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 16:38:00 volumio volumio[1272]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 16:38:00 volumio volumio[1272]: TypeError: Cannot read property 'slice' of null Dec 12 16:38:00 volumio volumio[1272]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Dec 12 16:38:00 volumio volumio[1272]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Dec 12 16:38:00 volumio volumio[1272]: at Object.onceWrapper (events.js:520:26) Dec 12 16:38:00 volumio volumio[1272]: at ClientRequest.emit (events.js:400:28) Dec 12 16:38:00 volumio volumio[1272]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Dec 12 16:38:00 volumio volumio[1272]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Dec 12 16:38:00 volumio volumio[1272]: at TLSSocket.socketOnData (_http_client.js:515:22) Dec 12 16:38:00 volumio volumio[1272]: at TLSSocket.emit (events.js:400:28) Dec 12 16:38:00 volumio volumio[1272]: at addChunk (internal/streams/readable.js:293:12) Dec 12 16:38:00 volumio volumio[1272]: at readableAddChunk (internal/streams/readable.js:267:9) Dec 12 16:38:00 volumio volumio[1272]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Dec 12 16:38:00 volumio volumio[1272]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Dec 12 16:38:00 volumio volumio[1272]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 16:38:00 volumio sudo[26767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-12 16:37 Dec 12 16:38:00 volumio sudo[26767]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 07:31:13 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="674d725e3f749f2a23785c9f94a28aa3"