Oct 13 17:35:00 volumio wireless.js[1029]: WIRELESS.JS - INFO: trying... Oct 13 17:35:00 volumio sudo[2161]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:35:00 volumio sudo[2161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 17:35:00 volumio sudo[2161]: pam_unix(sudo:session): session closed for user root Oct 13 17:35:00 volumio sudo[2164]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 17:35:00 volumio sudo[2164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 17:35:00 volumio sudo[2164]: pam_unix(sudo:session): session closed for user root Oct 13 17:35:00 volumio wireless.js[1029]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Oct 13 17:35:00 volumio dhcpcd[1041]: wlan0: leased 192.168.1.80 for 25200 seconds Oct 13 17:35:00 volumio avahi-daemon[1007]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.80. Oct 13 17:35:00 volumio avahi-daemon[1007]: New relevant interface wlan0.IPv4 for mDNS. Oct 13 17:35:00 volumio avahi-daemon[1007]: Registering new address record for 192.168.1.80 on wlan0.IPv4. Oct 13 17:35:00 volumio dhcpcd[1041]: wlan0: adding route to 192.168.1.0/24 Oct 13 17:35:00 volumio dhcpcd[1041]: wlan0: adding default route via 192.168.1.1 Oct 13 17:35:00 volumio systemd[1]: welcome.service: Deactivated successfully. Oct 13 17:35:00 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 13 17:35:00 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 13 17:35:00 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 13 17:35:00 volumio welcome[2171]: Resolved ip:[1] 192.168.1.80 Oct 13 17:35:00 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 13 17:35:00 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Oct 13 17:35:00 volumio volumio[1397]: verbose: New Socket.io Connection to 192.168.1.80 from 192.168.1.146 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:157.0) Gecko/20100101 Firefox/157.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 13 17:35:00 volumio volumio[1397]: verbose: New Socket.io Connection to 192.168.1.80 from 192.168.1.146 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:157.0) Gecko/20100101 Firefox/157.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 13 17:35:00 volumio volumio[1397]: verbose: New Socket.io Connection to 192.168.1.80 from 192.168.1.146 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:157.0) Gecko/20100101 Firefox/157.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 13 17:35:00 volumio volumio[1397]: verbose: New Socket.io Connection to 192.168.1.80 from 192.168.1.146 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:157.0) Gecko/20100101 Firefox/157.0 Engine version: 3 Transport: polling Total Clients: 10 Oct 13 17:35:00 volumio systemd[1]: Started nmbd.service - Samba NMB Daemon. Oct 13 17:35:00 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... Oct 13 17:35:00 volumio winbindd[2194]: [2026/10/13 17:35:00.489527, 0] ../../source3/winbindd/winbindd.c:1440(main) Oct 13 17:35:00 volumio winbindd[2194]: winbindd version 4.17.8-Raspbian started. Oct 13 17:35:00 volumio winbindd[2194]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Oct 13 17:35:00 volumio winbindd[2194]: [2026/10/13 17:35:00.493315, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Oct 13 17:35:00 volumio winbindd[2194]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 13 17:35:00 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. Oct 13 17:35:00 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... Oct 13 17:35:00 volumio volumio[1397]: info: Received Get System Info Oct 13 17:35:00 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 17:35:00 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 17:35:00 volumio volumio[1397]: info: Discovery: Getting this device information Oct 13 17:35:00 volumio volumio[1397]: info: CoreCommandRouter::volumioGetState Oct 13 17:35:00 volumio volumio[1397]: info: CorePlayQueue::getTrack 0 Oct 13 17:35:00 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 17:35:00 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 13 17:35:00 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 13 17:35:00 volumio smbd[2211]: [2026/10/13 17:35:00.654793, 0] ../../source3/smbd/server.c:1741(main) Oct 13 17:35:00 volumio smbd[2211]: smbd version 4.17.8-Raspbian started. Oct 13 17:35:00 volumio smbd[2211]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Oct 13 17:35:00 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Oct 13 17:35:01 volumio wireless.js[1029]: WIRELESS.JS - INFO: trying... Oct 13 17:35:01 volumio sudo[2229]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:35:01 volumio sudo[2229]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 17:35:01 volumio sudo[2229]: pam_unix(sudo:session): session closed for user root Oct 13 17:35:01 volumio sudo[2232]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 17:35:01 volumio sudo[2232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 17:35:01 volumio sudo[2232]: pam_unix(sudo:session): session closed for user root Oct 13 17:35:01 volumio wireless.js[1029]: WIRELESS.JS - INFO: ... wlan0 IPv4 is 192.168.1.80, ipV6 is undefined Oct 13 17:35:01 volumio wireless.js[1029]: WIRELESS.JS - INFO: Connected to SSID: Tenda_5G Oct 13 17:35:01 volumio wireless.js[1029]: WIRELESS.JS - INFO: It's done! AP Oct 13 17:35:01 volumio wireless.js[1029]: WIRELESS.JS - INFO: Restarting avahi-daemon... Oct 13 17:35:01 volumio sudo[2237]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon Oct 13 17:35:01 volumio sudo[2237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 17:35:01 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 13 17:35:01 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 13 17:35:01 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 17:35:01 volumio systemd[1]: shairport-sync.service: Consumed 1.516s CPU time. Oct 13 17:35:01 volumio avahi-daemon[1007]: Got SIGTERM, quitting. Oct 13 17:35:01 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Oct 13 17:35:01 volumio avahi-daemon[1007]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.80. Oct 13 17:35:01 volumio volumio5-onboarding[1593]: time=2026-10-13T17:35:01.092+03:00 level=WARN msg="disconnected from Avahi daemon, trying to reconnect" component=discovery/localnet error="avahi: Daemon connection failed" Oct 13 17:35:01 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: dns service error: unknown Oct 13 17:35:01 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:01 volumio avahi-daemon[1007]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Oct 13 17:35:01 volumio avahi-daemon[1007]: avahi-daemon 0.8 exiting. Oct 13 17:35:01 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. Oct 13 17:35:01 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Oct 13 17:35:01 volumio volumio[1397]: error: Discovery: Advertisement error: Error: dns service error: unknown Oct 13 17:35:01 volumio volumio[1397]: error: Discovery: advertisement error: Error: dns service error: unknown Oct 13 17:35:01 volumio volumio[1397]: info: Discovery: Advertisement raised the following error Error: dns service error: unknown Oct 13 17:35:01 volumio volumio[1397]: info: Discovery: Stopping Advertising Immediately Oct 13 17:35:01 volumio volumio[1397]: info: Discovery: Stopping existing advertisement Oct 13 17:35:01 volumio dbus-daemon[1009]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.26' (uid=0 pid=2211 comm="/usr/sbin/smbd --foreground --no-process-group") Oct 13 17:35:01 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Oct 13 17:35:01 volumio avahi-daemon[2239]: Process 1007 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Oct 13 17:35:01 volumio avahi-daemon[2239]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Oct 13 17:35:01 volumio avahi-daemon[2239]: Successfully dropped root privileges. Oct 13 17:35:01 volumio avahi-daemon[2239]: avahi-daemon 0.8 starting up. Oct 13 17:35:01 volumio dbus-daemon[1009]: [system] Successfully activated service 'org.freedesktop.Avahi' Oct 13 17:35:01 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Oct 13 17:35:01 volumio avahi-daemon[2239]: Successfully called chroot(). Oct 13 17:35:01 volumio avahi-daemon[2239]: Successfully dropped remaining capabilities. Oct 13 17:35:01 volumio avahi-daemon[2239]: No service file found in /etc/avahi/services. Oct 13 17:35:01 volumio avahi-daemon[2239]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.80. Oct 13 17:35:01 volumio avahi-daemon[2239]: New relevant interface wlan0.IPv4 for mDNS. Oct 13 17:35:01 volumio avahi-daemon[2239]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Oct 13 17:35:01 volumio avahi-daemon[2239]: New relevant interface lo.IPv4 for mDNS. Oct 13 17:35:01 volumio avahi-daemon[2239]: Network interface enumeration completed. Oct 13 17:35:01 volumio avahi-daemon[2239]: Registering new address record for 192.168.1.80 on wlan0.IPv4. Oct 13 17:35:01 volumio avahi-daemon[2239]: Registering new address record for 127.0.0.1 on lo.IPv4. Oct 13 17:35:01 volumio sudo[2237]: pam_unix(sudo:session): session closed for user root Oct 13 17:35:01 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 17:35:01 volumio wireless.js[1029]: WIRELESS.JS - INFO: Notified systemd about wireless ready Oct 13 17:35:01 volumio ntpd[1201]: IO: Listen normally on 3 wlan0 192.168.1.80:123 Oct 13 17:35:01 volumio ntpd[1201]: IO: new interface(s) found: waking up resolver Oct 13 17:35:01 volumio ntpd[1201]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 13 17:35:01 volumio ntpd[1201]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 13 17:35:01 volumio ntpd[1201]: DNS: Pool taking: 178.159.240.10 Oct 13 17:35:01 volumio ntpd[1201]: DNS: Pool taking: 134.17.27.157 Oct 13 17:35:01 volumio ntpd[1201]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Oct 13 17:35:01 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:01 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:01 volumio volumio[1397]: info: Discovery: Restart already pending, ignoring duplicate call Oct 13 17:35:01 volumio avahi-daemon[2239]: Server startup complete. Host name is volumio.local. Local service cookie is 3783993691. Oct 13 17:35:02 volumio ntpd[1201]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 13 17:35:02 volumio ntpd[1201]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 13 17:35:02 volumio ntpd[1201]: DNS: Pool taking: 82.209.210.87 Oct 13 17:35:02 volumio ntpd[1201]: DNS: Pool taking: 130.49.141.168 Oct 13 17:35:02 volumio ntpd[1201]: DNS: Pool taking: 2a05:5502:12:11::123 Oct 13 17:35:02 volumio ntpd[1201]: DNS: Pool taking: 2001:67c:2268::50 Oct 13 17:35:02 volumio ntpd[1201]: DNS: Pool taking: 2a0c:b1c2::c Oct 13 17:35:02 volumio ntpd[1201]: DNS: Pool taking: 2a02:2208:1:3::12 Oct 13 17:35:02 volumio ntpd[1201]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Oct 13 17:35:02 volumio volumio5-onboarding[1593]: time=2026-10-13T17:35:02.858+03:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 13 17:35:02 volumio volumio[1397]: verbose: New Socket.io Connection to 192.168.1.80 from 192.168.1.146 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:157.0) Gecko/20100101 Firefox/157.0 Engine version: 3 Transport: polling Total Clients: 11 Oct 13 17:35:02 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 17:35:03 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Oct 13 17:35:03 volumio volumio[1397]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 17:35:03 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 17:35:03 volumio volumio[1397]: info: CoreCommandRouter::volumioGetState Oct 13 17:35:03 volumio volumio[1397]: info: CorePlayQueue::getTrack 0 Oct 13 17:35:03 volumio volumio[1397]: info: CoreCommandRouter::volumioGetState Oct 13 17:35:03 volumio volumio[1397]: info: CorePlayQueue::getTrack 0 Oct 13 17:35:03 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 13 17:35:03 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 13 17:35:03 volumio volumio[1397]: info: Received Get System Info Oct 13 17:35:03 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 17:35:03 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 17:35:03 volumio volumio[1397]: info: Discovery: Getting this device information Oct 13 17:35:03 volumio volumio[1397]: info: CoreCommandRouter::volumioGetState Oct 13 17:35:03 volumio volumio[1397]: info: CorePlayQueue::getTrack 0 Oct 13 17:35:03 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 17:35:03 volumio volumio[1397]: info: CoreCommandRouter::volumioGetState Oct 13 17:35:03 volumio volumio[1397]: info: CorePlayQueue::getTrack 0 Oct 13 17:35:03 volumio volumio[1397]: info: Listing playlists Oct 13 17:35:03 volumio volumio[1397]: info: CoreCommandRouter::volumioGetQueue Oct 13 17:35:03 volumio volumio[1397]: info: CoreStateMachine::getQueue Oct 13 17:35:03 volumio volumio[1397]: info: CorePlayQueue::getQueue Oct 13 17:35:03 volumio ntpd[1201]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 13 17:35:03 volumio ntpd[1201]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 13 17:35:03 volumio ntpd[1201]: DNS: Pool taking: 31.130.200.50 Oct 13 17:35:03 volumio ntpd[1201]: DNS: Pool taking: 91.215.176.2 Oct 13 17:35:03 volumio ntpd[1201]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Oct 13 17:35:03 volumio dhcpcd[928]: timed out Oct 13 17:35:03 volumio sh[913]: timed out Oct 13 17:35:03 volumio sh[849]: ifup: failed to bring up eth0 Oct 13 17:35:03 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Oct 13 17:35:03 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Oct 13 17:35:04 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 13 17:35:04 volumio ntpd[1201]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 13 17:35:04 volumio ntpd[1201]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 13 17:35:04 volumio ntpd[1201]: DNS: Pool taking: 178.124.164.106 Oct 13 17:35:04 volumio ntpd[1201]: DNS: Pool taking: 178.124.164.105 Oct 13 17:35:04 volumio ntpd[1201]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Oct 13 17:35:05 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 13 17:35:05 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Oct 13 17:35:05 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 17:35:05 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 17:35:05 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Oct 13 17:35:05 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Oct 13 17:35:05 volumio sudo[2133]: pam_unix(sudo:session): session closed for user root Oct 13 17:35:05 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Oct 13 17:35:05 volumio volumio[1397]: info: Upmpdcli Daemon Started Oct 13 17:35:05 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 13 17:35:05 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Oct 13 17:35:05 volumio systemd[1]: Startup finished in 11.466s (kernel) + 33.403s (userspace) = 44.869s. Oct 13 17:35:06 volumio volumio[1397]: info: Discovery: Restarting Advertising Oct 13 17:35:06 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 17:35:06 volumio volumio[1397]: info: Received Get System Info Oct 13 17:35:06 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 17:35:06 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 17:35:06 volumio volumio[1397]: info: Discovery: Getting this device information Oct 13 17:35:06 volumio volumio[1397]: info: CoreCommandRouter::volumioGetState Oct 13 17:35:06 volumio volumio[1397]: info: CorePlayQueue::getTrack 0 Oct 13 17:35:06 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 17:35:07 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 13 17:35:07 volumio volumio[1397]: info: Adding plugin bluetooth to MyMusic Plugins Oct 13 17:35:07 volumio volumio[1397]: info: Adding plugin multiroom to MyMusic Plugins Oct 13 17:35:07 volumio volumio[1397]: info: Adding plugin metavolumio to MyMusic Plugins Oct 13 17:35:07 volumio volumio[1397]: info: Adding plugin cd_controller to MyMusic Plugins Oct 13 17:35:07 volumio volumio[1397]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 13 17:35:07 volumio volumio[1397]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 13 17:35:07 volumio volumio[1397]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 13 17:35:07 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:35:07 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:35:07 volumio volumio[1397]: info: Starting MyVolumio Remote Streaming Endpoints Oct 13 17:35:07 volumio volumio[1397]: info: MyVolumio login type: Token Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 13 17:35:07 volumio volumio[1397]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 13 17:35:08 volumio volumio[1397]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 13 17:35:08 volumio volumio[1397]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 13 17:35:08 volumio volumio[1397]: info: Streaming services startup Oct 13 17:35:08 volumio volumio[1397]: info: Starting Streaming Daemon Oct 13 17:35:08 volumio sudo[2304]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 13 17:35:08 volumio sudo[2304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 17:35:08 volumio volumio[1397]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 13 17:35:08 volumio sudo[2304]: pam_unix(sudo:session): session closed for user root Oct 13 17:35:08 volumio volumio[1397]: error: Cannot start Volumio Streaming Daemon Oct 13 17:35:08 volumio volumio[1397]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 13 17:35:08 volumio volumio[1397]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 13 17:35:08 volumio volumio[1397]: info: Volumio Network Manager: Network status updated: 2 Oct 13 17:35:08 volumio volumio[1397]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 13 17:35:09 volumio volumio[1397]: info: MyVolumio token set successfully Oct 13 17:35:09 volumio volumio[1397]: info: MYVOLUMIO: Adding device Oct 13 17:35:09 volumio volumio[1397]: info: MYVOLUMIO: Evaluating Server Oct 13 17:35:09 volumio volumio[1397]: info: MyVolumio status changed Oct 13 17:35:09 volumio volumio[1397]: info: Streaming services startup Oct 13 17:35:09 volumio volumio[1397]: info: Starting Streaming Daemon Oct 13 17:35:09 volumio volumio[1397]: info: Removing browser output: myVolumio user plan is not superstar Oct 13 17:35:09 volumio volumio[1397]: info: Removing audio output: Oct 13 17:35:09 volumio volumio[1397]: info: Stoppping Tunnel 1 Oct 13 17:35:09 volumio sudo[2347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 13 17:35:09 volumio sudo[2347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 17:35:09 volumio sudo[2345]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 13 17:35:09 volumio sudo[2345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 17:35:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 13 17:35:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 13 17:35:09 volumio sudo[2345]: pam_unix(sudo:session): session closed for user root Oct 13 17:35:09 volumio volumio[1397]: error: Cannot start Volumio Streaming Daemon Oct 13 17:35:09 volumio volumio[1397]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 13 17:35:09 volumio volumio[1397]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 13 17:35:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 13 17:35:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 13 17:35:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 13 17:35:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 13 17:35:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 13 17:35:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 13 17:35:09 volumio sudo[2347]: pam_unix(sudo:session): session closed for user root Oct 13 17:35:09 volumio volumio[1397]: info: Remote SSH Stopped Oct 13 17:35:09 volumio volumio[1397]: info: Setting Geolocation for MyVolumio to eu4 Oct 13 17:35:09 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:35:09 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:35:09 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:35:09 volumio volumio[1397]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.119&uuid=1fa69c0de59be271d4a158dad0c88c9f" http://updates.volumio.org/downloader-v1/track-device Oct 13 17:35:09 volumio volumio[1397]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 17:35:09 volumio volumio[1397]: Dload Upload Total Spent Left Speed Oct 13 17:35:09 volumio volumio[1397]: [132B blob data] Oct 13 17:35:09 volumio volumio[1397]: retrying in 5 seconds, trial 1 Oct 13 17:35:09 volumio volumio[1397]: info: Volumio Calling Home Oct 13 17:35:10 volumio volumio[1397]: info: Volumio called home Oct 13 17:35:10 volumio volumio[1397]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 13 17:35:10 volumio volumio[1397]: info: Updating MyVolumio device info Oct 13 17:35:10 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:35:10 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:35:10 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:35:10 volumio volumio[1397]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 13 17:35:11 volumio volumio[1397]: info: Discovery: Started advertising with name: Volumio Oct 13 17:35:12 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:12 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:12 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:12 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:12 volumio volumio[1397]: info: Discovery: Restart already pending, ignoring duplicate call Oct 13 17:35:12 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 17:35:12 volumio volumio[1397]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 13 17:35:12 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 13 17:35:12 volumio volumio[1397]: info: Received Get System Version Oct 13 17:35:12 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 17:35:12 volumio volumio[1397]: info: Received Get System Info Oct 13 17:35:12 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 17:35:12 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 17:35:12 volumio volumio[1397]: info: Discovery: Getting this device information Oct 13 17:35:12 volumio volumio[1397]: info: CoreCommandRouter::volumioGetState Oct 13 17:35:12 volumio volumio[1397]: info: CorePlayQueue::getTrack 0 Oct 13 17:35:12 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 17:35:14 volumio volumio[1397]: info: MYVOLUMIO: Adding device Oct 13 17:35:14 volumio volumio[1397]: info: MYVOLUMIO: Evaluating Server Oct 13 17:35:14 volumio volumio[1397]: info: Bluetoothremote--- Checking for trusted devices to reconnect... Oct 13 17:35:14 volumio volumio[1397]: info: Bluetoothremote--- Device list cleared and placeholder written. Oct 13 17:35:14 volumio bluetoothd[1008]: Adv Monitor app :1.34 disconnected from D-Bus Oct 13 17:35:14 volumio bluetoothd[1008]: Path / reserved for Adv Monitor app :1.35 Oct 13 17:35:14 volumio bluetoothd[1008]: Adv Monitor app :1.35 disconnected from D-Bus Oct 13 17:35:14 volumio volumio[1397]: info: Bluetoothremote--- Reconnecting trusted device: G20S PRO (69:98:98:24:58:79) Oct 13 17:35:14 volumio bluetoothd[1008]: Path / reserved for Adv Monitor app :1.36 Oct 13 17:35:14 volumio volumio[1397]: info: Setting Geolocation for MyVolumio to eu4 Oct 13 17:35:14 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:35:14 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:35:14 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:35:15 volumio volumio[1397]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 13 17:35:15 volumio volumio[1397]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 17:35:15 volumio volumio[1397]: info: Bluetoothremote--- Device found: Press scan to detect BT device - xx Oct 13 17:35:15 volumio bluetoothd[1008]: Adv Monitor app :1.37 disconnected from D-Bus Oct 13 17:35:15 volumio volumio[1397]: info: Updating MyVolumio device info Oct 13 17:35:15 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:35:15 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:35:15 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:35:16 volumio volumio[1397]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 13 17:35:17 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:17 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:17 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:17 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:17 volumio volumio[1397]: info: Discovery: Restart already pending, ignoring duplicate call Oct 13 17:35:19 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 17:35:19 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:35:19 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 17:35:19 volumio volumio-remote-updater[1027]: Test mode disabled Oct 13 17:35:19 volumio volumio-remote-updater[1027]: Alpha mode disabled Oct 13 17:35:19 volumio volumio-remote-updater[1027]: Alpha legacy test mode disabled Oct 13 17:35:19 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 13 17:35:19 volumio volumio[1397]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 13 17:35:19 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 13 17:35:21 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 13 17:35:22 volumio upmpdcli[2421]: writing RSA key Oct 13 17:35:22 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:22 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:22 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:22 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:22 volumio volumio[1397]: info: Discovery: Restart already pending, ignoring duplicate call Oct 13 17:35:22 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 17:35:22 volumio volumio[1397]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Oct 13 17:35:22 volumio volumio[1397]: info: CoreCommandRouter::volumioGetState Oct 13 17:35:22 volumio volumio[1397]: info: CorePlayQueue::getTrack 0 Oct 13 17:35:24 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 13 17:35:24 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 13 17:35:27 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:27 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:27 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:27 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:27 volumio volumio[1397]: info: Discovery: Restart already pending, ignoring duplicate call Oct 13 17:35:27 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:35:27 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:35:27 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 13 17:35:27 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 13 17:35:27 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 13 17:35:27 volumio volumio[1397]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 17:35:27 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 17:35:28 volumio volumio[1397]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 13 17:35:28 volumio volumio[1397]: info: Completed starting MyVolumio Plugin Oct 13 17:35:28 volumio volumio[1397]: [Metrics] CommandRouter: 40s 506.68ms Oct 13 17:35:28 volumio volumio[1397]: info: CoreCommandRouter::volumiosetStartupVolume Oct 13 17:35:28 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:35:28 volumio volumio[1397]: info: VolumeController:: Setting startup Volume 15 Oct 13 17:35:28 volumio volumio[1397]: info: VolumeController::SetAlsaVolume15 Oct 13 17:35:28 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:35:28 volumio volumio[1397]: info: CoreCommandRouter::Close All Modals sent Oct 13 17:35:28 volumio volumio[1397]: info: CoreCommandRouter::Close All Modals sent Oct 13 17:35:28 volumio volumio[1397]: info: CoreStateMachine::pushState Oct 13 17:35:28 volumio volumio[1397]: info: CorePlayQueue::getTrack 0 Oct 13 17:35:28 volumio volumio[1397]: info: CoreCommandRouter::volumioPushState Oct 13 17:35:29 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 13 17:35:29 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 17:35:29 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 13 17:35:32 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:32 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:32 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:32 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:32 volumio volumio[1397]: info: Discovery: Restart already pending, ignoring duplicate call Oct 13 17:35:35 volumio volumio[1397]: info: BOOT COMPLETED Oct 13 17:35:37 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:37 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:37 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:37 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:37 volumio volumio[1397]: info: Discovery: Restart already pending, ignoring duplicate call Oct 13 17:35:42 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:42 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:42 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:42 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:42 volumio volumio[1397]: info: Discovery: Restart already pending, ignoring duplicate call Oct 13 17:35:47 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:47 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:47 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:47 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:47 volumio volumio[1397]: info: Discovery: Restart already pending, ignoring duplicate call Oct 13 17:35:52 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:52 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:52 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:52 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:52 volumio volumio[1397]: info: Discovery: Restart already pending, ignoring duplicate call Oct 13 17:35:56 volumio bluetoothd[1008]: Adv Monitor app :1.36 disconnected from D-Bus Oct 13 17:35:56 volumio volumio[1397]: error: Bluetoothremote--- Failed to reconnect G20S PRO: Command failed: bluetoothctl connect 69:98:98:24:58:79 Oct 13 17:35:57 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:57 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:57 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:35:57 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:35:57 volumio volumio[1397]: info: Discovery: Restart already pending, ignoring duplicate call Oct 13 17:36:02 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:36:02 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:36:02 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:36:02 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:36:02 volumio volumio[1397]: info: Discovery: Restart already pending, ignoring duplicate call Oct 13 17:36:07 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:36:07 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:36:07 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:36:07 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:36:07 volumio volumio[1397]: info: Discovery: Restart already pending, ignoring duplicate call Oct 13 17:36:12 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:36:12 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:36:12 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:36:12 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:36:12 volumio volumio[1397]: info: Discovery: Restart already pending, ignoring duplicate call Oct 13 17:36:17 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:36:17 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:36:17 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:36:17 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:36:17 volumio volumio[1397]: info: Discovery: Restart already pending, ignoring duplicate call Oct 13 17:36:22 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:36:22 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:36:22 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:36:22 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:36:22 volumio volumio[1397]: info: Discovery: Restart already pending, ignoring duplicate call Oct 13 17:36:22 volumio volumio[1397]: info: CoreCommandRouter::volumioGetState Oct 13 17:36:22 volumio volumio[1397]: info: CorePlayQueue::getTrack 0 Oct 13 17:36:25 volumio volumio[1397]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 17:36:25 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 13 17:36:25 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:36:25 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 17:36:25 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:36:25 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:36:25 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:36:25 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:36:25 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:36:25 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:36:25 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:36:25 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 13 17:36:25 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 13 17:36:27 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:36:27 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:36:27 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:36:27 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:36:27 volumio volumio[1397]: info: Discovery: Restart already pending, ignoring duplicate call Oct 13 17:36:30 volumio volumio[1397]: info: CoreCommandRouter::volumioGetState Oct 13 17:36:30 volumio volumio[1397]: info: CorePlayQueue::getTrack 0 Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 17:36:32 volumio volumio[1397]: info: Retrieving Cloud Streaming UI Oct 13 17:36:32 volumio volumio[1397]: info: Getting Tidal Cloud Configuration Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 17:36:32 volumio volumio[1397]: info: Getting Qobuz Cloud Configuration Oct 13 17:36:32 volumio volumio[1397]: info: Asking plugin for UI Config Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 17:36:32 volumio volumio[1397]: info: Getting Spotify Cloud Configuration Oct 13 17:36:32 volumio volumio[1397]: info: Asking plugin for UI Config Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 17:36:32 volumio volumio[1397]: info: Saving Spotify Acccount Oct 13 17:36:32 volumio volumio[1397]: info: Got Tidal Cloud Configuration Oct 13 17:36:32 volumio volumio[1397]: info: Got it Oct 13 17:36:32 volumio volumio[1397]: info: Got it Oct 13 17:36:32 volumio volumio[1397]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 17:36:32 volumio volumio[1397]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 17:36:32 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 13 17:36:32 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:36:32 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:36:32 volumio volumio[1397]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 13 17:36:32 volumio volumio[1397]: info: Discovery: Restarting Browsing Oct 13 17:36:32 volumio volumio[1397]: info: Discovery: Restart already pending, ignoring duplicate call Oct 13 17:36:33 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 13 17:36:33 volumio volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 13 17:36:33 volumio volumio[1397]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 17:36:33 volumio volumio[1397]: Error: getaddrinfo -3007 Oct 13 17:36:33 volumio volumio[1397]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Oct 13 17:36:33 volumio volumio[1397]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Oct 13 17:36:33 volumio volumio[1397]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Oct 13 17:36:33 volumio volumio[1397]: code: -3007, Oct 13 17:36:33 volumio volumio[1397]: errno: -3007, Oct 13 17:36:33 volumio volumio[1397]: syscall: 'getaddrinfo' Oct 13 17:36:33 volumio volumio[1397]: } Oct 13 17:36:33 volumio volumio[1397]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 17:36:33 volumio sudo[2618]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 17:35' Oct 13 17:36:33 volumio sudo[2618]: 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"