Apr 14 22:27:00 volumio wpa_supplicant[1116]: MBO: Disable MBO/OCE due to misbehaving AP not having enabled PMF Apr 14 22:27:00 volumio wpa_supplicant[1116]: wlan0: Trying to associate with 5c:62:8b:45:f1:1b (SSID='TNT' freq=2457 MHz) Apr 14 22:27:00 volumio wireless.js[721]: WIRELESS.JS - INFO: WpaStateMachine: State transition: NULL -> ASSOCIATING (duration: 0ms) Apr 14 22:27:00 volumio wpa_supplicant[1116]: wlan0: Associated with 5c:62:8b:45:f1:1b Apr 14 22:27:00 volumio wpa_supplicant[1116]: MBO: Disable MBO/OCE due to misbehaving AP not having enabled PMF Apr 14 22:27:00 volumio wpa_supplicant[1116]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 14 22:27:00 volumio wpa_supplicant[1116]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Apr 14 22:27:00 volumio wpa_supplicant[1116]: wlan0: WPA: Key negotiation completed with 5c:62:8b:45:f1:1b [PTK=CCMP GTK=CCMP] Apr 14 22:27:00 volumio wpa_supplicant[1116]: wlan0: CTRL-EVENT-CONNECTED - Connection to 5c:62:8b:45:f1:1b completed [id=0 id_str=] Apr 14 22:27:00 volumio dhcpcd[793]: wlan0: carrier acquired Apr 14 22:27:00 volumio dhcpcd[793]: wlan0: IAID eb:54:02:df Apr 14 22:27:00 volumio dhcpcd[793]: wlan0: soliciting an IPv6 router Apr 14 22:27:00 volumio wireless.js[721]: WIRELESS.JS - INFO: WpaStateMachine: State transition: ASSOCIATING -> COMPLETED (duration: 520ms) Apr 14 22:27:00 volumio wireless.js[721]: WIRELESS.JS - INFO: WpaStateMachine: COMPLETED - connection successful Apr 14 22:27:00 volumio wireless.js[721]: WIRELESS.JS - INFO: STAGE 2: Connection successful - Connected to 5c:62:8b:45:f1:1b Apr 14 22:27:00 volumio wireless.js[721]: WIRELESS.JS - INFO: Onboard WiFi adapter detected, using standard dhcpcd flow Apr 14 22:27:01 volumio sudo[1155]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd wlan0 Apr 14 22:27:01 volumio sudo[1155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 14 22:27:01 volumio dhcpcd[793]: control command: /sbin/dhcpcd wlan0 Apr 14 22:27:01 volumio sudo[1155]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:01 volumio dhcpcd[793]: control_free: No such file or directory Apr 14 22:27:01 volumio dhcpcd[793]: wlan0: rebinding lease of 192.168.1.107 Apr 14 22:27:01 volumio volumio-remote-updater[720]: [2026-04-14 22:27:01] [info] asio async_connect error: asio.system:111 (Connection refused) Apr 14 22:27:01 volumio volumio-remote-updater[720]: [2026-04-14 22:27:01] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Apr 14 22:27:01 volumio volumio-remote-updater[720]: [2026-04-14 22:27:01] [error] handle_connect error: Connection refused Apr 14 22:27:03 volumio wireless.js[721]: WIRELESS.JS - INFO: Start ap Apr 14 22:27:04 volumio wireless.js[721]: WIRELESS.JS - INFO: Notified systemd about wireless ready Apr 14 22:27:04 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 14 22:27:04 volumio systemd[1]: Started wireless.service - Wireless Services. Apr 14 22:27:04 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 14 22:27:04 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Apr 14 22:27:04 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Apr 14 22:27:04 volumio systemd[1]: Received SIGRTMIN+21 from PID 313 (plymouthd). Apr 14 22:27:04 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Apr 14 22:27:04 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Apr 14 22:27:04 volumio systemd[1]: Received SIGRTMIN+21 from PID 313 (n/a). Apr 14 22:27:04 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Apr 14 22:27:04 volumio systemd[1]: Reached target getty.target - Login Prompts. Apr 14 22:27:04 volumio volumio-cpu-tweak[1167]: Setting RT Priority for mpd Apr 14 22:27:04 volumio volumio-cpu-tweak[1182]: pid 35's current scheduling policy: SCHED_OTHER Apr 14 22:27:04 volumio volumio-cpu-tweak[1182]: pid 35's current scheduling priority: 0 Apr 14 22:27:04 volumio volumio-cpu-tweak[1167]: Setting MPD Affinity Apr 14 22:27:04 volumio volumio-cpu-tweak[1183]: pid 3's current affinity mask: f Apr 14 22:27:04 volumio volumio-cpu-tweak[1167]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 14 22:27:04 volumio volumio-cpu-tweak[1167]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 14 22:27:04 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Apr 14 22:27:04 volumio wireless.js[721]: WIRELESS.JS - INFO: trying... Apr 14 22:27:05 volumio sudo[1196]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 14 22:27:05 volumio sudo[1196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:27:05 volumio sudo[1196]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:05 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Apr 14 22:27:05 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 22:27:05 volumio kernel: hwmon hwmon1: Voltage normalised Apr 14 22:27:05 volumio sudo[1199]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 22:27:05 volumio sudo[1199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 14 22:27:05 volumio sudo[1199]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:05 volumio wireless.js[721]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Apr 14 22:27:05 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 22:27:05 volumio upmpdcli[1200]: Could not open config: /tmp/upmpdcli.conf Apr 14 22:27:05 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 14 22:27:05 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 14 22:27:05 volumio wireless.js[721]: WIRELESS.JS - INFO: trying... Apr 14 22:27:06 volumio sudo[1213]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 14 22:27:06 volumio sudo[1213]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:27:06 volumio sudo[1213]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:06 volumio sudo[1216]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 22:27:06 volumio sudo[1216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 14 22:27:06 volumio sudo[1216]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:06 volumio wireless.js[721]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Apr 14 22:27:06 volumio dhcpcd[793]: wlan0: probing address 192.168.1.107/24 Apr 14 22:27:06 volumio wireless.js[721]: WIRELESS.JS - INFO: WARNING: dhcpcd running but no IP assigned yet Apr 14 22:27:06 volumio wireless.js[721]: WIRELESS.JS - INFO: trying... Apr 14 22:27:07 volumio sudo[1232]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 14 22:27:07 volumio sudo[1232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:27:07 volumio sudo[1232]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:07 volumio sudo[1235]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 22:27:07 volumio sudo[1235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 14 22:27:07 volumio sudo[1235]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:07 volumio wireless.js[721]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Apr 14 22:27:07 volumio wireless.js[721]: WIRELESS.JS - INFO: trying... Apr 14 22:27:08 volumio sudo[1247]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 14 22:27:08 volumio sudo[1247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:27:08 volumio sudo[1247]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:08 volumio sudo[1250]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 22:27:08 volumio sudo[1250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 14 22:27:08 volumio sudo[1250]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:08 volumio wireless.js[721]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Apr 14 22:27:08 volumio wireless.js[721]: WIRELESS.JS - INFO: trying... Apr 14 22:27:08 volumio volumio[1165]: info: ------------------------------------------- Apr 14 22:27:08 volumio volumio[1165]: info: ----- Volumio3 ---- Apr 14 22:27:08 volumio volumio[1165]: info: ------------------------------------------- Apr 14 22:27:08 volumio volumio[1165]: info: ----- System startup ---- Apr 14 22:27:08 volumio volumio[1165]: info: ------------------------------------------- Apr 14 22:27:09 volumio sudo[1267]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 14 22:27:09 volumio sudo[1267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:27:09 volumio sudo[1267]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:09 volumio sudo[1270]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 22:27:09 volumio sudo[1270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 14 22:27:09 volumio sudo[1270]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:09 volumio wireless.js[721]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Apr 14 22:27:09 volumio wireless.js[721]: WIRELESS.JS - INFO: trying... Apr 14 22:27:10 volumio sudo[1283]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 14 22:27:10 volumio sudo[1283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:27:10 volumio sudo[1283]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:10 volumio sudo[1286]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 22:27:10 volumio sudo[1286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 14 22:27:10 volumio sudo[1286]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:10 volumio wireless.js[721]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Apr 14 22:27:10 volumio volumio[1165]: info: MYVOLUMIO Environment detected Apr 14 22:27:10 volumio volumio[1165]: info: Plugin folders cleanup Apr 14 22:27:10 volumio volumio[1165]: info: Scanning into folder /volumio/app/plugins/ Apr 14 22:27:10 volumio volumio[1165]: info: Scanning category audio_interface Apr 14 22:27:10 volumio volumio[1165]: info: Scanning category miscellanea Apr 14 22:27:10 volumio volumio[1165]: info: Scanning category music_service Apr 14 22:27:10 volumio volumio[1165]: info: Scanning category plugins.json Apr 14 22:27:10 volumio volumio[1165]: info: Scanning category system_controller Apr 14 22:27:10 volumio volumio[1165]: info: Scanning category user_interface Apr 14 22:27:10 volumio volumio[1165]: info: Scanning into folder /data/plugins/ Apr 14 22:27:10 volumio volumio[1165]: info: Plugin folders cleanup completed Apr 14 22:27:10 volumio volumio[1165]: info: ------------------------------------------- Apr 14 22:27:10 volumio volumio[1165]: info: ----- Core plugins startup ---- Apr 14 22:27:10 volumio volumio[1165]: info: ------------------------------------------- Apr 14 22:27:10 volumio volumio[1165]: info: Loading plugins from folder /volumio/app/plugins/ Apr 14 22:27:10 volumio volumio[1165]: info: Adding plugin upnp to MyMusic Plugins Apr 14 22:27:10 volumio volumio[1165]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 14 22:27:10 volumio volumio[1165]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 14 22:27:10 volumio volumio[1165]: info: Loading plugins from folder /data/plugins/ Apr 14 22:27:10 volumio volumio[1165]: info: Loading plugin "system"... Apr 14 22:27:10 volumio wireless.js[721]: WIRELESS.JS - INFO: trying... Apr 14 22:27:10 volumio volumio[1165]: info: Loading plugin "appearance"... Apr 14 22:27:11 volumio sudo[1303]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 14 22:27:11 volumio sudo[1303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:27:11 volumio sudo[1303]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:11 volumio sudo[1306]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 22:27:11 volumio sudo[1306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 14 22:27:11 volumio sudo[1306]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:11 volumio wireless.js[721]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Apr 14 22:27:11 volumio dhcpcd[793]: wlan0: leased 192.168.1.107 for 7200 seconds Apr 14 22:27:11 volumio avahi-daemon[699]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.107. Apr 14 22:27:11 volumio avahi-daemon[699]: New relevant interface wlan0.IPv4 for mDNS. Apr 14 22:27:11 volumio avahi-daemon[699]: Registering new address record for 192.168.1.107 on wlan0.IPv4. Apr 14 22:27:11 volumio dhcpcd[793]: wlan0: adding route to 192.168.1.0/24 Apr 14 22:27:11 volumio dhcpcd[793]: wlan0: adding default route via 192.168.1.1 Apr 14 22:27:11 volumio systemd[1]: welcome.service: Deactivated successfully. Apr 14 22:27:11 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Apr 14 22:27:11 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Apr 14 22:27:11 volumio wireless.js[721]: WIRELESS.JS - INFO: trying... Apr 14 22:27:11 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Apr 14 22:27:11 volumio systemd[1]: Started nmbd.service - Samba NMB Daemon. Apr 14 22:27:11 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... Apr 14 22:27:11 volumio welcome[1321]: Resolved ip:[1] 192.168.1.107 Apr 14 22:27:11 volumio volumio-remote-updater[720]: [2026-04-14 22:27:11] [connect] Successful connection Apr 14 22:27:11 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Apr 14 22:27:11 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Apr 14 22:27:12 volumio sudo[1349]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 14 22:27:12 volumio sudo[1349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:27:12 volumio sudo[1349]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:12 volumio sudo[1353]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 22:27:12 volumio sudo[1353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 14 22:27:12 volumio sudo[1353]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:12 volumio wireless.js[721]: WIRELESS.JS - INFO: ... wlan0 IPv4 is 192.168.1.107, ipV6 is undefined Apr 14 22:27:12 volumio wireless.js[721]: WIRELESS.JS - INFO: Connected to SSID: TNT Apr 14 22:27:12 volumio wireless.js[721]: WIRELESS.JS - INFO: It's done! AP Apr 14 22:27:12 volumio wireless.js[721]: WIRELESS.JS - INFO: Restarting avahi-daemon... Apr 14 22:27:12 volumio sudo[1358]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon Apr 14 22:27:12 volumio sudo[1358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 14 22:27:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 14 22:27:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 14 22:27:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 14 22:27:12 volumio systemd[1]: shairport-sync.service: Consumed 1.889s CPU time. Apr 14 22:27:12 volumio avahi-daemon[699]: Got SIGTERM, quitting. Apr 14 22:27:12 volumio avahi-daemon[699]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.107. Apr 14 22:27:12 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Apr 14 22:27:12 volumio avahi-daemon[699]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Apr 14 22:27:12 volumio avahi-daemon[699]: avahi-daemon 0.8 exiting. Apr 14 22:27:12 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. Apr 14 22:27:12 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Apr 14 22:27:12 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Apr 14 22:27:12 volumio avahi-daemon[1360]: Process 699 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Apr 14 22:27:12 volumio avahi-daemon[1360]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Apr 14 22:27:12 volumio avahi-daemon[1360]: Successfully dropped root privileges. Apr 14 22:27:12 volumio avahi-daemon[1360]: avahi-daemon 0.8 starting up. Apr 14 22:27:12 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Apr 14 22:27:12 volumio sudo[1358]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:12 volumio avahi-daemon[1360]: Successfully called chroot(). Apr 14 22:27:12 volumio avahi-daemon[1360]: Successfully dropped remaining capabilities. Apr 14 22:27:12 volumio avahi-daemon[1360]: No service file found in /etc/avahi/services. Apr 14 22:27:12 volumio avahi-daemon[1360]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.107. Apr 14 22:27:12 volumio avahi-daemon[1360]: New relevant interface wlan0.IPv4 for mDNS. Apr 14 22:27:12 volumio avahi-daemon[1360]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Apr 14 22:27:12 volumio avahi-daemon[1360]: New relevant interface lo.IPv4 for mDNS. Apr 14 22:27:12 volumio avahi-daemon[1360]: Network interface enumeration completed. Apr 14 22:27:12 volumio avahi-daemon[1360]: Registering new address record for 192.168.1.107 on wlan0.IPv4. Apr 14 22:27:12 volumio avahi-daemon[1360]: Registering new address record for 127.0.0.1 on lo.IPv4. Apr 14 22:27:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 14 22:27:12 volumio wireless.js[721]: WIRELESS.JS - INFO: Notified systemd about wireless ready Apr 14 22:27:12 volumio winbindd[1350]: [2026/04/14 22:27:12.804306, 0] ../../source3/winbindd/winbindd.c:1440(main) Apr 14 22:27:12 volumio winbindd[1350]: winbindd version 4.17.8-Raspbian started. Apr 14 22:27:12 volumio winbindd[1350]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Apr 14 22:27:12 volumio winbindd[1350]: [2026/04/14 22:27:12.833934, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Apr 14 22:27:12 volumio winbindd[1350]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 14 22:27:13 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. Apr 14 22:27:13 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... Apr 14 22:27:13 volumio ntpd[892]: IO: Listen normally on 3 wlan0 192.168.1.107:123 Apr 14 22:27:13 volumio ntpd[892]: IO: new interface(s) found: waking up resolver Apr 14 22:27:13 volumio ntpd[892]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 14 22:27:13 volumio ntpd[892]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Apr 14 22:27:13 volumio ntpd[892]: DNS: Pool taking: 45.79.189.79 Apr 14 22:27:13 volumio ntpd[892]: DNS: Pool taking: 5.78.62.36 Apr 14 22:27:13 volumio ntpd[892]: DNS: Pool taking: 144.202.41.38 Apr 14 22:27:13 volumio ntpd[892]: DNS: Pool taking: 129.146.193.200 Apr 14 22:27:13 volumio ntpd[892]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Apr 14 22:27:13 volumio avahi-daemon[1360]: Server startup complete. Host name is volumio.local. Local service cookie is 2678822074. Apr 14 22:27:14 volumio ntpd[892]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 14 22:27:14 volumio ntpd[892]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Apr 14 22:27:14 volumio ntpd[892]: DNS: Pool taking: 66.42.71.197 Apr 14 22:27:14 volumio ntpd[892]: DNS: Pool taking: 198.23.132.33 Apr 14 22:27:14 volumio ntpd[892]: DNS: Pool taking: 172.104.209.204 Apr 14 22:27:14 volumio ntpd[892]: DNS: Pool taking: 158.51.99.19 Apr 14 22:27:14 volumio ntpd[892]: DNS: Pool taking: 2001:19f0:5c01:11f2:5400:1ff:fed3:6295 Apr 14 22:27:14 volumio ntpd[892]: DNS: Pool taking: 2603:c020:0:8369:0:ba11:ba11:ba11 Apr 14 22:27:14 volumio ntpd[892]: DNS: Pool taking: 2600:3c03::f03c:91ff:fef1:af4e Apr 14 22:27:14 volumio ntpd[892]: DNS: Pool taking: 2603:c020:0:8369:31ed:f940:927:9a57 Apr 14 22:27:14 volumio ntpd[892]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Apr 14 22:27:14 volumio smbd[1385]: [2026/04/14 22:27:14.876401, 0] ../../source3/smbd/server.c:1741(main) Apr 14 22:27:14 volumio smbd[1385]: smbd version 4.17.8-Raspbian started. Apr 14 22:27:14 volumio smbd[1385]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Apr 14 22:27:15 volumio ntpd[892]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 14 22:27:15 volumio ntpd[892]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Apr 14 22:27:15 volumio ntpd[892]: DNS: Pool taking: 45.55.58.103 Apr 14 22:27:15 volumio ntpd[892]: DNS: Pool taking: 66.118.231.14 Apr 14 22:27:15 volumio ntpd[892]: DNS: Pool taking: 162.159.200.1 Apr 14 22:27:15 volumio ntpd[892]: DNS: Pool taking: 69.89.207.99 Apr 14 22:27:15 volumio ntpd[892]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Apr 14 22:27:15 volumio volumio[1165]: info: Loading plugin "network"... Apr 14 22:27:16 volumio volumio[1165]: info: Refreshing Cached IP Addresses Apr 14 22:27:16 volumio sudo[1405]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 22:27:16 volumio sudo[1405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:27:16 volumio sudo[1405]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:16 volumio ntpd[892]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 14 22:27:16 volumio sudo[1407]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 22:27:16 volumio sudo[1407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:27:16 volumio sudo[1407]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:16 volumio volumio[1165]: info: Loading plugin "services"... Apr 14 22:27:16 volumio ntpd[892]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Apr 14 22:27:16 volumio ntpd[892]: DNS: Pool taking: 185.234.20.134 Apr 14 22:27:16 volumio volumio[1165]: info: Loading plugin "volumio5onboarding"... Apr 14 22:27:16 volumio ntpd[892]: DNS: Pool taking: 162.159.200.123 Apr 14 22:27:16 volumio ntpd[892]: DNS: Pool taking: 23.186.168.124 Apr 14 22:27:16 volumio ntpd[892]: DNS: Pool taking: 72.30.35.89 Apr 14 22:27:16 volumio ntpd[892]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Apr 14 22:27:16 volumio sudo[1418]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 14 22:27:16 volumio sudo[1418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:27:16 volumio volumio[1165]: info: Loading plugin "alsa_controller"... Apr 14 22:27:16 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 22:27:16 volumio volumio[1165]: info: Loading plugin "wizard"... Apr 14 22:27:16 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Apr 14 22:27:16 volumio volumio[1165]: info: Loading plugin "networkfs"... Apr 14 22:27:16 volumio volumio[1165]: info: Starting Udev Watcher for removable devices Apr 14 22:27:16 volumio sudo[1438]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=read-only,password=Zalupa2024,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.100/backup/music /mnt/NAS/NAS-music Apr 14 22:27:16 volumio sudo[1438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:27:16 volumio volumio[1165]: info: Ignoring mount for partition: boot Apr 14 22:27:16 volumio volumio[1165]: info: Ignoring mount for partition: volumio Apr 14 22:27:16 volumio volumio[1165]: info: Ignoring mount for partition: volumio_data Apr 14 22:27:16 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 22:27:16 volumio volumio[1165]: info: Loading plugin "volumio_command_line_client"... Apr 14 22:27:16 volumio volumio[1165]: info: Loading plugin "upnp"... Apr 14 22:27:16 volumio volumio[1165]: info: [1776220036756] Starting Upmpd Daemon Apr 14 22:27:16 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 22:27:16 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Apr 14 22:27:16 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Apr 14 22:27:16 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Apr 14 22:27:16 volumio volumio[1165]: info: Loading plugin "my_music"... Apr 14 22:27:16 volumio volumio[1165]: info: Loading plugin "mpd"... Apr 14 22:27:16 volumio kernel: netfs: FS-Cache loaded Apr 14 22:27:16 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Apr 14 22:27:16 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Apr 14 22:27:16 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Apr 14 22:27:16 volumio systemd[1]: Startup finished in 16.329s (kernel) + 33.415s (userspace) = 49.744s. Apr 14 22:27:17 volumio sudo[1418]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:17 volumio kernel: Key type cifs.spnego registered Apr 14 22:27:17 volumio kernel: Key type cifs.idmap registered Apr 14 22:27:17 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Apr 14 22:27:17 volumio kernel: CIFS: Attempting to mount //192.168.1.100/backup/music Apr 14 22:27:17 volumio sudo[1438]: pam_unix(sudo:session): session closed for user root Apr 14 22:27:17 volumio volumio[1165]: info: Loading plugin "upnp_browser"... Apr 14 22:27:18 volumio dhcpcd[690]: timed out Apr 14 22:27:18 volumio sh[676]: timed out Apr 14 22:27:18 volumio sh[638]: ifup: failed to bring up eth0 Apr 14 22:27:18 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Apr 14 22:27:18 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Apr 14 22:27:55 volumio ntpd[892]: CLOCK: time stepped by 35.962598 Apr 14 22:27:55 volumio ntpd[892]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Apr 14 22:27:55 volumio volumio[1165]: info: Starting UPNP Browser Apr 14 22:27:55 volumio volumio[1165]: info: Loading plugin "alarm-clock"... Apr 14 22:27:56 volumio volumio[1165]: info: Loading plugin "airplay_emulation"... Apr 14 22:27:56 volumio volumio[1165]: info: Starting Shairport Sync Apr 14 22:27:56 volumio volumio[1165]: info: Loading plugin "last_100"... Apr 14 22:27:56 volumio volumio[1165]: info: Loading plugin "webradio"... Apr 14 22:27:56 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Apr 14 22:27:56 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 22:27:56 volumio volumio[1165]: info: Loading plugin "i2s_dacs"... Apr 14 22:27:56 volumio volumio[1165]: info: I2S DAC not set, start Auto-detection Apr 14 22:27:56 volumio volumio[1165]: info: Loading plugin "volumiodiscovery"... Apr 14 22:27:56 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 22:27:56 volumio upmpdcli[1479]: Could not open config: /tmp/upmpdcli.conf Apr 14 22:27:56 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 14 22:27:56 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 14 22:27:56 volumio volumio[1165]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 22:27:56 volumio node[1165]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 22:27:56 volumio volumio[1165]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 22:27:56 volumio node[1165]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 22:27:56 volumio volumio[1165]: *** WARNING *** For more information see Apr 14 22:27:56 volumio node[1165]: *** WARNING *** For more information see Apr 14 22:27:56 volumio volumio[1165]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 22:27:56 volumio node[1165]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 22:27:56 volumio volumio[1165]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 22:27:56 volumio node[1165]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 22:27:56 volumio volumio[1165]: *** WARNING *** For more information see Apr 14 22:27:56 volumio node[1165]: *** WARNING *** For more information see Apr 14 22:27:56 volumio volumio[1165]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 14 22:27:56 volumio volumio[1165]: info: Discovery: Started advertising with name: Volumio Apr 14 22:27:56 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 22:27:56 volumio volumio[1165]: info: Loading plugin "outputs"... Apr 14 22:27:56 volumio volumio[1165]: info: Loading plugin "albumart"... Apr 14 22:27:56 volumio volumio[1165]: info: Plugin example_plugin is not enabled Apr 14 22:27:56 volumio volumio[1165]: info: Loading plugin "inputs"... Apr 14 22:27:56 volumio volumio[1165]: info: Loading plugin "updater_comm"... Apr 14 22:27:57 volumio volumio[1165]: info: Plugin mpdemulation is not enabled Apr 14 22:27:57 volumio volumio[1165]: info: Loading plugin "rest_api"... Apr 14 22:27:57 volumio volumio[1165]: info: Loading plugin "websocket"... Apr 14 22:27:57 volumio volumio[1165]: info: Starting Socket.io Server version 1.7.4 Apr 14 22:27:57 volumio volumio[1165]: info: Loading i18n strings for locale en Apr 14 22:27:57 volumio volumio[1165]: Updating browse sources language Apr 14 22:27:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 22:27:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 22:27:57 volumio volumio[1165]: info: CoreCommandRouter::initPlayerControls Apr 14 22:27:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:27:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:27:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:27:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:27:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:27:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:27:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:27:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:27:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 22:27:57 volumio volumio[1165]: Express server listening on port 3000 Apr 14 22:27:57 volumio volumio[1165]: [Metrics] WebUI: 14s 139.60ms Apr 14 22:27:57 volumio volumio[1165]: info: CoreStateMachine::resetVolumioState Apr 14 22:27:57 volumio volumio[1165]: info: CoreStateMachine::getcurrentVolume Apr 14 22:27:57 volumio volumio[1165]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 22:27:57 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 14 22:27:57 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Apr 14 22:27:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 22:27:57 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 14 22:27:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:27:57 volumio volumio[1165]: info: Volumio Network Manager: Network status updated: 2 Apr 14 22:27:58 volumio volumio[1165]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Apr 14 22:27:58 volumio volumio[1482]: Forking 3 albumart workers Apr 14 22:27:58 volumio volumio[1165]: info: Reloading queue from file Apr 14 22:27:58 volumio volumio[1165]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Apr 14 22:27:58 volumio volumio[1165]: info: CoreStateMachine::setRepeat null single undefined Apr 14 22:27:58 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 14 22:27:58 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Apr 14 22:27:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 22:27:58 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 14 22:27:58 volumio volumio[1165]: info: CoreStateMachine::setRandom null Apr 14 22:27:59 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 14 22:27:59 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Apr 14 22:27:59 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 14 22:27:59 volumio volumio[1165]: info: Setting Device type: Raspberry PI Apr 14 22:27:59 volumio volumio[1165]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Apr 14 22:27:59 volumio volumio[1165]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Apr 14 22:27:59 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Apr 14 22:27:59 volumio volumio[1165]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Apr 14 22:27:59 volumio volumio[1165]: info: Discovery: adding 7cc7de5b-33e7-41ed-ac91-012bb09346d9 Apr 14 22:27:59 volumio volumio[1165]: info: Discovery: Found device Volumio Apr 14 22:27:59 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState Apr 14 22:27:59 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Apr 14 22:28:00 volumio volumio[1165]: info: Discovery: this is already registered, 7cc7de5b-33e7-41ed-ac91-012bb09346d9 Apr 14 22:28:00 volumio volumio[1165]: info: Discovery: Found device Volumio Apr 14 22:28:00 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState Apr 14 22:28:00 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Apr 14 22:28:00 volumio volumio[1165]: info: Completed loading Core Plugins Apr 14 22:28:00 volumio volumio[1165]: info: Preparing to generate the ALSA configuration file Apr 14 22:28:00 volumio volumio[1165]: info: Asound.conf file unchanged, so no further update is needed Apr 14 22:28:00 volumio volumio[1165]: info: Output device has changed, restarting MPD Apr 14 22:28:00 volumio volumio[1165]: info: Output device has changed, restarting Shairport Sync Apr 14 22:28:00 volumio sudo[1538]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 22:28:00 volumio sudo[1538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:28:00 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:00 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:28:00 volumio sudo[1538]: pam_unix(sudo:session): session closed for user root Apr 14 22:28:00 volumio sudo[1540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 22:28:00 volumio sudo[1540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:28:01 volumio volumio[1165]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 22:28:01 volumio volumio[1165]: info: ___________ START PLUGINS ___________ Apr 14 22:28:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 14 22:28:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 14 22:28:01 volumio volumio[1165]: info: ControllerMpd::onStart: Initializing MPD Apr 14 22:28:01 volumio volumio[1165]: info: Creating MPD Configuration file Apr 14 22:28:01 volumio sudo[1548]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 22:28:01 volumio sudo[1548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 14 22:28:01 volumio sudo[1553]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 14 22:28:01 volumio sudo[1548]: pam_unix(sudo:session): session closed for user root Apr 14 22:28:01 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 22:28:01 volumio sudo[1550]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Apr 14 22:28:01 volumio sudo[1550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:28:01 volumio volumio[1165]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 22:28:01 volumio volumio[1165]: info: [1776220081463] CoreMusicLibrary::Adding element Media Servers Apr 14 22:28:01 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 22:28:01 volumio sudo[1552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 22:28:01 volumio volumio[1165]: info: UPNP Browser: Client initialized successfully Apr 14 22:28:01 volumio sudo[1557]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 22:28:01 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 14 22:28:01 volumio sudo[1557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:28:01 volumio sudo[1550]: pam_unix(sudo:session): session closed for user root Apr 14 22:28:01 volumio sudo[1552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:28:01 volumio sudo[1552]: pam_unix(sudo:session): session closed for user root Apr 14 22:28:01 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 14 22:28:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 14 22:28:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 14 22:28:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 14 22:28:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 14 22:28:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 14 22:28:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 14 22:28:02 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:02 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:28:02 volumio sudo[1563]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 22:28:02 volumio sudo[1563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 14 22:28:02 volumio sudo[1570]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 14 22:28:02 volumio sudo[1563]: pam_unix(sudo:session): session closed for user root Apr 14 22:28:02 volumio volumio5-onboarding[1558]: time=2026-04-14T22:28:02.297-04:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Apr 14 22:28:02 volumio volumio[1165]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 22:28:02 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:02 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:28:02 volumio volumio[1165]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 22:28:02 volumio volumio[1165]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 22:28:02 volumio volumio[1165]: info: [1776220082545] CoreMusicLibrary::Adding element Last_100 Apr 14 22:28:02 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 22:28:02 volumio volumio[1165]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 22:28:02 volumio volumio[1165]: info: [1776220082613] CoreMusicLibrary::Adding element Webradio Apr 14 22:28:02 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 22:28:02 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 22:28:02 volumio volumio[1165]: info: Initializing BBC Radios Apr 14 22:28:02 volumio volumio-remote-updater[720]: [2026-04-14 22:28:02] [connect] Successful connection Apr 14 22:28:03 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 22:28:03 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:28:03 volumio volumio[1165]: info: Volumio Calling Home Apr 14 22:28:06 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Apr 14 22:28:09 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 14 22:28:09 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 14 22:28:09 volumio systemd[1]: setdatetime-helper.service: Consumed 1.140s CPU time. Apr 14 22:28:09 volumio sudo[1632]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 22:28:09 volumio sudo[1632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:28:09 volumio sudo[1632]: pam_unix(sudo:session): session closed for user root Apr 14 22:28:09 volumio volumio[1165]: info: MPD Permissions set Apr 14 22:28:09 volumio sudo[1638]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 22:28:09 volumio sudo[1638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:28:09 volumio volumio[1165]: info: MPD Permissions set Apr 14 22:28:09 volumio sudo[1638]: pam_unix(sudo:session): session closed for user root Apr 14 22:28:10 volumio volumio[1165]: 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: 6 Apr 14 22:28:10 volumio volumio[1165]: info: Volumio called home Apr 14 22:28:10 volumio mpd[1573]: 2026-04-14T22:28:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 14 22:28:10 volumio volumio[1496]: Starting albumart workers Apr 14 22:28:10 volumio volumio[1165]: 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: 6 Apr 14 22:28:10 volumio volumio[1165]: info: Received Get System Info Apr 14 22:28:10 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 22:28:10 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 22:28:10 volumio volumio[1165]: info: Discovery: Getting this device information Apr 14 22:28:10 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState Apr 14 22:28:10 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Apr 14 22:28:10 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 22:28:10 volumio volumio5-onboarding[1558]: time=2026-04-14T22:28:10.705-04:00 level=INFO msg="system info for 3aaa8fd4b27ae61340135cfabde6a573" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Apr 14 22:28:10 volumio volumio5-onboarding[1558]: time=2026-04-14T22:28:10.741-04:00 level=INFO msg="bootstrapping state" hasInternet=true Apr 14 22:28:10 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 14 22:28:10 volumio sudo[1557]: pam_unix(sudo:session): session closed for user root Apr 14 22:28:10 volumio sudo[1540]: pam_unix(sudo:session): session closed for user root Apr 14 22:28:11 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:11 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:11 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:11 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:11 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:11 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:11 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:11 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:11 volumio volumio[1497]: Starting albumart workers Apr 14 22:28:11 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Apr 14 22:28:11 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 22:28:11 volumio volumio[1498]: Starting albumart workers Apr 14 22:28:11 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 22:28:11 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:28:11 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:11 volumio upmpdcli[1650]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.1.107 port 49152 IPV6 port 49152 Apr 14 22:28:11 volumio upmpdcli[1650]: :3:../src/mediaserver/cdplugins/plgwithslave.cxx:248::PlgWithSlave: starting streaming proxy on port 49149 Apr 14 22:28:11 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:11 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:11 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:11 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:11 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:11 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:11 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:11 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:11 volumio upmpdcli[1663]: writing RSA key Apr 14 22:28:12 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:28:12 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:12 volumio volumio[1165]: info: Received Get System Info Apr 14 22:28:12 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 22:28:12 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 22:28:12 volumio volumio[1165]: info: Discovery: Getting this device information Apr 14 22:28:12 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState Apr 14 22:28:12 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Apr 14 22:28:12 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 22:28:12 volumio volumio[1165]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 14 22:28:12 volumio volumio[1165]: info: Completed starting Core Plugins Apr 14 22:28:12 volumio volumio[1165]: info: ------------------------------------------- Apr 14 22:28:12 volumio volumio[1165]: info: ----- MyVolumio plugins startup ---- Apr 14 22:28:12 volumio volumio[1165]: info: ------------------------------------------- Apr 14 22:28:12 volumio volumio[1165]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 14 22:28:12 volumio volumio[1165]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 14 22:28:13 volumio volumio[1165]: error: MPD error: The expression evaluated to a falsy value: Apr 14 22:28:13 volumio volumio[1165]: assert.ok(self.idling) Apr 14 22:28:14 volumio volumio[1165]: error: The expression evaluated to a falsy value: Apr 14 22:28:14 volumio volumio[1165]: assert.ok(self.idling) Apr 14 22:28:14 volumio volumio[1165]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 14 22:28:14 volumio volumio[1165]: info: MPD running with PID1573 Apr 14 22:28:14 volumio volumio[1165]: ,establishing connection Apr 14 22:28:14 volumio volumio[1165]: info: Starting Shairport Sync Apr 14 22:28:14 volumio volumio[1165]: info: Starting Shairport Sync Apr 14 22:28:14 volumio volumio[1165]: info: Starting Shairport Sync Apr 14 22:28:14 volumio sudo[1697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 22:28:14 volumio sudo[1697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:28:14 volumio volumio[1165]: error: updateQueue error: null Apr 14 22:28:14 volumio sudo[1695]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 14 22:28:14 volumio sudo[1695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:28:14 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 14 22:28:14 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 14 22:28:14 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 14 22:28:14 volumio systemd[1]: shairport-sync.service: Consumed 1.557s CPU time. Apr 14 22:28:14 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 14 22:28:14 volumio sudo[1697]: pam_unix(sudo:session): session closed for user root Apr 14 22:28:14 volumio sudo[1702]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 22:28:14 volumio sudo[1702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:28:14 volumio sudo[1699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 22:28:14 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 14 22:28:14 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 14 22:28:14 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 14 22:28:14 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 14 22:28:14 volumio sudo[1702]: pam_unix(sudo:session): session closed for user root Apr 14 22:28:14 volumio sudo[1699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:28:14 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 14 22:28:14 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 14 22:28:14 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 14 22:28:14 volumio sudo[1695]: pam_unix(sudo:session): session closed for user root Apr 14 22:28:14 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 14 22:28:15 volumio volumio[1165]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 14 22:28:15 volumio sudo[1699]: pam_unix(sudo:session): session closed for user root Apr 14 22:28:15 volumio volumio[1165]: info: Shairport-Sync Started Apr 14 22:28:15 volumio volumio[1165]: Error adding Membership: Error: addMembership EINVAL Apr 14 22:28:15 volumio volumio[1165]: info: Shairport-Sync Started Apr 14 22:28:15 volumio volumio[1165]: error: updateQueue error: null Apr 14 22:28:15 volumio volumio[1165]: info: Shairport-Sync Started Apr 14 22:28:15 volumio volumio[1165]: info: Upmpdcli Daemon Started Apr 14 22:28:16 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState Apr 14 22:28:16 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Apr 14 22:28:17 volumio volumio-remote-updater[720]: [2026-04-14 22:28:17] [connect] Successful connection Apr 14 22:28:17 volumio volumio-remote-updater[720]: [2026-04-14 22:28:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1776220097 101 Apr 14 22:28:17 volumio volumio[1165]: 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: 11 Apr 14 22:28:19 volumio volumio[1165]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 14 22:28:20 volumio volumio[1165]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Apr 14 22:28:21 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 22:28:21 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 22:28:21 volumio volumio[1165]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 14 22:28:21 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 14 22:28:21 volumio volumio[1165]: info: Received Get System Version Apr 14 22:28:21 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 22:28:21 volumio volumio[1165]: info: Received Get System Info Apr 14 22:28:21 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 22:28:21 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 22:28:21 volumio volumio[1165]: info: Discovery: Getting this device information Apr 14 22:28:21 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState Apr 14 22:28:21 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Apr 14 22:28:21 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 22:28:22 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 22:28:22 volumio volumio[1165]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 22:28:22 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 22:28:22 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState Apr 14 22:28:22 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Apr 14 22:28:22 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState Apr 14 22:28:22 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Apr 14 22:28:22 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 22:28:22 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 22:28:22 volumio volumio[1165]: info: Received Get System Info Apr 14 22:28:22 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 22:28:22 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 22:28:22 volumio volumio[1165]: info: Discovery: Getting this device information Apr 14 22:28:22 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState Apr 14 22:28:22 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Apr 14 22:28:22 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 22:28:22 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState Apr 14 22:28:22 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Apr 14 22:28:22 volumio volumio[1165]: info: Listing playlists Apr 14 22:28:36 volumio volumio[1165]: error: MyVolumio Plugin failed to start in a timely fashion Apr 14 22:28:36 volumio volumio[1165]: [Metrics] CommandRouter: 51s 214.14ms Apr 14 22:28:36 volumio volumio[1165]: info: CoreCommandRouter::volumiosetStartupVolume Apr 14 22:28:36 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:28:36 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:28:36 volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Apr 14 22:28:36 volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Apr 14 22:28:36 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 22:28:36 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:28:36 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 22:28:40 volumio volumio5-onboarding[1558]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Apr 14 22:28:40 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 14 22:28:40 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 14 22:28:40 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Apr 14 22:28:40 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 14 22:28:41 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 14 22:28:41 volumio volumio5-onboarding[1795]: time=2026-04-14T22:28:41.127-04:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Apr 14 22:28:43 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 14 22:28:43 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 22:28:43 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 14 22:28:43 volumio volumio[1165]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 22:28:43 volumio volumio[1165]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Apr 14 22:28:43 volumio volumio[1165]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Apr 14 22:28:43 volumio volumio[1165]: at new NodeError (node:internal/errors:405:5) Apr 14 22:28:43 volumio volumio[1165]: at assert (node:internal/assert:14:11) Apr 14 22:28:43 volumio volumio[1165]: at internalConnectMultiple (node:net:1118:3) Apr 14 22:28:43 volumio volumio[1165]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Apr 14 22:28:43 volumio volumio[1165]: at listOnTimeout (node:internal/timers:575:11) Apr 14 22:28:43 volumio volumio[1165]: at process.processTimers (node:internal/timers:514:7) { Apr 14 22:28:43 volumio volumio[1165]: code: 'ERR_INTERNAL_ASSERTION' Apr 14 22:28:43 volumio volumio[1165]: } Apr 14 22:28:43 volumio volumio[1165]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 22:28:50 volumio sudo[1835]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-14 22:27' Apr 14 22:28:50 volumio sudo[1835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 22:28:51 volumio volumio5-onboarding[1795]: 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:52288->127.0.0.1:3000: i/o timeout Apr 14 22:28:51 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 14 22:28:51 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 14 22:28:51 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Apr 14 22:28:51 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 14 22:28:51 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 14 22:28:51 volumio volumio5-onboarding[1837]: time=2026-04-14T22:28:51.537-04:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z 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"