Oct 15 11:31:41 volumio-rila systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 15 11:31:41 volumio-rila systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 15 11:31:41 volumio-rila winbindd[1702]: [2026/10/15 11:31:41, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1702]: reopen_one_log: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1702]: [2026/10/15 11:31:41, 0] lib/util/util.c:221(directory_create_or_exist) Oct 15 11:31:41 volumio-rila winbindd[1702]: directory_create_or_exist: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1702]: [2026/10/15 11:31:41, 0] source3/lib/dumpcore.c:60(get_default_corepath) Oct 15 11:31:41 volumio-rila winbindd[1702]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 15 11:31:41 volumio-rila winbindd[1702]: [2026/10/15 11:31:41, 0] source3/lib/dumpcore.c:257(dump_core_setup) Oct 15 11:31:41 volumio-rila winbindd[1702]: Unable to setup corepath for winbindd: No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1702]: [2026/10/15 11:31:41.108400, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1702]: reopen_one_log: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1702]: [2026/10/15 11:31:41.108400, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1702]: reopen_one_log: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1702]: [2026/10/15 11:31:41.108502, 0] source3/winbindd/winbindd.c:1445(main) Oct 15 11:31:41 volumio-rila winbindd[1702]: winbindd version 4.22.11-Debian-4.22.11+dfsg-0+deb13u1 started. Oct 15 11:31:41 volumio-rila winbindd[1702]: Copyright Andrew Tridgell and the Samba Team 1992-2025 Oct 15 11:31:41 volumio-rila winbindd[1702]: [2026/10/15 11:31:41.110856, 0] lib/util/util.c:221(directory_create_or_exist) Oct 15 11:31:41 volumio-rila winbindd[1702]: directory_create_or_exist: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1702]: [2026/10/15 11:31:41.110947, 0] source3/lib/dumpcore.c:60(get_default_corepath) Oct 15 11:31:41 volumio-rila winbindd[1702]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 15 11:31:41 volumio-rila winbindd[1702]: [2026/10/15 11:31:41.111000, 0] source3/lib/dumpcore.c:257(dump_core_setup) Oct 15 11:31:41 volumio-rila winbindd[1702]: Unable to setup corepath for winbindd: No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1702]: [2026/10/15 11:31:41.111610, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1702]: reopen_one_log: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1702]: [2026/10/15 11:31:41.112236, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1702]: reopen_one_log: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1702]: [2026/10/15 11:31:41.110856, 0] lib/util/util.c:221(directory_create_or_exist) Oct 15 11:31:41 volumio-rila winbindd[1702]: directory_create_or_exist: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1702]: [2026/10/15 11:31:41.110947, 0] source3/lib/dumpcore.c:60(get_default_corepath) Oct 15 11:31:41 volumio-rila winbindd[1702]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 15 11:31:41 volumio-rila winbindd[1702]: [2026/10/15 11:31:41.111000, 0] source3/lib/dumpcore.c:257(dump_core_setup) Oct 15 11:31:41 volumio-rila winbindd[1702]: Unable to setup corepath for winbindd: No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1702]: [2026/10/15 11:31:41.111610, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1702]: reopen_one_log: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1702]: [2026/10/15 11:31:41.112236, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1702]: reopen_one_log: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 15 11:31:41 volumio-rila systemd[1]: Started winbind.service - Samba Winbind Daemon. Oct 15 11:31:41 volumio-rila winbindd[1719]: [2026/10/15 11:31:41.168201, 0, traceid=1] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1719]: reopen_one_log: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO-RILA': No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1719]: [2026/10/15 11:31:41.168201, 0, traceid=1] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1719]: reopen_one_log: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO-RILA': No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1719]: [2026/10/15 11:31:41.169561, 0, traceid=1] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1719]: reopen_one_log: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO-RILA': No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1719]: [2026/10/15 11:31:41.169561, 0, traceid=1] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1719]: reopen_one_log: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO-RILA': No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1719]: [2026/10/15 11:31:41.170243, 0, traceid=1] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1719]: reopen_one_log: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO-RILA': No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1719]: [2026/10/15 11:31:41.170243, 0, traceid=1] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1719]: reopen_one_log: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO-RILA': No such file or directory Oct 15 11:31:41 volumio-rila systemd[1]: Starting smbd.service - Samba SMB Daemon... Oct 15 11:31:41 volumio-rila smbd[1727]: [2026/10/15 11:31:41, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila smbd[1727]: reopen_one_log: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 15 11:31:41 volumio-rila smbd[1727]: [2026/10/15 11:31:41, 0] lib/util/util.c:221(directory_create_or_exist) Oct 15 11:31:41 volumio-rila smbd[1727]: directory_create_or_exist: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 15 11:31:41 volumio-rila smbd[1727]: [2026/10/15 11:31:41, 0] source3/lib/dumpcore.c:60(get_default_corepath) Oct 15 11:31:41 volumio-rila smbd[1727]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 15 11:31:41 volumio-rila smbd[1727]: [2026/10/15 11:31:41, 0] source3/lib/dumpcore.c:257(dump_core_setup) Oct 15 11:31:41 volumio-rila smbd[1727]: Unable to setup corepath for smbd: No such file or directory Oct 15 11:31:41 volumio-rila smbd[1727]: [2026/10/15 11:31:41.695640, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila smbd[1727]: reopen_one_log: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 15 11:31:41 volumio-rila smbd[1727]: [2026/10/15 11:31:41.695736, 0] source3/smbd/server.c:1971(main) Oct 15 11:31:41 volumio-rila smbd[1727]: smbd version 4.22.11-Debian-4.22.11+dfsg-0+deb13u1 started. Oct 15 11:31:41 volumio-rila smbd[1727]: Copyright Andrew Tridgell and the Samba Team 1992-2025 Oct 15 11:31:41 volumio-rila smbd[1727]: [2026/10/15 11:31:41.695640, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila smbd[1727]: reopen_one_log: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 15 11:31:41 volumio-rila smbd[1727]: [2026/10/15 11:31:41.702101, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila smbd[1727]: reopen_one_log: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 15 11:31:41 volumio-rila smbd[1727]: [2026/10/15 11:31:41.702789, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila smbd[1727]: reopen_one_log: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 15 11:31:41 volumio-rila smbd[1727]: [2026/10/15 11:31:41.702884, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila smbd[1727]: reopen_one_log: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 15 11:31:41 volumio-rila smbd[1727]: [2026/10/15 11:31:41.702101, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila smbd[1727]: reopen_one_log: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 15 11:31:41 volumio-rila smbd[1727]: [2026/10/15 11:31:41.702789, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila smbd[1727]: reopen_one_log: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 15 11:31:41 volumio-rila smbd[1727]: [2026/10/15 11:31:41.702884, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila smbd[1727]: reopen_one_log: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 15 11:31:41 volumio-rila smbd[1730]: [2026/10/15 11:31:41.746011, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila smbd[1730]: reopen_one_log: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 15 11:31:41 volumio-rila smbd[1730]: [2026/10/15 11:31:41.746011, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila smbd[1730]: reopen_one_log: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1732]: [2026/10/15 11:31:41.768324, 0, traceid=1] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1732]: reopen_one_log: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1732]: [2026/10/15 11:31:41.768522, 0, traceid=1] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1732]: reopen_one_log: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1732]: [2026/10/15 11:31:41.768626, 0, traceid=1] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1732]: reopen_one_log: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1732]: [2026/10/15 11:31:41.768324, 0, traceid=1] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1732]: reopen_one_log: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1732]: [2026/10/15 11:31:41.768522, 0, traceid=1] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1732]: reopen_one_log: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Oct 15 11:31:41 volumio-rila winbindd[1732]: [2026/10/15 11:31:41.768626, 0, traceid=1] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:41 volumio-rila winbindd[1732]: reopen_one_log: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Oct 15 11:31:41 volumio-rila systemd[1]: Started smbd.service - Samba SMB Daemon. Oct 15 11:31:41 volumio-rila systemd[1]: Reached target multi-user.target - Multi-User System. Oct 15 11:31:41 volumio-rila systemd[1]: Reached target graphical.target - Graphical Interface. Oct 15 11:31:41 volumio-rila systemd[1]: Startup finished in 11.432s (firmware) + 16.101s (loader) + 24.270s (kernel) + 14.481s (userspace) = 1min 6.285s. Oct 15 11:31:41 volumio-rila ntpd[1066]: IO: Listen normally on 6 eth0 192.168.200.160:123 Oct 15 11:31:41 volumio-rila ntpd[1066]: IO: new interface(s) found: waking up resolver Oct 15 11:31:41 volumio-rila ntpd[1066]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 15 11:31:41 volumio-rila ntpd[1066]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 15 11:31:41 volumio-rila ntpd[1066]: DNS: Pool taking: 176.12.60.87 Oct 15 11:31:41 volumio-rila ntpd[1066]: DNS: Pool taking: 91.216.57.143 Oct 15 11:31:41 volumio-rila ntpd[1066]: DNS: Pool taking: 87.120.244.14 Oct 15 11:31:41 volumio-rila ntpd[1066]: DNS: Pool taking: 88.203.195.14 Oct 15 11:31:41 volumio-rila ntpd[1066]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Oct 15 11:31:42 volumio-rila volumio[1554]: info: ------------------------------------------- Oct 15 11:31:42 volumio-rila volumio[1554]: info: ----- Volumio3 ---- Oct 15 11:31:42 volumio-rila volumio[1554]: info: ------------------------------------------- Oct 15 11:31:42 volumio-rila volumio[1554]: info: ----- System startup ---- Oct 15 11:31:42 volumio-rila volumio[1554]: info: ------------------------------------------- Oct 15 11:31:42 volumio-rila ntpd[1066]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 15 11:31:42 volumio-rila ntpd[1066]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 15 11:31:42 volumio-rila ntpd[1066]: DNS: Pool taking: 185.117.82.71 Oct 15 11:31:42 volumio-rila ntpd[1066]: DNS: Pool taking: 91.210.88.23 Oct 15 11:31:42 volumio-rila dhcpcd[892]: eth0: leased 192.168.200.160 for 1800 seconds Oct 15 11:31:42 volumio-rila ntpd[1066]: DNS: Pool taking: 195.238.84.84 Oct 15 11:31:42 volumio-rila dhcpcd[892]: eth0: adding route to 192.168.200.0/24 Oct 15 11:31:42 volumio-rila ntpd[1066]: DNS: Pool skipping: 88.203.195.14 Oct 15 11:31:42 volumio-rila ntpd[1066]: DNS: Pool taking: 2001:67c:21bc:1e::1:123 Oct 15 11:31:42 volumio-rila ntpd[1066]: DNS: Pool taking: 2001:67c:21bc:1e::123 Oct 15 11:31:42 volumio-rila ntpd[1066]: DNS: Pool taking: 2001:ac8:30:68:0:1:0:2 Oct 15 11:31:42 volumio-rila ntpd[1066]: DNS: Pool taking: 2a11:840:4:1b::3a2f:f22b Oct 15 11:31:42 volumio-rila ntpd[1066]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Oct 15 11:31:43 volumio-rila systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 15 11:31:43 volumio-rila systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 15 11:31:43 volumio-rila systemd[1]: welcome.service: Deactivated successfully. Oct 15 11:31:43 volumio-rila systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 15 11:31:43 volumio-rila systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 15 11:31:43 volumio-rila systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 15 11:31:43 volumio-rila dhcpcd[892]: eth0: adding default route via 192.168.200.1 Oct 15 11:31:43 volumio-rila welcome[1820]: Resolved ip:[1] 192.168.200.160 Oct 15 11:31:43 volumio-rila systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 15 11:31:43 volumio-rila systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 15 11:31:43 volumio-rila volumio[1554]: info: MYVOLUMIO Environment detected Oct 15 11:31:43 volumio-rila volumio[1554]: info: Plugin folders cleanup Oct 15 11:31:43 volumio-rila volumio[1554]: info: Scanning into folder /volumio/app/plugins/ Oct 15 11:31:43 volumio-rila volumio[1554]: info: Scanning category audio_interface Oct 15 11:31:43 volumio-rila volumio[1554]: info: Scanning category miscellanea Oct 15 11:31:43 volumio-rila volumio[1554]: info: Scanning category music_service Oct 15 11:31:43 volumio-rila volumio[1554]: info: Scanning category plugins.json Oct 15 11:31:43 volumio-rila volumio[1554]: info: Scanning category system_controller Oct 15 11:31:43 volumio-rila volumio[1554]: info: Scanning category user_interface Oct 15 11:31:43 volumio-rila volumio[1554]: info: Scanning into folder /data/plugins/ Oct 15 11:31:43 volumio-rila volumio[1554]: info: Plugin folders cleanup completed Oct 15 11:31:43 volumio-rila volumio[1554]: info: ------------------------------------------- Oct 15 11:31:43 volumio-rila volumio[1554]: info: ----- Core plugins startup ---- Oct 15 11:31:43 volumio-rila volumio[1554]: info: ------------------------------------------- Oct 15 11:31:43 volumio-rila volumio[1554]: info: Loading plugins from folder /volumio/app/plugins/ Oct 15 11:31:43 volumio-rila volumio[1554]: info: Adding plugin upnp to MyMusic Plugins Oct 15 11:31:43 volumio-rila volumio[1554]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 15 11:31:43 volumio-rila volumio[1554]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 15 11:31:43 volumio-rila volumio[1554]: info: Loading plugins from folder /data/plugins/ Oct 15 11:31:43 volumio-rila volumio[1554]: info: Loading plugin "system"... Oct 15 11:31:43 volumio-rila ntpd[1066]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 15 11:31:43 volumio-rila ntpd[1066]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 15 11:31:43 volumio-rila ntpd[1066]: DNS: Pool taking: 195.85.215.215 Oct 15 11:31:43 volumio-rila ntpd[1066]: DNS: Pool taking: 78.159.131.45 Oct 15 11:31:43 volumio-rila ntpd[1066]: DNS: Pool taking: 78.83.137.244 Oct 15 11:31:43 volumio-rila ntpd[1066]: DNS: Pool taking: 195.85.215.8 Oct 15 11:31:43 volumio-rila ntpd[1066]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Oct 15 11:31:43 volumio-rila volumio[1554]: info: Loading plugin "appearance"... Oct 15 11:31:44 volumio-rila ntpd[1066]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 15 11:31:44 volumio-rila ntpd[1066]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 15 11:31:44 volumio-rila ntpd[1066]: DNS: Pool taking: 95.43.236.49 Oct 15 11:31:44 volumio-rila ntpd[1066]: DNS: Pool taking: 94.72.140.160 Oct 15 11:31:44 volumio-rila ntpd[1066]: DNS: Pool skipping: 176.12.60.87 Oct 15 11:31:44 volumio-rila ntpd[1066]: DNS: Pool taking: 162.159.200.123 Oct 15 11:31:44 volumio-rila ntpd[1066]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Oct 15 11:31:45 volumio-rila volumio[1554]: info: Loading plugin "network"... Oct 15 11:31:45 volumio-rila volumio[1554]: info: Refreshing Cached IP Addresses Oct 15 11:31:45 volumio-rila volumio[1554]: info: Loading plugin "services"... Oct 15 11:31:45 volumio-rila volumio[1554]: info: Loading plugin "volumio5onboarding"... Oct 15 11:31:45 volumio-rila volumio[1554]: info: Loading plugin "alsa_controller"... Oct 15 11:31:45 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 11:31:45 volumio-rila volumio[1554]: info: Loading plugin "wizard"... Oct 15 11:31:45 volumio-rila volumio[1554]: info: Loading plugin "networkfs"... Oct 15 11:31:45 volumio-rila winbindd[1897]: [2026/10/15 11:31:45, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:45 volumio-rila winbindd[1897]: reopen_one_log: Unable to open new log file '/var/log/samba/log.samba-dcerpcd': No such file or directory Oct 15 11:31:45 volumio-rila winbindd[1897]: [2026/10/15 11:31:45, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:45 volumio-rila winbindd[1897]: reopen_one_log: Unable to open new log file '/var/log/samba/log.samba-dcerpcd': No such file or directory Oct 15 11:31:45 volumio-rila volumio[1554]: info: Starting Udev Watcher for removable devices Oct 15 11:31:45 volumio-rila winbindd[1921]: [2026/10/15 11:31:45, 0] lib/util/util.c:221(directory_create_or_exist) Oct 15 11:31:45 volumio-rila winbindd[1921]: directory_create_or_exist: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 15 11:31:45 volumio-rila winbindd[1921]: [2026/10/15 11:31:45, 0] source3/lib/dumpcore.c:60(get_default_corepath) Oct 15 11:31:45 volumio-rila winbindd[1921]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 15 11:31:45 volumio-rila winbindd[1921]: [2026/10/15 11:31:45, 0] source3/lib/dumpcore.c:257(dump_core_setup) Oct 15 11:31:45 volumio-rila winbindd[1921]: Unable to setup corepath for samba-dcerpcd: No such file or directory Oct 15 11:31:45 volumio-rila winbindd[1921]: [2026/10/15 11:31:45.682943, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:45 volumio-rila winbindd[1921]: reopen_one_log: Unable to open new log file '/var/log/samba/log.samba-dcerpcd': No such file or directory Oct 15 11:31:45 volumio-rila winbindd[1921]: [2026/10/15 11:31:45.683038, 0] source3/rpc_server/rpc_host.c:2894(main) Oct 15 11:31:45 volumio-rila winbindd[1921]: samba-dcerpcd version 4.22.11-Debian-4.22.11+dfsg-0+deb13u1 started. Oct 15 11:31:45 volumio-rila winbindd[1921]: Copyright Andrew Tridgell and the Samba Team 1992-2025 Oct 15 11:31:45 volumio-rila samba-dcerpcd[1921]: [2026/10/15 11:31:45.682943, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:45 volumio-rila samba-dcerpcd[1921]: reopen_one_log: Unable to open new log file '/var/log/samba/log.samba-dcerpcd': No such file or directory Oct 15 11:31:45 volumio-rila volumio[1554]: info: Ignoring mount for partition: boot Oct 15 11:31:45 volumio-rila volumio[1554]: info: Ignoring mount for partition: volumio Oct 15 11:31:45 volumio-rila volumio[1554]: info: Ignoring mount for partition: volumio_data Oct 15 11:31:45 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 11:31:45 volumio-rila volumio[1554]: info: Loading plugin "volumio_command_line_client"... Oct 15 11:31:45 volumio-rila volumio[1554]: info: Loading plugin "upnp"... Oct 15 11:31:45 volumio-rila volumio[1554]: info: [1792053105743] Starting Upmpd Daemon Oct 15 11:31:45 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 11:31:45 volumio-rila volumio[1554]: info: Loading plugin "my_music"... Oct 15 11:31:45 volumio-rila volumio[1554]: info: Loading plugin "mpd"... Oct 15 11:31:46 volumio-rila volumio-remote-updater[877]: [2026-10-15 11:31:46] [connect] Successful connection Oct 15 11:31:46 volumio-rila volumio[1554]: info: Loading plugin "upnp_browser"... Oct 15 11:31:47 volumio-rila winbindd[1925]: [2026/10/15 11:31:47, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:47 volumio-rila winbindd[1925]: reopen_one_log: Unable to open new log file '/var/log/samba/log.rpcd_fsrvp': No such file or directory Oct 15 11:31:47 volumio-rila winbindd[1929]: [2026/10/15 11:31:47, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:47 volumio-rila winbindd[1929]: reopen_one_log: Unable to open new log file '/var/log/samba/log.rpcd_winreg': No such file or directory Oct 15 11:31:47 volumio-rila winbindd[1930]: [2026/10/15 11:31:47, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:47 volumio-rila winbindd[1930]: reopen_one_log: Unable to open new log file '/var/log/samba/log.rpcd_witness': No such file or directory Oct 15 11:31:47 volumio-rila winbindd[1924]: [2026/10/15 11:31:47, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:47 volumio-rila winbindd[1924]: reopen_one_log: Unable to open new log file '/var/log/samba/log.rpcd_epmapper': No such file or directory Oct 15 11:31:47 volumio-rila winbindd[1927]: [2026/10/15 11:31:47, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:47 volumio-rila winbindd[1927]: reopen_one_log: Unable to open new log file '/var/log/samba/log.rpcd_mdssvc': No such file or directory Oct 15 11:31:47 volumio-rila winbindd[1928]: [2026/10/15 11:31:47, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:47 volumio-rila winbindd[1928]: reopen_one_log: Unable to open new log file '/var/log/samba/log.rpcd_spoolss': No such file or directory Oct 15 11:31:47 volumio-rila winbindd[1926]: [2026/10/15 11:31:47, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:47 volumio-rila winbindd[1926]: reopen_one_log: Unable to open new log file '/var/log/samba/log.rpcd_lsad': No such file or directory Oct 15 11:31:47 volumio-rila winbindd[1923]: [2026/10/15 11:31:47, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:47 volumio-rila winbindd[1923]: reopen_one_log: Unable to open new log file '/var/log/samba/log.rpcd_classic': No such file or directory Oct 15 11:31:47 volumio-rila dhcpcd[892]: eth0: no IPv6 Routers available Oct 15 11:31:48 volumio-rila ntpd[1066]: CLOCK: time stepped by 0.675104 Oct 15 11:31:48 volumio-rila ntpd[1066]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Oct 15 11:31:48 volumio-rila dhcpcd[726]: eth0: no IPv6 Routers available Oct 15 11:31:48 volumio-rila winbindd[1972]: [2026/10/15 11:31:48, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:48 volumio-rila winbindd[1972]: reopen_one_log: Unable to open new log file '/var/log/samba/log.rpcd_lsad': No such file or directory Oct 15 11:31:48 volumio-rila winbindd[1972]: [2026/10/15 11:31:48, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:48 volumio-rila winbindd[1972]: reopen_one_log: Unable to open new log file '/var/log/samba/log.rpcd_lsad': No such file or directory Oct 15 11:31:48 volumio-rila winbindd[1972]: [2026/10/15 11:31:48, 0] lib/util/util.c:221(directory_create_or_exist) Oct 15 11:31:48 volumio-rila winbindd[1972]: directory_create_or_exist: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 15 11:31:48 volumio-rila winbindd[1972]: [2026/10/15 11:31:48, 0] source3/lib/dumpcore.c:60(get_default_corepath) Oct 15 11:31:48 volumio-rila winbindd[1972]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 15 11:31:48 volumio-rila winbindd[1972]: [2026/10/15 11:31:48, 0] source3/lib/dumpcore.c:257(dump_core_setup) Oct 15 11:31:48 volumio-rila winbindd[1972]: Unable to setup corepath for rpcd_lsad: No such file or directory Oct 15 11:31:48 volumio-rila winbindd[1972]: [2026/10/15 11:31:48.979839, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:48 volumio-rila winbindd[1972]: reopen_one_log: Unable to open new log file '/var/log/samba/log.rpcd_lsad': No such file or directory Oct 15 11:31:48 volumio-rila winbindd[1972]: [2026/10/15 11:31:48.979929, 0] source3/rpc_server/rpc_worker.c:1148(rpc_worker_main) Oct 15 11:31:48 volumio-rila winbindd[1972]: rpcd_lsad version 4.22.11-Debian-4.22.11+dfsg-0+deb13u1 started. Oct 15 11:31:48 volumio-rila winbindd[1972]: Copyright Andrew Tridgell and the Samba Team 1992-2025 Oct 15 11:31:48 volumio-rila rpcd_lsad[1972]: [2026/10/15 11:31:48.979839, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:48 volumio-rila rpcd_lsad[1972]: reopen_one_log: Unable to open new log file '/var/log/samba/log.rpcd_lsad': No such file or directory Oct 15 11:31:49 volumio-rila winbindd[1997]: [2026/10/15 11:31:49, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:49 volumio-rila winbindd[1997]: reopen_one_log: Unable to open new log file '/var/log/samba/log.rpcd_lsad': No such file or directory Oct 15 11:31:49 volumio-rila winbindd[1997]: [2026/10/15 11:31:49, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:49 volumio-rila winbindd[1997]: reopen_one_log: Unable to open new log file '/var/log/samba/log.rpcd_lsad': No such file or directory Oct 15 11:31:49 volumio-rila winbindd[1997]: [2026/10/15 11:31:49, 0] lib/util/util.c:221(directory_create_or_exist) Oct 15 11:31:49 volumio-rila winbindd[1997]: directory_create_or_exist: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 15 11:31:49 volumio-rila winbindd[1997]: [2026/10/15 11:31:49, 0] source3/lib/dumpcore.c:60(get_default_corepath) Oct 15 11:31:49 volumio-rila winbindd[1997]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 15 11:31:49 volumio-rila winbindd[1997]: [2026/10/15 11:31:49, 0] source3/lib/dumpcore.c:257(dump_core_setup) Oct 15 11:31:49 volumio-rila winbindd[1997]: Unable to setup corepath for rpcd_lsad: No such file or directory Oct 15 11:31:49 volumio-rila winbindd[1997]: [2026/10/15 11:31:49.496839, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:49 volumio-rila winbindd[1997]: reopen_one_log: Unable to open new log file '/var/log/samba/log.rpcd_lsad': No such file or directory Oct 15 11:31:49 volumio-rila winbindd[1997]: [2026/10/15 11:31:49.496934, 0] source3/rpc_server/rpc_worker.c:1148(rpc_worker_main) Oct 15 11:31:49 volumio-rila winbindd[1997]: rpcd_lsad version 4.22.11-Debian-4.22.11+dfsg-0+deb13u1 started. Oct 15 11:31:49 volumio-rila winbindd[1997]: Copyright Andrew Tridgell and the Samba Team 1992-2025 Oct 15 11:31:49 volumio-rila rpcd_lsad[1997]: [2026/10/15 11:31:49.496839, 0] lib/util/debug.c:1269(reopen_one_log) Oct 15 11:31:49 volumio-rila rpcd_lsad[1997]: reopen_one_log: Unable to open new log file '/var/log/samba/log.rpcd_lsad': No such file or directory Oct 15 11:31:49 volumio-rila volumio[1554]: info: Starting UPNP Browser Oct 15 11:31:49 volumio-rila volumio[1554]: info: Loading plugin "alarm-clock"... Oct 15 11:31:49 volumio-rila sudo[1883]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 11:31:49 volumio-rila sudo[1883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 11:31:49 volumio-rila sudo[1883]: pam_unix(sudo:session): session closed for user root Oct 15 11:31:49 volumio-rila sudo[1898]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 15 11:31:49 volumio-rila sudo[1898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 11:31:49 volumio-rila sudo[1888]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 11:31:49 volumio-rila sudo[1888]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 11:31:49 volumio-rila sudo[1888]: pam_unix(sudo:session): session closed for user root Oct 15 11:31:49 volumio-rila systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Oct 15 11:31:49 volumio-rila systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 15 11:31:49 volumio-rila upmpdcli[2014]: Could not open config: /tmp/upmpdcli.conf Oct 15 11:31:49 volumio-rila systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 15 11:31:49 volumio-rila systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 15 11:31:49 volumio-rila volumio[1554]: info: Loading plugin "airplay_emulation"... Oct 15 11:31:49 volumio-rila volumio[1554]: info: Starting Shairport Sync Oct 15 11:31:49 volumio-rila volumio[1554]: info: Loading plugin "last_100"... Oct 15 11:31:49 volumio-rila volumio[1554]: info: Loading plugin "webradio"... Oct 15 11:31:49 volumio-rila sudo[1898]: pam_unix(sudo:session): session closed for user root Oct 15 11:31:50 volumio-rila volumio[1554]: info: Loading plugin "i2s_dacs"... Oct 15 11:31:50 volumio-rila volumio[1554]: info: I2S DAC not set, start Auto-detection Oct 15 11:31:50 volumio-rila volumio[1554]: info: Loading plugin "volumiodiscovery"... Oct 15 11:31:50 volumio-rila volumio[1554]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 15 11:31:50 volumio-rila volumio[1554]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 11:31:50 volumio-rila volumio[1554]: *** WARNING *** For more information see Oct 15 11:31:50 volumio-rila volumio[1554]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 15 11:31:50 volumio-rila node[1554]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 15 11:31:50 volumio-rila volumio[1554]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 11:31:50 volumio-rila volumio[1554]: *** WARNING *** For more information see Oct 15 11:31:50 volumio-rila node[1554]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 11:31:50 volumio-rila node[1554]: *** WARNING *** For more information see Oct 15 11:31:50 volumio-rila node[1554]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 15 11:31:50 volumio-rila node[1554]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 11:31:50 volumio-rila node[1554]: *** WARNING *** For more information see Oct 15 11:31:50 volumio-rila volumio[1554]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 15 11:31:50 volumio-rila volumio[1554]: info: Discovery: Started advertising with name: Volumio-Rila Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 11:31:50 volumio-rila volumio[1554]: info: Loading plugin "outputs"... Oct 15 11:31:50 volumio-rila volumio[1554]: info: Loading plugin "albumart"... Oct 15 11:31:50 volumio-rila volumio[1554]: info: Plugin example_plugin is not enabled Oct 15 11:31:50 volumio-rila volumio[1554]: info: Loading plugin "inputs"... Oct 15 11:31:50 volumio-rila volumio[1554]: info: Loading plugin "updater_comm"... Oct 15 11:31:50 volumio-rila volumio[1554]: info: Plugin mpdemulation is not enabled Oct 15 11:31:50 volumio-rila volumio[1554]: info: Loading plugin "rest_api"... Oct 15 11:31:50 volumio-rila volumio[1554]: info: Loading plugin "websocket"... Oct 15 11:31:50 volumio-rila volumio[1554]: info: Starting Socket.io Server version 1.7.4 Oct 15 11:31:50 volumio-rila volumio[1554]: info: Loading i18n strings for locale en Oct 15 11:31:50 volumio-rila volumio[1554]: Updating browse sources language Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreCommandRouter::initPlayerControls Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 11:31:50 volumio-rila volumio[1554]: Express server listening on port 3000 Oct 15 11:31:50 volumio-rila volumio[1554]: [Metrics] WebUI: 8s 254.56ms Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreStateMachine::resetVolumioState Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreStateMachine::getcurrentVolume Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 11:31:50 volumio-rila volumio[1554]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 15 11:31:50 volumio-rila volumio[1554]: info: Completed loading Core Plugins Oct 15 11:31:50 volumio-rila volumio[1554]: info: Preparing to generate the ALSA configuration file Oct 15 11:31:50 volumio-rila volumio[1554]: info: Volumio Network Manager: Network status updated: 1 Oct 15 11:31:50 volumio-rila volumio[1554]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 15 11:31:50 volumio-rila volumio[2019]: Forking 1 albumart workers Oct 15 11:31:50 volumio-rila volumio[1554]: info: VolumeController:: Volume=49 Mute =false Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreStateMachine::pushState Oct 15 11:31:50 volumio-rila volumio[1554]: info: CorePlayQueue::getTrack 0 Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreCommandRouter::volumioPushState Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreStateMachine::updateTrackBlock Oct 15 11:31:50 volumio-rila volumio[1554]: info: CorePlayQueue::getTrackBlock Oct 15 11:31:50 volumio-rila volumio[1554]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 11:31:50 volumio-rila volumio-remote-updater[877]: [2026-10-15 11:31:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1792053106 101 Oct 15 11:31:51 volumio-rila volumio[1554]: 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: 1 Oct 15 11:31:51 volumio-rila volumio[1554]: info: Reloading queue from file Oct 15 11:31:51 volumio-rila volumio[1554]: info: Asound.conf file unchanged, so no further update is needed Oct 15 11:31:51 volumio-rila volumio[1554]: info: Output device has changed, restarting MPD Oct 15 11:31:51 volumio-rila volumio[1554]: info: Output device has changed, restarting Shairport Sync Oct 15 11:31:51 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:31:51 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 11:31:51 volumio-rila volumio[1554]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 11:31:51 volumio-rila volumio[1554]: info: ___________ START PLUGINS ___________ Oct 15 11:31:51 volumio-rila sudo[2052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 11:31:51 volumio-rila sudo[2054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 11:31:51 volumio-rila sudo[2052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 11:31:51 volumio-rila sudo[2054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 11:31:51 volumio-rila sudo[2052]: pam_unix(sudo:session): session closed for user root Oct 15 11:31:51 volumio-rila volumio[1554]: info: ControllerMpd::onStart: Initializing MPD Oct 15 11:31:51 volumio-rila volumio[1554]: info: Creating MPD Configuration file Oct 15 11:31:51 volumio-rila sudo[2054]: pam_unix(sudo:session): session closed for user root Oct 15 11:31:51 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 11:31:51 volumio-rila sudo[2064]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 15 11:31:51 volumio-rila sudo[2064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 11:31:51 volumio-rila volumio[1554]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 11:31:51 volumio-rila volumio[1554]: info: [1792053111301] CoreMusicLibrary::Adding element Media Servers Oct 15 11:31:51 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 11:31:51 volumio-rila volumio[1554]: info: UPNP Browser: Client initialized successfully Oct 15 11:31:51 volumio-rila sudo[2066]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 11:31:51 volumio-rila sudo[2066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 11:31:51 volumio-rila systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 15 11:31:51 volumio-rila sudo[2066]: pam_unix(sudo:session): session closed for user root Oct 15 11:31:51 volumio-rila sudo[2068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 11:31:51 volumio-rila sudo[2068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 11:31:51 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:31:51 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 11:31:51 volumio-rila sudo[2064]: pam_unix(sudo:session): session closed for user root Oct 15 11:31:51 volumio-rila sudo[2068]: pam_unix(sudo:session): session closed for user root Oct 15 11:31:51 volumio-rila volumio[1554]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 11:31:51 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:31:51 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 11:31:51 volumio-rila volumio[1554]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 11:31:51 volumio-rila volumio[1554]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 11:31:51 volumio-rila volumio[1554]: info: [1792053111482] CoreMusicLibrary::Adding element Last_100 Oct 15 11:31:51 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 11:31:51 volumio-rila volumio[1554]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 11:31:51 volumio-rila volumio[1554]: info: [1792053111486] CoreMusicLibrary::Adding element Webradio Oct 15 11:31:51 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 11:31:51 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 11:31:51 volumio-rila volumio[1554]: info: Initializing BBC Radios Oct 15 11:31:51 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 11:31:51 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 11:31:51 volumio-rila volumio[1554]: info: Cannot retrieve data for calling home Oct 15 11:31:51 volumio-rila volumio5-onboarding[2073]: time=2026-10-15T11:31:51.832+03:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 15 11:31:52 volumio-rila volumio[1554]: info: CoreStateMachine::setRepeat null single undefined Oct 15 11:31:52 volumio-rila volumio[1554]: info: CoreStateMachine::pushState Oct 15 11:31:52 volumio-rila volumio[1554]: info: CorePlayQueue::getTrack 0 Oct 15 11:31:52 volumio-rila volumio[1554]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 11:31:52 volumio-rila volumio[1554]: info: CoreCommandRouter::volumioPushState Oct 15 11:31:52 volumio-rila volumio[1554]: info: CoreStateMachine::setRandom null Oct 15 11:31:52 volumio-rila volumio[1554]: info: CoreStateMachine::pushState Oct 15 11:31:52 volumio-rila volumio[1554]: info: CorePlayQueue::getTrack 0 Oct 15 11:31:52 volumio-rila volumio[1554]: info: CoreCommandRouter::volumioPushState Oct 15 11:31:52 volumio-rila volumio[1554]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Oct 15 11:31:52 volumio-rila volumio[1554]: Failed to restart mpd.service: Unit mpd.service not found. Oct 15 11:31:52 volumio-rila volumio[1554]: info: MPD Permissions set Oct 15 11:31:52 volumio-rila volumio[1554]: info: Completed starting Core Plugins Oct 15 11:31:52 volumio-rila volumio[1554]: info: ------------------------------------------- Oct 15 11:31:52 volumio-rila volumio[1554]: info: ----- MyVolumio plugins startup ---- Oct 15 11:31:52 volumio-rila volumio[1554]: info: ------------------------------------------- Oct 15 11:31:52 volumio-rila volumio[1554]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 15 11:31:52 volumio-rila volumio[1554]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Oct 15 11:31:52 volumio-rila volumio[1554]: Failed to restart mpd.service: Unit mpd.service not found. Oct 15 11:31:52 volumio-rila volumio[1554]: info: MPD Permissions set Oct 15 11:31:52 volumio-rila volumio[1554]: info: VolumeController:: Volume=49 Mute =false Oct 15 11:31:52 volumio-rila volumio[1554]: info: CoreStateMachine::pushState Oct 15 11:31:52 volumio-rila volumio[1554]: info: CorePlayQueue::getTrack 0 Oct 15 11:31:52 volumio-rila volumio[1554]: info: CoreCommandRouter::volumioPushState Oct 15 11:31:52 volumio-rila volumio[1554]: error: MPD error: Error: connect ENOENT /run/mpd/socket Oct 15 11:31:52 volumio-rila volumio[1554]: error: connect ENOENT /run/mpd/socket {"address":"/run/mpd/socket","code":"ENOENT","errno":-2,"syscall":"connect"} Oct 15 11:31:52 volumio-rila volumio[1554]: error: MPD error: Error: connect ENOENT /run/mpd/socket Oct 15 11:31:52 volumio-rila volumio[1554]: error: connect ENOENT /run/mpd/socket {"address":"/run/mpd/socket","code":"ENOENT","errno":-2,"syscall":"connect"} Oct 15 11:31:52 volumio-rila volumio[1554]: # /usr/bin/node[1554]: void node::cares_wrap::{anonymous}::GetAddrInfo(const v8::FunctionCallbackInfo&) at ../src/cares_wrap.cc:1571 Oct 15 11:31:52 volumio-rila volumio[1554]: # Assertion failed: args[4]->IsUint32() Oct 15 11:31:52 volumio-rila volumio[1554]: ----- Native stack trace ----- Oct 15 11:31:52 volumio-rila volumio[1554]: 1: 0x7fb988a7c00d node::Assert(node::AssertionInfo const&) [/lib/x86_64-linux-gnu/libnode.so.115] Oct 15 11:31:52 volumio-rila volumio[1554]: 2: 0x7fb9889a382c [/lib/x86_64-linux-gnu/libnode.so.115] Oct 15 11:31:52 volumio-rila volumio[1554]: 3: 0x7fb988f6342c v8::internal::FunctionCallbackArguments::Call(v8::internal::CallHandlerInfo) [/lib/x86_64-linux-gnu/libnode.so.115] Oct 15 11:31:52 volumio-rila volumio[1554]: 4: 0x7fb988f6398b [/lib/x86_64-linux-gnu/libnode.so.115] Oct 15 11:31:52 volumio-rila volumio[1554]: 5: 0x7fb988f64170 v8::internal::Builtin_HandleApiCall(int, unsigned long*, v8::internal::Isolate*) [/lib/x86_64-linux-gnu/libnode.so.115] Oct 15 11:31:52 volumio-rila volumio[1554]: 6: 0x7fb988de8376 [/lib/x86_64-linux-gnu/libnode.so.115] Oct 15 11:31:52 volumio-rila volumio[1554]: ----- JavaScript stack trace ----- Oct 15 11:31:52 volumio-rila volumio[1554]: 1: getaddrinfo_0_11 (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:117:21) Oct 15 11:31:52 volumio-rila volumio[1554]: 2: /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:153:7 Oct 15 11:31:52 volumio-rila volumio[1554]: 3: getaddrinfo (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:152:14) Oct 15 11:31:52 volumio-rila volumio[1554]: 4: next (/volumio/node_modules/mdns/lib/browser.js:109:21) Oct 15 11:31:52 volumio-rila volumio[1554]: 5: on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:33:11) Oct 15 11:31:52 volumio-rila volumio[1554]: 6: MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) Oct 15 11:31:52 volumio-rila volumio5-onboarding[2073]: 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:56690->127.0.0.1:3000: read: connection reset by peer Oct 15 11:31:52 volumio-rila volumio-remote-updater[877]: [2026-10-15 11:31:52] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 15 11:31:52 volumio-rila volumio-remote-updater[877]: [2026-10-15 11:31:52] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 15 11:31:52 volumio-rila systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 15 11:31:52 volumio-rila systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 15 11:31:52 volumio-rila systemd[1]: volumio.service: Main process exited, code=killed, status=6/ABRT Oct 15 11:31:52 volumio-rila systemd[1]: volumio.service: Failed with result 'signal'. Oct 15 11:31:52 volumio-rila systemd[1]: volumio.service: Consumed 10.779s CPU time, 166.2M memory peak. Oct 15 11:31:52 volumio-rila systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 15 11:31:52 volumio-rila systemd[1]: dynamicswap.service: Deactivated successfully. Oct 15 11:31:52 volumio-rila systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Oct 15 11:31:52 volumio-rila systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Oct 15 11:31:52 volumio-rila systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 15 11:31:52 volumio-rila systemd[1]: Started volumio.service - Volumio Backend Module. Oct 15 11:31:52 volumio-rila systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 15 11:31:52 volumio-rila systemd[1]: dynamicswap.service: Deactivated successfully. Oct 15 11:31:52 volumio-rila volumio5-onboarding[2113]: time=2026-10-15T11:31:52.834+03:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 15 11:31:52 volumio-rila volumio5-onboarding[2113]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: dial tcp 127.0.0.1:3000: connect: connection refused Oct 15 11:31:52 volumio-rila systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 15 11:31:52 volumio-rila systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 15 11:31:53 volumio-rila systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Oct 15 11:31:53 volumio-rila systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 15 11:31:53 volumio-rila volumio5-onboarding[2134]: time=2026-10-15T11:31:53.284+03:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 15 11:31:53 volumio-rila volumio5-onboarding[2134]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: dial tcp 127.0.0.1:3000: connect: connection refused Oct 15 11:31:53 volumio-rila systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 15 11:31:53 volumio-rila systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 15 11:31:53 volumio-rila systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 3. Oct 15 11:31:53 volumio-rila systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 15 11:31:53 volumio-rila volumio5-onboarding[2142]: time=2026-10-15T11:31:53.708+03:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 15 11:31:53 volumio-rila volumio5-onboarding[2142]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: dial tcp 127.0.0.1:3000: connect: connection refused Oct 15 11:31:53 volumio-rila systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 15 11:31:53 volumio-rila systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 15 11:31:54 volumio-rila systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 4. Oct 15 11:31:54 volumio-rila systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 15 11:31:54 volumio-rila volumio5-onboarding[2149]: time=2026-10-15T11:31:54.139+03:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 15 11:31:54 volumio-rila volumio5-onboarding[2149]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: dial tcp 127.0.0.1:3000: connect: connection refused Oct 15 11:31:54 volumio-rila systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 15 11:31:54 volumio-rila systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 15 11:31:54 volumio-rila systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 5. Oct 15 11:31:54 volumio-rila systemd[1]: volumio5-onboarding.service: Start request repeated too quickly. Oct 15 11:31:54 volumio-rila systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 15 11:31:54 volumio-rila systemd[1]: Failed to start volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 15 11:31:55 volumio-rila volumio[2112]: info: ------------------------------------------- Oct 15 11:31:55 volumio-rila volumio[2112]: info: ----- Volumio3 ---- Oct 15 11:31:55 volumio-rila volumio[2112]: info: ------------------------------------------- Oct 15 11:31:55 volumio-rila volumio[2112]: info: ----- System startup ---- Oct 15 11:31:55 volumio-rila volumio[2112]: info: ------------------------------------------- Oct 15 11:31:55 volumio-rila volumio[2112]: info: MYVOLUMIO Environment detected Oct 15 11:31:55 volumio-rila volumio[2112]: info: Plugin folders cleanup Oct 15 11:31:55 volumio-rila volumio[2112]: info: Scanning into folder /volumio/app/plugins/ Oct 15 11:31:55 volumio-rila volumio[2112]: info: Scanning category audio_interface Oct 15 11:31:55 volumio-rila volumio[2112]: info: Scanning category miscellanea Oct 15 11:31:55 volumio-rila volumio[2112]: info: Scanning category music_service Oct 15 11:31:55 volumio-rila volumio[2112]: info: Scanning category plugins.json Oct 15 11:31:55 volumio-rila volumio[2112]: info: Scanning category system_controller Oct 15 11:31:55 volumio-rila volumio[2112]: info: Scanning category user_interface Oct 15 11:31:55 volumio-rila volumio[2112]: info: Scanning into folder /data/plugins/ Oct 15 11:31:55 volumio-rila volumio[2112]: info: Plugin folders cleanup completed Oct 15 11:31:55 volumio-rila volumio[2112]: info: ------------------------------------------- Oct 15 11:31:55 volumio-rila volumio[2112]: info: ----- Core plugins startup ---- Oct 15 11:31:55 volumio-rila volumio[2112]: info: ------------------------------------------- Oct 15 11:31:55 volumio-rila volumio[2112]: info: Loading plugins from folder /volumio/app/plugins/ Oct 15 11:31:55 volumio-rila volumio[2112]: info: Adding plugin upnp to MyMusic Plugins Oct 15 11:31:55 volumio-rila volumio[2112]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 15 11:31:55 volumio-rila volumio[2112]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 15 11:31:55 volumio-rila volumio[2112]: info: Loading plugins from folder /data/plugins/ Oct 15 11:31:55 volumio-rila volumio[2112]: info: Loading plugin "system"... Oct 15 11:31:55 volumio-rila volumio[2112]: info: Loading plugin "appearance"... Oct 15 11:31:56 volumio-rila volumio[2112]: info: Loading plugin "network"... Oct 15 11:31:56 volumio-rila volumio[2112]: info: Refreshing Cached IP Addresses Oct 15 11:31:56 volumio-rila sudo[2173]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 11:31:56 volumio-rila sudo[2173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 11:31:56 volumio-rila volumio[2112]: info: Loading plugin "services"... Oct 15 11:31:56 volumio-rila sudo[2173]: pam_unix(sudo:session): session closed for user root Oct 15 11:31:56 volumio-rila volumio[2112]: info: Loading plugin "volumio5onboarding"... Oct 15 11:31:56 volumio-rila sudo[2175]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 11:31:56 volumio-rila sudo[2175]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 11:31:56 volumio-rila sudo[2183]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 15 11:31:56 volumio-rila sudo[2183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 11:31:56 volumio-rila volumio[2112]: info: Loading plugin "alsa_controller"... Oct 15 11:31:56 volumio-rila sudo[2175]: pam_unix(sudo:session): session closed for user root Oct 15 11:31:56 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 11:31:56 volumio-rila volumio[2112]: info: Loading plugin "wizard"... Oct 15 11:31:56 volumio-rila volumio[2112]: info: Loading plugin "networkfs"... Oct 15 11:31:56 volumio-rila volumio[2112]: info: Starting Udev Watcher for removable devices Oct 15 11:31:56 volumio-rila volumio[2112]: info: Ignoring mount for partition: boot Oct 15 11:31:56 volumio-rila volumio[2112]: info: Ignoring mount for partition: volumio Oct 15 11:31:56 volumio-rila volumio[2112]: info: Ignoring mount for partition: volumio_data Oct 15 11:31:56 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 11:31:56 volumio-rila volumio[2112]: info: Loading plugin "volumio_command_line_client"... Oct 15 11:31:57 volumio-rila volumio[2112]: info: Loading plugin "upnp"... Oct 15 11:31:57 volumio-rila volumio[2112]: info: [1792053117004] Starting Upmpd Daemon Oct 15 11:31:57 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 11:31:57 volumio-rila volumio[2112]: info: Loading plugin "my_music"... Oct 15 11:31:57 volumio-rila volumio[2112]: info: Loading plugin "mpd"... Oct 15 11:31:57 volumio-rila sudo[2183]: pam_unix(sudo:session): session closed for user root Oct 15 11:31:57 volumio-rila volumio[2112]: info: Loading plugin "upnp_browser"... Oct 15 11:31:57 volumio-rila volumio-remote-updater[877]: [2026-10-15 11:31:57] [connect] Successful connection Oct 15 11:31:59 volumio-rila volumio[2112]: info: Starting UPNP Browser Oct 15 11:31:59 volumio-rila volumio[2112]: info: Loading plugin "alarm-clock"... Oct 15 11:31:59 volumio-rila volumio[2112]: info: Loading plugin "airplay_emulation"... Oct 15 11:31:59 volumio-rila volumio[2112]: info: Starting Shairport Sync Oct 15 11:31:59 volumio-rila volumio[2112]: info: Loading plugin "last_100"... Oct 15 11:31:59 volumio-rila volumio[2112]: info: Loading plugin "webradio"... Oct 15 11:31:59 volumio-rila volumio[2112]: info: Loading plugin "i2s_dacs"... Oct 15 11:31:59 volumio-rila volumio[2112]: info: I2S DAC not set, start Auto-detection Oct 15 11:31:59 volumio-rila volumio[2112]: info: Loading plugin "volumiodiscovery"... Oct 15 11:31:59 volumio-rila volumio[2112]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 15 11:31:59 volumio-rila volumio[2112]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 11:31:59 volumio-rila volumio[2112]: *** WARNING *** For more information see Oct 15 11:31:59 volumio-rila volumio[2112]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 15 11:31:59 volumio-rila node[2112]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 15 11:31:59 volumio-rila volumio[2112]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 11:31:59 volumio-rila volumio[2112]: *** WARNING *** For more information see Oct 15 11:31:59 volumio-rila node[2112]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 11:31:59 volumio-rila node[2112]: *** WARNING *** For more information see Oct 15 11:31:59 volumio-rila node[2112]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 15 11:31:59 volumio-rila node[2112]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 11:31:59 volumio-rila node[2112]: *** WARNING *** For more information see Oct 15 11:31:59 volumio-rila volumio[2112]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 15 11:31:59 volumio-rila volumio[2112]: info: Discovery: Started advertising with name: Volumio-Rila Oct 15 11:31:59 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 11:31:59 volumio-rila volumio[2112]: info: Loading plugin "outputs"... Oct 15 11:31:59 volumio-rila volumio[2112]: info: Loading plugin "albumart"... Oct 15 11:32:00 volumio-rila volumio[2112]: info: Plugin example_plugin is not enabled Oct 15 11:32:00 volumio-rila volumio[2112]: info: Loading plugin "inputs"... Oct 15 11:32:00 volumio-rila volumio[2112]: info: Loading plugin "updater_comm"... Oct 15 11:32:00 volumio-rila volumio[2112]: info: Plugin mpdemulation is not enabled Oct 15 11:32:00 volumio-rila volumio[2112]: info: Loading plugin "rest_api"... Oct 15 11:32:00 volumio-rila volumio[2112]: info: Loading plugin "websocket"... Oct 15 11:32:00 volumio-rila volumio[2112]: info: Starting Socket.io Server version 1.7.4 Oct 15 11:32:00 volumio-rila volumio[2112]: info: Loading i18n strings for locale en Oct 15 11:32:00 volumio-rila volumio[2112]: Updating browse sources language Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::initPlayerControls Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 11:32:00 volumio-rila volumio[2112]: Express server listening on port 3000 Oct 15 11:32:00 volumio-rila volumio[2112]: [Metrics] WebUI: 6s 81.91ms Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreStateMachine::resetVolumioState Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreStateMachine::getcurrentVolume Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 11:32:00 volumio-rila volumio[2112]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 15 11:32:00 volumio-rila volumio[2112]: info: Completed loading Core Plugins Oct 15 11:32:00 volumio-rila volumio[2112]: info: Preparing to generate the ALSA configuration file Oct 15 11:32:00 volumio-rila volumio[2112]: info: Volumio Network Manager: Network status updated: 1 Oct 15 11:32:00 volumio-rila volumio[2112]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 15 11:32:00 volumio-rila volumio-remote-updater[877]: [2026-10-15 11:32:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1792053117 101 Oct 15 11:32:00 volumio-rila volumio[2112]: 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: 1 Oct 15 11:32:00 volumio-rila volumio[2112]: info: VolumeController:: Volume=49 Mute =false Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreStateMachine::pushState Oct 15 11:32:00 volumio-rila volumio[2112]: info: CorePlayQueue::getTrack 0 Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::volumioPushState Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreStateMachine::updateTrackBlock Oct 15 11:32:00 volumio-rila volumio[2112]: info: CorePlayQueue::getTrackBlock Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 11:32:00 volumio-rila volumio[2209]: Forking 1 albumart workers Oct 15 11:32:00 volumio-rila volumio[2112]: info: Reloading queue from file Oct 15 11:32:00 volumio-rila volumio[2112]: info: Asound.conf file unchanged, so no further update is needed Oct 15 11:32:00 volumio-rila volumio[2112]: info: Output device has changed, restarting MPD Oct 15 11:32:00 volumio-rila volumio[2112]: info: Output device has changed, restarting Shairport Sync Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 11:32:00 volumio-rila volumio[2112]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 11:32:00 volumio-rila volumio[2112]: info: ___________ START PLUGINS ___________ Oct 15 11:32:00 volumio-rila sudo[2237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 11:32:00 volumio-rila sudo[2237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 11:32:00 volumio-rila sudo[2235]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 11:32:00 volumio-rila sudo[2235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 11:32:00 volumio-rila sudo[2235]: pam_unix(sudo:session): session closed for user root Oct 15 11:32:00 volumio-rila volumio[2112]: info: ControllerMpd::onStart: Initializing MPD Oct 15 11:32:00 volumio-rila volumio[2112]: info: Creating MPD Configuration file Oct 15 11:32:00 volumio-rila sudo[2237]: pam_unix(sudo:session): session closed for user root Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 11:32:00 volumio-rila volumio[2112]: info: [1792053120762] CoreMusicLibrary::Adding element Media Servers Oct 15 11:32:00 volumio-rila sudo[2246]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 15 11:32:00 volumio-rila sudo[2246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 11:32:00 volumio-rila sudo[2248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 11:32:00 volumio-rila sudo[2248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 11:32:00 volumio-rila sudo[2248]: pam_unix(sudo:session): session closed for user root Oct 15 11:32:00 volumio-rila volumio[2112]: info: UPNP Browser: Client initialized successfully Oct 15 11:32:00 volumio-rila systemd[1]: volumio5-onboarding.service: Start request repeated too quickly. Oct 15 11:32:00 volumio-rila systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 15 11:32:00 volumio-rila systemd[1]: Failed to start volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 15 11:32:00 volumio-rila sudo[2246]: pam_unix(sudo:session): session closed for user root Oct 15 11:32:00 volumio-rila sudo[2250]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 11:32:00 volumio-rila sudo[2250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 11:32:00 volumio-rila volumio[2112]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 11:32:00 volumio-rila sudo[2250]: pam_unix(sudo:session): session closed for user root Oct 15 11:32:00 volumio-rila volumio[2112]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 11:32:00 volumio-rila volumio[2112]: info: [1792053120900] CoreMusicLibrary::Adding element Last_100 Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 11:32:00 volumio-rila volumio[2112]: info: [1792053120903] CoreMusicLibrary::Adding element Webradio Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 11:32:00 volumio-rila volumio[2112]: info: Initializing BBC Radios Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 11:32:00 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 11:32:00 volumio-rila volumio[2112]: info: Cannot retrieve data for calling home Oct 15 11:32:01 volumio-rila volumio[2112]: info: CoreStateMachine::setRepeat null single undefined Oct 15 11:32:01 volumio-rila volumio[2112]: info: CoreStateMachine::pushState Oct 15 11:32:01 volumio-rila volumio[2112]: info: CorePlayQueue::getTrack 0 Oct 15 11:32:01 volumio-rila volumio[2112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 11:32:01 volumio-rila volumio[2112]: info: CoreCommandRouter::volumioPushState Oct 15 11:32:01 volumio-rila volumio[2112]: info: CoreStateMachine::setRandom null Oct 15 11:32:01 volumio-rila volumio[2112]: info: CoreStateMachine::pushState Oct 15 11:32:01 volumio-rila volumio[2112]: info: CorePlayQueue::getTrack 0 Oct 15 11:32:01 volumio-rila volumio[2112]: info: CoreCommandRouter::volumioPushState Oct 15 11:32:01 volumio-rila volumio[2112]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Oct 15 11:32:01 volumio-rila volumio[2112]: Failed to restart mpd.service: Unit mpd.service not found. Oct 15 11:32:01 volumio-rila volumio[2112]: info: MPD Permissions set Oct 15 11:32:01 volumio-rila volumio[2112]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 11:32:01 volumio-rila volumio[2112]: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio5-onboarding.service Oct 15 11:32:01 volumio-rila volumio[2112]: Job for volumio5-onboarding.service failed because the control process exited with error code. Oct 15 11:32:01 volumio-rila volumio[2112]: See "systemctl status volumio5-onboarding.service" and "journalctl -xeu volumio5-onboarding.service" for details. Oct 15 11:32:01 volumio-rila volumio[2112]: at genericNodeError (node:internal/errors:984:15) Oct 15 11:32:01 volumio-rila volumio[2112]: at wrappedFn (node:internal/errors:538:14) Oct 15 11:32:01 volumio-rila volumio[2112]: at ChildProcess.exithandler (node:child_process:422:12) Oct 15 11:32:01 volumio-rila volumio[2112]: at ChildProcess.emit (node:events:524:28) Oct 15 11:32:01 volumio-rila volumio[2112]: at maybeClose (node:internal/child_process:1104:16) Oct 15 11:32:01 volumio-rila volumio[2112]: at Socket. (node:internal/child_process:456:11) Oct 15 11:32:01 volumio-rila volumio[2112]: at Socket.emit (node:events:524:28) Oct 15 11:32:01 volumio-rila volumio[2112]: at Pipe. (node:net:344:12) { Oct 15 11:32:01 volumio-rila volumio[2112]: code: 1, Oct 15 11:32:01 volumio-rila volumio[2112]: killed: false, Oct 15 11:32:01 volumio-rila volumio[2112]: signal: null, Oct 15 11:32:01 volumio-rila volumio[2112]: cmd: '/usr/bin/sudo /bin/systemctl start volumio5-onboarding.service', Oct 15 11:32:01 volumio-rila volumio[2112]: stdout: '', Oct 15 11:32:01 volumio-rila volumio[2112]: stderr: 'Job for volumio5-onboarding.service failed because the control process exited with error code.\n' + Oct 15 11:32:01 volumio-rila volumio[2112]: 'See "systemctl status volumio5-onboarding.service" and "journalctl -xeu volumio5-onboarding.service" for details.\n' Oct 15 11:32:01 volumio-rila volumio[2112]: } Oct 15 11:32:01 volumio-rila volumio[2112]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 11:32:01 volumio-rila volumio[2233]: Starting albumart workers Oct 15 11:32:02 volumio-rila sudo[2293]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-15 11:31' Oct 15 11:32:02 volumio-rila sudo[2293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 13 (trixie)" NAME="Debian GNU/Linux" VERSION_ID="13" VERSION="13 (trixie)" VERSION_CODENAME=trixie DEBIAN_VERSION_FULL=13.7 ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/"