Jan 11 14:31:25 volumio systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Jan 11 14:31:25 volumio systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Jan 11 14:31:25 volumio systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Jan 11 14:31:25 volumio systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. Jan 11 14:32:11 volumio sudo[3867]: pam_unix(sudo:session): session closed for user root Jan 11 14:32:11 volumio volumio[1006]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jan 11 14:32:13 volumio volumio[1006]: info: CoreCommandRouter::volumioPlay Jan 11 14:32:13 volumio volumio[1006]: info: CoreStateMachine::play index undefined Jan 11 14:32:13 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 14:32:13 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Jan 11 14:32:13 volumio volumio[1006]: info: CoreStateMachine::startPlaybackTimer Jan 11 14:32:13 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Jan 11 14:32:13 volumio volumio[1006]: info: ControllerMpd::resume Jan 11 14:32:13 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand play Jan 11 14:32:13 volumio volumio[1006]: info: Jan 11 14:32:13 volumio volumio[1006]: ---------------------------- MPD announces state update: player Jan 11 14:32:13 volumio volumio[1006]: info: sendMpdCommand play took 7 milliseconds Jan 11 14:32:13 volumio volumio[1006]: info: ControllerMpd::getState Jan 11 14:32:13 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Jan 11 14:32:13 volumio volumio[1006]: info: Jan 11 14:32:13 volumio volumio[1006]: ---------------------------- MPD announces state update: player Jan 11 14:32:13 volumio volumio[1006]: info: ControllerMpd::getState Jan 11 14:32:13 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Jan 11 14:32:13 volumio volumio[1006]: info: Jan 11 14:32:13 volumio volumio[1006]: ---------------------------- MPD announces state update: player Jan 11 14:32:13 volumio volumio[1006]: info: ControllerMpd::getState Jan 11 14:32:13 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Jan 11 14:32:13 volumio volumio[1006]: info: sendMpdCommand status took 2 milliseconds Jan 11 14:32:13 volumio volumio[1006]: info: sendMpdCommand status took 1 milliseconds Jan 11 14:32:13 volumio volumio[1006]: info: sendMpdCommand status took 1 milliseconds Jan 11 14:32:13 volumio volumio[1006]: verbose: ControllerMpd::parseState Jan 11 14:32:13 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 14:32:13 volumio volumio[1006]: verbose: ControllerMpd::parseState Jan 11 14:32:13 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 14:32:13 volumio volumio[1006]: verbose: ControllerMpd::parseState Jan 11 14:32:13 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 14:32:13 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 11 14:32:13 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 14:32:13 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 14:32:13 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Jan 11 14:32:13 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Jan 11 14:32:13 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Jan 11 14:32:13 volumio volumio[1006]: info: ControllerMpd::pushState Jan 11 14:32:13 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Jan 11 14:32:13 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Jan 11 14:32:13 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69491,"duration":299,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"989 Kbps","isStreaming":false,"title":"Forgiven","artist":"Alanis Morissette","album":"Jagged Little Pill","uri":"USB/PYXIDIS/$RECYCLE.BIN/S-1-5-21-4055760510-927123360-3969959783-1001/$RP24M7B.flac","trackType":"flac"} Jan 11 14:32:13 volumio volumio[1006]: verbose: CURRENT POSITION 0 Jan 11 14:32:13 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Jan 11 14:32:13 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus pause Jan 11 14:32:13 volumio volumio[1006]: info: CoreStateMachine::pushState Jan 11 14:32:13 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Jan 11 14:32:13 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:32:13 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Jan 11 14:32:13 volumio volumio[1006]: info: ControllerMpd::pushState Jan 11 14:32:13 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Jan 11 14:32:13 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Jan 11 14:32:13 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69491,"duration":299,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"989 Kbps","isStreaming":false,"title":"Forgiven","artist":"Alanis Morissette","album":"Jagged Little Pill","uri":"USB/PYXIDIS/$RECYCLE.BIN/S-1-5-21-4055760510-927123360-3969959783-1001/$RP24M7B.flac","trackType":"flac"} Jan 11 14:32:13 volumio volumio[1006]: verbose: CURRENT POSITION 0 Jan 11 14:32:13 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Jan 11 14:32:13 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Jan 11 14:32:13 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Jan 11 14:32:13 volumio volumio[1006]: info: CoreStateMachine::pushState Jan 11 14:32:13 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Jan 11 14:32:13 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:32:13 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Jan 11 14:32:13 volumio volumio[1006]: info: CoreStateMachine::pushState Jan 11 14:32:13 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Jan 11 14:32:13 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Jan 11 14:32:13 volumio volumio[1006]: info: ControllerMpd::pushState Jan 11 14:32:13 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Jan 11 14:32:13 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Jan 11 14:32:13 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69491,"duration":299,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"989 Kbps","isStreaming":false,"title":"Forgiven","artist":"Alanis Morissette","album":"Jagged Little Pill","uri":"USB/PYXIDIS/$RECYCLE.BIN/S-1-5-21-4055760510-927123360-3969959783-1001/$RP24M7B.flac","trackType":"flac"} Jan 11 14:32:13 volumio volumio[1006]: verbose: CURRENT POSITION 0 Jan 11 14:32:13 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Jan 11 14:32:13 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Jan 11 14:32:13 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Jan 11 14:32:13 volumio volumio[1006]: info: CoreStateMachine::pushState Jan 11 14:32:13 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Jan 11 14:32:13 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Jan 11 14:32:13 volumio volumio[1006]: info: CoreStateMachine::pushState Jan 11 14:32:13 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Jan 11 14:32:13 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Jan 11 14:32:13 volumio volumio[1006]: info: ------------------------------ 18ms Jan 11 14:32:13 volumio volumio[1006]: info: ------------------------------ 16ms Jan 11 14:32:13 volumio volumio[1006]: info: ------------------------------ 16ms Jan 11 14:32:19 volumio volumio[1006]: info: CoreCommandRouter::volumioPause Jan 11 14:32:19 volumio volumio[1006]: info: CoreStateMachine::pause Jan 11 14:32:19 volumio volumio[1006]: info: CoreStateMachine::stPlaybackTimer Jan 11 14:32:19 volumio volumio[1006]: info: CoreStateMachine::servicePause Jan 11 14:32:19 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Jan 11 14:32:19 volumio volumio[1006]: info: CoreCommandRouter::servicePause Jan 11 14:32:19 volumio volumio[1006]: info: ControllerMpd::pause Jan 11 14:32:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand pause Jan 11 14:32:19 volumio volumio[1006]: info: Jan 11 14:32:19 volumio volumio[1006]: ---------------------------- MPD announces state update: player Jan 11 14:32:19 volumio volumio[1006]: info: sendMpdCommand pause took 4 milliseconds Jan 11 14:32:19 volumio volumio[1006]: info: ControllerMpd::getState Jan 11 14:32:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Jan 11 14:32:19 volumio volumio[1006]: info: Jan 11 14:32:19 volumio volumio[1006]: ---------------------------- MPD announces state update: player Jan 11 14:32:19 volumio volumio[1006]: info: ControllerMpd::getState Jan 11 14:32:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Jan 11 14:32:19 volumio volumio[1006]: info: Jan 11 14:32:19 volumio volumio[1006]: ---------------------------- MPD announces state update: player Jan 11 14:32:19 volumio volumio[1006]: info: ControllerMpd::getState Jan 11 14:32:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Jan 11 14:32:19 volumio volumio[1006]: info: sendMpdCommand status took 1 milliseconds Jan 11 14:32:19 volumio volumio[1006]: info: sendMpdCommand status took 1 milliseconds Jan 11 14:32:19 volumio volumio[1006]: info: sendMpdCommand status took 1 milliseconds Jan 11 14:32:19 volumio volumio[1006]: verbose: ControllerMpd::parseState Jan 11 14:32:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 14:32:19 volumio volumio[1006]: verbose: ControllerMpd::parseState Jan 11 14:32:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 14:32:19 volumio volumio[1006]: verbose: ControllerMpd::parseState Jan 11 14:32:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 14:32:19 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 11 14:32:19 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 11 14:32:19 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 11 14:32:19 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Jan 11 14:32:19 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Jan 11 14:32:19 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Jan 11 14:32:19 volumio volumio[1006]: info: ControllerMpd::pushState Jan 11 14:32:19 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Jan 11 14:32:19 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Jan 11 14:32:19 volumio volumio[1006]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":78712,"duration":299,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"993 Kbps","isStreaming":false,"title":"Forgiven","artist":"Alanis Morissette","album":"Jagged Little Pill","uri":"USB/PYXIDIS/$RECYCLE.BIN/S-1-5-21-4055760510-927123360-3969959783-1001/$RP24M7B.flac","trackType":"flac"} Jan 11 14:32:19 volumio volumio[1006]: verbose: CURRENT POSITION 0 Jan 11 14:32:19 volumio volumio[1006]: info: CoreStateMachine::syncState stateService pause Jan 11 14:32:19 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus pause Jan 11 14:32:19 volumio volumio[1006]: info: CoreStateMachine::pushState Jan 11 14:32:19 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Jan 11 14:32:19 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:32:19 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Jan 11 14:32:19 volumio volumio[1006]: info: CoreStateMachine::stPlaybackTimer Jan 11 14:32:19 volumio volumio[1006]: info: ControllerMpd::pushState Jan 11 14:32:19 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Jan 11 14:32:19 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Jan 11 14:32:19 volumio volumio[1006]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":78712,"duration":299,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"993 Kbps","isStreaming":false,"title":"Forgiven","artist":"Alanis Morissette","album":"Jagged Little Pill","uri":"USB/PYXIDIS/$RECYCLE.BIN/S-1-5-21-4055760510-927123360-3969959783-1001/$RP24M7B.flac","trackType":"flac"} Jan 11 14:32:19 volumio volumio[1006]: verbose: CURRENT POSITION 0 Jan 11 14:32:19 volumio volumio[1006]: info: CoreStateMachine::syncState stateService pause Jan 11 14:32:19 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus pause Jan 11 14:32:19 volumio volumio[1006]: info: CoreStateMachine::pushState Jan 11 14:32:19 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Jan 11 14:32:19 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Jan 11 14:32:19 volumio volumio[1006]: info: CoreStateMachine::stPlaybackTimer Jan 11 14:32:19 volumio volumio[1006]: info: ControllerMpd::pushState Jan 11 14:32:19 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Jan 11 14:32:19 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Jan 11 14:32:19 volumio volumio[1006]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":78712,"duration":299,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"993 Kbps","isStreaming":false,"title":"Forgiven","artist":"Alanis Morissette","album":"Jagged Little Pill","uri":"USB/PYXIDIS/$RECYCLE.BIN/S-1-5-21-4055760510-927123360-3969959783-1001/$RP24M7B.flac","trackType":"flac"} Jan 11 14:32:19 volumio volumio[1006]: verbose: CURRENT POSITION 0 Jan 11 14:32:19 volumio volumio[1006]: info: CoreStateMachine::syncState stateService pause Jan 11 14:32:19 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus pause Jan 11 14:32:19 volumio volumio[1006]: info: CoreStateMachine::pushState Jan 11 14:32:19 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Jan 11 14:32:19 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Jan 11 14:32:19 volumio volumio[1006]: info: CoreStateMachine::stPlaybackTimer Jan 11 14:32:19 volumio volumio[1006]: info: ------------------------------ 11ms Jan 11 14:32:19 volumio volumio[1006]: info: ------------------------------ 12ms Jan 11 14:32:19 volumio volumio[1006]: info: ------------------------------ 12ms Jan 11 14:32:23 volumio volumio[1006]: info: CoreCommandRouter::volumioGetQueue Jan 11 14:32:23 volumio volumio[1006]: info: CoreStateMachine::getQueue Jan 11 14:32:23 volumio volumio[1006]: info: CorePlayQueue::getQueue Jan 11 14:32:31 volumio volumio[1006]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 14:32:31 volumio volumio[1006]: TypeError: Cannot read properties of null (reading 'slice') Jan 11 14:32:31 volumio volumio[1006]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Jan 11 14:32:31 volumio volumio[1006]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Jan 11 14:32:31 volumio volumio[1006]: at Object.onceWrapper (node:events:629:26) Jan 11 14:32:31 volumio volumio[1006]: at ClientRequest.emit (node:events:514:28) Jan 11 14:32:31 volumio volumio[1006]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Jan 11 14:32:31 volumio volumio[1006]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Jan 11 14:32:31 volumio volumio[1006]: at TLSSocket.socketOnData (node:_http_client:535:22) Jan 11 14:32:31 volumio volumio[1006]: at TLSSocket.emit (node:events:514:28) Jan 11 14:32:31 volumio volumio[1006]: at addChunk (node:internal/streams/readable:343:12) Jan 11 14:32:31 volumio volumio[1006]: at readableAddChunk (node:internal/streams/readable:316:9) Jan 11 14:32:31 volumio volumio[1006]: at Readable.push (node:internal/streams/readable:253:10) Jan 11 14:32:31 volumio volumio[1006]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Jan 11 14:32:31 volumio volumio[1006]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 14:32:31 volumio sudo[4419]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-11 14:31' Jan 11 14:32:31 volumio sudo[4419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"