Mar 28 06:46:58 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 28 06:46:59 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 28 06:46:59 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 28 06:47:49 volumio volumio[1278]: info: CorePlayQueue::getTrack 6 Mar 28 06:47:49 volumio volumio[1278]: info: CorePlayQueue::getTrack 7 Mar 28 06:47:49 volumio volumio[1278]: info: Prefetching next song Mar 28 06:47:49 volumio volumio[1278]: info: DOING PREFETCH IN MPD Mar 28 06:47:49 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand add "USB/64/Chill/Foggy_-_Come_Into_My_Dream_Aurora_UK_Chillout_Aurora_UK_Chillout_62805359.mp3" Mar 28 06:47:49 volumio volumio[1278]: info: Mar 28 06:47:49 volumio volumio[1278]: ---------------------------- MPD announces system playlist update Mar 28 06:47:49 volumio volumio[1278]: info: Ignoring MPD Status Update Mar 28 06:47:49 volumio volumio[1278]: info: sendMpdCommand add "USB/64/Chill/Foggy_-_Come_Into_My_Dream_Aurora_UK_Chillout_Aurora_UK_Chillout_62805359.mp3" took 1 milliseconds Mar 28 06:47:49 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 28 06:47:49 volumio volumio[1278]: info: Mar 28 06:47:49 volumio volumio[1278]: ---------------------------- MPD announces system playlist update Mar 28 06:47:49 volumio volumio[1278]: info: Ignoring MPD Status Update Mar 28 06:47:49 volumio volumio[1278]: info: ------------------------------ 2ms Mar 28 06:47:49 volumio volumio[1278]: info: sendMpdCommand consume 1 took 1 milliseconds Mar 28 06:47:49 volumio volumio[1278]: info: ------------------------------ 1ms Mar 28 06:47:54 volumio volumio[1278]: info: CoreStateMachine::startPlaybackTimer Mar 28 06:47:54 volumio volumio[1278]: info: CorePlayQueue::getTrack 7 Mar 28 06:47:54 volumio volumio[1278]: info: CoreStateMachine::pushState Mar 28 06:47:54 volumio volumio[1278]: info: CorePlayQueue::getTrack 7 Mar 28 06:47:54 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 06:47:54 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Mar 28 06:47:54 volumio volumio5-onboarding[1836]: time=2026-03-28T06:47:54.844Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc000187350" state=STATUS_PLAYING positionMs=250 volume=33 Mar 28 06:47:54 volumio volumio5-onboarding[1836]: time=2026-03-28T06:47:54.930Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.13:56477 Mar 28 06:47:54 volumio volumio5-onboarding[1836]: time=2026-03-28T06:47:54.937Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc000187350" id=mnt/USB/64/Chill/Foggy_-_Come_Into_My_Dream_Aurora_UK_Chillout_Aurora_UK_Chillout_62805359.mp3 title="Come Into My Dream (Aurora UK Chillout) (Aurora UK Chillout)" Mar 28 06:47:55 volumio volumio[1278]: info: Mar 28 06:47:55 volumio volumio[1278]: ---------------------------- MPD announces system playlist update Mar 28 06:47:55 volumio volumio[1278]: info: Ignoring MPD Status Update Mar 28 06:47:55 volumio volumio[1278]: info: Mar 28 06:47:55 volumio volumio[1278]: ---------------------------- MPD announces state update: player Mar 28 06:47:55 volumio volumio[1278]: info: ControllerMpd::getState Mar 28 06:47:55 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Mar 28 06:47:55 volumio volumio[1278]: info: Mar 28 06:47:55 volumio volumio[1278]: ---------------------------- MPD announces system playlist update Mar 28 06:47:55 volumio volumio[1278]: info: Ignoring MPD Status Update Mar 28 06:47:55 volumio volumio[1278]: info: Mar 28 06:47:55 volumio volumio[1278]: ---------------------------- MPD announces state update: player Mar 28 06:47:55 volumio volumio[1278]: info: ControllerMpd::getState Mar 28 06:47:55 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Mar 28 06:47:55 volumio volumio[1278]: info: ------------------------------ 1ms Mar 28 06:47:55 volumio volumio[1278]: info: sendMpdCommand status took 1 milliseconds Mar 28 06:47:55 volumio volumio[1278]: verbose: ControllerMpd::parseState Mar 28 06:47:55 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 06:47:55 volumio volumio[1278]: info: ------------------------------ 1ms Mar 28 06:47:55 volumio volumio[1278]: info: sendMpdCommand status took 0 milliseconds Mar 28 06:47:55 volumio volumio[1278]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 28 06:47:55 volumio volumio[1278]: verbose: ControllerMpd::parseState Mar 28 06:47:55 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 06:47:55 volumio volumio[1278]: verbose: ControllerMpd::parseTrackInfo Mar 28 06:47:55 volumio volumio[1278]: info: ControllerMpd::pushState Mar 28 06:47:55 volumio volumio[1278]: info: CoreCommandRouter::servicePushState Mar 28 06:47:55 volumio volumio[1278]: info: CorePlayQueue::getTrack 7 Mar 28 06:47:55 volumio volumio[1278]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":212,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Come Into My Dream (Aurora UK Chillout) (Aurora UK Chillout)","artist":"Foggy","album":null,"uri":"USB/64/Chill/Foggy_-_Come_Into_My_Dream_Aurora_UK_Chillout_Aurora_UK_Chillout_62805359.mp3","trackType":"mp3"} Mar 28 06:47:55 volumio volumio[1278]: verbose: CURRENT POSITION 7 Mar 28 06:47:55 volumio volumio[1278]: info: CoreStateMachine::syncState stateService play Mar 28 06:47:55 volumio volumio[1278]: info: CoreStateMachine::syncState currentStatus play Mar 28 06:47:55 volumio volumio[1278]: info: Received an update from plugin. extracting info from payload Mar 28 06:47:55 volumio volumio[1278]: info: CoreStateMachine::pushState Mar 28 06:47:55 volumio volumio[1278]: info: CorePlayQueue::getTrack 7 Mar 28 06:47:55 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 06:47:55 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Mar 28 06:47:55 volumio volumio[1278]: info: CoreStateMachine::pushState Mar 28 06:47:55 volumio volumio[1278]: info: CorePlayQueue::getTrack 7 Mar 28 06:47:55 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Mar 28 06:47:55 volumio volumio5-onboarding[1836]: time=2026-03-28T06:47:55.485Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc000187350" state=STATUS_PLAYING positionMs=91 volume=33 Mar 28 06:47:55 volumio volumio[1278]: info: ------------------------------ 5ms Mar 28 06:47:55 volumio volumio5-onboarding[1836]: time=2026-03-28T06:47:55.486Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc000187350" state=STATUS_PLAYING positionMs=91 volume=33 Mar 28 06:47:55 volumio volumio[1278]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 28 06:47:55 volumio volumio[1278]: verbose: ControllerMpd::parseTrackInfo Mar 28 06:47:55 volumio volumio[1278]: info: ControllerMpd::pushState Mar 28 06:47:55 volumio volumio[1278]: info: CoreCommandRouter::servicePushState Mar 28 06:47:55 volumio volumio[1278]: info: CorePlayQueue::getTrack 7 Mar 28 06:47:55 volumio volumio[1278]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":212,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Come Into My Dream (Aurora UK Chillout) (Aurora UK Chillout)","artist":"Foggy","album":null,"uri":"USB/64/Chill/Foggy_-_Come_Into_My_Dream_Aurora_UK_Chillout_Aurora_UK_Chillout_62805359.mp3","trackType":"mp3"} Mar 28 06:47:55 volumio volumio[1278]: verbose: CURRENT POSITION 7 Mar 28 06:47:55 volumio volumio[1278]: info: CoreStateMachine::syncState stateService play Mar 28 06:47:55 volumio volumio[1278]: info: CoreStateMachine::syncState currentStatus play Mar 28 06:47:55 volumio volumio[1278]: info: Received an update from plugin. extracting info from payload Mar 28 06:47:55 volumio volumio[1278]: info: CoreStateMachine::pushState Mar 28 06:47:55 volumio volumio[1278]: info: CorePlayQueue::getTrack 7 Mar 28 06:47:55 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Mar 28 06:47:55 volumio volumio[1278]: info: CoreStateMachine::pushState Mar 28 06:47:55 volumio volumio[1278]: info: CorePlayQueue::getTrack 7 Mar 28 06:47:55 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Mar 28 06:47:55 volumio volumio5-onboarding[1836]: time=2026-03-28T06:47:55.491Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc000187350" state=STATUS_PLAYING positionMs=91 volume=33 Mar 28 06:47:55 volumio volumio5-onboarding[1836]: time=2026-03-28T06:47:55.492Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc000187350" state=STATUS_PLAYING positionMs=91 volume=33 Mar 28 06:47:55 volumio volumio[1278]: info: ------------------------------ 13ms Mar 28 06:47:55 volumio volumio5-onboarding[1836]: time=2026-03-28T06:47:55.537Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc000187350" id=mnt/USB/64/Chill/Foggy_-_Come_Into_My_Dream_Aurora_UK_Chillout_Aurora_UK_Chillout_62805359.mp3 title="Come Into My Dream (Aurora UK Chillout) (Aurora UK Chillout)" Mar 28 06:47:55 volumio volumio5-onboarding[1836]: time=2026-03-28T06:47:55.597Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc000187350" id=mnt/USB/64/Chill/Foggy_-_Come_Into_My_Dream_Aurora_UK_Chillout_Aurora_UK_Chillout_62805359.mp3 title="Come Into My Dream (Aurora UK Chillout) (Aurora UK Chillout)" Mar 28 06:47:55 volumio volumio5-onboarding[1836]: time=2026-03-28T06:47:55.689Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc000187350" id=mnt/USB/64/Chill/Foggy_-_Come_Into_My_Dream_Aurora_UK_Chillout_Aurora_UK_Chillout_62805359.mp3 title="Come Into My Dream (Aurora UK Chillout) (Aurora UK Chillout)" Mar 28 06:47:55 volumio volumio5-onboarding[1836]: time=2026-03-28T06:47:55.777Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc000187350" id=mnt/USB/64/Chill/Foggy_-_Come_Into_My_Dream_Aurora_UK_Chillout_Aurora_UK_Chillout_62805359.mp3 title="Come Into My Dream (Aurora UK Chillout) (Aurora UK Chillout)" Mar 28 06:47:56 volumio volumio[1278]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 06:47:56 volumio volumio[1278]: TypeError: Cannot read properties of null (reading 'slice') Mar 28 06:47:56 volumio volumio[1278]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 28 06:47:56 volumio volumio[1278]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 28 06:47:56 volumio volumio[1278]: at Object.onceWrapper (node:events:629:26) Mar 28 06:47:56 volumio volumio[1278]: at ClientRequest.emit (node:events:514:28) Mar 28 06:47:56 volumio volumio[1278]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Mar 28 06:47:56 volumio volumio[1278]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Mar 28 06:47:56 volumio volumio[1278]: at TLSSocket.socketOnData (node:_http_client:535:22) Mar 28 06:47:56 volumio volumio[1278]: at TLSSocket.emit (node:events:514:28) Mar 28 06:47:56 volumio volumio[1278]: at addChunk (node:internal/streams/readable:343:12) Mar 28 06:47:56 volumio volumio[1278]: at readableAddChunk (node:internal/streams/readable:316:9) Mar 28 06:47:56 volumio volumio[1278]: at Readable.push (node:internal/streams/readable:253:10) Mar 28 06:47:56 volumio volumio[1278]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Mar 28 06:47:56 volumio volumio[1278]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 06:47:57 volumio sudo[5752]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 06:46' Mar 28 06:47:57 volumio sudo[5752]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 17:11:42 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="21a5161aef4cb85a1f93b140ccadc4f1"