Dec 26 17:36:13 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Dec 26 17:36:13 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Dec 26 17:36:13 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 26 17:36:13 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 26 17:36:13 volumio systemd[1]: setdatetime-helper.service: Consumed 1.045s CPU time. Dec 26 17:36:13 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Dec 26 17:36:13 volumio ntpd[846]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 26 17:36:13 volumio ntpd[846]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 26 17:36:13 volumio ntpd[846]: DNS: Pool taking: 212.6.50.243 Dec 26 17:36:13 volumio ntpd[846]: DNS: Pool taking: 185.157.229.254 Dec 26 17:36:13 volumio ntpd[846]: DNS: Pool taking: 37.247.53.178 Dec 26 17:36:13 volumio ntpd[846]: DNS: Pool taking: 162.159.200.123 Dec 26 17:36:13 volumio ntpd[846]: DNS: Pool taking: 2a00:6d41:10:1194::1 Dec 26 17:36:13 volumio ntpd[846]: DNS: Pool taking: 2a00:6d41:200:2::12 Dec 26 17:36:13 volumio ntpd[846]: DNS: Pool taking: 2a00:6d41:200:2::13 Dec 26 17:36:13 volumio ntpd[846]: DNS: Pool taking: 2606:4700:f1::1 Dec 26 17:36:13 volumio ntpd[846]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 26 17:36:13 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Dec 26 17:36:13 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Dec 26 17:36:13 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Dec 26 17:36:13 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Dec 26 17:36:13 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Dec 26 17:36:14 volumio ntpd[846]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 26 17:36:14 volumio ntpd[846]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 26 17:36:14 volumio ntpd[846]: DNS: Pool taking: 89.46.74.148 Dec 26 17:36:14 volumio ntpd[846]: DNS: Pool taking: 5.249.149.252 Dec 26 17:36:14 volumio ntpd[846]: DNS: Pool taking: 185.19.184.35 Dec 26 17:36:14 volumio ntpd[846]: DNS: Pool skipping: 185.157.229.254 Dec 26 17:36:14 volumio ntpd[846]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 26 17:36:15 volumio ntpd[846]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 26 17:36:15 volumio ntpd[846]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 26 17:36:15 volumio ntpd[846]: DNS: Pool taking: 195.32.70.195 Dec 26 17:36:15 volumio ntpd[846]: DNS: Pool taking: 162.159.200.1 Dec 26 17:36:15 volumio ntpd[846]: DNS: Pool skipping: 37.247.53.178 Dec 26 17:36:15 volumio ntpd[846]: DNS: Pool taking: 93.94.88.51 Dec 26 17:36:15 volumio ntpd[846]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 26 17:36:20 volumio ntpd[846]: CLOCK: time stepped by 0.893818 Dec 26 17:36:20 volumio ntpd[846]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 26 17:36:20 volumio nmbd[976]: [2025/12/26 16:36:20.971366, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 26 17:36:20 volumio nmbd[976]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Dec 26 17:36:20 volumio nmbd[976]: [2025/12/26 16:36:20.971778, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 26 17:36:20 volumio nmbd[976]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Dec 26 17:36:20 volumio nmbd[976]: [2025/12/26 16:36:20.971944, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Dec 26 17:36:20 volumio nmbd[976]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Dec 26 17:36:20 volumio nmbd[976]: [2025/12/26 16:36:20.972030, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 26 17:36:20 volumio nmbd[976]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Dec 26 17:36:20 volumio nmbd[976]: [2025/12/26 16:36:20.972104, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Dec 26 17:36:20 volumio nmbd[976]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Dec 26 17:36:20 volumio nmbd[976]: [2025/12/26 16:36:20.972182, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 26 17:36:20 volumio nmbd[976]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Dec 26 17:36:20 volumio nmbd[976]: [2025/12/26 16:36:20.972269, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Dec 26 17:36:20 volumio nmbd[976]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Dec 26 17:36:20 volumio nmbd[976]: [2025/12/26 16:36:20.972354, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 26 17:36:20 volumio nmbd[976]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Dec 26 17:36:20 volumio nmbd[976]: [2025/12/26 16:36:20.972410, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Dec 26 17:36:20 volumio nmbd[976]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Dec 26 17:36:20 volumio nmbd[976]: [2025/12/26 16:36:20.972471, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 26 17:36:20 volumio nmbd[976]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Dec 26 17:36:20 volumio nmbd[976]: [2025/12/26 16:36:20.972528, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Dec 26 17:36:20 volumio nmbd[976]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Dec 26 17:36:23 volumio volumio[1073]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 26 17:36:23 volumio volumio[1073]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 26 17:36:23 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:36:23 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:36:23 volumio volumio[1073]: info: Starting MyVolumio Remote Streaming Endpoints Dec 26 17:36:23 volumio volumio[1073]: info: MyVolumio not started Dec 26 17:36:23 volumio volumio[1073]: info: Initializing device activation check Dec 26 17:36:23 volumio volumio[1073]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 26 17:36:23 volumio volumio[1073]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 26 17:36:30 volumio upmpdcli[1720]: writing RSA key Dec 26 17:36:31 volumio volumio[1073]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 26 17:36:31 volumio volumio[1073]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 26 17:36:31 volumio volumio[1073]: info: Streaming services startup Dec 26 17:36:31 volumio volumio[1073]: info: Starting Streaming Daemon Dec 26 17:36:31 volumio volumio[1073]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 26 17:36:31 volumio sudo[1731]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 26 17:36:31 volumio sudo[1731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:36:32 volumio sudo[1731]: pam_unix(sudo:session): session closed for user root Dec 26 17:36:32 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 17:36:32 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:36:32 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 17:36:32 volumio volumio[1073]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.073&uuid=5c26f2411e38c1331c998a21a70c52b3" http://updates.volumio.org/downloader-v1/track-device Dec 26 17:36:32 volumio volumio[1073]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 26 17:36:32 volumio volumio[1073]: Dload Upload Total Spent Left Speed Dec 26 17:36:32 volumio volumio[1073]: [132B blob data] Dec 26 17:36:32 volumio volumio[1073]: retrying in 5 seconds, trial 0 Dec 26 17:36:32 volumio volumio[1073]: info: Volumio Calling Home Dec 26 17:36:32 volumio volumio[1073]: info: Volumio Network Manager: Network status updated: 1 Dec 26 17:36:32 volumio volumio[1073]: error: Cannot start Volumio Streaming Daemon Dec 26 17:36:32 volumio volumio[1073]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 26 17:36:32 volumio volumio[1073]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 26 17:36:32 volumio volumio[1073]: info: Discovery: A device disappeared from network Dec 26 17:36:32 volumio volumio[1073]: info: Discovery: A device disappeared from network Dec 26 17:36:32 volumio volumio[1073]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Dec 26 17:36:33 volumio volumio[1073]: info: Volumio called home Dec 26 17:36:33 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 17:36:33 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 17:36:33 volumio volumio[1073]: info: Discovery: Getting this device information Dec 26 17:36:33 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Dec 26 17:36:33 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Dec 26 17:36:33 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 17:36:33 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 17:36:33 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 17:36:33 volumio volumio[1073]: info: Discovery: Getting this device information Dec 26 17:36:33 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Dec 26 17:36:33 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Dec 26 17:36:33 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 17:36:33 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 17:36:33 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 17:36:33 volumio volumio[1073]: info: Discovery: Getting this device information Dec 26 17:36:33 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Dec 26 17:36:33 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Dec 26 17:36:33 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 17:36:33 volumio volumio[1073]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Dec 26 17:36:33 volumio volumio[1073]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Dec 26 17:36:33 volumio volumio[1073]: verbose: New Socket.io Connection to 192.168.1.7:3000 from 192.168.1.6 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Dec 26 17:36:33 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 26 17:36:33 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 26 17:36:34 volumio volumio-remote-updater[662]: Test mode disabled Dec 26 17:36:34 volumio volumio-remote-updater[662]: Alpha mode disabled Dec 26 17:36:34 volumio volumio-remote-updater[662]: Alpha legacy test mode disabled Dec 26 17:36:34 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 26 17:36:34 volumio volumio[1073]: verbose: New Socket.io Connection to 192.168.1.7:3000 from 192.168.1.6 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Dec 26 17:36:34 volumio volumio[1073]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 26 17:36:34 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 26 17:36:34 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 17:36:34 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 17:36:34 volumio volumio[1073]: info: Discovery: Getting this device information Dec 26 17:36:34 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Dec 26 17:36:34 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Dec 26 17:36:34 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 17:36:38 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 17:36:38 volumio volumio[1073]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 26 17:36:38 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Dec 26 17:36:38 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Dec 26 17:36:38 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 17:36:38 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 17:36:38 volumio volumio[1073]: info: Discovery: Getting this device information Dec 26 17:36:38 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Dec 26 17:36:38 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Dec 26 17:36:38 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 17:36:38 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 26 17:36:38 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 26 17:36:38 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 26 17:36:38 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 26 17:36:39 volumio sudo[1768]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 26 17:36:39 volumio sudo[1768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:36:39 volumio sudo[1768]: pam_unix(sudo:session): session closed for user root Dec 26 17:36:39 volumio sudo[1763]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 26 17:36:39 volumio sudo[1763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:36:39 volumio sudo[1774]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 26 17:36:39 volumio sudo[1774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:36:39 volumio sudo[1788]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 17:36:39 volumio sudo[1786]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 17:36:39 volumio sudo[1778]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 26 17:36:39 volumio sudo[1778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:36:39 volumio sudo[1763]: pam_unix(sudo:session): session closed for user root Dec 26 17:36:39 volumio sudo[1774]: pam_unix(sudo:session): session closed for user root Dec 26 17:36:39 volumio sudo[1786]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:36:39 volumio sudo[1778]: pam_unix(sudo:session): session closed for user root Dec 26 17:36:39 volumio sudo[1788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:36:39 volumio sudo[1786]: pam_unix(sudo:session): session closed for user root Dec 26 17:36:39 volumio sudo[1788]: pam_unix(sudo:session): session closed for user root Dec 26 17:36:39 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 26 17:36:39 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 26 17:36:39 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 17:36:39 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 17:36:39 volumio volumio[1073]: info: Discovery: Getting this device information Dec 26 17:36:39 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Dec 26 17:36:39 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Dec 26 17:36:39 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 17:36:39 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 17:36:39 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 17:36:39 volumio volumio[1073]: info: Discovery: Getting this device information Dec 26 17:36:39 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Dec 26 17:36:39 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Dec 26 17:36:39 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 17:36:39 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Dec 26 17:36:39 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Dec 26 17:36:39 volumio volumio[1073]: verbose: New Socket.io Connection to 192.168.1.7:3000 from 192.168.1.6 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Dec 26 17:36:39 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 26 17:36:39 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 26 17:36:39 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 26 17:36:39 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 26 17:36:39 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 26 17:36:40 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 26 17:36:40 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 26 17:36:42 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 26 17:36:42 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 26 17:36:42 volumio volumio[1073]: info: Loading i18n strings for locale it Dec 26 17:36:43 volumio volumio[1073]: Updating browse sources language Dec 26 17:36:43 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 17:36:43 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:36:43 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:36:43 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 26 17:36:43 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 26 17:36:43 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 26 17:36:43 volumio volumio[1073]: info: CoreCommandRouter::volumioGetBrowseSources Dec 26 17:36:43 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 17:36:43 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 26 17:36:43 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 26 17:36:43 volumio volumio[1073]: info: Fetching Streaming Services browse cache Dec 26 17:36:43 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 26 17:36:43 volumio volumio[1073]: info: Setting timezone to Europe/Rome Dec 26 17:36:43 volumio sudo[1798]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 26 17:36:43 volumio sudo[1798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:36:43 volumio sudo[1798]: pam_unix(sudo:session): session closed for user root Dec 26 17:36:43 volumio sudo[1802]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Rome /etc/localtime Dec 26 17:36:43 volumio sudo[1802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:36:43 volumio sudo[1802]: pam_unix(sudo:session): session closed for user root Dec 26 17:36:44 volumio sudo[1806]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 26 17:36:44 volumio sudo[1806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:36:44 volumio sudo[1806]: pam_unix(sudo:session): session closed for user root Dec 26 17:36:44 volumio sudo[1810]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Rome Dec 26 17:36:44 volumio sudo[1810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:36:44 volumio dbus-daemon[645]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.21' (uid=0 pid=1811 comm="/usr/bin/timedatectl set-timezone Europe/Rome") Dec 26 17:36:44 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Dec 26 17:36:44 volumio dbus-daemon[645]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 26 17:36:44 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Dec 26 17:36:44 volumio sudo[1810]: pam_unix(sudo:session): session closed for user root Dec 26 17:36:44 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 26 17:36:44 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 26 17:36:44 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 26 17:36:44 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 26 17:36:46 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 17:36:46 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 17:36:46 volumio volumio[1073]: info: Discovery: Getting this device information Dec 26 17:36:46 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Dec 26 17:36:46 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Dec 26 17:36:46 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 17:36:51 volumio volumio[1073]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 26 17:36:51 volumio volumio[1073]: info: Completed starting MyVolumio Plugin Dec 26 17:36:51 volumio volumio[1073]: [Metrics] CommandRouter: 69s 661.81ms Dec 26 17:36:51 volumio volumio[1073]: info: CoreCommandRouter::volumiosetStartupVolume Dec 26 17:36:51 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:36:51 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:36:51 volumio volumio[1073]: info: CoreCommandRouter::Close All Modals sent Dec 26 17:36:51 volumio volumio[1073]: info: CoreCommandRouter::Close All Modals sent Dec 26 17:36:52 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 26 17:36:52 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 17:36:53 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 26 17:36:58 volumio volumio[1073]: info: BOOT COMPLETED Dec 26 17:37:10 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 26 17:37:10 volumio volumio[1073]: info: System name has changed, restarting Shairport Sync Dec 26 17:37:10 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:37:10 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:37:11 volumio volumio[1073]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 17:37:11 volumio volumio[1073]: info: Discovery: Restarting Advertising due to device name change Dec 26 17:37:11 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 17:37:11 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 17:37:11 volumio volumio[1073]: info: Discovery: Getting this device information Dec 26 17:37:11 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Dec 26 17:37:11 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Dec 26 17:37:11 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 17:37:11 volumio sudo[1886]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 26 17:37:11 volumio sudo[1886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:37:11 volumio sudo[1886]: pam_unix(sudo:session): session closed for user root Dec 26 17:37:11 volumio sudo[1889]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Dec 26 17:37:11 volumio sudo[1889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:37:11 volumio volumio[1073]: info: Starting Shairport Sync Dec 26 17:37:11 volumio sudo[1889]: pam_unix(sudo:session): session closed for user root Dec 26 17:37:11 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 17:37:11 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 17:37:11 volumio volumio[1073]: info: Discovery: Getting this device information Dec 26 17:37:11 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Dec 26 17:37:11 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Dec 26 17:37:11 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 17:37:11 volumio volumio[1073]: info: Permissions for /etc/hosts set Dec 26 17:37:11 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 26 17:37:11 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 26 17:37:11 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 26 17:37:11 volumio sudo[1892]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 17:37:11 volumio sudo[1892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:37:11 volumio sudo[1894]: volumio : unable to resolve host volumio: System error Dec 26 17:37:11 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 17:37:11 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 17:37:11 volumio sudo[1894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname living-room Dec 26 17:37:11 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 17:37:11 volumio systemd[1]: shairport-sync.service: Consumed 1.732s CPU time. Dec 26 17:37:11 volumio sudo[1894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:37:11 living-room sudo[1894]: pam_unix(sudo:session): session closed for user root Dec 26 17:37:11 living-room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 17:37:11 living-room sudo[1892]: pam_unix(sudo:session): session closed for user root Dec 26 17:37:11 living-room sudo[1897]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 26 17:37:11 living-room sudo[1897]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:37:12 living-room volumio[1073]: info: Shairport-Sync Started Dec 26 17:37:12 living-room volumio[1073]: info: New hostname set Dec 26 17:37:12 living-room sudo[1910]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 26 17:37:12 living-room sudo[1910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:37:12 living-room sudo[1899]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 26 17:37:12 living-room systemd[1]: upmpdcli.service: Deactivated successfully. Dec 26 17:37:12 living-room systemd[1]: upmpdcli.service: Consumed 16.890s CPU time. Dec 26 17:37:12 living-room sudo[1897]: pam_unix(sudo:session): session closed for user root Dec 26 17:37:12 living-room sudo[1910]: pam_unix(sudo:session): session closed for user root Dec 26 17:37:12 living-room sudo[1899]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:37:12 living-room sudo[1914]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 26 17:37:12 living-room sudo[1899]: pam_unix(sudo:session): session closed for user root Dec 26 17:37:12 living-room volumio[1073]: info: Hostname now is living-room Dec 26 17:37:12 living-room sudo[1914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:37:12 living-room sudo[1937]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 17:37:12 living-room sudo[1937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:37:12 living-room sudo[1914]: pam_unix(sudo:session): session closed for user root Dec 26 17:37:12 living-room sudo[1937]: pam_unix(sudo:session): session closed for user root Dec 26 17:37:12 living-room sudo[1935]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 17:37:12 living-room sudo[1935]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:37:12 living-room sudo[1935]: pam_unix(sudo:session): session closed for user root Dec 26 17:37:12 living-room sudo[1944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Dec 26 17:37:12 living-room sudo[1944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:37:12 living-room volumio[1073]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 26 17:37:12 living-room volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 26 17:37:12 living-room sudo[1944]: pam_unix(sudo:session): session closed for user root Dec 26 17:37:12 living-room volumio[1073]: info: Discovery: A device disappeared from network Dec 26 17:37:12 living-room volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 26 17:37:12 living-room sudo[1948]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 26 17:37:12 living-room sudo[1948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:37:12 living-room sudo[1953]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 26 17:37:12 living-room sudo[1953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:37:12 living-room sudo[1956]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 26 17:37:12 living-room sudo[1956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:37:12 living-room sudo[1953]: pam_unix(sudo:session): session closed for user root Dec 26 17:37:12 living-room sudo[1956]: pam_unix(sudo:session): session closed for user root Dec 26 17:37:12 living-room sudo[1948]: pam_unix(sudo:session): session closed for user root Dec 26 17:37:13 living-room sudo[1973]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 17:37:13 living-room sudo[1973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:37:13 living-room sudo[1967]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 26 17:37:13 living-room sudo[1967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:37:13 living-room volumio[1073]: info: Permissions for /etc/avahi/services/volumio.service Dec 26 17:37:13 living-room sudo[1973]: pam_unix(sudo:session): session closed for user root Dec 26 17:37:13 living-room sudo[1967]: pam_unix(sudo:session): session closed for user root Dec 26 17:37:13 living-room volumio[1073]: info: Discovery: A device disappeared from network Dec 26 17:37:13 living-room sudo[1975]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 17:37:13 living-room avahi-daemon[641]: Files changed, reloading. Dec 26 17:37:13 living-room avahi-daemon[641]: Loading service file /services/volumio.service. Dec 26 17:37:13 living-room sudo[1975]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:37:13 living-room volumio[1073]: info: Avahi name changed to living-room Dec 26 17:37:13 living-room sudo[1975]: pam_unix(sudo:session): session closed for user root Dec 26 17:37:13 living-room volumio[1073]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 26 17:37:13 living-room volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 26 17:37:14 living-room avahi-daemon[641]: Service "Living Room" (/services/volumio.service) successfully established. Dec 26 17:37:14 living-room volumio[1073]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 26 17:37:14 living-room volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 17:37:14 living-room systemd[1]: systemd-timedated.service: Deactivated successfully. Dec 26 17:37:15 living-room volumio-remote-updater[662]: Test mode disabled Dec 26 17:37:15 living-room volumio-remote-updater[662]: Alpha mode disabled Dec 26 17:37:15 living-room volumio-remote-updater[662]: Alpha legacy test mode disabled Dec 26 17:37:15 living-room volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 26 17:37:15 living-room volumio[1073]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 26 17:37:15 living-room volumio[1073]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 26 17:37:16 living-room volumio[1073]: info: Discovery: Started advertising with name: Living Room Dec 26 17:37:17 living-room volumio[1073]: compat.c: read() failed: Resource temporarily unavailable Dec 26 17:37:17 living-room volumio[1073]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 17:37:17 living-room volumio[1073]: Error: dns service error: unknown Dec 26 17:37:17 living-room volumio[1073]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 26 17:37:17 living-room volumio[1073]: errorCode: -65537 Dec 26 17:37:17 living-room volumio[1073]: } Dec 26 17:37:17 living-room volumio[1073]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 17:37:22 living-room sudo[2013]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-26 17:36' Dec 26 17:37:22 living-room sudo[2013]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"