-- Logs begin at Sun 2025-11-02 14:43:27 CET, end at Sun 2025-11-02 14:45:10 CET. -- Nov 02 14:44:00 volumio volumio[1164]: info: Setting Geolocation for MyVolumio to eu10 Nov 02 14:44:00 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 14:44:00 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 14:44:00 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 14:44:00 volumio volumio[1164]: info: Successfully Added MyVolumio device Nov 02 14:44:01 volumio volumio[1164]: info: Updating MyVolumio device info Nov 02 14:44:01 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 14:44:01 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 14:44:01 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 14:44:02 volumio volumio[1164]: info: Successfully Updated MyVolumio device Nov 02 14:44:03 volumio sudo[1509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.121:/srv/dev-disk-by-uuid-2A6493266492F3B3/MUSICA /mnt/NAS/MusicaFTP Nov 02 14:44:03 volumio sudo[1509]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 14:44:03 volumio sudo[1509]: pam_unix(sudo:session): session closed for user root Nov 02 14:44:03 volumio volumio[1164]: info: Cannot mount NAS MusicaFTP at system boot, trial number 4 ,retrying in 5 seconds Nov 02 14:44:03 volumio volumio[1443]: ..........................................................................................................................++++ Nov 02 14:44:03 volumio volumio[1443]: e is 65537 (0x010001) Nov 02 14:44:03 volumio volumio[1443]: writing RSA key Nov 02 14:44:08 volumio sudo[1554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.121:/srv/dev-disk-by-uuid-2A6493266492F3B3/MUSICA /mnt/NAS/MusicaFTP Nov 02 14:44:08 volumio sudo[1554]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 14:44:08 volumio sudo[1554]: pam_unix(sudo:session): session closed for user root Nov 02 14:44:08 volumio volumio[1164]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 02 14:44:12 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 02 14:44:12 volumio volumio[1164]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 02 14:44:12 volumio volumio[1164]: info: CoreCommandRouter::volumioGetState Nov 02 14:44:12 volumio volumio[1164]: info: CorePlayQueue::getTrack 0 Nov 02 14:44:17 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 14:44:17 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 14:44:17 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 02 14:44:17 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 02 14:44:17 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 02 14:44:17 volumio volumio[1164]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 14:44:17 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 14:44:17 volumio volumio[1164]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 02 14:44:17 volumio volumio[1164]: info: Completed starting MyVolumio Plugin Nov 02 14:44:17 volumio volumio[1164]: [Metrics] CommandRouter: 31s 490.42ms Nov 02 14:44:17 volumio volumio[1164]: info: CoreCommandRouter::volumiosetStartupVolume Nov 02 14:44:17 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 14:44:17 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 14:44:17 volumio volumio[1164]: info: CoreCommandRouter::Close All Modals sent Nov 02 14:44:17 volumio volumio[1164]: info: CoreCommandRouter::Close All Modals sent Nov 02 14:44:17 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 14:44:17 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 14:44:17 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 14:44:18 volumio volumio-remote-updater[779]: No test mode Nov 02 14:44:18 volumio volumio-remote-updater[779]: No alpha test mode Nov 02 14:44:18 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 02 14:44:18 volumio volumio[1164]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 02 14:44:18 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 02 14:44:18 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 02 14:44:18 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 14:44:18 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 02 14:44:19 volumio systemd[1]: systemd-timedated.service: Succeeded. Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 14:44:23 volumio volumio[1164]: info: Retrieving Cloud Streaming UI Nov 02 14:44:23 volumio volumio[1164]: info: Getting Tidal Cloud Configuration Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 14:44:23 volumio volumio[1164]: info: Getting Qobuz Cloud Configuration Nov 02 14:44:23 volumio volumio[1164]: info: Asking plugin for UI Config Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 14:44:23 volumio volumio[1164]: info: Getting Spotify Cloud Configuration Nov 02 14:44:23 volumio volumio[1164]: info: Asking plugin for UI Config Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 14:44:23 volumio volumio[1164]: info: Saving Spotify Acccount Nov 02 14:44:23 volumio volumio[1164]: info: Got Tidal Cloud Configuration Nov 02 14:44:23 volumio volumio[1164]: info: Got it Nov 02 14:44:23 volumio volumio[1164]: info: Got it Nov 02 14:44:23 volumio volumio[1164]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 14:44:23 volumio volumio[1164]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 14:44:23 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 02 14:44:24 volumio volumio[1164]: info: BOOT COMPLETED Nov 02 14:44:27 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 14:44:31 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 14:44:33 volumio ntpd[959]: Soliciting pool server 37.247.53.178 Nov 02 14:44:34 volumio ntpd[959]: Soliciting pool server 89.46.74.148 Nov 02 14:44:35 volumio ntpd[959]: Soliciting pool server 95.110.254.234 Nov 02 14:44:35 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 14:44:36 volumio ntpd[959]: Soliciting pool server 93.94.88.50 Nov 02 14:44:37 volumio ntpd[959]: Soliciting pool server 151.22.209.90 Nov 02 14:44:37 volumio ntpd[959]: Soliciting pool server 217.61.62.224 Nov 02 14:44:38 volumio ntpd[959]: Soliciting pool server 185.19.184.35 Nov 02 14:44:38 volumio ntpd[959]: Soliciting pool server 185.157.229.254 Nov 02 14:44:39 volumio ntpd[959]: Soliciting pool server 162.159.200.123 Nov 02 14:44:39 volumio ntpd[959]: Soliciting pool server 85.199.214.99 Nov 02 14:44:39 volumio ntpd[959]: Soliciting pool server 129.152.16.145 Nov 02 14:44:39 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 14:44:40 volumio ntpd[959]: Soliciting pool server 172.232.208.229 Nov 02 14:44:41 volumio ntpd[959]: Soliciting pool server 2a00:6d41:10:1194::3 Nov 02 14:44:43 volumio ntpd[959]: receive: Unexpected origin timestamp 0xecb1deca.75591eb8 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xecb1decb.3b0a57aa Nov 02 14:44:43 volumio ntpd[959]: receive: Unexpected origin timestamp 0xecb1deca.755e4cb2 does not match aorg 0000000000.00000000 from server@151.22.209.90 xmt 0xecb1decb.3c3ef49d Nov 02 14:44:43 volumio ntpd[959]: receive: Unexpected origin timestamp 0xecb1deca.755d1c32 does not match aorg 0000000000.00000000 from server@217.61.62.224 xmt 0xecb1decb.3b084848 Nov 02 14:44:43 volumio ntpd[959]: receive: Unexpected origin timestamp 0xecb1deca.755bce42 does not match aorg 0000000000.00000000 from server@185.19.184.35 xmt 0xecb1decb.3ad2db1f Nov 02 14:44:44 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 14:44:48 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 14:44:52 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 14:44:56 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 14:45:00 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 14:45:04 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 14:45:08 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 14:45:10 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Nov 02 14:45:10 volumio volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Nov 02 14:45:10 volumio volumio[1164]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 14:45:10 volumio volumio[1164]: Error: getaddrinfo -3007 Nov 02 14:45:10 volumio volumio[1164]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Nov 02 14:45:10 volumio volumio[1164]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Nov 02 14:45:10 volumio volumio[1164]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Nov 02 14:45:10 volumio volumio[1164]: code: -3007, Nov 02 14:45:10 volumio volumio[1164]: errno: -3007, Nov 02 14:45:10 volumio volumio[1164]: syscall: 'getaddrinfo' Nov 02 14:45:10 volumio volumio[1164]: } Nov 02 14:45:10 volumio volumio[1164]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 14:45:10 volumio sudo[1733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-02 14:44 Nov 02 14:45:10 volumio sudo[1733]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"