Apr 03 13:20:05 volumio4 wireless.js[744]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Apr 03 13:20:05 volumio4 systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Apr 03 13:20:05 volumio4 systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Apr 03 13:20:05 volumio4 systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 03 13:20:05 volumio4 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 03 13:20:05 volumio4 systemd[1]: setdatetime-helper.service: Consumed 3.163s CPU time. Apr 03 13:20:05 volumio4 systemd[1]: systemd-rfkill.service: Deactivated successfully. Apr 03 13:20:05 volumio4 wireless.js[744]: WIRELESS.JS - INFO: === SNM TRANSITION === Apr 03 13:20:05 volumio4 wireless.js[744]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Apr 03 13:20:05 volumio4 wireless.js[744]: WIRELESS.JS - INFO: New ethernet state: connected Apr 03 13:20:05 volumio4 wireless.js[744]: WIRELESS.JS - INFO: Single Network Mode: enabled Apr 03 13:20:05 volumio4 wireless.js[744]: WIRELESS.JS - INFO: First start: yes Apr 03 13:20:05 volumio4 systemd[1]: Started bluealsa.service - BlueALSA service. Apr 03 13:20:05 volumio4 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Apr 03 13:20:05 volumio4 wireless.js[744]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Apr 03 13:20:05 volumio4 systemd[1]: Reached target bluetooth.target - Bluetooth Support. Apr 03 13:20:05 volumio4 wireless.js[744]: WIRELESS.JS - INFO: === END TRANSITION === Apr 03 13:20:05 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Apr 03 13:20:05 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Apr 03 13:20:05 volumio4 systemd[1]: welcome.service: Deactivated successfully. Apr 03 13:20:05 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Apr 03 13:20:05 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Apr 03 13:20:05 volumio4 wireless.js[744]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Apr 03 13:20:05 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Apr 03 13:20:05 volumio4 welcome[1168]: Resolved ip:[1] 192.168.1.109 Apr 03 13:20:05 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Apr 03 13:20:05 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Apr 03 13:20:06 volumio4 nmbd[1165]: [2026/04/03 13:20:06.073776, 0] ../../source3/nmbd/nmbd.c:901(main) Apr 03 13:20:06 volumio4 nmbd[1165]: nmbd version 4.17.8-Raspbian started. Apr 03 13:20:06 volumio4 nmbd[1165]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Apr 03 13:20:06 volumio4 nmbd[1165]: [2026/04/03 13:20:06.085794, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Apr 03 13:20:06 volumio4 nmbd[1165]: started asyncdns process 1178 Apr 03 13:20:06 volumio4 systemd[1]: Started nmbd.service - Samba NMB Daemon. Apr 03 13:20:06 volumio4 systemd[1]: Reloading. Apr 03 13:20:08 volumio4 volumio-remote-updater[740]: [2026-04-03 13:20:08] [info] asio async_connect error: asio.system:111 (Connection refused) Apr 03 13:20:08 volumio4 volumio-remote-updater[740]: [2026-04-03 13:20:08] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Apr 03 13:20:08 volumio4 volumio-remote-updater[740]: [2026-04-03 13:20:08] [error] handle_connect error: Connection refused Apr 03 13:20:09 volumio4 systemd[1]: Reloading. Apr 03 13:20:12 volumio4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Apr 03 13:20:12 volumio4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 13:20:12 volumio4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 13:20:12 volumio4 systemd[1]: Starting winbind.service - Samba Winbind Daemon... Apr 03 13:20:12 volumio4 systemd[1]: volumiossh.service: Deactivated successfully. Apr 03 13:20:12 volumio4 systemd[1]: Finished volumiossh.service - Volumio SSH enabler. Apr 03 13:20:12 volumio4 systemd[1]: volumiossh.service: Consumed 2.437s CPU time. Apr 03 13:20:12 volumio4 upmpdcli[1216]: Could not open config: /tmp/upmpdcli.conf Apr 03 13:20:12 volumio4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 03 13:20:12 volumio4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 03 13:20:13 volumio4 sudo[1220]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 03 13:20:13 volumio4 sudo[1220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 03 13:20:13 volumio4 sudo[1220]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:13 volumio4 sudo[1223]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 03 13:20:13 volumio4 sudo[1223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 03 13:20:13 volumio4 sudo[1223]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:13 volumio4 wireless.js[744]: WIRELESS.JS - INFO: Cleaning previous... Apr 03 13:20:13 volumio4 sudo[1226]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 03 13:20:13 volumio4 sudo[1226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 03 13:20:13 volumio4 sudo[1226]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:13 volumio4 wireless.js[744]: WIRELESS.JS - INFO: ensureInterfaceReady: ifconfig up failed: Error: Command failed: /usr/bin/sudo /sbin/ifconfig wlan0 up Apr 03 13:20:13 volumio4 wireless.js[744]: SIOCSIFFLAGS: Operation not possible due to RF-kill Apr 03 13:20:13 volumio4 wireless.js[744]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Apr 03 13:20:13 volumio4 wireless.js[744]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 20ms Apr 03 13:20:13 volumio4 wireless.js[744]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: b8:27:eb:0a:d2:6e) Apr 03 13:20:13 volumio4 sudo[1233]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 03 13:20:13 volumio4 sudo[1233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 13:20:13 volumio4 sudo[1233]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:13 volumio4 sudo[1241]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 03 13:20:13 volumio4 sudo[1241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 13:20:13 volumio4 sudo[1241]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:13 volumio4 wireless.js[744]: command failed: Network is down (-100) Apr 03 13:20:13 volumio4 wireless.js[744]: WIRELESS.JS - INFO: netconfigured file not found, starting hotspot Apr 03 13:20:13 volumio4 wireless.js[744]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Apr 03 13:20:13 volumio4 wireless.js[744]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Apr 03 13:20:13 volumio4 wireless.js[744]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Apr 03 13:20:13 volumio4 winbindd[1222]: [2026/04/03 13:20:13.925433, 0] ../../source3/winbindd/winbindd.c:1440(main) Apr 03 13:20:13 volumio4 winbindd[1222]: winbindd version 4.17.8-Raspbian started. Apr 03 13:20:13 volumio4 winbindd[1222]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Apr 03 13:20:13 volumio4 winbindd[1222]: [2026/04/03 13:20:13.962723, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Apr 03 13:20:13 volumio4 winbindd[1222]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 03 13:20:13 volumio4 systemd[1]: Started winbind.service - Samba Winbind Daemon. Apr 03 13:20:14 volumio4 sudo[1254]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 03 13:20:14 volumio4 sudo[1254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 03 13:20:14 volumio4 sudo[1254]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:14 volumio4 wireless.js[744]: WIRELESS.JS - INFO: keepWlanUpWithoutIP: Failed to bring interface UP: Error: Command failed: /usr/bin/sudo /sbin/ifconfig wlan0 up Apr 03 13:20:14 volumio4 wireless.js[744]: SIOCSIFFLAGS: Operation not possible due to RF-kill Apr 03 13:20:14 volumio4 wireless.js[744]: WIRELESS.JS - INFO: Failed to maintain scan mode: Error: Command failed: /usr/bin/sudo /sbin/ifconfig wlan0 up Apr 03 13:20:14 volumio4 wireless.js[744]: SIOCSIFFLAGS: Operation not possible due to RF-kill Apr 03 13:20:14 volumio4 wireless.js[744]: WIRELESS.JS - INFO: Falling back to interface DOWN Apr 03 13:20:14 volumio4 systemd[1]: Starting smbd.service - Samba SMB Daemon... Apr 03 13:20:14 volumio4 wireless.js[744]: WIRELESS.JS - INFO: Notified systemd about wireless ready Apr 03 13:20:14 volumio4 systemd[1]: Started wireless.service - Wireless Services. Apr 03 13:20:14 volumio4 systemd[1]: Started volumio.service - Volumio Backend Module. Apr 03 13:20:14 volumio4 systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Apr 03 13:20:14 volumio4 systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Apr 03 13:20:14 volumio4 systemd[1]: Received SIGRTMIN+21 from PID 315 (plymouthd). Apr 03 13:20:14 volumio4 systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Apr 03 13:20:14 volumio4 systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Apr 03 13:20:14 volumio4 systemd[1]: Received SIGRTMIN+21 from PID 315 (n/a). Apr 03 13:20:14 volumio4 systemd[1]: Started getty@tty1.service - Getty on tty1. Apr 03 13:20:14 volumio4 systemd[1]: Reached target getty.target - Login Prompts. Apr 03 13:20:14 volumio4 volumio-cpu-tweak[1262]: Setting RT Priority for mpd Apr 03 13:20:14 volumio4 volumio-cpu-tweak[1280]: pid 35's current scheduling policy: SCHED_OTHER Apr 03 13:20:14 volumio4 volumio-cpu-tweak[1280]: pid 35's current scheduling priority: 0 Apr 03 13:20:14 volumio4 volumio-cpu-tweak[1262]: Setting MPD Affinity Apr 03 13:20:14 volumio4 volumio-cpu-tweak[1282]: pid 3's current affinity mask: f Apr 03 13:20:14 volumio4 volumio-cpu-tweak[1262]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 03 13:20:14 volumio4 volumio-cpu-tweak[1262]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 03 13:20:14 volumio4 systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Apr 03 13:20:15 volumio4 smbd[1278]: [2026/04/03 13:20:15.377059, 0] ../../source3/smbd/server.c:1741(main) Apr 03 13:20:15 volumio4 smbd[1278]: smbd version 4.17.8-Raspbian started. Apr 03 13:20:15 volumio4 smbd[1278]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Apr 03 13:20:18 volumio4 volumio-remote-updater[740]: [2026-04-03 13:20:18] [info] asio async_connect error: asio.system:111 (Connection refused) Apr 03 13:20:18 volumio4 volumio-remote-updater[740]: [2026-04-03 13:20:18] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Apr 03 13:20:18 volumio4 volumio-remote-updater[740]: [2026-04-03 13:20:18] [error] handle_connect error: Connection refused Apr 03 13:20:18 volumio4 systemd[1]: Started smbd.service - Samba SMB Daemon. Apr 03 13:20:18 volumio4 systemd[1]: Reached target multi-user.target - Multi-User System. Apr 03 13:20:18 volumio4 systemd[1]: Reached target graphical.target - Graphical Interface. Apr 03 13:20:18 volumio4 systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Apr 03 13:20:19 volumio4 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Apr 03 13:20:19 volumio4 systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Apr 03 13:20:19 volumio4 systemd[1]: Startup finished in 17.029s (kernel) + 33.122s (userspace) = 50.151s. Apr 03 13:20:20 volumio4 systemd[1]: systemd-fsckd.service: Deactivated successfully. Apr 03 13:20:24 volumio4 volumio[1260]: info: ------------------------------------------- Apr 03 13:20:24 volumio4 volumio[1260]: info: ----- Volumio3 ---- Apr 03 13:20:24 volumio4 volumio[1260]: info: ------------------------------------------- Apr 03 13:20:24 volumio4 volumio[1260]: info: ----- System startup ---- Apr 03 13:20:24 volumio4 volumio[1260]: info: ------------------------------------------- Apr 03 13:20:26 volumio4 volumio[1260]: info: MYVOLUMIO Environment detected Apr 03 13:20:26 volumio4 volumio[1260]: info: Plugin folders cleanup Apr 03 13:20:26 volumio4 volumio[1260]: info: Scanning into folder /volumio/app/plugins/ Apr 03 13:20:26 volumio4 volumio[1260]: info: Scanning category audio_interface Apr 03 13:20:26 volumio4 volumio[1260]: info: Scanning category miscellanea Apr 03 13:20:26 volumio4 volumio[1260]: info: Scanning category music_service Apr 03 13:20:26 volumio4 volumio[1260]: info: Scanning category plugins.json Apr 03 13:20:26 volumio4 volumio[1260]: info: Scanning category system_controller Apr 03 13:20:26 volumio4 volumio[1260]: info: Scanning category user_interface Apr 03 13:20:26 volumio4 volumio[1260]: info: Scanning into folder /data/plugins/ Apr 03 13:20:26 volumio4 volumio[1260]: info: Scanning category music_service Apr 03 13:20:26 volumio4 volumio[1260]: info: Plugin folders cleanup completed Apr 03 13:20:26 volumio4 volumio[1260]: info: ------------------------------------------- Apr 03 13:20:26 volumio4 volumio[1260]: info: ----- Core plugins startup ---- Apr 03 13:20:26 volumio4 volumio[1260]: info: ------------------------------------------- Apr 03 13:20:26 volumio4 volumio[1260]: info: Loading plugins from folder /volumio/app/plugins/ Apr 03 13:20:26 volumio4 volumio[1260]: info: Adding plugin upnp to MyMusic Plugins Apr 03 13:20:26 volumio4 volumio[1260]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 03 13:20:26 volumio4 volumio[1260]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 03 13:20:26 volumio4 volumio[1260]: info: Loading plugins from folder /data/plugins/ Apr 03 13:20:26 volumio4 volumio[1260]: info: Loading plugin "system"... Apr 03 13:20:26 volumio4 volumio[1260]: info: Loading plugin "appearance"... Apr 03 13:20:27 volumio4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Apr 03 13:20:27 volumio4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 13:20:28 volumio4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 13:20:28 volumio4 upmpdcli[1317]: Could not open config: /tmp/upmpdcli.conf Apr 03 13:20:28 volumio4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 03 13:20:28 volumio4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 03 13:20:28 volumio4 volumio-remote-updater[740]: [2026-04-03 13:20:28] [connect] Successful connection Apr 03 13:20:30 volumio4 volumio[1260]: info: Loading plugin "network"... Apr 03 13:20:30 volumio4 volumio[1260]: info: Refreshing Cached IP Addresses Apr 03 13:20:30 volumio4 volumio[1260]: info: Loading plugin "services"... Apr 03 13:20:30 volumio4 volumio[1260]: info: Loading plugin "volumio5onboarding"... Apr 03 13:20:30 volumio4 sudo[1321]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 03 13:20:30 volumio4 sudo[1321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 13:20:30 volumio4 sudo[1319]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 03 13:20:30 volumio4 sudo[1319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 13:20:30 volumio4 sudo[1321]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:30 volumio4 volumio[1260]: info: Loading plugin "alsa_controller"... Apr 03 13:20:30 volumio4 sudo[1332]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 03 13:20:30 volumio4 sudo[1332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 13:20:30 volumio4 sudo[1319]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:30 volumio4 sudo[1332]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:30 volumio4 volumio[1260]: warn: Unable to locate the audio output device HiFiBerry DAC Plus. Please configure a valid output device. Apr 03 13:20:30 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 03 13:20:30 volumio4 volumio[1260]: info: Loading plugin "wizard"... Apr 03 13:20:30 volumio4 volumio[1260]: info: Loading plugin "networkfs"... Apr 03 13:20:31 volumio4 volumio[1260]: info: Starting Udev Watcher for removable devices Apr 03 13:20:31 volumio4 volumio[1260]: info: Ignoring mount for partition: boot Apr 03 13:20:31 volumio4 volumio[1260]: info: Ignoring mount for partition: volumio Apr 03 13:20:31 volumio4 volumio[1260]: info: Ignoring mount for partition: volumio_data Apr 03 13:20:31 volumio4 volumio[1260]: info: Mounting Device VOLUMIO1 Apr 03 13:20:31 volumio4 sudo[1355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/VOLUMIO1 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 03 13:20:31 volumio4 sudo[1355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 13:20:31 volumio4 kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Apr 03 13:20:31 volumio4 kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Apr 03 13:20:31 volumio4 sudo[1355]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:31 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 03 13:20:31 volumio4 volumio[1260]: info: Loading plugin "volumio_command_line_client"... Apr 03 13:20:31 volumio4 volumio[1260]: info: Loading plugin "upnp"... Apr 03 13:20:31 volumio4 volumio[1260]: info: [1775215231743] Starting Upmpd Daemon Apr 03 13:20:31 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 03 13:20:31 volumio4 volumio[1260]: info: Loading plugin "my_music"... Apr 03 13:20:31 volumio4 volumio[1260]: info: Loading plugin "mpd"... Apr 03 13:20:31 volumio4 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Apr 03 13:20:32 volumio4 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Apr 03 13:20:33 volumio4 volumio[1260]: info: Loading plugin "upnp_browser"... Apr 03 13:20:35 volumio4 systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 03 13:20:35 volumio4 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 03 13:20:35 volumio4 systemd[1]: setdatetime-helper.service: Consumed 2.069s CPU time. Apr 03 13:20:38 volumio4 volumio[1260]: info: Starting UPNP Browser Apr 03 13:20:38 volumio4 volumio[1260]: info: Loading plugin "alarm-clock"... Apr 03 13:20:38 volumio4 volumio[1260]: info: Loading plugin "airplay_emulation"... Apr 03 13:20:38 volumio4 volumio[1260]: info: Starting Shairport Sync Apr 03 13:20:38 volumio4 volumio[1260]: info: Loading plugin "last_100"... Apr 03 13:20:38 volumio4 volumio[1260]: info: Loading plugin "webradio"... Apr 03 13:20:39 volumio4 volumio[1260]: info: Loading plugin "i2s_dacs"... Apr 03 13:20:39 volumio4 volumio[1260]: info: Loading plugin "volumiodiscovery"... Apr 03 13:20:39 volumio4 volumio[1260]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 03 13:20:39 volumio4 volumio[1260]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 13:20:39 volumio4 volumio[1260]: *** WARNING *** For more information see Apr 03 13:20:39 volumio4 volumio[1260]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 03 13:20:39 volumio4 volumio[1260]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 13:20:39 volumio4 volumio[1260]: *** WARNING *** For more information see Apr 03 13:20:39 volumio4 node[1260]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 03 13:20:39 volumio4 node[1260]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 13:20:39 volumio4 node[1260]: *** WARNING *** For more information see Apr 03 13:20:39 volumio4 node[1260]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 03 13:20:39 volumio4 node[1260]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 13:20:39 volumio4 node[1260]: *** WARNING *** For more information see Apr 03 13:20:39 volumio4 volumio[1260]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 03 13:20:39 volumio4 volumio[1260]: info: Discovery: Started advertising with name: Volumio4 Apr 03 13:20:39 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 03 13:20:39 volumio4 volumio[1260]: info: Loading plugin "outputs"... Apr 03 13:20:39 volumio4 volumio[1260]: info: Loading plugin "albumart"... Apr 03 13:20:39 volumio4 volumio[1260]: info: Plugin example_plugin is not enabled Apr 03 13:20:39 volumio4 volumio[1260]: info: Loading plugin "inputs"... Apr 03 13:20:39 volumio4 volumio[1260]: info: Loading plugin "updater_comm"... Apr 03 13:20:40 volumio4 volumio[1260]: info: Plugin mpdemulation is not enabled Apr 03 13:20:40 volumio4 volumio[1260]: info: Loading plugin "rest_api"... Apr 03 13:20:40 volumio4 volumio[1260]: info: Loading plugin "websocket"... Apr 03 13:20:40 volumio4 volumio[1260]: info: Starting Socket.io Server version 1.7.4 Apr 03 13:20:40 volumio4 volumio[1260]: info: Plugin cdplayer is not enabled Apr 03 13:20:40 volumio4 volumio[1260]: info: Loading i18n strings for locale de Apr 03 13:20:40 volumio4 volumio[1260]: Updating browse sources language Apr 03 13:20:40 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 13:20:41 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 13:20:41 volumio4 volumio[1260]: info: CoreCommandRouter::initPlayerControls Apr 03 13:20:41 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:20:41 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:20:41 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:20:41 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:20:41 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:20:41 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:20:41 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:20:41 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:20:41 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 13:20:41 volumio4 volumio[1260]: Express server listening on port 3000 Apr 03 13:20:41 volumio4 volumio[1260]: [Metrics] WebUI: 19s 216.15ms Apr 03 13:20:41 volumio4 volumio[1260]: info: CoreStateMachine::resetVolumioState Apr 03 13:20:41 volumio4 volumio[1260]: info: CoreStateMachine::getcurrentVolume Apr 03 13:20:41 volumio4 volumio[1260]: info: CoreCommandRouter::volumioRetrievevolume Apr 03 13:20:41 volumio4 volumio[1260]: info: CoreStateMachine::pushState Apr 03 13:20:41 volumio4 volumio[1260]: info: CorePlayQueue::getTrack 0 Apr 03 13:20:41 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 13:20:41 volumio4 volumio[1260]: info: CoreCommandRouter::volumioPushState Apr 03 13:20:41 volumio4 volumio[1260]: info: Cannot read play queue from file Apr 03 13:20:42 volumio4 volumio[1260]: info: Volumio Network Manager: Network status updated: 1 Apr 03 13:20:42 volumio4 volumio[1388]: Forking 3 albumart workers Apr 03 13:20:42 volumio4 volumio[1260]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 03 13:20:42 volumio4 volumio[1260]: wlan0 Interface doesn't support scanning : Network is down Apr 03 13:20:42 volumio4 volumio[1260]: info: Cannot use regular scanning, forcing with ap-force Apr 03 13:20:42 volumio4 sudo[1418]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 03 13:20:42 volumio4 sudo[1418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 13:20:42 volumio4 sudo[1418]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:42 volumio4 volumio[1260]: command failed: Network is down (-100) Apr 03 13:20:42 volumio4 volumio[1260]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Apr 03 13:20:42 volumio4 volumio[1260]: command failed: Network is down (-100) Apr 03 13:20:42 volumio4 sudo[1447]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 03 13:20:42 volumio4 sudo[1447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 13:20:43 volumio4 sudo[1447]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:43 volumio4 sudo[1449]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 03 13:20:43 volumio4 sudo[1449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 13:20:43 volumio4 sudo[1449]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:43 volumio4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Apr 03 13:20:43 volumio4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 13:20:43 volumio4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 13:20:43 volumio4 upmpdcli[1453]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.1.109 port 49152 IPV6 port 49152 Apr 03 13:20:43 volumio4 upmpdcli[1453]: :3:../src/mediaserver/cdplugins/plgwithslave.cxx:248::PlgWithSlave: starting streaming proxy on port 49149 Apr 03 13:20:43 volumio4 upmpdcli[1453]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Apr 03 13:20:43 volumio4 upmpdcli[1453]: :2:../src/main.cxx:732::MPD connection failed Apr 03 13:20:43 volumio4 volumio-remote-updater[740]: [2026-04-03 13:20:43] [connect] Successful connection Apr 03 13:20:44 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 13:20:44 volumio4 volumio-remote-updater[740]: [2026-04-03 13:20:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1775215243 101 Apr 03 13:20:44 volumio4 volumio[1260]: 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 Apr 03 13:20:44 volumio4 volumio[1260]: info: CoreStateMachine::setRepeat null single undefined Apr 03 13:20:44 volumio4 volumio[1260]: info: CoreStateMachine::pushState Apr 03 13:20:44 volumio4 volumio[1260]: info: CorePlayQueue::getTrack 0 Apr 03 13:20:44 volumio4 volumio[1260]: info: CoreCommandRouter::volumioPushState Apr 03 13:20:44 volumio4 volumio[1260]: info: CoreStateMachine::setRandom null Apr 03 13:20:44 volumio4 volumio[1260]: info: CoreStateMachine::pushState Apr 03 13:20:44 volumio4 volumio[1260]: info: CorePlayQueue::getTrack 0 Apr 03 13:20:44 volumio4 volumio[1260]: info: CoreCommandRouter::volumioPushState Apr 03 13:20:45 volumio4 volumio[1260]: info: Setting Device type: Raspberry PI Apr 03 13:20:45 volumio4 upmpdcli[1453]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Apr 03 13:20:45 volumio4 upmpdcli[1453]: :2:../src/main.cxx:732::MPD connection failed Apr 03 13:20:45 volumio4 volumio[1260]: info: Discovery: adding cb52b557-5e64-4c92-809c-7ac84d5a9fd2 Apr 03 13:20:45 volumio4 volumio[1260]: info: Discovery: Found device Volumio4 Apr 03 13:20:45 volumio4 volumio[1260]: info: CoreCommandRouter::volumioGetState Apr 03 13:20:45 volumio4 volumio[1260]: info: CorePlayQueue::getTrack 0 Apr 03 13:20:45 volumio4 volumio[1260]: info: Discovery: this is already registered, cb52b557-5e64-4c92-809c-7ac84d5a9fd2 Apr 03 13:20:45 volumio4 volumio[1260]: info: Discovery: Found device Volumio4 Apr 03 13:20:45 volumio4 volumio[1260]: info: CoreCommandRouter::volumioGetState Apr 03 13:20:45 volumio4 volumio[1260]: info: CorePlayQueue::getTrack 0 Apr 03 13:20:46 volumio4 sudo[1475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 03 13:20:46 volumio4 sudo[1475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 13:20:46 volumio4 sudo[1475]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:46 volumio4 volumio[1260]: info: Completed loading Core Plugins Apr 03 13:20:46 volumio4 volumio[1260]: info: Preparing to generate the ALSA configuration file Apr 03 13:20:47 volumio4 volumio[1260]: info: Upmpdcli Daemon Started Apr 03 13:20:47 volumio4 volumio[1260]: info: Asound.conf file unchanged, so no further update is needed Apr 03 13:20:47 volumio4 volumio[1260]: info: Output device has changed, restarting MPD Apr 03 13:20:47 volumio4 volumio[1260]: info: Output device has changed, restarting Shairport Sync Apr 03 13:20:47 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:20:47 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 13:20:47 volumio4 sudo[1478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 03 13:20:47 volumio4 sudo[1478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 13:20:48 volumio4 sudo[1480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 03 13:20:48 volumio4 sudo[1480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 13:20:48 volumio4 sudo[1478]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:48 volumio4 volumio[1260]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 13:20:48 volumio4 volumio[1260]: info: ___________ START PLUGINS ___________ Apr 03 13:20:48 volumio4 volumio[1260]: info: ControllerMpd::onStart: Initializing MPD Apr 03 13:20:48 volumio4 volumio[1260]: info: Creating MPD Configuration file Apr 03 13:20:48 volumio4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 03 13:20:48 volumio4 systemd[1]: Starting mpd.service - Music Player Daemon... Apr 03 13:20:48 volumio4 sudo[1488]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Apr 03 13:20:48 volumio4 sudo[1488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 13:20:48 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 13:20:48 volumio4 volumio[1260]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 03 13:20:48 volumio4 volumio[1260]: info: [1775215248755] CoreMusicLibrary::Adding element Medienserver Apr 03 13:20:48 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 13:20:48 volumio4 sudo[1493]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 03 13:20:48 volumio4 sudo[1493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 03 13:20:49 volumio4 sudo[1496]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 03 13:20:49 volumio4 sudo[1493]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:49 volumio4 sudo[1490]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 03 13:20:49 volumio4 sudo[1495]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 03 13:20:49 volumio4 sudo[1490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 13:20:49 volumio4 sudo[1495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 13:20:49 volumio4 volumio[1260]: info: UPNP Browser: Client initialized successfully Apr 03 13:20:49 volumio4 sudo[1490]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:49 volumio4 systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 13:20:49 volumio4 sudo[1488]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:49 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:20:49 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 13:20:49 volumio4 systemd[1]: mpd.service: Deactivated successfully. Apr 03 13:20:49 volumio4 systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 03 13:20:49 volumio4 systemd[1]: mpd.socket: Deactivated successfully. Apr 03 13:20:49 volumio4 upmpdcli[1453]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection reset by peer Apr 03 13:20:49 volumio4 upmpdcli[1453]: :2:../src/main.cxx:732::MPD connection failed Apr 03 13:20:49 volumio4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 03 13:20:49 volumio4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 03 13:20:49 volumio4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 03 13:20:49 volumio4 systemd[1]: Starting mpd.service - Music Player Daemon... Apr 03 13:20:50 volumio4 volumio[1260]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 13:20:50 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:20:50 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 13:20:50 volumio4 sudo[1508]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 03 13:20:50 volumio4 sudo[1508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 03 13:20:50 volumio4 sudo[1514]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 03 13:20:50 volumio4 sudo[1508]: pam_unix(sudo:session): session closed for user root Apr 03 13:20:50 volumio4 volumio5-onboarding[1500]: time=2026-04-03T13:20:50.591+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Apr 03 13:20:50 volumio4 volumio[1260]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 13:20:50 volumio4 volumio[1260]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 03 13:20:50 volumio4 volumio[1260]: info: [1775215250674] CoreMusicLibrary::Adding element Last_100 Apr 03 13:20:50 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 13:20:50 volumio4 volumio[1260]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 03 13:20:50 volumio4 volumio[1260]: info: [1775215250692] CoreMusicLibrary::Adding element Webradio Apr 03 13:20:50 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 13:20:50 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 03 13:20:50 volumio4 volumio[1260]: info: Initializing BBC Radios Apr 03 13:20:51 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 03 13:20:51 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 13:20:52 volumio4 volumio[1260]: info: Volumio Calling Home Apr 03 13:20:59 volumio4 upmpdcli[1453]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Apr 03 13:20:59 volumio4 upmpdcli[1453]: :2:../src/main.cxx:732::MPD connection failed Apr 03 13:21:00 volumio4 volumio5-onboarding[1500]: 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:36612->127.0.0.1:3000: i/o timeout Apr 03 13:21:00 volumio4 systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 03 13:21:00 volumio4 systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 03 13:21:00 volumio4 systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Apr 03 13:21:01 volumio4 systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 13:21:01 volumio4 systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 13:21:01 volumio4 volumio5-onboarding[1552]: time=2026-04-03T13:21:01.194+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Apr 03 13:21:02 volumio4 mpd[1516]: 2026-04-03T13:21:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 03 13:21:03 volumio4 systemd[1]: Started mpd.service - Music Player Daemon. Apr 03 13:21:03 volumio4 sudo[1495]: pam_unix(sudo:session): session closed for user root Apr 03 13:21:03 volumio4 sudo[1480]: pam_unix(sudo:session): session closed for user root Apr 03 13:21:03 volumio4 volumio[1417]: Starting albumart workers Apr 03 13:21:04 volumio4 volumio[1260]: info: MPD Permissions set Apr 03 13:21:04 volumio4 volumio[1260]: info: Completed starting Core Plugins Apr 03 13:21:04 volumio4 volumio[1260]: info: ------------------------------------------- Apr 03 13:21:04 volumio4 volumio[1260]: info: ----- MyVolumio plugins startup ---- Apr 03 13:21:04 volumio4 volumio[1260]: info: ------------------------------------------- Apr 03 13:21:04 volumio4 volumio[1260]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 03 13:21:04 volumio4 volumio[1419]: Starting albumart workers Apr 03 13:21:04 volumio4 volumio[1260]: info: MPD Permissions set Apr 03 13:21:05 volumio4 volumio[1420]: Starting albumart workers Apr 03 13:21:05 volumio4 volumio[1260]: info: Volumio called home Apr 03 13:21:07 volumio4 volumio[1260]: error: MPD error: The expression evaluated to a falsy value: Apr 03 13:21:07 volumio4 volumio[1260]: assert.ok(self.idling) Apr 03 13:21:07 volumio4 volumio[1260]: error: The expression evaluated to a falsy value: Apr 03 13:21:07 volumio4 volumio[1260]: assert.ok(self.idling) Apr 03 13:21:07 volumio4 volumio[1260]: 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: 2 Apr 03 13:21:07 volumio4 volumio[1260]: info: MPD running with PID1516 Apr 03 13:21:07 volumio4 volumio[1260]: ,establishing connection Apr 03 13:21:07 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:21:07 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:21:07 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:21:07 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:21:07 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:21:07 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:21:07 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:21:07 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:21:07 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 13:21:07 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:21:07 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:21:07 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:21:07 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:21:08 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:21:08 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:21:08 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:21:08 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:21:08 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:21:08 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 13:21:08 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:21:08 volumio4 volumio[1260]: error: updateQueue error: null Apr 03 13:21:09 volumio4 volumio[1260]: 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: 2 Apr 03 13:21:09 volumio4 volumio[1260]: info: Received Get System Info Apr 03 13:21:09 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 13:21:09 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 13:21:09 volumio4 volumio[1260]: info: Discovery: Getting this device information Apr 03 13:21:09 volumio4 volumio[1260]: info: CoreCommandRouter::volumioGetState Apr 03 13:21:09 volumio4 volumio[1260]: info: CorePlayQueue::getTrack 0 Apr 03 13:21:09 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 13:21:09 volumio4 volumio5-onboarding[1552]: time=2026-04-03T13:21:09.603+02:00 level=INFO msg="system info for 1a0c7bf6bc9bbb4e5121560e754b39ff" deviceName=Volumio4 deviceVariant=volumio deviceModel= softwareVersion=4.119 Apr 03 13:21:09 volumio4 volumio5-onboarding[1552]: time=2026-04-03T13:21:09.677+02:00 level=INFO msg="bootstrapping state" hasInternet=true Apr 03 13:21:10 volumio4 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 03 13:21:10 volumio4 volumio[1260]: info: Received Get System Info Apr 03 13:21:10 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 13:21:10 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 13:21:10 volumio4 volumio[1260]: info: Discovery: Getting this device information Apr 03 13:21:10 volumio4 volumio[1260]: info: CoreCommandRouter::volumioGetState Apr 03 13:21:10 volumio4 volumio[1260]: info: CorePlayQueue::getTrack 0 Apr 03 13:21:10 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 13:21:11 volumio4 volumio-remote-updater[740]: Test mode disabled Apr 03 13:21:11 volumio4 volumio-remote-updater[740]: Alpha mode disabled Apr 03 13:21:11 volumio4 volumio-remote-updater[740]: Alpha legacy test mode disabled Apr 03 13:21:11 volumio4 volumio[1260]: error: updateQueue error: null Apr 03 13:21:12 volumio4 volumio[1260]: info: Starting Shairport Sync Apr 03 13:21:12 volumio4 volumio[1260]: info: Starting Shairport Sync Apr 03 13:21:12 volumio4 sudo[1595]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 13:21:12 volumio4 sudo[1595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 13:21:12 volumio4 volumio[1260]: info: Starting Shairport Sync Apr 03 13:21:12 volumio4 sudo[1597]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 13:21:12 volumio4 sudo[1597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 13:21:12 volumio4 volumio[1260]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 03 13:21:12 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 03 13:21:12 volumio4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 03 13:21:12 volumio4 systemd[1]: shairport-sync.service: Deactivated successfully. Apr 03 13:21:12 volumio4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 03 13:21:12 volumio4 systemd[1]: shairport-sync.service: Consumed 2.720s CPU time. Apr 03 13:21:12 volumio4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 03 13:21:12 volumio4 sudo[1595]: pam_unix(sudo:session): session closed for user root Apr 03 13:21:12 volumio4 sudo[1600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 13:21:12 volumio4 sudo[1600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 13:21:12 volumio4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 03 13:21:12 volumio4 systemd[1]: shairport-sync.service: Deactivated successfully. Apr 03 13:21:12 volumio4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 03 13:21:12 volumio4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 03 13:21:12 volumio4 sudo[1597]: pam_unix(sudo:session): session closed for user root Apr 03 13:21:13 volumio4 ntpd[872]: CLOCK: time stepped by 0.424009 Apr 03 13:21:13 volumio4 ntpd[872]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Apr 03 13:21:13 volumio4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 03 13:21:13 volumio4 systemd[1]: shairport-sync.service: Deactivated successfully. Apr 03 13:21:13 volumio4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 03 13:21:13 volumio4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 03 13:21:13 volumio4 sudo[1600]: pam_unix(sudo:session): session closed for user root Apr 03 13:21:14 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 03 13:21:14 volumio4 volumio5-onboarding[1552]: time=2026-04-03T13:21:14.565+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Apr 03 13:21:14 volumio4 volumio5-onboarding[1552]: time=2026-04-03T13:21:14.567+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Apr 03 13:21:14 volumio4 volumio5-onboarding[1552]: time=2026-04-03T13:21:14.568+02:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Apr 03 13:21:16 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 03 13:21:17 volumio4 volumio[1260]: info: Shairport-Sync Started Apr 03 13:21:17 volumio4 volumio[1260]: Error adding Membership: Error: addMembership EINVAL Apr 03 13:21:17 volumio4 volumio[1260]: info: Shairport-Sync Started Apr 03 13:21:17 volumio4 volumio[1260]: info: Shairport-Sync Started Apr 03 13:21:24 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 03 13:21:25 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 03 13:21:25 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 13:21:25 volumio4 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 03 13:21:25 volumio4 volumio[1260]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 13:21:25 volumio4 volumio[1260]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Apr 03 13:21:25 volumio4 volumio[1260]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Apr 03 13:21:25 volumio4 volumio[1260]: at new NodeError (node:internal/errors:405:5) Apr 03 13:21:25 volumio4 volumio[1260]: at assert (node:internal/assert:14:11) Apr 03 13:21:25 volumio4 volumio[1260]: at internalConnectMultiple (node:net:1118:3) Apr 03 13:21:25 volumio4 volumio[1260]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Apr 03 13:21:25 volumio4 volumio[1260]: at listOnTimeout (node:internal/timers:575:11) Apr 03 13:21:25 volumio4 volumio[1260]: at process.processTimers (node:internal/timers:514:7) { Apr 03 13:21:25 volumio4 volumio[1260]: code: 'ERR_INTERNAL_ASSERTION' Apr 03 13:21:25 volumio4 volumio[1260]: } Apr 03 13:21:25 volumio4 volumio[1260]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 13:21:34 volumio4 volumio5-onboarding[1552]: failed to configure discovery: failed to get system info: could not get system info: context deadline exceeded Apr 03 13:21:34 volumio4 systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 03 13:21:34 volumio4 systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 03 13:21:34 volumio4 systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Apr 03 13:21:34 volumio4 systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 13:21:34 volumio4 systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 13:21:34 volumio4 volumio5-onboarding[1714]: time=2026-04-03T13:21:34.856+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Apr 03 13:21:41 volumio4 sudo[1734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-03 13:20' Apr 03 13:21:41 volumio4 sudo[1734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"