Jul 18 17:27:04 volumio volumio[1132]: info: CoreCommandRouter::volumioGetState Jul 18 17:27:04 volumio volumio[1132]: info: CorePlayQueue::getTrack 0 Jul 18 17:27:04 volumio volumio[1132]: info: Listing playlists Jul 18 17:27:04 volumio volumio[1132]: info: Listing playlists Jul 18 17:27:14 volumio volumio[1132]: info: CoreCommandRouter::volumioGetState Jul 18 17:27:14 volumio volumio[1132]: info: CorePlayQueue::getTrack 0 Jul 18 17:27:24 volumio volumio[1132]: info: CoreCommandRouter::volumioGetState Jul 18 17:27:24 volumio volumio[1132]: info: CorePlayQueue::getTrack 0 Jul 18 17:27:24 volumio volumio[1132]: info: Listing playlists Jul 18 17:27:24 volumio volumio[1132]: info: Listing playlists Jul 18 17:27:34 volumio volumio[1132]: info: CoreCommandRouter::volumioGetState Jul 18 17:27:34 volumio volumio[1132]: info: CorePlayQueue::getTrack 0 Jul 18 17:27:44 volumio volumio[1132]: info: CoreCommandRouter::volumioGetState Jul 18 17:27:44 volumio volumio[1132]: info: CorePlayQueue::getTrack 0 Jul 18 17:27:44 volumio volumio[1132]: info: Listing playlists Jul 18 17:27:44 volumio volumio[1132]: info: Listing playlists Jul 18 17:27:54 volumio volumio[1132]: info: CoreCommandRouter::volumioGetState Jul 18 17:27:54 volumio volumio[1132]: info: CorePlayQueue::getTrack 0 Jul 18 17:28:04 volumio volumio[1132]: info: CoreCommandRouter::volumioGetState Jul 18 17:28:04 volumio volumio[1132]: info: CorePlayQueue::getTrack 0 Jul 18 17:28:04 volumio volumio[1132]: info: Listing playlists Jul 18 17:28:04 volumio volumio[1132]: info: Listing playlists Jul 18 17:28:04 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 18 17:28:04 volumio dbus-daemon[624]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.55' (uid=0 pid=4012 comm="timedatectl show --property=NTPSynchronized --valu") Jul 18 17:28:04 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 18 17:28:04 volumio dbus-daemon[624]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 18 17:28:04 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 18 17:28:04 volumio setdatetime-helper.sh[4011]: Time is not synchronized. Attempting to sync... Jul 18 17:28:04 volumio sudo[4020]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Fri, 18 Jul 2025 15:28:05 GMT#015' Jul 18 17:28:04 volumio sudo[4020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 18 17:28:05 volumio setdatetime-helper.sh[4021]: Fri Jul 18 17:28:05 CEST 2025 Jul 18 17:28:05 volumio sudo[4020]: pam_unix(sudo:session): session closed for user root Jul 18 17:28:05 volumio setdatetime-helper.sh[4011]: Time synchronized successfully. Jul 18 17:28:05 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 18 17:28:05 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 18 17:28:14 volumio volumio[1132]: info: CoreCommandRouter::volumioGetState Jul 18 17:28:14 volumio volumio[1132]: info: CorePlayQueue::getTrack 0 Jul 18 17:28:19 volumio volumio[1132]: verbose: New Socket.io Connection to 192.168.101.11 from 192.168.101.124 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jul 18 17:28:19 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 18 17:28:20 volumio volumio[1132]: info: CoreCommandRouter::volumioGetVisibleSources Jul 18 17:28:20 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 17:28:20 volumio volumio[1132]: info: CoreCommandRouter::volumioGetState Jul 18 17:28:20 volumio volumio[1132]: info: CorePlayQueue::getTrack 0 Jul 18 17:28:20 volumio volumio[1132]: info: CoreCommandRouter::volumioGetState Jul 18 17:28:20 volumio volumio[1132]: info: CorePlayQueue::getTrack 0 Jul 18 17:28:20 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 18 17:28:20 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 18 17:28:20 volumio volumio[1132]: info: Received Get System Info Jul 18 17:28:20 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 17:28:20 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 17:28:20 volumio volumio[1132]: info: Discovery: Getting this device information Jul 18 17:28:20 volumio volumio[1132]: info: CoreCommandRouter::volumioGetState Jul 18 17:28:20 volumio volumio[1132]: info: CorePlayQueue::getTrack 0 Jul 18 17:28:20 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 17:28:20 volumio volumio[1132]: info: CoreCommandRouter::volumioGetState Jul 18 17:28:20 volumio volumio[1132]: info: CorePlayQueue::getTrack 0 Jul 18 17:28:20 volumio volumio[1132]: info: Listing playlists Jul 18 17:28:20 volumio volumio[1132]: info: CoreCommandRouter::volumioGetQueue Jul 18 17:28:20 volumio volumio[1132]: info: CoreStateMachine::getQueue Jul 18 17:28:20 volumio volumio[1132]: info: CorePlayQueue::getQueue Jul 18 17:28:20 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 18 17:28:24 volumio volumio[1132]: info: CoreCommandRouter::volumioGetState Jul 18 17:28:24 volumio volumio[1132]: info: CorePlayQueue::getTrack 0 Jul 18 17:28:24 volumio volumio[1132]: info: Listing playlists Jul 18 17:28:24 volumio volumio[1132]: info: Listing playlists Jul 18 17:28:26 volumio volumio[1132]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 17:28:26 volumio volumio[1132]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 17:28:26 volumio volumio[1132]: info: Retrieving Cloud Streaming UI Jul 18 17:28:26 volumio volumio[1132]: info: Getting Tidal Cloud Configuration Jul 18 17:28:26 volumio volumio[1132]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 17:28:26 volumio volumio[1132]: info: Getting Qobuz Cloud Configuration Jul 18 17:28:26 volumio volumio[1132]: info: Asking plugin for UI Config Jul 18 17:28:26 volumio volumio[1132]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 17:28:26 volumio volumio[1132]: info: Getting Spotify Cloud Configuration Jul 18 17:28:26 volumio volumio[1132]: info: Asking plugin for UI Config Jul 18 17:28:26 volumio volumio[1132]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 17:28:26 volumio volumio[1132]: info: Saving Spotify Acccount Jul 18 17:28:26 volumio volumio[1132]: info: Got Tidal Cloud Configuration Jul 18 17:28:26 volumio volumio[1132]: info: Got it Jul 18 17:28:26 volumio volumio[1132]: info: Got it Jul 18 17:28:26 volumio volumio[1132]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 18 17:28:26 volumio volumio[1132]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 17:28:26 volumio volumio[1132]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jul 18 17:28:26 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 18 17:28:26 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 18 17:28:26 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 18 17:28:26 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 18 17:28:26 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 17:28:26 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 17:28:26 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 17:28:26 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 17:28:26 volumio volumio[1132]: info: CoreCommandRouter::volumioGetBrowseSources Jul 18 17:28:26 volumio volumio[1132]: info: CoreCommandRouter::volumioGetBrowseSources Jul 18 17:28:26 volumio volumio[1132]: info: CoreCommandRouter::volumioGetBrowseSources Jul 18 17:28:27 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 17:28:27 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 18 17:28:31 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 17:28:32 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jul 18 17:28:32 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jul 18 17:28:33 volumio wpa_supplicant[2744]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f8:0d:a9:1b:a8:18 reason=6 Jul 18 17:28:33 volumio dhcpcd[702]: wlan0: carrier lost Jul 18 17:28:33 volumio wpa_supplicant[2744]: MBO: Disable MBO/OCE due to misbehaving AP not having enabled PMF Jul 18 17:28:33 volumio wpa_supplicant[2744]: wlan0: Trying to associate with f8:0d:a9:1b:a8:18 (SSID='Ender' freq=2462 MHz) Jul 18 17:28:33 volumio wpa_supplicant[2744]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 18 17:28:33 volumio wpa_supplicant[2744]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Jul 18 17:28:33 volumio avahi-daemon[2854]: Withdrawing address record for 192.168.101.171 on wlan0. Jul 18 17:28:33 volumio avahi-daemon[2854]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.101.171. Jul 18 17:28:33 volumio dhcpcd[702]: wlan0: deleting route to 192.168.101.0/24 Jul 18 17:28:33 volumio avahi-daemon[2854]: Interface wlan0.IPv4 no longer relevant for mDNS. Jul 18 17:28:33 volumio dhcpcd[702]: wlan0: deleting default route via 192.168.101.1 Jul 18 17:28:33 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jul 18 17:28:33 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jul 18 17:28:33 volumio systemd[1]: welcome.service: Deactivated successfully. Jul 18 17:28:33 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jul 18 17:28:33 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jul 18 17:28:33 volumio wpa_supplicant[2744]: wlan0: Associated with f8:0d:a9:1b:a8:18 Jul 18 17:28:33 volumio wpa_supplicant[2744]: MBO: Disable MBO/OCE due to misbehaving AP not having enabled PMF Jul 18 17:28:33 volumio wpa_supplicant[2744]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 18 17:28:33 volumio wpa_supplicant[2744]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=NL Jul 18 17:28:33 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jul 18 17:28:33 volumio welcome[4093]: Resolved ip:[1] 192.168.101.11 Jul 18 17:28:33 volumio wpa_supplicant[2744]: wlan0: WPA: Key negotiation completed with f8:0d:a9:1b:a8:18 [PTK=CCMP GTK=CCMP] Jul 18 17:28:33 volumio wpa_supplicant[2744]: wlan0: CTRL-EVENT-CONNECTED - Connection to f8:0d:a9:1b:a8:18 completed [id=0 id_str=] Jul 18 17:28:33 volumio dhcpcd[702]: wlan0: carrier acquired Jul 18 17:28:33 volumio dhcpcd[702]: wlan0: IAID eb:81:ba:3c Jul 18 17:28:33 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jul 18 17:28:33 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jul 18 17:28:33 volumio dhcpcd[702]: wlan0: soliciting an IPv6 router Jul 18 17:28:33 volumio dhcpcd[702]: wlan0: rebinding lease of 192.168.101.171 Jul 18 17:28:33 volumio dhcpcd[702]: wlan0: probing address 192.168.101.171/24 Jul 18 17:28:33 volumio volumio[1132]: info: Volumio Network Manager: Network status updated: 1 Jul 18 17:28:34 volumio volumio[1132]: info: CoreCommandRouter::volumioGetState Jul 18 17:28:34 volumio volumio[1132]: info: CorePlayQueue::getTrack 0 Jul 18 17:28:34 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 18 17:28:35 volumio ntpd[825]: IO: Deleting interface #8 wlan0, 192.168.101.171#123, interface stats: received=0, sent=0, dropped=0, active_time=530 secs Jul 18 17:28:35 volumio volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 17:28:37 volumio volumio[1132]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 17:28:37 volumio volumio[1132]: Error: dns service error: unknown Jul 18 17:28:37 volumio volumio[1132]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Jul 18 17:28:37 volumio volumio[1132]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jul 18 17:28:37 volumio volumio[1132]: errorCode: -65537 Jul 18 17:28:37 volumio volumio[1132]: } Jul 18 17:28:37 volumio volumio[1132]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 17:28:38 volumio dhcpcd[702]: wlan0: leased 192.168.101.171 for 86400 seconds Jul 18 17:28:38 volumio avahi-daemon[2854]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.101.171. Jul 18 17:28:38 volumio avahi-daemon[2854]: New relevant interface wlan0.IPv4 for mDNS. Jul 18 17:28:38 volumio avahi-daemon[2854]: Registering new address record for 192.168.101.171 on wlan0.IPv4. Jul 18 17:28:38 volumio dhcpcd[702]: wlan0: adding route to 192.168.101.0/24 Jul 18 17:28:38 volumio dhcpcd[702]: wlan0: adding default route via 192.168.101.1 Jul 18 17:28:38 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jul 18 17:28:38 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jul 18 17:28:38 volumio systemd[1]: welcome.service: Deactivated successfully. Jul 18 17:28:38 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jul 18 17:28:38 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jul 18 17:28:38 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jul 18 17:28:38 volumio welcome[4147]: Resolved ip:[2] 192.168.101.11 192.168.101.171 Jul 18 17:28:38 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jul 18 17:28:38 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jul 18 17:28:38 volumio sudo[4159]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-18 17:27' Jul 18 17:28:38 volumio sudo[4159]: 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"