Apr 02 15:28:50 volumio ntpd[1271]: CLOCK: time stepped by 11910.618072 Apr 02 15:28:50 volumio ntpd[1271]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Apr 02 15:28:50 volumio volumio[1367]: info: Discovery: A device disappeared from network Apr 02 15:28:50 volumio volumio[1367]: info: Discovery: A device disappeared from network Apr 02 15:28:50 volumio volumio[1367]: info: Updating MyVolumio device info Apr 02 15:28:50 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:28:50 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:28:50 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:28:50 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 02 15:28:50 volumio volumio[1367]: info: Received Get System Info Apr 02 15:28:50 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 15:28:50 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 15:28:50 volumio volumio[1367]: info: Discovery: Getting this device information Apr 02 15:28:50 volumio volumio[1367]: info: CoreCommandRouter::volumioGetState Apr 02 15:28:50 volumio volumio[1367]: info: CorePlayQueue::getTrack 0 Apr 02 15:28:50 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 15:28:50 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 02 15:28:50 volumio volumio[1367]: info: Received Get System Info Apr 02 15:28:50 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 15:28:50 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 15:28:50 volumio volumio[1367]: info: Discovery: Getting this device information Apr 02 15:28:50 volumio volumio[1367]: info: CoreCommandRouter::volumioGetState Apr 02 15:28:50 volumio volumio[1367]: info: CorePlayQueue::getTrack 0 Apr 02 15:28:50 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 15:28:50 volumio volumio[1367]: info: Discovery: adding 2789afbc-a051-4fab-81e3-f1706161c3f4 Apr 02 15:28:50 volumio volumio[1367]: info: Discovery: Found device Volumio Apr 02 15:28:50 volumio volumio[1367]: info: CoreCommandRouter::volumioGetState Apr 02 15:28:50 volumio volumio[1367]: info: CorePlayQueue::getTrack 0 Apr 02 15:28:50 volumio volumio[1367]: info: Discovery: this is already registered, 2789afbc-a051-4fab-81e3-f1706161c3f4 Apr 02 15:28:50 volumio volumio[1367]: info: Discovery: Found device Volumio Apr 02 15:28:50 volumio volumio[1367]: info: CoreCommandRouter::volumioGetState Apr 02 15:28:50 volumio volumio[1367]: info: CorePlayQueue::getTrack 0 Apr 02 15:28:51 volumio volumio[1367]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 02 15:28:53 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 02 15:28:53 volumio upmpdcli[2307]: writing RSA key Apr 02 15:28:53 volumio volumio[1367]: info: MYVOLUMIO: Adding device Apr 02 15:28:53 volumio volumio[1367]: info: MYVOLUMIO: Evaluating Server Apr 02 15:28:54 volumio volumio[1367]: info: Setting Geolocation for MyVolumio to eu5 Apr 02 15:28:54 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:28:54 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:28:54 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:28:54 volumio volumio[1367]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 02 15:28:55 volumio volumio[1367]: info: Updating MyVolumio device info Apr 02 15:28:55 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:28:55 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:28:55 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:28:55 volumio volumio[1367]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 02 15:28:59 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Apr 02 15:29:01 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Apr 02 15:29:01 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 02 15:29:01 volumio volumio[1367]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 02 15:29:01 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 02 15:29:01 volumio volumio[1367]: info: Received Get System Version Apr 02 15:29:01 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 02 15:29:01 volumio volumio[1367]: info: Received Get System Info Apr 02 15:29:01 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 15:29:01 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 15:29:01 volumio volumio[1367]: info: Discovery: Getting this device information Apr 02 15:29:01 volumio volumio[1367]: info: CoreCommandRouter::volumioGetState Apr 02 15:29:01 volumio volumio[1367]: info: CorePlayQueue::getTrack 0 Apr 02 15:29:01 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 15:29:01 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 02 15:29:01 volumio volumio[1367]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 02 15:29:01 volumio volumio[1367]: info: CoreCommandRouter::volumioGetState Apr 02 15:29:01 volumio volumio[1367]: info: CorePlayQueue::getTrack 0 Apr 02 15:29:06 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 02 15:29:06 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:29:06 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 02 15:29:06 volumio volumio-remote-updater[1111]: Test mode disabled Apr 02 15:29:06 volumio volumio-remote-updater[1111]: Alpha mode disabled Apr 02 15:29:06 volumio volumio-remote-updater[1111]: Alpha legacy test mode disabled Apr 02 15:29:06 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 02 15:29:06 volumio volumio[1367]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 02 15:29:06 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 02 15:29:06 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 15:29:06 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 15:29:06 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 02 15:29:06 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 02 15:29:06 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 02 15:29:06 volumio volumio[1367]: info: CoreCommandRouter::volumioGetBrowseSources Apr 02 15:29:06 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 02 15:29:07 volumio volumio[1367]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 02 15:29:07 volumio volumio[1367]: info: Completed starting MyVolumio Plugin Apr 02 15:29:07 volumio volumio[1367]: [Metrics] CommandRouter: 33s 778.64ms Apr 02 15:29:07 volumio volumio[1367]: info: CoreCommandRouter::volumiosetStartupVolume Apr 02 15:29:07 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 15:29:07 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 15:29:07 volumio volumio[1367]: info: CoreCommandRouter::Close All Modals sent Apr 02 15:29:07 volumio volumio[1367]: info: CoreCommandRouter::Close All Modals sent Apr 02 15:29:08 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 02 15:29:08 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 02 15:29:08 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 02 15:29:14 volumio volumio[1367]: info: BOOT COMPLETED Apr 02 15:29:18 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Apr 02 15:29:20 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 02 15:29:20 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 15:29:29 volumio volumio[1367]: info: Retrieving Cloud Streaming UI Apr 02 15:29:29 volumio volumio[1367]: info: Getting Tidal Cloud Configuration Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 15:29:29 volumio volumio[1367]: info: Getting Qobuz Cloud Configuration Apr 02 15:29:29 volumio volumio[1367]: info: Asking plugin for UI Config Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 15:29:29 volumio volumio[1367]: info: Getting Spotify Cloud Configuration Apr 02 15:29:29 volumio volumio[1367]: info: Asking plugin for UI Config Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 15:29:29 volumio volumio[1367]: info: Saving Spotify Acccount Apr 02 15:29:29 volumio volumio[1367]: info: Got Tidal Cloud Configuration Apr 02 15:29:29 volumio volumio[1367]: info: Got it Apr 02 15:29:29 volumio volumio[1367]: info: Got it Apr 02 15:29:29 volumio volumio[1367]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 15:29:29 volumio volumio[1367]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::volumioGetBrowseSources Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::volumioGetBrowseSources Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::volumioGetBrowseSources Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 02 15:29:29 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 02 15:29:32 volumio volumio[1367]: info: CoreCommandRouter::volumioGetState Apr 02 15:29:32 volumio volumio[1367]: info: CorePlayQueue::getTrack 0 Apr 02 15:29:36 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 02 15:29:36 volumio volumio[1367]: info: CURURI: artists:// Apr 02 15:29:36 volumio volumio[1367]: info: Preload queue cleared Apr 02 15:29:47 volumio volumio[1367]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 02 15:29:47 volumio volumio[1367]: info: CURURI: artists://Hungarian%20National%20Philharmonic%20Orchestra%20%2F%20Philharmonia%20Slavonica%2C%20Alberto%20Lizzio Apr 02 15:29:47 volumio volumio[1367]: info: Preload queue cleared Apr 02 15:29:47 volumio volumio[1367]: info: Preload queue cleared Apr 02 15:29:47 volumio volumio[1367]: info: Preloading song: music-library/NAS/Music/100 Masterpieces of Classical Music - Time Life 5CD/CD3/19 Track 19.flac Apr 02 15:29:47 volumio volumio[1367]: info: Exploding uri music-library/NAS/Music/100 Masterpieces of Classical Music - Time Life 5CD/CD3/19 Track 19.flac in service mpd Apr 02 15:29:47 volumio volumio[1367]: info: ALBUMART /albumart?cacheid=262&web=Hungarian%20National%20Philharmonic%20Orchestra%20%2F%20Philharmonia%20Slavonica%2C%20Alberto%20Lizzio/100%20Masterpieces%20of%20Classical%20Music/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F100%20Masterpieces%20of%20Classical%20Music%20-%20Time%20Life%205CD%2FCD3%2F19%20Track%2019.flac&metadata=false Apr 02 15:29:47 volumio volumio[1367]: info: URI /mnt/NAS/Music/100 Masterpieces of Classical Music - Time Life 5CD/CD3/19 Track 19.flac Apr 02 15:29:58 volumio volumio[1367]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 15:29:58 volumio volumio[1367]: TypeError: Cannot read properties of null (reading 'slice') Apr 02 15:29:58 volumio volumio[1367]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Apr 02 15:29:58 volumio volumio[1367]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Apr 02 15:29:58 volumio volumio[1367]: at Object.onceWrapper (node:events:629:26) Apr 02 15:29:58 volumio volumio[1367]: at ClientRequest.emit (node:events:514:28) Apr 02 15:29:58 volumio volumio[1367]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Apr 02 15:29:58 volumio volumio[1367]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Apr 02 15:29:58 volumio volumio[1367]: at TLSSocket.socketOnData (node:_http_client:535:22) Apr 02 15:29:58 volumio volumio[1367]: at TLSSocket.emit (node:events:514:28) Apr 02 15:29:58 volumio volumio[1367]: at addChunk (node:internal/streams/readable:343:12) Apr 02 15:29:58 volumio volumio[1367]: at readableAddChunk (node:internal/streams/readable:316:9) Apr 02 15:29:58 volumio volumio[1367]: at Readable.push (node:internal/streams/readable:253:10) Apr 02 15:29:58 volumio volumio[1367]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Apr 02 15:29:58 volumio volumio[1367]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 15:29:58 volumio sudo[2498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-02 15:28' Apr 02 15:29:58 volumio sudo[2498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"