May 26 01:24:00 volumio volumio-remote-updater[679]: [2026-05-26 01:24:00] [info] asio async_connect error: asio.system:111 (Connection refused) May 26 01:24:00 volumio volumio-remote-updater[679]: [2026-05-26 01:24:00] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) May 26 01:24:00 volumio volumio-remote-updater[679]: [2026-05-26 01:24:00] [error] handle_connect error: Connection refused May 26 01:24:00 volumio wireless.js[680]: WIRELESS.JS - INFO: WARNING: dhcpcd running but no IP assigned yet May 26 01:24:00 volumio wireless.js[680]: WIRELESS.JS - INFO: trying... May 26 01:24:00 volumio sudo[1198]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 26 01:24:00 volumio sudo[1198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:24:00 volumio sudo[1198]: pam_unix(sudo:session): session closed for user root May 26 01:24:01 volumio sudo[1201]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 01:24:01 volumio sudo[1201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 01:24:01 volumio sudo[1201]: pam_unix(sudo:session): session closed for user root May 26 01:24:01 volumio wireless.js[680]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined May 26 01:24:01 volumio wireless.js[680]: WIRELESS.JS - INFO: trying... May 26 01:24:01 volumio sudo[1212]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 26 01:24:01 volumio sudo[1212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:24:01 volumio sudo[1212]: pam_unix(sudo:session): session closed for user root May 26 01:24:02 volumio sudo[1215]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 01:24:02 volumio sudo[1215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 01:24:02 volumio sudo[1215]: pam_unix(sudo:session): session closed for user root May 26 01:24:02 volumio wireless.js[680]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined May 26 01:24:02 volumio wireless.js[680]: WIRELESS.JS - INFO: trying... May 26 01:24:02 volumio dhcpcd[774]: wlan0: leased 192.168.1.19 for 86400 seconds May 26 01:24:02 volumio avahi-daemon[660]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.19. May 26 01:24:02 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. May 26 01:24:02 volumio avahi-daemon[660]: New relevant interface wlan0.IPv4 for mDNS. May 26 01:24:02 volumio avahi-daemon[660]: Registering new address record for 192.168.1.19 on wlan0.IPv4. May 26 01:24:02 volumio dhcpcd[774]: wlan0: adding route to 192.168.1.0/24 May 26 01:24:02 volumio dhcpcd[774]: wlan0: adding default route via 192.168.1.1 May 26 01:24:02 volumio systemd[1]: welcome.service: Deactivated successfully. May 26 01:24:02 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 26 01:24:02 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 26 01:24:02 volumio nmbd[1000]: [2026/05/26 01:24:02.938064, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 26 01:24:02 volumio nmbd[1000]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.19 for name WORKGROUP<1d>. May 26 01:24:02 volumio nmbd[1000]: This response was from IP 192.168.1.101, reporting an IP address of 192.168.1.101. May 26 01:24:02 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 26 01:24:02 volumio systemd[1]: Started nmbd.service - Samba NMB Daemon. May 26 01:24:02 volumio welcome[1236]: Resolved ip:[1] 192.168.1.19 May 26 01:24:03 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... May 26 01:24:03 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 26 01:24:03 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 26 01:24:03 volumio sudo[1254]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 26 01:24:03 volumio sudo[1254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:24:03 volumio sudo[1254]: pam_unix(sudo:session): session closed for user root May 26 01:24:03 volumio wireless.js[680]: WIRELESS.JS - INFO: trying... May 26 01:24:03 volumio sudo[1258]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 01:24:03 volumio sudo[1258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 01:24:03 volumio sudo[1258]: pam_unix(sudo:session): session closed for user root May 26 01:24:04 volumio winbindd[1259]: [2026/05/26 01:24:04.360296, 0] ../../source3/winbindd/winbindd.c:1440(main) May 26 01:24:04 volumio winbindd[1259]: winbindd version 4.17.8-Raspbian started. May 26 01:24:04 volumio winbindd[1259]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 26 01:24:04 volumio winbindd[1259]: [2026/05/26 01:24:04.383724, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) May 26 01:24:04 volumio winbindd[1259]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 26 01:24:04 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. May 26 01:24:04 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... May 26 01:24:04 volumio sudo[1270]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 26 01:24:04 volumio sudo[1270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:24:04 volumio sudo[1270]: pam_unix(sudo:session): session closed for user root May 26 01:24:04 volumio ntpd[846]: IO: Listen normally on 3 wlan0 192.168.1.19:123 May 26 01:24:04 volumio ntpd[846]: IO: new interface(s) found: waking up resolver May 26 01:24:04 volumio ntpd[846]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 May 26 01:24:04 volumio wireless.js[680]: WIRELESS.JS - INFO: ... wlan0 IPv4 is 192.168.1.19, ipV6 is undefined May 26 01:24:04 volumio wireless.js[680]: WIRELESS.JS - INFO: Connected to SSID: F660P-6t65-A May 26 01:24:04 volumio wireless.js[680]: WIRELESS.JS - INFO: It's done! AP May 26 01:24:04 volumio wireless.js[680]: WIRELESS.JS - INFO: Restarting avahi-daemon... May 26 01:24:04 volumio sudo[1279]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 01:24:04 volumio sudo[1279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 01:24:04 volumio sudo[1279]: pam_unix(sudo:session): session closed for user root May 26 01:24:04 volumio sudo[1283]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon May 26 01:24:04 volumio sudo[1283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 01:24:05 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 01:24:05 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 26 01:24:05 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 01:24:05 volumio systemd[1]: shairport-sync.service: Consumed 1.654s CPU time. May 26 01:24:05 volumio avahi-daemon[660]: Got SIGTERM, quitting. May 26 01:24:05 volumio avahi-daemon[660]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.19. May 26 01:24:05 volumio ntpd[846]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 May 26 01:24:05 volumio ntpd[846]: DNS: Pool taking: 45.76.211.39 May 26 01:24:05 volumio avahi-daemon[660]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 26 01:24:05 volumio ntpd[846]: DNS: Pool taking: 160.16.113.133 May 26 01:24:05 volumio ntpd[846]: DNS: Pool taking: 162.159.200.123 May 26 01:24:05 volumio ntpd[846]: DNS: Pool taking: 129.250.35.250 May 26 01:24:05 volumio ntpd[846]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 May 26 01:24:05 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... May 26 01:24:05 volumio avahi-daemon[660]: avahi-daemon 0.8 exiting. May 26 01:24:05 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. May 26 01:24:05 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. May 26 01:24:05 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... May 26 01:24:05 volumio avahi-daemon[1298]: Process 660 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) May 26 01:24:05 volumio avahi-daemon[1298]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). May 26 01:24:05 volumio avahi-daemon[1298]: Successfully dropped root privileges. May 26 01:24:05 volumio avahi-daemon[1298]: avahi-daemon 0.8 starting up. May 26 01:24:05 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. May 26 01:24:05 volumio avahi-daemon[1298]: Successfully called chroot(). May 26 01:24:05 volumio avahi-daemon[1298]: Successfully dropped remaining capabilities. May 26 01:24:05 volumio avahi-daemon[1298]: No service file found in /etc/avahi/services. May 26 01:24:05 volumio avahi-daemon[1298]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.19. May 26 01:24:05 volumio avahi-daemon[1298]: New relevant interface wlan0.IPv4 for mDNS. May 26 01:24:05 volumio avahi-daemon[1298]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 26 01:24:05 volumio avahi-daemon[1298]: New relevant interface lo.IPv4 for mDNS. May 26 01:24:05 volumio avahi-daemon[1298]: Network interface enumeration completed. May 26 01:24:05 volumio avahi-daemon[1298]: Registering new address record for 192.168.1.19 on wlan0.IPv4. May 26 01:24:05 volumio avahi-daemon[1298]: Registering new address record for 127.0.0.1 on lo.IPv4. May 26 01:24:05 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 01:24:05 volumio sudo[1283]: pam_unix(sudo:session): session closed for user root May 26 01:24:05 volumio ntpd[846]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 May 26 01:24:05 volumio wireless.js[680]: WIRELESS.JS - INFO: ... wlan0 IPv4 is 192.168.1.19, ipV6 is undefined May 26 01:24:05 volumio wireless.js[680]: WIRELESS.JS - INFO: Connected to SSID: F660P-6t65-A May 26 01:24:05 volumio wireless.js[680]: WIRELESS.JS - INFO: It's done! AP May 26 01:24:05 volumio wireless.js[680]: WIRELESS.JS - INFO: Restarting avahi-daemon... May 26 01:24:05 volumio smbd[1287]: [2026/05/26 01:24:05.796882, 0] ../../source3/smbd/server.c:1741(main) May 26 01:24:05 volumio smbd[1287]: smbd version 4.17.8-Raspbian started. May 26 01:24:05 volumio smbd[1287]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 26 01:24:05 volumio sudo[1319]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon May 26 01:24:05 volumio sudo[1319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 01:24:06 volumio ntpd[846]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 May 26 01:24:06 volumio ntpd[846]: DNS: Pool taking: 208.88.66.8 May 26 01:24:06 volumio ntpd[846]: DNS: Pool taking: 162.159.200.1 May 26 01:24:06 volumio ntpd[846]: DNS: Pool taking: 139.162.81.45 May 26 01:24:06 volumio ntpd[846]: DNS: Pool taking: 142.91.108.61 May 26 01:24:06 volumio ntpd[846]: DNS: Pool taking: 2a0a:6040:977b:a::a18c:4fb3 May 26 01:24:06 volumio ntpd[846]: DNS: Pool taking: 2406:da14:10c4:9aa0:123:: May 26 01:24:06 volumio ntpd[846]: DNS: Pool taking: 2001:678:8::123 May 26 01:24:06 volumio ntpd[846]: DNS: Pool taking: 2401:d560:0:105:1c00:daff:fe00:219 May 26 01:24:06 volumio ntpd[846]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 May 26 01:24:06 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 01:24:06 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 26 01:24:06 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 01:24:06 volumio avahi-daemon[1298]: Got SIGTERM, quitting. May 26 01:24:06 volumio avahi-daemon[1298]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.19. May 26 01:24:06 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... May 26 01:24:06 volumio avahi-daemon[1298]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 26 01:24:06 volumio avahi-daemon[1298]: avahi-daemon 0.8 exiting. May 26 01:24:06 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. May 26 01:24:06 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. May 26 01:24:06 volumio sh[659]: timed out May 26 01:24:06 volumio dhcpcd[666]: timed out May 26 01:24:06 volumio sh[633]: ifup: failed to bring up eth0 May 26 01:24:06 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... May 26 01:24:06 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 26 01:24:06 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 26 01:24:06 volumio avahi-daemon[1326]: Process 1298 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) May 26 01:24:06 volumio avahi-daemon[1326]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). May 26 01:24:06 volumio avahi-daemon[1326]: Successfully dropped root privileges. May 26 01:24:06 volumio avahi-daemon[1326]: avahi-daemon 0.8 starting up. May 26 01:24:06 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. May 26 01:24:06 volumio avahi-daemon[1326]: Successfully called chroot(). May 26 01:24:06 volumio avahi-daemon[1326]: Successfully dropped remaining capabilities. May 26 01:24:06 volumio avahi-daemon[1326]: No service file found in /etc/avahi/services. May 26 01:24:06 volumio avahi-daemon[1326]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.19. May 26 01:24:06 volumio avahi-daemon[1326]: New relevant interface wlan0.IPv4 for mDNS. May 26 01:24:06 volumio avahi-daemon[1326]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 26 01:24:06 volumio avahi-daemon[1326]: New relevant interface lo.IPv4 for mDNS. May 26 01:24:06 volumio avahi-daemon[1326]: Network interface enumeration completed. May 26 01:24:06 volumio sudo[1319]: pam_unix(sudo:session): session closed for user root May 26 01:24:06 volumio avahi-daemon[1326]: Registering new address record for 192.168.1.19 on wlan0.IPv4. May 26 01:24:06 volumio avahi-daemon[1326]: Registering new address record for 127.0.0.1 on lo.IPv4. May 26 01:24:06 volumio ntpd[846]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 May 26 01:24:06 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 01:24:06 volumio wireless.js[680]: WIRELESS.JS - INFO: Notified systemd about wireless ready May 26 01:24:06 volumio wireless.js[680]: WIRELESS.JS - INFO: Notified systemd about wireless ready May 26 01:24:07 volumio ntpd[846]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 May 26 01:24:07 volumio ntpd[846]: DNS: Pool taking: 172.105.192.74 May 26 01:24:07 volumio ntpd[846]: DNS: Pool taking: 167.179.119.205 May 26 01:24:07 volumio ntpd[846]: DNS: Pool taking: 172.104.124.149 May 26 01:24:07 volumio ntpd[846]: DNS: Pool skipping: 142.91.108.61 May 26 01:24:07 volumio ntpd[846]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 May 26 01:24:07 volumio avahi-daemon[1326]: Server startup complete. Host name is volumio.local. Local service cookie is 2734342686. May 26 01:24:07 volumio ntpd[846]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 26 01:24:08 volumio ntpd[846]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 26 01:24:08 volumio ntpd[846]: DNS: Pool taking: 85.121.50.82 May 26 01:24:08 volumio ntpd[846]: DNS: Pool skipping: 45.76.211.39 May 26 01:24:08 volumio ntpd[846]: DNS: Pool skipping: 162.159.200.123 May 26 01:24:08 volumio ntpd[846]: DNS: Pool skipping: 129.250.35.250 May 26 01:24:08 volumio ntpd[846]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 May 26 01:24:08 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. May 26 01:24:08 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 26 01:24:08 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 26 01:24:08 volumio upmpdcli[1352]: Could not open config: /tmp/upmpdcli.conf May 26 01:24:08 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 26 01:24:08 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 26 01:24:09 volumio sshd[1225]: Accepted password for volumio from 192.168.1.13 port 59259 ssh2 May 26 01:24:09 volumio sshd[1225]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) May 26 01:24:09 volumio systemd-logind[674]: New session 1 of user volumio. May 26 01:24:09 volumio systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. May 26 01:24:09 volumio systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... May 26 01:24:10 volumio systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. May 26 01:24:10 volumio systemd[1]: Starting user@1000.service - User Manager for UID 1000... May 26 01:24:10 volumio (systemd)[1361]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) May 26 01:24:10 volumio volumio-remote-updater[679]: [2026-05-26 01:24:10] [connect] Successful connection May 26 01:24:10 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. May 26 01:24:10 volumio systemd[1]: Reached target multi-user.target - Multi-User System. May 26 01:24:10 volumio systemd[1]: Reached target graphical.target - Graphical Interface. May 26 01:24:10 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... May 26 01:24:35 volumio ntpd[846]: CLOCK: time stepped by 25.048273 May 26 01:24:35 volumio ntpd[846]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 26 01:24:35 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. May 26 01:24:35 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. May 26 01:24:35 volumio volumio[1133]: info: ------------------------------------------- May 26 01:24:35 volumio volumio[1133]: info: ----- Volumio3 ---- May 26 01:24:35 volumio volumio[1133]: info: ------------------------------------------- May 26 01:24:35 volumio volumio[1133]: info: ----- System startup ---- May 26 01:24:35 volumio volumio[1133]: info: ------------------------------------------- May 26 01:24:36 volumio systemd[1361]: Queued start job for default target default.target. May 26 01:24:36 volumio systemd[1361]: Created slice app.slice - User Application Slice. May 26 01:24:36 volumio systemd[1361]: Reached target paths.target - Paths. May 26 01:24:36 volumio systemd[1361]: Reached target timers.target - Timers. May 26 01:24:36 volumio systemd[1361]: Listening on dirmngr.socket - GnuPG network certificate management daemon. May 26 01:24:36 volumio systemd[1361]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). May 26 01:24:36 volumio systemd[1361]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). May 26 01:24:36 volumio systemd[1361]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). May 26 01:24:36 volumio systemd[1361]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. May 26 01:24:36 volumio systemd[1361]: Reached target sockets.target - Sockets. May 26 01:24:36 volumio systemd[1361]: Reached target basic.target - Basic System. May 26 01:24:36 volumio systemd[1]: Started user@1000.service - User Manager for UID 1000. May 26 01:24:36 volumio systemd[1361]: Started mpris-proxy.service - Bluetooth mpris proxy. May 26 01:24:36 volumio systemd[1361]: Reached target default.target - Main User Target. May 26 01:24:36 volumio systemd[1361]: Startup finished in 1.546s. May 26 01:24:36 volumio systemd[1]: Started session-1.scope - Session 1 of User volumio. May 26 01:24:36 volumio systemd[1]: Startup finished in 15.676s (kernel) + 44.068s (userspace) = 59.745s. May 26 01:24:36 volumio mpris-proxy[1381]: Can't get on session bus May 26 01:24:36 volumio systemd[1361]: mpris-proxy.service: Main process exited, code=exited, status=1/FAILURE May 26 01:24:36 volumio systemd[1361]: mpris-proxy.service: Failed with result 'exit-code'. May 26 01:24:37 volumio sshd[1225]: pam_env(sshd:session): deprecated reading of user environment enabled May 26 01:24:38 volumio volumio[1133]: info: MYVOLUMIO Environment detected May 26 01:24:38 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 26 01:24:38 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 26 01:24:38 volumio volumio[1133]: info: Plugin folders cleanup May 26 01:24:38 volumio volumio[1133]: info: Scanning into folder /volumio/app/plugins/ May 26 01:24:38 volumio volumio[1133]: info: Scanning category audio_interface May 26 01:24:38 volumio volumio[1133]: info: Scanning category miscellanea May 26 01:24:38 volumio volumio[1133]: info: Scanning category music_service May 26 01:24:38 volumio volumio[1133]: info: Scanning category plugins.json May 26 01:24:38 volumio volumio[1133]: info: Scanning category system_controller May 26 01:24:38 volumio volumio[1133]: info: Scanning category user_interface May 26 01:24:38 volumio volumio[1133]: info: Scanning into folder /data/plugins/ May 26 01:24:38 volumio volumio[1133]: info: Scanning category audio_interface May 26 01:24:38 volumio volumio[1133]: info: Scanning category music_service May 26 01:24:38 volumio volumio[1133]: info: Plugin folders cleanup completed May 26 01:24:38 volumio volumio[1133]: info: ------------------------------------------- May 26 01:24:38 volumio volumio[1133]: info: ----- Core plugins startup ---- May 26 01:24:38 volumio volumio[1133]: info: ------------------------------------------- May 26 01:24:38 volumio volumio[1133]: info: Loading plugins from folder /volumio/app/plugins/ May 26 01:24:38 volumio volumio[1133]: info: Adding plugin upnp to MyMusic Plugins May 26 01:24:38 volumio volumio[1133]: info: Adding plugin airplay_emulation to MyMusic Plugins May 26 01:24:38 volumio volumio[1133]: info: Adding plugin upnp_browser to MyMusic Plugins May 26 01:24:38 volumio volumio[1133]: info: Loading plugins from folder /data/plugins/ May 26 01:24:38 volumio volumio[1133]: info: Loading plugin "system"... May 26 01:24:39 volumio volumio[1133]: info: Loading plugin "appearance"... May 26 01:24:42 volumio volumio[1133]: info: Loading plugin "network"... May 26 01:24:42 volumio volumio[1133]: info: Refreshing Cached IP Addresses May 26 01:24:42 volumio volumio[1133]: info: Loading plugin "services"... May 26 01:24:42 volumio sudo[1421]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 01:24:42 volumio sudo[1423]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 01:24:42 volumio volumio[1133]: info: Loading plugin "volumio5onboarding"... May 26 01:24:42 volumio sudo[1423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:24:42 volumio sudo[1423]: pam_unix(sudo:session): session closed for user root May 26 01:24:42 volumio sudo[1421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:24:42 volumio sudo[1431]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 26 01:24:42 volumio sudo[1431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:24:42 volumio sudo[1421]: pam_unix(sudo:session): session closed for user root May 26 01:24:42 volumio volumio[1133]: info: Loading plugin "alsa_controller"... May 26 01:24:42 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 01:24:42 volumio volumio[1133]: info: Loading plugin "wizard"... May 26 01:24:42 volumio volumio[1133]: info: Loading plugin "networkfs"... May 26 01:24:42 volumio volumio[1133]: info: Starting Udev Watcher for removable devices May 26 01:24:43 volumio volumio[1133]: info: Ignoring mount for partition: boot May 26 01:24:43 volumio volumio[1133]: info: Ignoring mount for partition: volumio May 26 01:24:43 volumio volumio[1133]: info: Ignoring mount for partition: volumio_data May 26 01:24:43 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 26 01:24:43 volumio volumio[1133]: info: Loading plugin "volumio_command_line_client"... May 26 01:24:43 volumio sudo[1452]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=volumio,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.1 //Living.local/NAS /mnt/NAS/NAS May 26 01:24:43 volumio sudo[1452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:24:43 volumio volumio[1133]: info: Loading plugin "upnp"... May 26 01:24:43 volumio volumio[1133]: info: [1779726283090] Starting Upmpd Daemon May 26 01:24:43 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 26 01:24:43 volumio volumio[1133]: info: Loading plugin "my_music"... May 26 01:24:43 volumio volumio[1133]: info: Loading plugin "mpd"... May 26 01:24:43 volumio volumio[1133]: info: Loading plugin "upnp_browser"... May 26 01:24:45 volumio kernel: netfs: FS-Cache loaded May 26 01:24:45 volumio sudo[1431]: pam_unix(sudo:session): session closed for user root May 26 01:24:46 volumio kernel: Key type cifs.spnego registered May 26 01:24:46 volumio kernel: Key type cifs.idmap registered May 26 01:24:46 volumio kernel: CIFS: Attempting to mount //Living.local/NAS May 26 01:24:46 volumio sudo[1452]: pam_unix(sudo:session): session closed for user root May 26 01:24:48 volumio volumio[1133]: info: Starting UPNP Browser May 26 01:24:48 volumio volumio[1133]: info: Loading plugin "alarm-clock"... May 26 01:24:49 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. May 26 01:24:49 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 26 01:24:49 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 26 01:24:49 volumio volumio[1133]: info: Loading plugin "airplay_emulation"... May 26 01:24:49 volumio volumio[1133]: info: Starting Shairport Sync May 26 01:24:49 volumio volumio[1133]: info: Loading plugin "last_100"... May 26 01:24:49 volumio volumio[1133]: info: Loading plugin "webradio"... May 26 01:24:49 volumio upmpdcli[1485]: Could not open config: /tmp/upmpdcli.conf May 26 01:24:49 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 26 01:24:49 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 26 01:24:49 volumio volumio[1133]: info: Loading plugin "i2s_dacs"... May 26 01:24:49 volumio volumio[1133]: info: Loading plugin "volumiodiscovery"... May 26 01:24:49 volumio volumio[1133]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 26 01:24:49 volumio node[1133]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 26 01:24:49 volumio volumio[1133]: *** WARNING *** Please fix your application to use the native API of Avahi! May 26 01:24:49 volumio volumio[1133]: *** WARNING *** For more information see May 26 01:24:49 volumio volumio[1133]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 26 01:24:49 volumio volumio[1133]: *** WARNING *** Please fix your application to use the native API of Avahi! May 26 01:24:49 volumio volumio[1133]: *** WARNING *** For more information see May 26 01:24:49 volumio node[1133]: *** WARNING *** Please fix your application to use the native API of Avahi! May 26 01:24:49 volumio node[1133]: *** WARNING *** For more information see May 26 01:24:49 volumio node[1133]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 26 01:24:49 volumio node[1133]: *** WARNING *** Please fix your application to use the native API of Avahi! May 26 01:24:49 volumio node[1133]: *** WARNING *** For more information see May 26 01:24:49 volumio volumio[1133]: info: Applying required configuration parameters for plugin volumiodiscovery May 26 01:24:49 volumio volumio[1133]: info: Discovery: Started advertising with name: Volumio May 26 01:24:50 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. May 26 01:24:50 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 26 01:24:50 volumio systemd[1]: setdatetime-helper.service: Consumed 2.127s CPU time. May 26 01:24:50 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 26 01:24:50 volumio volumio[1133]: info: Loading plugin "spop"... May 26 01:24:50 volumio volumio-remote-updater[679]: [2026-05-26 01:24:50] [connect] Successful connection May 26 01:24:53 volumio volumio[1133]: info: Loading plugin "outputs"... May 26 01:24:53 volumio volumio[1133]: info: Loading plugin "albumart"... May 26 01:24:53 volumio volumio[1133]: info: Plugin example_plugin is not enabled May 26 01:24:53 volumio volumio[1133]: info: Loading plugin "inputs"... May 26 01:24:53 volumio volumio[1133]: info: Loading plugin "updater_comm"... May 26 01:24:54 volumio volumio[1133]: info: Plugin mpdemulation is not enabled May 26 01:24:54 volumio volumio[1133]: info: Loading plugin "rest_api"... May 26 01:24:54 volumio volumio[1133]: info: Loading plugin "websocket"... May 26 01:24:54 volumio volumio[1133]: info: Starting Socket.io Server version 1.7.4 May 26 01:24:54 volumio volumio[1133]: info: Loading plugin "fusiondsp"... May 26 01:24:56 volumio volumio[1133]: info: Applying required configuration parameters for plugin fusiondsp May 26 01:24:56 volumio volumio[1506]: Forking 3 albumart workers May 26 01:24:57 volumio volumio[1133]: info: Loading i18n strings for locale ja May 26 01:24:57 volumio volumio[1133]: Updating browse sources language May 26 01:24:57 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 01:24:57 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 01:24:57 volumio volumio[1133]: info: CoreCommandRouter::initPlayerControls May 26 01:24:57 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:24:57 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:24:57 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:24:57 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:24:57 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:24:57 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:24:57 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:24:57 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:24:57 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 01:24:57 volumio volumio[1133]: Express server listening on port 3000 May 26 01:24:57 volumio volumio[1133]: [Metrics] WebUI: 26s 452.34ms May 26 01:24:58 volumio volumio[1133]: info: CoreStateMachine::resetVolumioState May 26 01:24:58 volumio volumio[1133]: info: CoreStateMachine::getcurrentVolume May 26 01:24:58 volumio volumio[1133]: info: CoreCommandRouter::volumioRetrievevolume May 26 01:24:58 volumio volumio[1133]: info: CoreStateMachine::pushState May 26 01:24:58 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:24:58 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 01:24:58 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState May 26 01:24:58 volumio sudo[1550]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 01:24:58 volumio sudo[1550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:24:58 volumio sudo[1550]: pam_unix(sudo:session): session closed for user root May 26 01:24:58 volumio volumio[1133]: info: Cannot read play queue from file May 26 01:24:58 volumio sudo[1552]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 01:24:58 volumio sudo[1552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:24:58 volumio sudo[1552]: pam_unix(sudo:session): session closed for user root May 26 01:24:59 volumio volumio[1133]: info: Volumio Network Manager: Network status updated: 2 May 26 01:24:59 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 May 26 01:24:59 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 May 26 01:25:00 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 01:25:00 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 May 26 01:25:00 volumio volumio[1133]: info: CoreStateMachine::setRepeat null single undefined May 26 01:25:00 volumio volumio[1133]: info: CoreStateMachine::pushState May 26 01:25:00 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:00 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState May 26 01:25:00 volumio volumio[1133]: info: CoreStateMachine::setRandom null May 26 01:25:00 volumio volumio[1133]: info: CoreStateMachine::pushState May 26 01:25:00 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:00 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState May 26 01:25:00 volumio volumio[1133]: info: Setting Device type: Raspberry PI May 26 01:25:00 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.6 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 26 01:25:00 volumio volumio[1133]: info: Completed loading Core Plugins May 26 01:25:00 volumio volumio[1133]: info: Preparing to generate the ALSA configuration file May 26 01:25:01 volumio volumio[1133]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 26 01:25:01 volumio volumio[1133]: info: Reading ALSA contributions from plugins. May 26 01:25:01 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.14 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 26 01:25:01 volumio sudo[1564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 26 01:25:01 volumio sudo[1564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:25:01 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 May 26 01:25:01 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7 May 26 01:25:01 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 8 May 26 01:25:02 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 9 May 26 01:25:02 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 10 May 26 01:25:02 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 11 May 26 01:25:02 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 12 May 26 01:25:02 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.17 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 May 26 01:25:02 volumio volumio[1133]: info: Discovery: adding 85e7ceb3-6efe-47be-a8f4-7cb215acfd98 May 26 01:25:02 volumio volumio[1133]: info: Discovery: Found device Volumio May 26 01:25:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState May 26 01:25:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:02 volumio volumio[1133]: info: Discovery: this is already registered, 85e7ceb3-6efe-47be-a8f4-7cb215acfd98 May 26 01:25:02 volumio volumio[1133]: info: Discovery: Found device Volumio May 26 01:25:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState May 26 01:25:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:03 volumio volumio[1133]: info: Discovery: adding 6da077c2-714c-48dc-ae21-367c3a201897 May 26 01:25:03 volumio volumio[1133]: info: Discovery: Found device Living May 26 01:25:03 volumio volumio[1133]: info: Discovery: Connecting to remote: 192.168.1.14 May 26 01:25:04 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 14 May 26 01:25:04 volumio volumio[1133]: info: Discovery: adding b4ffa1ef-478e-4510-9c74-3178853e6a81 May 26 01:25:04 volumio volumio[1133]: info: Discovery: Found device Workshop May 26 01:25:04 volumio volumio[1133]: info: Discovery: Connecting to remote: 192.168.1.17 May 26 01:25:04 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 15 May 26 01:25:04 volumio volumio[1133]: info: Discovery: adding 1a76064b-ddb9-49c1-8a0d-36057336e484 May 26 01:25:04 volumio volumio[1133]: info: Discovery: Found device Bedroom May 26 01:25:04 volumio volumio[1133]: info: Discovery: Connecting to remote: 192.168.1.6 May 26 01:25:04 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 16 May 26 01:25:04 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. May 26 01:25:04 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 26 01:25:04 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 26 01:25:04 volumio sudo[1564]: pam_unix(sudo:session): session closed for user root May 26 01:25:04 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 17 May 26 01:25:05 volumio volumio[1133]: info: Discovery: Connected to remote: 192.168.1.14 May 26 01:25:05 volumio volumio[1517]: Starting albumart workers May 26 01:25:05 volumio volumio-remote-updater[679]: [2026-05-26 01:25:05] [connect] Successful connection May 26 01:25:05 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 18 May 26 01:25:05 volumio volumio[1133]: info: Discovery: Connected to remote: 192.168.1.6 May 26 01:25:05 volumio volumio[1133]: info: Discovery: Connected to remote: 192.168.1.17 May 26 01:25:06 volumio volumio[1133]: info: Upmpdcli Daemon Started May 26 01:25:06 volumio volumio[1133]: info: Asound.conf file written May 26 01:25:06 volumio sudo[1596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 26 01:25:06 volumio sudo[1596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:25:06 volumio sudo[1596]: pam_unix(sudo:session): session closed for user root May 26 01:25:06 volumio volumio[1133]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 26 01:25:06 volumio volumio[1133]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 26 01:25:06 volumio volumio[1133]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 26 01:25:06 volumio volumio[1133]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 May 26 01:25:06 volumio volumio[1133]: info: Output device has changed, restarting MPD May 26 01:25:06 volumio volumio[1133]: info: Output device has changed, restarting Shairport Sync May 26 01:25:06 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:06 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 01:25:07 volumio sudo[1604]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 01:25:07 volumio sudo[1604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:25:07 volumio sudo[1602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 01:25:07 volumio sudo[1602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:25:07 volumio volumio[1133]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 01:25:07 volumio volumio[1133]: info: ___________ START PLUGINS ___________ May 26 01:25:07 volumio sudo[1602]: pam_unix(sudo:session): session closed for user root May 26 01:25:07 volumio volumio[1133]: info: ControllerMpd::onStart: Initializing MPD May 26 01:25:07 volumio volumio[1133]: info: Creating MPD Configuration file May 26 01:25:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 01:25:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 26 01:25:07 volumio sudo[1613]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service May 26 01:25:07 volumio sudo[1613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:25:07 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 01:25:07 volumio volumio[1133]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 01:25:07 volumio volumio[1133]: info: [1779726307504] CoreMusicLibrary::Adding element DLNAサーバー May 26 01:25:07 volumio sudo[1616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 01:25:07 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 01:25:07 volumio sudo[1616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:25:07 volumio sudo[1616]: pam_unix(sudo:session): session closed for user root May 26 01:25:07 volumio volumio[1133]: info: UPNP Browser: Client initialized successfully May 26 01:25:07 volumio volumio[1518]: Starting albumart workers May 26 01:25:07 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:07 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 01:25:07 volumio sudo[1618]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 01:25:07 volumio sudo[1618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:25:07 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 26 01:25:07 volumio sudo[1613]: pam_unix(sudo:session): session closed for user root May 26 01:25:07 volumio volumio[1133]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 01:25:07 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:07 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 01:25:08 volumio volumio[1516]: Starting albumart workers May 26 01:25:08 volumio systemd[1]: mpd.service: Deactivated successfully. May 26 01:25:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 26 01:25:08 volumio systemd[1]: mpd.socket: Deactivated successfully. May 26 01:25:08 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 26 01:25:08 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 26 01:25:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 01:25:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 26 01:25:08 volumio volumio[1133]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 01:25:08 volumio volumio[1133]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 01:25:08 volumio volumio[1133]: info: [1779726308439] CoreMusicLibrary::Adding element Last_100 May 26 01:25:08 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 01:25:08 volumio volumio[1133]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 01:25:08 volumio volumio[1133]: info: [1779726308553] CoreMusicLibrary::Adding element Webradio May 26 01:25:08 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 01:25:08 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 01:25:08 volumio volumio[1133]: info: Initializing BBC Radios May 26 01:25:09 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 01:25:09 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 01:25:09 volumio volumio[1133]: info: Creating Spotify config file May 26 01:25:09 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:09 volumio volumio[1133]: info: FusionDsp - CamillaDSP binary found. May 26 01:25:09 volumio volumio[1133]: info: Loading i18n strings for locale ja May 26 01:25:10 volumio volumio5-onboarding[1621]: time=2026-05-26T01:25:10.066+09:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z May 26 01:25:10 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 26 01:25:10 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:10 volumio volumio[1133]: info: FusionDsp - mixtype--------------------- None May 26 01:25:10 volumio volumio[1133]: info: Volumio Calling Home May 26 01:25:10 volumio volumio[1133]: info: Preparing to generate the ALSA configuration file May 26 01:25:11 volumio sudo[1632]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 26 01:25:11 volumio sudo[1632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 01:25:11 volumio sudo[1661]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 26 01:25:11 volumio sudo[1632]: pam_unix(sudo:session): session closed for user root May 26 01:25:15 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 19 May 26 01:25:15 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 20 May 26 01:25:16 volumio volumio[1133]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 26 01:25:16 volumio volumio[1133]: info: Reading ALSA contributions from plugins. May 26 01:25:16 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 21 May 26 01:25:16 volumio volumio[1133]: info: MPD Permissions set May 26 01:25:16 volumio volumio[1133]: info: MPD Permissions set May 26 01:25:17 volumio volumio[1133]: info: Discovery: Disconnected from remote: 192.168.1.14 May 26 01:25:17 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 22 May 26 01:25:17 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 23 May 26 01:25:17 volumio volumio[1133]: info: Spotify config file written May 26 01:25:17 volumio sudo[1692]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 26 01:25:17 volumio sudo[1692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:25:17 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 01:25:17 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 01:25:17 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 24 May 26 01:25:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:17 volumio go-librespot[1694]: go-librespot daemon starting... May 26 01:25:17 volumio go-librespot[1695]: /bin/start-go-librespot.sh: 7: /usr/bin/go-librespot: not found May 26 01:25:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 26 01:25:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 01:25:17 volumio sudo[1692]: pam_unix(sudo:session): session closed for user root May 26 01:25:17 volumio volumio[1133]: info: Volumio called home May 26 01:25:17 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:17 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:17 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:17 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:17 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:17 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:17 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:17 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:18 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 01:25:18 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:18 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:18 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:18 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:18 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:18 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:18 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:18 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:18 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:18 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 01:25:18 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:18 volumio volumio[1133]: info: No need to fix Spotify hosts May 26 01:25:20 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.14 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 May 26 01:25:20 volumio volumio[1133]: info: Discovery: Disconnected from remote: 192.168.1.6 May 26 01:25:20 volumio volumio5-onboarding[1621]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:41626->127.0.0.1:3000: i/o timeout May 26 01:25:20 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE May 26 01:25:20 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. May 26 01:25:20 volumio volumio[1133]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 26 01:25:20 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. May 26 01:25:20 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. May 26 01:25:20 volumio volumio-remote-updater[679]: [2026-05-26 01:25:20] [connect] Successful connection May 26 01:25:20 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 26 01:25:20 volumio volumio[1133]: info: camilladsp service started and running in background, instance 1 May 26 01:25:20 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:20 volumio volumio5-onboarding[1713]: time=2026-05-26T01:25:20.727+09:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z May 26 01:25:20 volumio volumio[1133]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA May 26 01:25:20 volumio volumio[1133]: info: FusionDsp loaded May 26 01:25:20 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 01:25:20 volumio volumio[1133]: info: FusionDsp - --[object Object] May 26 01:25:20 volumio volumio[1133]: error: FusionDsp - Socket connection not established May 26 01:25:20 volumio volumio[1133]: info: FusionDsp - Reporting Fusion DSP Enabled May 26 01:25:20 volumio volumio[1133]: info: Adding Signal Path Element [object Object] May 26 01:25:20 volumio volumio[1133]: info: Adding fusiondspeq DSP Signal Path Element May 26 01:25:20 volumio volumio[1133]: info: FusionDsp - ---- installed callbackRead (debounced 200ms) May 26 01:25:20 volumio volumio[1133]: info: FusionDsp - PEQ graph server listening on port 10015 May 26 01:25:20 volumio sudo[1726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 26 01:25:20 volumio sudo[1726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:25:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. May 26 01:25:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:20 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.6 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 May 26 01:25:20 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.17 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 27 May 26 01:25:20 volumio volumio[1133]: error: FusionDsp - Reload WebSocket error: [object Object] May 26 01:25:20 volumio volumio[1133]: info: Starting Shairport Sync May 26 01:25:21 volumio volumio[1133]: info: Starting Shairport Sync May 26 01:25:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:21 volumio go-librespot[1731]: go-librespot daemon starting... May 26 01:25:21 volumio go-librespot[1735]: /bin/start-go-librespot.sh: 7: /usr/bin/go-librespot: not found May 26 01:25:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 26 01:25:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 01:25:21 volumio volumio[1133]: info: Starting Shairport Sync May 26 01:25:21 volumio systemd[1]: Starting fusiondsp.service - FusionDsp Daemon... May 26 01:25:21 volumio sudo[1738]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 01:25:21 volumio sudo[1738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:25:21 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 28 May 26 01:25:21 volumio sudo[1733]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 01:25:21 volumio sudo[1733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:25:21 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 01:25:21 volumio sudo[1736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 01:25:21 volumio sudo[1736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:25:21 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 26 01:25:21 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 01:25:21 volumio systemd[1]: shairport-sync.service: Consumed 1.884s CPU time. May 26 01:25:21 volumio volumio[1133]: info: Discovery: Connected to remote: 192.168.1.14 May 26 01:25:21 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 01:25:21 volumio sudo[1738]: pam_unix(sudo:session): session closed for user root May 26 01:25:21 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 01:25:21 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 26 01:25:21 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 01:25:21 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 01:25:21 volumio sudo[1736]: pam_unix(sudo:session): session closed for user root May 26 01:25:21 volumio sudo[1733]: pam_unix(sudo:session): session closed for user root May 26 01:25:21 volumio volumio[1133]: info: Shairport-Sync Started May 26 01:25:21 volumio volumio[1133]: Error adding Membership: Error: addMembership EINVAL May 26 01:25:21 volumio volumio[1133]: info: Asound.conf file unchanged, so no further update is needed May 26 01:25:21 volumio volumio[1133]: info: Output device has changed, restarting MPD May 26 01:25:22 volumio volumio[1133]: info: Output device has changed, restarting Shairport Sync May 26 01:25:22 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:22 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 01:25:22 volumio sudo[1746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 01:25:22 volumio sudo[1746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:25:22 volumio sudo[1746]: pam_unix(sudo:session): session closed for user root May 26 01:25:22 volumio sudo[1748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 01:25:22 volumio sudo[1748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:25:22 volumio volumio[1133]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 01:25:22 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 29 May 26 01:25:22 volumio systemd[1]: mpd.service: Deactivated successfully. May 26 01:25:22 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 26 01:25:22 volumio systemd[1]: mpd.service: Consumed 3.631s CPU time. May 26 01:25:22 volumio systemd[1]: mpd.socket: Deactivated successfully. May 26 01:25:22 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 26 01:25:22 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 26 01:25:22 volumio volumio[1133]: info: Shairport-Sync Started May 26 01:25:22 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 01:25:22 volumio volumio[1133]: info: Shairport-Sync Started May 26 01:25:22 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 26 01:25:22 volumio volumio[1133]: error: Upnp client error: Error: read ECONNRESET May 26 01:25:22 volumio volumio[1133]: error: Upnp client error: Error: read ECONNRESET May 26 01:25:22 volumio volumio[1133]: error: Upnp client error: Error: read ECONNRESET May 26 01:25:22 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.6 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 30 May 26 01:25:22 volumio volumio[1133]: info: Discovery: Connected to remote: 192.168.1.6 May 26 01:25:22 volumio volumio[1133]: info: MPD Permissions set May 26 01:25:23 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.14 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 31 May 26 01:25:23 volumio volumio[1133]: info: go-librespot daemon successfully initialized May 26 01:25:23 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.17 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 32 May 26 01:25:23 volumio volumio[1133]: info: Discovery: Disconnected from remote: 192.168.1.14 May 26 01:25:23 volumio volumio[1133]: info: Discovery: Disconnected from remote: 192.168.1.14 May 26 01:25:23 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:23 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:23 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:23 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:23 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:23 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:23 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:23 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:23 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 01:25:23 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 01:25:23 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 33 May 26 01:25:23 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.6 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 34 May 26 01:25:23 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.14 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 35 May 26 01:25:23 volumio volumio[1133]: info: Starting Shairport Sync May 26 01:25:24 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.17 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 36 May 26 01:25:24 volumio volumio[1133]: info: Discovery: Disconnected from remote: 192.168.1.6 May 26 01:25:24 volumio volumio[1133]: info: Discovery: Disconnected from remote: 192.168.1.6 May 26 01:25:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. May 26 01:25:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:24 volumio sudo[1791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 01:25:24 volumio sudo[1791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:25:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:24 volumio go-librespot[1792]: go-librespot daemon starting... May 26 01:25:24 volumio go-librespot[1794]: /bin/start-go-librespot.sh: 7: /usr/bin/go-librespot: not found May 26 01:25:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 26 01:25:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 01:25:24 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 01:25:24 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 26 01:25:24 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 01:25:24 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 37 May 26 01:25:24 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.6 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 38 May 26 01:25:24 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 01:25:24 volumio sudo[1791]: pam_unix(sudo:session): session closed for user root May 26 01:25:24 volumio volumio[1133]: info: Discovery: Connected to remote: 192.168.1.14 May 26 01:25:24 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.14 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 39 May 26 01:25:24 volumio volumio[1133]: info: Shairport-Sync Started May 26 01:25:24 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.17 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 40 May 26 01:25:24 volumio volumio[1133]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 41 May 26 01:25:24 volumio sudo[1768]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 26 01:25:24 volumio sudo[1768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 01:25:24 volumio sudo[1809]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 26 01:25:24 volumio sudo[1768]: pam_unix(sudo:session): session closed for user root May 26 01:25:24 volumio volumio[1133]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 42 May 26 01:25:25 volumio volumio-remote-updater[679]: [2026-05-26 01:25:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1779726320 101 May 26 01:25:25 volumio volumio[1133]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 43 May 26 01:25:25 volumio volumio[1133]: info: Discovery: Disconnected from remote: 192.168.1.14 May 26 01:25:25 volumio volumio[1133]: info: Discovery: Disconnected from remote: 192.168.1.14 May 26 01:25:25 volumio volumio[1133]: info: Discovery: Disconnected from remote: 192.168.1.14 May 26 01:25:25 volumio volumio[1133]: info: Discovery: Connected to remote: 192.168.1.6 May 26 01:25:25 volumio volumio[1133]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 44 May 26 01:25:26 volumio volumio[1133]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 44 May 26 01:25:26 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 45 May 26 01:25:26 volumio volumio[1133]: info: Initializing connection to go-librespot Websocket May 26 01:25:26 volumio volumio[1133]: info: Received Get System Info May 26 01:25:26 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 01:25:26 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 01:25:26 volumio volumio[1133]: info: Discovery: Getting this device information May 26 01:25:26 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState May 26 01:25:26 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:26 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 01:25:26 volumio volumio5-onboarding[1713]: time=2026-05-26T01:25:26.338+09:00 level=INFO msg="system info for 116c8f5e91c110b69af849a73876729b" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 May 26 01:25:26 volumio volumio5-onboarding[1713]: time=2026-05-26T01:25:26.358+09:00 level=INFO msg="bootstrapping state" hasInternet=true May 26 01:25:26 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. May 26 01:25:26 volumio sudo[1726]: pam_unix(sudo:session): session closed for user root May 26 01:25:26 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.6 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 46 May 26 01:25:26 volumio volumio[1133]: info: Received Get System Info May 26 01:25:26 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 01:25:26 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 01:25:26 volumio volumio[1133]: info: Discovery: Getting this device information May 26 01:25:26 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState May 26 01:25:26 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:26 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 01:25:26 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.14 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 47 May 26 01:25:26 volumio volumio[1133]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 26 01:25:26 volumio volumio-remote-updater[679]: Test mode disabled May 26 01:25:26 volumio volumio-remote-updater[679]: Alpha mode disabled May 26 01:25:26 volumio volumio-remote-updater[679]: Alpha legacy test mode disabled May 26 01:25:26 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.17 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 48 May 26 01:25:26 volumio volumio[1133]: info: Discovery: Disconnected from remote: 192.168.1.6 May 26 01:25:26 volumio volumio[1133]: info: Discovery: Disconnected from remote: 192.168.1.6 May 26 01:25:27 volumio volumio[1133]: info: Discovery: Disconnected from remote: 192.168.1.6 May 26 01:25:27 volumio volumio[1133]: info: Discovery: Connected to remote: 192.168.1.14 May 26 01:25:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. May 26 01:25:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 26 01:25:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 01:25:27 volumio go-librespot[1816]: go-librespot daemon starting... May 26 01:25:27 volumio go-librespot[1817]: /bin/start-go-librespot.sh: 7: /usr/bin/go-librespot: not found May 26 01:25:27 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 49 May 26 01:25:28 volumio volumio[1133]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 26 01:25:28 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 26 01:25:28 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 01:25:28 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 01:25:28 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 26 01:25:28 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 01:25:29 volumio volumio[1133]: info: CoreCommandRouter::volumioGetVisibleSources May 26 01:25:29 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 01:25:29 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState May 26 01:25:29 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:29 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState May 26 01:25:29 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:29 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 26 01:25:29 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 26 01:25:29 volumio volumio[1133]: info: Received Get System Info May 26 01:25:29 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 01:25:29 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 01:25:29 volumio volumio[1133]: info: Discovery: Getting this device information May 26 01:25:29 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState May 26 01:25:29 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:29 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 01:25:29 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState May 26 01:25:29 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:29 volumio volumio[1133]: info: Listing playlists May 26 01:25:29 volumio volumio[1133]: info: Discovery: Connected to remote: 192.168.1.6 May 26 01:25:30 volumio volumio[1133]: info: Initializing connection to go-librespot Websocket May 26 01:25:30 volumio volumio[1133]: info: Received Get System Info May 26 01:25:30 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 01:25:30 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 01:25:30 volumio volumio[1133]: info: Discovery: Getting this device information May 26 01:25:30 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState May 26 01:25:30 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:30 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 01:25:30 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 26 01:25:30 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState May 26 01:25:30 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:30 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 01:25:30 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 01:25:30 volumio volumio5-onboarding[1713]: time=2026-05-26T01:25:30.364+09:00 level=INFO msg="enabling local network discovery" May 26 01:25:30 volumio volumio5-onboarding[1713]: time=2026-05-26T01:25:30.402+09:00 level=INFO msg="enabling BLE discovery" May 26 01:25:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. May 26 01:25:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:30 volumio volumio[1133]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 26 01:25:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:30 volumio go-librespot[1822]: go-librespot daemon starting... May 26 01:25:30 volumio go-librespot[1823]: /bin/start-go-librespot.sh: 7: /usr/bin/go-librespot: not found May 26 01:25:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 26 01:25:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 01:25:31 volumio volumio[1133]: info: Discovery: Disconnected from remote: 192.168.1.6 May 26 01:25:31 volumio volumio[1133]: info: Discovery: Disconnected from remote: 192.168.1.6 May 26 01:25:31 volumio volumio[1133]: info: Discovery: Disconnected from remote: 192.168.1.6 May 26 01:25:31 volumio volumio[1133]: info: Discovery: Disconnected from remote: 192.168.1.6 May 26 01:25:31 volumio volumio[1133]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 01:25:31 volumio volumio5-onboarding[1713]: time=2026-05-26T01:25:31.961+09:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 01:25:32 volumio volumio[1133]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 01:25:32 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState May 26 01:25:32 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:33 volumio volumio[1133]: info: Discovery: Connected to remote: 192.168.1.6 May 26 01:25:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. May 26 01:25:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:33 volumio go-librespot[1840]: go-librespot daemon starting... May 26 01:25:34 volumio go-librespot[1841]: /bin/start-go-librespot.sh: 7: /usr/bin/go-librespot: not found May 26 01:25:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 26 01:25:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 01:25:36 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.14 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 49 May 26 01:25:36 volumio shairport-sync[1795]: ALSA lib /home/pi/volumio-hook-plugin/src/pcm_volumiohook.c:101:(_snd_pcm_volumiohook_execute) The PCM fusiondsphook failed to run command echo '44100,S32_LE,2,32' >/tmp/fusiondsp_stream_params.log May 26 01:25:36 volumio volumio[1133]: info: Initializing connection to go-librespot Websocket May 26 01:25:36 volumio shairport-sync[1846]: % Total % Received % Xferd Average Speed Time Time Time Current May 26 01:25:36 volumio shairport-sync[1846]: Dload Upload Total Spent Left Speed May 26 01:25:36 volumio shairport-sync[1846]: [79B blob data] May 26 01:25:36 volumio shairport-sync[1847]: % Total % Received % Xferd Average Speed Time Time Time Current May 26 01:25:36 volumio shairport-sync[1847]: Dload Upload Total Spent Left Speed May 26 01:25:37 volumio shairport-sync[1847]: [79B blob data] May 26 01:25:37 volumio shairport-sync[1860]: % Total % Received % Xferd Average Speed Time Time Time Current May 26 01:25:37 volumio shairport-sync[1860]: Dload Upload Total Spent Left Speed May 26 01:25:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. May 26 01:25:37 volumio shairport-sync[1860]: [79B blob data] May 26 01:25:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:37 volumio go-librespot[1863]: go-librespot daemon starting... May 26 01:25:37 volumio go-librespot[1864]: /bin/start-go-librespot.sh: 7: /usr/bin/go-librespot: not found May 26 01:25:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 26 01:25:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 01:25:37 volumio shairport-sync[1847]: [79B blob data] May 26 01:25:37 volumio shairport-sync[1866]: % Total % Received % Xferd Average Speed Time Time Time Current May 26 01:25:37 volumio shairport-sync[1866]: Dload Upload Total Spent Left Speed May 26 01:25:38 volumio shairport-sync[1866]: [79B blob data] May 26 01:25:38 volumio shairport-sync[1846]: [79B blob data] May 26 01:25:38 volumio volumio[1133]: info: Airplay started streaming, receiving metadatas May 26 01:25:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:38 volumio volumio[1133]: info: CoreCommandRouter::volumioStop May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::stop May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 01:25:38 volumio volumio[1133]: info: Airplay started streaming, receiving metadatas May 26 01:25:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:38 volumio volumio[1133]: info: CoreCommandRouter::volumioStop May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::stop May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 01:25:38 volumio volumio[1133]: info: Airplay started streaming, receiving metadatas May 26 01:25:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:38 volumio volumio[1133]: info: CoreCommandRouter::volumioStop May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::stop May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 01:25:38 volumio volumio[1133]: info: Airplay started streaming, receiving metadatas May 26 01:25:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:38 volumio volumio[1133]: info: CoreCommandRouter::volumioStop May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::stop May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 01:25:38 volumio volumio[1133]: info: CoreCommandRouter::servicePushState May 26 01:25:38 volumio shairport-sync[1847]: [79B blob data] May 26 01:25:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:38 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} May 26 01:25:38 volumio volumio[1133]: verbose: CURRENT POSITION 0 May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop May 26 01:25:38 volumio volumio[1133]: info: CoreCommandRouter::servicePushState May 26 01:25:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:38 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} May 26 01:25:38 volumio volumio[1133]: verbose: CURRENT POSITION 0 May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play May 26 01:25:38 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::pushState May 26 01:25:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:38 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 01:25:38 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::pushState May 26 01:25:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:38 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState May 26 01:25:38 volumio volumio[1133]: info: CoreCommandRouter::servicePushState May 26 01:25:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:38 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} May 26 01:25:38 volumio volumio[1133]: verbose: CURRENT POSITION 0 May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play May 26 01:25:38 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::pushState May 26 01:25:38 volumio shairport-sync[1860]: [79B blob data] May 26 01:25:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:38 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::pushState May 26 01:25:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:38 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState May 26 01:25:38 volumio volumio[1133]: info: CoreCommandRouter::servicePushState May 26 01:25:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:38 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} May 26 01:25:38 volumio volumio[1133]: verbose: CURRENT POSITION 0 May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play May 26 01:25:38 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::pushState May 26 01:25:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:38 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState May 26 01:25:38 volumio volumio[1133]: info: CoreStateMachine::pushState May 26 01:25:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:38 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState May 26 01:25:38 volumio shairport-sync[1866]: [79B blob data] May 26 01:25:38 volumio shairport-sync[1795]: ALSA lib /home/pi/volumio-hook-plugin/src/pcm_volumiohook.c:101:(_snd_pcm_volumiohook_execute) The PCM fusiondsphook failed to run command echo '44100,S32_LE,2,32' >/tmp/fusiondsp_stream_params.log May 26 01:25:39 volumio volumio[1133]: info: Airplay started streaming, receiving metadatas May 26 01:25:39 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:39 volumio volumio[1133]: info: CoreCommandRouter::volumioStop May 26 01:25:39 volumio volumio[1133]: info: CoreStateMachine::stop May 26 01:25:39 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 01:25:39 volumio volumio[1133]: info: CoreStateMachine::stPlaybackTimer May 26 01:25:39 volumio shairport-sync[1846]: [79B blob data] May 26 01:25:39 volumio volumio[1133]: info: CoreStateMachine::updateTrackBlock May 26 01:25:39 volumio volumio[1133]: info: CorePlayQueue::getTrackBlock May 26 01:25:39 volumio volumio[1133]: info: CoreStateMachine::pushState May 26 01:25:39 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:39 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState May 26 01:25:39 volumio shairport-sync[1847]: [79B blob data] May 26 01:25:39 volumio volumio[1133]: info: CoreStateMachine::serviceStop May 26 01:25:39 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 May 26 01:25:39 volumio volumio[1133]: info: ControllerMpd::stop May 26 01:25:39 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand stop May 26 01:25:39 volumio volumio[1133]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 01:25:39 volumio shairport-sync[1860]: [79B blob data] May 26 01:25:39 volumio volumio[1133]: TypeError: Cannot read properties of undefined (reading 'then') May 26 01:25:39 volumio volumio[1133]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) May 26 01:25:39 volumio volumio[1133]: at ControllerMpd.stop (/volumio/app/plugins/music_service/mpd/index.js:2792:15) May 26 01:25:39 volumio volumio[1133]: at CoreStateMachine.serviceStop (/volumio/app/statemachine.js:1279:24) May 26 01:25:39 volumio volumio[1133]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1248:19) May 26 01:25:39 volumio volumio[1133]: at CoreCommandRouter.volumioStop (/volumio/app/index.js:91:28) May 26 01:25:39 volumio volumio[1133]: at AirPlayInterface.prepareAirplayPlayback (/volumio/app/plugins/music_service/airplay_emulation/index.js:564:32) May 26 01:25:39 volumio volumio[1133]: at ShairportSyncReaderUDP. (/volumio/app/plugins/music_service/airplay_emulation/index.js:266:12) May 26 01:25:39 volumio volumio[1133]: at ShairportSyncReaderUDP.emit (node:events:526:35) May 26 01:25:39 volumio volumio[1133]: at ShairportSyncReaderUDP.useData (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-base.js:86:16) May 26 01:25:39 volumio volumio[1133]: at Socket. (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-udp.js:21:12) May 26 01:25:39 volumio volumio[1133]: at Socket.emit (node:events:514:28) May 26 01:25:39 volumio volumio[1133]: at UDP.onMessage [as onmessage] (node:dgram:941:8) May 26 01:25:39 volumio volumio[1133]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 01:25:40 volumio shairport-sync[1866]: [79B blob data] May 26 01:25:40 volumio shairport-sync[1846]: [79B blob data] May 26 01:25:40 volumio shairport-sync[1847]: [79B blob data] May 26 01:25:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. May 26 01:25:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 26 01:25:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 01:25:40 volumio go-librespot[1885]: go-librespot daemon starting... May 26 01:25:40 volumio go-librespot[1886]: /bin/start-go-librespot.sh: 7: /usr/bin/go-librespot: not found May 26 01:25:40 volumio shairport-sync[1860]: [79B blob data] May 26 01:25:41 volumio shairport-sync[1866]: [79B blob data] May 26 01:25:41 volumio shairport-sync[1846]: [79B blob data] May 26 01:25:41 volumio shairport-sync[1847]: [79B blob data] May 26 01:25:41 volumio shairport-sync[1860]: [79B blob data] May 26 01:25:42 volumio shairport-sync[1866]: [79B blob data] May 26 01:25:42 volumio shairport-sync[1846]: [79B blob data] May 26 01:25:42 volumio shairport-sync[1847]: [79B blob data] May 26 01:25:42 volumio shairport-sync[1860]: [79B blob data] May 26 01:25:43 volumio shairport-sync[1866]: [79B blob data] May 26 01:25:43 volumio shairport-sync[1846]: [79B blob data] May 26 01:25:43 volumio shairport-sync[1847]: [79B blob data] May 26 01:25:43 volumio shairport-sync[1860]: [79B blob data] May 26 01:25:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. May 26 01:25:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:43 volumio go-librespot[1888]: go-librespot daemon starting... May 26 01:25:43 volumio go-librespot[1889]: /bin/start-go-librespot.sh: 7: /usr/bin/go-librespot: not found May 26 01:25:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 26 01:25:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 01:25:44 volumio shairport-sync[1866]: [79B blob data] May 26 01:25:44 volumio shairport-sync[1846]: [79B blob data] May 26 01:25:44 volumio shairport-sync[1847]: [79B blob data] May 26 01:25:44 volumio shairport-sync[1860]: [79B blob data] May 26 01:25:45 volumio shairport-sync[1866]: [79B blob data] May 26 01:25:45 volumio shairport-sync[1846]: [79B blob data] May 26 01:25:45 volumio shairport-sync[1847]: [79B blob data] May 26 01:25:45 volumio shairport-sync[1860]: [79B blob data] May 26 01:25:46 volumio shairport-sync[1866]: [79B blob data] May 26 01:25:46 volumio shairport-sync[1846]: [79B blob data] May 26 01:25:46 volumio shairport-sync[1847]: [79B blob data] May 26 01:25:46 volumio shairport-sync[1860]: [79B blob data] May 26 01:25:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. May 26 01:25:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:47 volumio go-librespot[1904]: go-librespot daemon starting... May 26 01:25:47 volumio go-librespot[1905]: /bin/start-go-librespot.sh: 7: /usr/bin/go-librespot: not found May 26 01:25:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 26 01:25:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 01:25:47 volumio shairport-sync[1866]: [79B blob data] May 26 01:25:47 volumio shairport-sync[1846]: [79B blob data] May 26 01:25:47 volumio shairport-sync[1847]: [79B blob data] May 26 01:25:47 volumio shairport-sync[1860]: [79B blob data] May 26 01:25:48 volumio shairport-sync[1866]: [79B blob data] May 26 01:25:48 volumio shairport-sync[1846]: [79B blob data] May 26 01:25:48 volumio shairport-sync[1847]: [79B blob data] May 26 01:25:48 volumio shairport-sync[1860]: [79B blob data] May 26 01:25:49 volumio shairport-sync[1866]: [79B blob data] May 26 01:25:49 volumio shairport-sync[1846]: [79B blob data] May 26 01:25:49 volumio sudo[1908]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-26 01:24' May 26 01:25:49 volumio sudo[1908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 01:25:49 volumio shairport-sync[1847]: [79B blob data] May 26 01:25:49 volumio shairport-sync[1860]: [79B blob data] May 26 01:25:50 volumio shairport-sync[1866]: [79B blob data] May 26 01:25:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. May 26 01:25:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 01:25:50 volumio shairport-sync[1846]: [79B blob data] 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"