-- Logs begin at Wed 2026-05-27 06:19:35 CEST, end at Sat 2026-05-30 21:12:18 CEST. -- May 30 21:11:14 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 10 May 30 21:11:14 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:14 rivoplus402 volumio[3109]: info: Prefetching next song May 30 21:11:14 rivoplus402 volumio[3109]: info: [1780168274454] ControllerQobuz::prefetch May 30 21:11:14 rivoplus402 volumio[3109]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/312272726" May 30 21:11:14 rivoplus402 volumio[3109]: STREAMING PROXY: Handling url /?data=qobuz://song/312272726 May 30 21:11:14 rivoplus402 volumio[3109]: info: Executing endpoint getStreamUrlqobuz May 30 21:11:14 rivoplus402 volumio[3109]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 21:11:14 rivoplus402 volumio[3109]: info: getStreamUrl took 356 milliseconds May 30 21:11:14 rivoplus402 volumio[3109]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1335780&eid=312272726&fmt=7&profile=raw&app_id=539451548&cid=3814052&etsp=1780171874&hmac=AprEDq5UCN-LTDzxlcJc1ib0004 May 30 21:11:14 rivoplus402 volumio[3109]: STREAMING PROXY: Requesting upstream with range: none May 30 21:11:15 rivoplus402 volumio[3109]: STREAMING PROXY: Upstream status code: 200 May 30 21:11:15 rivoplus402 volumio[3109]: STREAMING PROXY: Client closed connection May 30 21:11:15 rivoplus402 volumio[3109]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/312272726" May 30 21:11:15 rivoplus402 volumio[3109]: STREAMING PROXY: Upstream closed. Received 478 bytes May 30 21:11:15 rivoplus402 volumio[3109]: STREAMING PROXY: Incomplete transfer. Expected 50395737, got 478 May 30 21:11:15 rivoplus402 volumio[3109]: STREAMING PROXY: Retrying with range: bytes=478- (attempt 1) May 30 21:11:15 rivoplus402 volumio[3109]: STREAMING PROXY: Handling url /?data=qobuz://song/312272726 May 30 21:11:15 rivoplus402 volumio[3109]: info: May 30 21:11:15 rivoplus402 volumio[3109]: ---------------------------- MPD announces system playlist update May 30 21:11:15 rivoplus402 volumio[3109]: info: Ignoring MPD Status Update May 30 21:11:15 rivoplus402 volumio[3109]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/312272726" took 4 milliseconds May 30 21:11:15 rivoplus402 volumio[3109]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 21:11:15 rivoplus402 volumio[3109]: verbose: ControllerMpd::sendMpdCommand consume 1 May 30 21:11:15 rivoplus402 volumio[3109]: info: May 30 21:11:15 rivoplus402 volumio[3109]: ---------------------------- MPD announces system playlist update May 30 21:11:15 rivoplus402 volumio[3109]: info: Ignoring MPD Status Update May 30 21:11:15 rivoplus402 volumio[3109]: info: May 30 21:11:15 rivoplus402 volumio[3109]: ---------------------------- MPD announces system playlist update May 30 21:11:15 rivoplus402 volumio[3109]: info: Ignoring MPD Status Update May 30 21:11:15 rivoplus402 volumio[3109]: info: ------------------------------ 14ms May 30 21:11:15 rivoplus402 volumio[3109]: info: sendMpdCommand consume 1 took 5 milliseconds May 30 21:11:15 rivoplus402 volumio[3109]: info: ------------------------------ 3ms May 30 21:11:15 rivoplus402 volumio[3109]: info: ------------------------------ 2ms May 30 21:11:15 rivoplus402 volumio[3109]: info: Executing endpoint getStreamUrlqobuz May 30 21:11:15 rivoplus402 volumio[3109]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 21:11:15 rivoplus402 volumio[3109]: info: getStreamUrl took 372 milliseconds May 30 21:11:15 rivoplus402 volumio[3109]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1335780&eid=312272726&fmt=7&profile=raw&app_id=539451548&cid=3814052&etsp=1780171875&hmac=Vj8XJERSgWrA54Kh1H4IsdkbwI0 May 30 21:11:15 rivoplus402 volumio[3109]: STREAMING PROXY: Requesting upstream with range: none May 30 21:11:15 rivoplus402 volumio[3109]: STREAMING PROXY: Upstream status code: 200 May 30 21:11:16 rivoplus402 volumio[3109]: STREAMING PROXY: Resuming with range: bytes=478- May 30 21:11:16 rivoplus402 volumio[3109]: STREAMING PROXY: Resume upstream status code: 206 May 30 21:11:17 rivoplus402 volumio[3109]: STREAMING PROXY: Upstream closed. Received 50395737 bytes May 30 21:11:17 rivoplus402 volumio[3109]: STREAMING PROXY: Client closed connection May 30 21:11:18 rivoplus402 volumio[3109]: STREAMING PROXY: Resume ended. Received 50395259 bytes May 30 21:11:18 rivoplus402 volumio[3109]: info: CoreStateMachine::startPlaybackTimer May 30 21:11:18 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:18 rivoplus402 volumio[3109]: info: CoreCommandRouter::volumioGetVisibleSources May 30 21:11:18 rivoplus402 volumio[3109]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 21:11:18 rivoplus402 volumio[3109]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 30 21:11:19 rivoplus402 volumio[3109]: info: CoreStateMachine::pushState May 30 21:11:19 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:19 rivoplus402 volumio[3109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 21:11:19 rivoplus402 volumio[3109]: info: CoreCommandRouter::volumioPushState May 30 21:11:19 rivoplus402 volumio[3109]: info: CoreCommandRouter::volumioGetState May 30 21:11:19 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:20 rivoplus402 volumio[3109]: info: May 30 21:11:20 rivoplus402 volumio[3109]: ---------------------------- MPD announces system playlist update May 30 21:11:20 rivoplus402 volumio[3109]: info: Ignoring MPD Status Update May 30 21:11:20 rivoplus402 volumio[3109]: info: May 30 21:11:20 rivoplus402 volumio[3109]: ---------------------------- MPD announces state update: player May 30 21:11:20 rivoplus402 volumio[3109]: info: ControllerMpd::getState May 30 21:11:20 rivoplus402 volumio[3109]: verbose: ControllerMpd::sendMpdCommand status May 30 21:11:20 rivoplus402 volumio[3109]: info: May 30 21:11:20 rivoplus402 volumio[3109]: ---------------------------- MPD announces system playlist update May 30 21:11:20 rivoplus402 volumio[3109]: info: Ignoring MPD Status Update May 30 21:11:20 rivoplus402 volumio[3109]: info: May 30 21:11:20 rivoplus402 volumio[3109]: ---------------------------- MPD announces state update: player May 30 21:11:20 rivoplus402 volumio[3109]: info: ControllerMpd::getState May 30 21:11:20 rivoplus402 volumio[3109]: verbose: ControllerMpd::sendMpdCommand status May 30 21:11:20 rivoplus402 volumio[3109]: info: May 30 21:11:20 rivoplus402 volumio[3109]: ---------------------------- MPD announces system playlist update May 30 21:11:20 rivoplus402 volumio[3109]: info: Ignoring MPD Status Update May 30 21:11:20 rivoplus402 volumio[3109]: info: May 30 21:11:20 rivoplus402 volumio[3109]: ---------------------------- MPD announces state update: player May 30 21:11:20 rivoplus402 volumio[3109]: info: ControllerMpd::getState May 30 21:11:20 rivoplus402 volumio[3109]: verbose: ControllerMpd::sendMpdCommand status May 30 21:11:20 rivoplus402 volumio[3109]: info: ------------------------------ 8ms May 30 21:11:20 rivoplus402 volumio[3109]: info: sendMpdCommand status took 6 milliseconds May 30 21:11:20 rivoplus402 volumio[3109]: info: ------------------------------ 7ms May 30 21:11:20 rivoplus402 volumio[3109]: info: sendMpdCommand status took 6 milliseconds May 30 21:11:20 rivoplus402 volumio[3109]: info: ------------------------------ 5ms May 30 21:11:20 rivoplus402 volumio[3109]: info: sendMpdCommand status took 4 milliseconds May 30 21:11:20 rivoplus402 volumio[3109]: verbose: ControllerMpd::parseState May 30 21:11:20 rivoplus402 volumio[3109]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 21:11:20 rivoplus402 volumio[3109]: verbose: ControllerMpd::parseState May 30 21:11:20 rivoplus402 volumio[3109]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 21:11:20 rivoplus402 volumio[3109]: verbose: ControllerMpd::parseState May 30 21:11:20 rivoplus402 volumio[3109]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 21:11:20 rivoplus402 volumio[3109]: info: sendMpdCommand playlistinfo took 5 milliseconds May 30 21:11:20 rivoplus402 volumio[3109]: info: sendMpdCommand playlistinfo took 6 milliseconds May 30 21:11:20 rivoplus402 volumio[3109]: info: sendMpdCommand playlistinfo took 5 milliseconds May 30 21:11:20 rivoplus402 volumio[3109]: verbose: ControllerMpd::parseTrackInfo May 30 21:11:20 rivoplus402 volumio[3109]: verbose: ControllerMpd::parseTrackInfo May 30 21:11:20 rivoplus402 volumio[3109]: info: ControllerMpd::pushState May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreCommandRouter::servicePushState May 30 21:11:20 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:20 rivoplus402 volumio[3109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":232,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1409 Kbps","isStreaming":false,"title":"312272726","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/312272726","trackType":"qobuz"} May 30 21:11:20 rivoplus402 volumio[3109]: verbose: CURRENT POSITION 11 May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreStateMachine::syncState stateService play May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreStateMachine::syncState currentStatus play May 30 21:11:20 rivoplus402 volumio[3109]: info: Received an update from plugin. extracting info from payload May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreStateMachine::pushState May 30 21:11:20 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreCommandRouter::volumioPushState May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreCommandRouter::volumioGetState May 30 21:11:20 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreStateMachine::pushState May 30 21:11:20 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreCommandRouter::volumioPushState May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreCommandRouter::volumioGetState May 30 21:11:20 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:20 rivoplus402 volumio[3109]: info: ControllerMpd::pushState May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreCommandRouter::servicePushState May 30 21:11:20 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:20 rivoplus402 volumio[3109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":232,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1409 Kbps","isStreaming":false,"title":"312272726","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/312272726","trackType":"qobuz"} May 30 21:11:20 rivoplus402 volumio[3109]: verbose: CURRENT POSITION 11 May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreStateMachine::syncState stateService play May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreStateMachine::syncState currentStatus play May 30 21:11:20 rivoplus402 volumio[3109]: info: Received an update from plugin. extracting info from payload May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreStateMachine::pushState May 30 21:11:20 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreCommandRouter::volumioPushState May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreCommandRouter::volumioGetState May 30 21:11:20 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreStateMachine::pushState May 30 21:11:20 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreCommandRouter::volumioPushState May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreCommandRouter::volumioGetState May 30 21:11:20 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:20 rivoplus402 volumio[3109]: info: ControllerMpd::pushState May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreCommandRouter::servicePushState May 30 21:11:20 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:20 rivoplus402 volumio[3109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":232,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1409 Kbps","isStreaming":false,"title":"312272726","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/312272726","trackType":"qobuz"} May 30 21:11:20 rivoplus402 volumio[3109]: verbose: CURRENT POSITION 11 May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreStateMachine::syncState stateService play May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreStateMachine::syncState currentStatus play May 30 21:11:20 rivoplus402 volumio[3109]: info: Received an update from plugin. extracting info from payload May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreStateMachine::pushState May 30 21:11:20 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreCommandRouter::volumioPushState May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreCommandRouter::volumioGetState May 30 21:11:20 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreStateMachine::pushState May 30 21:11:20 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreCommandRouter::volumioPushState May 30 21:11:20 rivoplus402 volumio[3109]: info: CoreCommandRouter::volumioGetState May 30 21:11:20 rivoplus402 volumio[3109]: info: CorePlayQueue::getTrack 11 May 30 21:11:20 rivoplus402 volumio[3109]: info: ------------------------------ 49ms May 30 21:11:20 rivoplus402 volumio[3109]: info: ------------------------------ 48ms May 30 21:11:20 rivoplus402 volumio[3109]: info: ------------------------------ 47ms May 30 21:12:18 rivoplus402 volumio[3109]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 21:12:18 rivoplus402 volumio[3109]: TypeError: Cannot read property 'slice' of null May 30 21:12:18 rivoplus402 volumio[3109]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) May 30 21:12:18 rivoplus402 volumio[3109]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) May 30 21:12:18 rivoplus402 volumio[3109]: at Object.onceWrapper (events.js:520:26) May 30 21:12:18 rivoplus402 volumio[3109]: at ClientRequest.emit (events.js:400:28) May 30 21:12:18 rivoplus402 volumio[3109]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) May 30 21:12:18 rivoplus402 volumio[3109]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) May 30 21:12:18 rivoplus402 volumio[3109]: at TLSSocket.socketOnData (_http_client.js:515:22) May 30 21:12:18 rivoplus402 volumio[3109]: at TLSSocket.emit (events.js:400:28) May 30 21:12:18 rivoplus402 volumio[3109]: at addChunk (internal/streams/readable.js:293:12) May 30 21:12:18 rivoplus402 volumio[3109]: at readableAddChunk (internal/streams/readable.js:267:9) May 30 21:12:18 rivoplus402 volumio[3109]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) May 30 21:12:18 rivoplus402 volumio[3109]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) May 30 21:12:18 rivoplus402 volumio[3109]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 21:12:18 rivoplus402 sudo[13828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-30 21:11 May 30 21:12:18 rivoplus402 sudo[13828]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 11:19:27 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="a7285124ffc88c43265bb2db03526f6a"