Feb 22 15:27:44 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 22 15:27:44 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 22 15:27:44 volumio systemd[1]: setdatetime-helper.service: Consumed 1.599s CPU time. Feb 22 15:27:44 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Feb 22 15:27:44 volumio dhcpcd[742]: eth0: leased 192.168.0.13 for 3600 seconds Feb 22 15:27:44 volumio dhcpcd[742]: eth0: adding route to 192.168.0.0/24 Feb 22 15:27:44 volumio dhcpcd[742]: eth0: adding default route via 192.168.0.1 Feb 22 15:27:44 volumio winbindd[1099]: [2026/02/22 15:27:44.129844, 0] ../../source3/winbindd/winbindd_idmap.c:372(wb_parent_idmap_setup_lookupname_done) Feb 22 15:27:44 volumio winbindd[1099]: wb_parent_idmap_setup_lookupname_done: Lookup domain name 'VOLUMIO' failed 'NT_STATUS_IO_TIMEOUT' Feb 22 15:27:44 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Feb 22 15:27:44 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Feb 22 15:27:44 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Feb 22 15:27:44 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Feb 22 15:27:44 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Feb 22 15:27:44 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Feb 22 15:27:44 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Feb 22 15:27:44 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Feb 22 15:27:44 volumio systemd[1]: welcome.service: Deactivated successfully. Feb 22 15:27:44 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 22 15:27:44 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 22 15:27:44 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 22 15:27:44 volumio welcome[1179]: Resolved ip:[1] 192.168.0.13 Feb 22 15:27:44 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Feb 22 15:27:44 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Feb 22 15:27:44 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 22 15:27:44 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 22 15:27:44 volumio smbd[1146]: [2026/02/22 15:27:44.532861, 0] ../../source3/smbd/server.c:1741(main) Feb 22 15:27:44 volumio smbd[1146]: smbd version 4.17.8-Raspbian started. Feb 22 15:27:44 volumio smbd[1146]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Feb 22 15:27:44 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Feb 22 15:27:44 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Feb 22 15:27:44 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Feb 22 15:27:44 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Feb 22 15:27:44 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Feb 22 15:27:44 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Feb 22 15:27:44 volumio systemd[1]: Startup finished in 15.397s (kernel) + 13.516s (userspace) = 28.914s. Feb 22 15:27:45 volumio ntpd[966]: IO: Listen normally on 3 eth0 192.168.0.13:123 Feb 22 15:27:45 volumio ntpd[966]: IO: new interface(s) found: waking up resolver Feb 22 15:27:45 volumio ntpd[966]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 22 15:27:45 volumio ntpd[966]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Feb 22 15:27:45 volumio ntpd[966]: DNS: Pool skipping: 185.234.20.134 Feb 22 15:27:45 volumio ntpd[966]: DNS: Pool skipping: 12.205.28.193 Feb 22 15:27:45 volumio ntpd[966]: DNS: Pool skipping: 23.186.168.131 Feb 22 15:27:45 volumio ntpd[966]: DNS: Pool skipping: 66.70.172.17 Feb 22 15:27:45 volumio ntpd[966]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Feb 22 15:27:46 volumio winbindd[1107]: [2026/02/22 15:27:46.400654, 0] ../../source3/winbindd/winbindd_dual.c:162(child_write_response) Feb 22 15:27:46 volumio winbindd[1107]: Could not write result Feb 22 15:27:46 volumio ntpd[966]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 22 15:27:46 volumio ntpd[966]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Feb 22 15:27:46 volumio ntpd[966]: DNS: Pool skipping: 192.48.105.15 Feb 22 15:27:46 volumio ntpd[966]: DNS: Pool skipping: 23.131.160.7 Feb 22 15:27:46 volumio ntpd[966]: DNS: Pool skipping: 66.42.71.197 Feb 22 15:27:46 volumio ntpd[966]: DNS: Pool skipping: 23.186.168.123 Feb 22 15:27:46 volumio ntpd[966]: DNS: Pool skipping: 2600:3c02::f03c:94ff:fee2:cb31 Feb 22 15:27:46 volumio ntpd[966]: DNS: Pool skipping: 2001:470:1f1e:b2::123 Feb 22 15:27:46 volumio ntpd[966]: DNS: Pool skipping: 2604:a880:1:20::17:5001 Feb 22 15:27:46 volumio ntpd[966]: DNS: Pool skipping: 2604:8800:52:81:38:229:52:9 Feb 22 15:27:46 volumio ntpd[966]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Feb 22 15:27:47 volumio ntpd[966]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 22 15:27:47 volumio ntpd[966]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Feb 22 15:27:47 volumio ntpd[966]: DNS: Pool taking: 144.202.41.38 Feb 22 15:27:47 volumio ntpd[966]: DNS: Pool taking: 23.95.49.216 Feb 22 15:27:47 volumio ntpd[966]: DNS: Pool taking: 44.190.5.123 Feb 22 15:27:47 volumio ntpd[966]: DNS: Pool taking: 45.33.53.84 Feb 22 15:27:47 volumio ntpd[966]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Feb 22 15:27:48 volumio ntpd[966]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 22 15:27:48 volumio ntpd[966]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Feb 22 15:27:48 volumio ntpd[966]: DNS: Pool taking: 216.229.0.50 Feb 22 15:27:48 volumio ntpd[966]: DNS: Pool taking: 167.160.187.12 Feb 22 15:27:48 volumio ntpd[966]: DNS: Pool taking: 23.95.35.34 Feb 22 15:27:48 volumio ntpd[966]: DNS: Pool taking: 172.234.37.140 Feb 22 15:27:48 volumio ntpd[966]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Feb 22 15:27:49 volumio volumio-remote-updater[673]: [2026-02-22 15:27:49] [connect] Successful connection Feb 22 15:27:49 volumio volumio[1112]: info: ------------------------------------------- Feb 22 15:27:49 volumio volumio[1112]: info: ----- Volumio3 ---- Feb 22 15:27:49 volumio volumio[1112]: info: ------------------------------------------- Feb 22 15:27:49 volumio volumio[1112]: info: ----- System startup ---- Feb 22 15:27:49 volumio volumio[1112]: info: ------------------------------------------- Feb 22 15:27:51 volumio volumio[1112]: info: MYVOLUMIO Environment detected Feb 22 15:27:51 volumio volumio[1112]: info: Plugin folders cleanup Feb 22 15:27:51 volumio volumio[1112]: info: Scanning into folder /volumio/app/plugins/ Feb 22 15:27:51 volumio volumio[1112]: info: Scanning category audio_interface Feb 22 15:27:51 volumio volumio[1112]: info: Scanning category miscellanea Feb 22 15:27:51 volumio volumio[1112]: info: Scanning category music_service Feb 22 15:27:51 volumio volumio[1112]: info: Scanning category plugins.json Feb 22 15:27:51 volumio volumio[1112]: info: Scanning category system_controller Feb 22 15:27:51 volumio volumio[1112]: info: Scanning category user_interface Feb 22 15:27:51 volumio volumio[1112]: info: Scanning into folder /data/plugins/ Feb 22 15:27:51 volumio volumio[1112]: info: Plugin folders cleanup completed Feb 22 15:27:51 volumio volumio[1112]: info: ------------------------------------------- Feb 22 15:27:51 volumio volumio[1112]: info: ----- Core plugins startup ---- Feb 22 15:27:51 volumio volumio[1112]: info: ------------------------------------------- Feb 22 15:27:51 volumio volumio[1112]: info: Loading plugins from folder /volumio/app/plugins/ Feb 22 15:27:51 volumio volumio[1112]: info: Adding plugin upnp to MyMusic Plugins Feb 22 15:27:51 volumio volumio[1112]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 22 15:27:51 volumio volumio[1112]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 22 15:27:51 volumio volumio[1112]: info: Loading plugins from folder /data/plugins/ Feb 22 15:27:51 volumio volumio[1112]: info: Loading plugin "system"... Feb 22 15:27:51 volumio volumio[1112]: info: Loading plugin "appearance"... Feb 22 15:27:52 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Feb 22 15:27:52 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 22 15:27:52 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 22 15:27:52 volumio upmpdcli[1212]: Could not open config: /tmp/upmpdcli.conf Feb 22 15:27:52 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:27:52 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 22 15:27:54 volumio ntpd[966]: CLOCK: time stepped by 0.580157 Feb 22 15:27:54 volumio ntpd[966]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 22 15:27:54 volumio volumio[1112]: info: Loading plugin "network"... Feb 22 15:27:54 volumio volumio[1112]: info: Refreshing Cached IP Addresses Feb 22 15:27:54 volumio volumio[1112]: info: Loading plugin "services"... Feb 22 15:27:54 volumio volumio[1112]: info: Loading plugin "volumio5onboarding"... Feb 22 15:27:54 volumio volumio[1112]: info: Loading plugin "alsa_controller"... Feb 22 15:27:54 volumio sudo[1216]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 15:27:54 volumio sudo[1214]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 15:27:54 volumio sudo[1214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:27:54 volumio sudo[1216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:27:54 volumio sudo[1214]: pam_unix(sudo:session): session closed for user root Feb 22 15:27:54 volumio sudo[1216]: pam_unix(sudo:session): session closed for user root Feb 22 15:27:55 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 15:27:55 volumio volumio[1112]: info: Loading plugin "wizard"... Feb 22 15:27:55 volumio volumio[1112]: info: Loading plugin "networkfs"... Feb 22 15:27:55 volumio volumio[1112]: info: Starting Udev Watcher for removable devices Feb 22 15:27:55 volumio volumio[1112]: info: Ignoring mount for partition: boot Feb 22 15:27:55 volumio volumio[1112]: info: Ignoring mount for partition: volumio Feb 22 15:27:55 volumio volumio[1112]: info: Ignoring mount for partition: volumio_data Feb 22 15:27:55 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 22 15:27:55 volumio volumio[1112]: info: Loading plugin "volumio_command_line_client"... Feb 22 15:27:55 volumio volumio[1112]: info: Plugin upnp is not enabled Feb 22 15:27:55 volumio volumio[1112]: info: Loading plugin "my_music"... Feb 22 15:27:55 volumio sudo[1246]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=ariel,password=123456,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.7/musica /mnt/NAS/MUSICA Feb 22 15:27:55 volumio sudo[1246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:27:55 volumio volumio[1112]: info: Loading plugin "mpd"... Feb 22 15:27:55 volumio kernel: netfs: FS-Cache loaded Feb 22 15:27:55 volumio kernel: Key type cifs.spnego registered Feb 22 15:27:55 volumio kernel: Key type cifs.idmap registered Feb 22 15:27:55 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. Feb 22 15:27:55 volumio kernel: CIFS: Attempting to mount //192.168.0.7/musica Feb 22 15:27:56 volumio sudo[1246]: pam_unix(sudo:session): session closed for user root Feb 22 15:27:56 volumio volumio[1112]: info: Plugin upnp_browser is not enabled Feb 22 15:27:56 volumio volumio[1112]: info: Loading plugin "alarm-clock"... Feb 22 15:27:56 volumio volumio[1112]: info: Plugin airplay_emulation is not enabled Feb 22 15:27:56 volumio volumio[1112]: info: Loading plugin "last_100"... Feb 22 15:27:56 volumio volumio[1112]: info: Loading plugin "webradio"... Feb 22 15:27:59 volumio volumio[1112]: info: Loading plugin "i2s_dacs"... Feb 22 15:27:59 volumio volumio[1112]: info: I2S DAC not set, start Auto-detection Feb 22 15:27:59 volumio volumio[1112]: info: Loading plugin "volumiodiscovery"... Feb 22 15:27:59 volumio volumio[1112]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 22 15:27:59 volumio volumio[1112]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 15:27:59 volumio volumio[1112]: *** WARNING *** For more information see Feb 22 15:27:59 volumio volumio[1112]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 22 15:27:59 volumio volumio[1112]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 15:27:59 volumio volumio[1112]: *** WARNING *** For more information see Feb 22 15:27:59 volumio node[1112]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 22 15:27:59 volumio node[1112]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 15:27:59 volumio node[1112]: *** WARNING *** For more information see Feb 22 15:27:59 volumio node[1112]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 22 15:27:59 volumio node[1112]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 15:27:59 volumio node[1112]: *** WARNING *** For more information see Feb 22 15:27:59 volumio volumio[1112]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 22 15:27:59 volumio volumio[1112]: info: Discovery: Started advertising with name: Volumio Feb 22 15:27:59 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 22 15:27:59 volumio volumio[1112]: info: Loading plugin "outputs"... Feb 22 15:27:59 volumio volumio[1112]: info: Loading plugin "albumart"... Feb 22 15:27:59 volumio volumio[1112]: info: Plugin example_plugin is not enabled Feb 22 15:27:59 volumio volumio[1112]: info: Loading plugin "inputs"... Feb 22 15:27:59 volumio volumio[1112]: info: Loading plugin "updater_comm"... Feb 22 15:27:59 volumio volumio[1112]: info: Plugin mpdemulation is not enabled Feb 22 15:27:59 volumio volumio[1112]: info: Loading plugin "rest_api"... Feb 22 15:27:59 volumio volumio[1112]: info: Loading plugin "websocket"... Feb 22 15:27:59 volumio volumio[1112]: info: Starting Socket.io Server version 1.7.4 Feb 22 15:27:59 volumio volumio[1112]: info: Loading i18n strings for locale es Feb 22 15:27:59 volumio volumio[1112]: Updating browse sources language Feb 22 15:27:59 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:28:00 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:28:00 volumio volumio[1112]: info: CoreCommandRouter::initPlayerControls Feb 22 15:28:00 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:00 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:00 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:00 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:00 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:00 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:00 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:00 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:00 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:28:00 volumio volumio[1112]: Express server listening on port 3000 Feb 22 15:28:00 volumio volumio[1112]: [Metrics] WebUI: 11s 126.16ms Feb 22 15:28:00 volumio volumio[1112]: info: CoreStateMachine::resetVolumioState Feb 22 15:28:00 volumio volumio[1112]: info: CoreStateMachine::getcurrentVolume Feb 22 15:28:00 volumio volumio[1112]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 15:28:00 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:28:00 volumio volumio[1112]: info: Volumio Network Manager: Network status updated: 1 Feb 22 15:28:00 volumio volumio[1112]: verbose: New Socket.io Connection to 192.168.0.13 from 192.168.0.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 22 15:28:00 volumio volumio[1112]: info: VolumeController:: Volume=86 Mute =false Feb 22 15:28:00 volumio volumio[1112]: info: CoreStateMachine::pushState Feb 22 15:28:00 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:00 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 15:28:00 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Feb 22 15:28:00 volumio volumio[1112]: info: CoreStateMachine::updateTrackBlock Feb 22 15:28:00 volumio volumio[1112]: info: CorePlayQueue::getTrackBlock Feb 22 15:28:00 volumio volumio[1112]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 15:28:00 volumio volumio[1286]: Forking 3 albumart workers Feb 22 15:28:00 volumio volumio[1112]: info: Reloading queue from file Feb 22 15:28:00 volumio volumio[1112]: verbose: New Socket.io Connection to 192.168.0.13 from 192.168.0.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Feb 22 15:28:00 volumio volumio[1112]: info: CoreStateMachine::setRepeat null single undefined Feb 22 15:28:00 volumio volumio[1112]: info: CoreStateMachine::pushState Feb 22 15:28:00 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:00 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 15:28:01 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Feb 22 15:28:01 volumio volumio[1112]: info: CoreStateMachine::setRandom null Feb 22 15:28:01 volumio volumio[1112]: info: CoreStateMachine::pushState Feb 22 15:28:01 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:01 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Feb 22 15:28:01 volumio volumio[1112]: info: Setting Device type: Raspberry PI Feb 22 15:28:01 volumio volumio[1112]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 22 15:28:01 volumio volumio[1112]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Feb 22 15:28:01 volumio volumio[1112]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 22 15:28:01 volumio volumio[1112]: verbose: New Socket.io Connection to 192.168.0.13 from 192.168.0.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Feb 22 15:28:01 volumio volumio[1112]: info: VolumeController:: Volume=86 Mute =false Feb 22 15:28:01 volumio volumio[1112]: info: CoreStateMachine::pushState Feb 22 15:28:01 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:01 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Feb 22 15:28:01 volumio volumio[1112]: info: Discovery: adding 2cb35304-90a2-4b64-bf39-a17d5e66e63c Feb 22 15:28:01 volumio volumio[1112]: info: Discovery: Found device Volumio Feb 22 15:28:01 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Feb 22 15:28:01 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:01 volumio volumio[1112]: info: Completed loading Core Plugins Feb 22 15:28:01 volumio volumio[1112]: info: Preparing to generate the ALSA configuration file Feb 22 15:28:01 volumio volumio[1112]: info: Discovery: this is already registered, 2cb35304-90a2-4b64-bf39-a17d5e66e63c Feb 22 15:28:01 volumio volumio[1112]: info: Discovery: Found device Volumio Feb 22 15:28:01 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Feb 22 15:28:01 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:01 volumio volumio[1112]: info: Asound.conf file unchanged, so no further update is needed Feb 22 15:28:01 volumio volumio[1112]: info: Output device has changed, restarting MPD Feb 22 15:28:01 volumio volumio[1112]: info: ___________ START PLUGINS ___________ Feb 22 15:28:02 volumio sudo[1343]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 15:28:02 volumio volumio[1112]: info: ControllerMpd::onStart: Initializing MPD Feb 22 15:28:02 volumio volumio[1112]: info: Creating MPD Configuration file Feb 22 15:28:02 volumio sudo[1343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:28:02 volumio sudo[1343]: pam_unix(sudo:session): session closed for user root Feb 22 15:28:02 volumio sudo[1345]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 15:28:02 volumio sudo[1345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:28:02 volumio volumio[1112]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 15:28:02 volumio volumio[1112]: info: [1771795682266] CoreMusicLibrary::Adding element Last_100 Feb 22 15:28:02 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:28:02 volumio volumio[1112]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 15:28:02 volumio volumio[1112]: info: [1771795682269] CoreMusicLibrary::Adding element Webradio Feb 22 15:28:02 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:28:02 volumio sudo[1347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 22 15:28:02 volumio sudo[1347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:28:02 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 15:28:02 volumio sudo[1351]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 15:28:02 volumio sudo[1351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:28:02 volumio volumio[1112]: info: Initializing BBC Radios Feb 22 15:28:02 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 15:28:02 volumio sudo[1351]: pam_unix(sudo:session): session closed for user root Feb 22 15:28:02 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 15:28:02 volumio sudo[1353]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 15:28:02 volumio sudo[1353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:28:02 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 22 15:28:02 volumio sudo[1347]: pam_unix(sudo:session): session closed for user root Feb 22 15:28:02 volumio sudo[1357]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 15:28:02 volumio sudo[1357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 15:28:02 volumio sudo[1369]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 22 15:28:02 volumio sudo[1357]: pam_unix(sudo:session): session closed for user root Feb 22 15:28:02 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 15:28:02 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:28:02 volumio volumio[1112]: info: Volumio Calling Home Feb 22 15:28:02 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 22 15:28:02 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 15:28:02 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 22 15:28:02 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 15:28:02 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 15:28:02 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 15:28:02 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 15:28:03 volumio sudo[1381]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 15:28:03 volumio sudo[1381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 15:28:03 volumio sudo[1384]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 22 15:28:03 volumio sudo[1381]: pam_unix(sudo:session): session closed for user root Feb 22 15:28:03 volumio volumio5-onboarding[1362]: time=2026-02-22T15:28:03.380-06:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 22 15:28:05 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Feb 22 15:28:05 volumio volumio-remote-updater[673]: [2026-02-22 15:28:05] [connect] Successful connection Feb 22 15:28:08 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Feb 22 15:28:08 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 22 15:28:08 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 22 15:28:08 volumio upmpdcli[1403]: Could not open config: /tmp/upmpdcli.conf Feb 22 15:28:08 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:28:08 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 22 15:28:09 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 22 15:28:10 volumio volumio[1112]: info: MPD Permissions set Feb 22 15:28:10 volumio volumio[1112]: info: MPD Permissions set Feb 22 15:28:10 volumio volumio[1112]: 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: 4 Feb 22 15:28:10 volumio volumio[1112]: info: Volumio called home Feb 22 15:28:10 volumio volumio[1112]: 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: 4 Feb 22 15:28:10 volumio volumio[1112]: info: Received Get System Info Feb 22 15:28:11 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:28:11 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:28:11 volumio volumio[1112]: info: Discovery: Getting this device information Feb 22 15:28:11 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Feb 22 15:28:11 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:11 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:28:11 volumio volumio5-onboarding[1362]: time=2026-02-22T15:28:11.124-06:00 level=INFO msg="system info for c3fddc5a9aaf3390c5310701cb3f4e79" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.096 Feb 22 15:28:11 volumio mpd[1385]: 2026-02-22T15:28:11 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 15:28:11 volumio volumio[1311]: Starting albumart workers Feb 22 15:28:12 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 22 15:28:12 volumio sudo[1353]: pam_unix(sudo:session): session closed for user root Feb 22 15:28:12 volumio sudo[1345]: pam_unix(sudo:session): session closed for user root Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:12 volumio volumio[1312]: Starting albumart workers Feb 22 15:28:12 volumio volumio[1112]: info: Received Get System Info Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:28:12 volumio volumio[1112]: info: Discovery: Getting this device information Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Feb 22 15:28:12 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:28:13 volumio volumio[1112]: verbose: New Socket.io Connection to 192.168.0.13 from 192.168.0.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 22 15:28:13 volumio volumio[1112]: info: Completed starting Core Plugins Feb 22 15:28:13 volumio volumio[1112]: info: ------------------------------------------- Feb 22 15:28:13 volumio volumio[1112]: info: ----- MyVolumio plugins startup ---- Feb 22 15:28:13 volumio volumio[1112]: info: ------------------------------------------- Feb 22 15:28:13 volumio volumio[1112]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 22 15:28:13 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 15:28:13 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 15:28:13 volumio volumio[1309]: Starting albumart workers Feb 22 15:28:13 volumio volumio5-onboarding[1362]: time=2026-02-22T15:28:13.927-06:00 level=INFO msg="enabling local network discovery" Feb 22 15:28:14 volumio volumio5-onboarding[1362]: time=2026-02-22T15:28:13.999-06:00 level=INFO msg="bootstrapping state" hasInternet=true Feb 22 15:28:14 volumio volumio[1112]: verbose: New Socket.io Connection to 192.168.0.13 from 192.168.0.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 22 15:28:14 volumio volumio[1112]: info: Received Get System Info Feb 22 15:28:14 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:28:14 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:28:14 volumio volumio[1112]: info: Discovery: Getting this device information Feb 22 15:28:14 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Feb 22 15:28:14 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:14 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:28:15 volumio volumio[1112]: error: MPD error: The expression evaluated to a falsy value: Feb 22 15:28:15 volumio volumio[1112]: assert.ok(self.idling) Feb 22 15:28:15 volumio volumio[1112]: error: The expression evaluated to a falsy value: Feb 22 15:28:15 volumio volumio[1112]: assert.ok(self.idling) Feb 22 15:28:15 volumio volumio[1112]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 22 15:28:15 volumio volumio[1112]: info: MPD running with PID1385 Feb 22 15:28:15 volumio volumio[1112]: ,establishing connection Feb 22 15:28:15 volumio volumio[1112]: error: updateQueue error: null Feb 22 15:28:15 volumio volumio[1112]: verbose: New Socket.io Connection to 192.168.0.13 from 192.168.0.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 22 15:28:15 volumio volumio[1112]: error: updateQueue error: null Feb 22 15:28:16 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Feb 22 15:28:16 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:18 volumio volumio[1112]: verbose: New Socket.io Connection to 192.168.0.13 from 192.168.0.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Feb 22 15:28:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 15:28:18 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 22 15:28:18 volumio volumio[1112]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 15:28:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:28:18 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Feb 22 15:28:18 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:18 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Feb 22 15:28:18 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 15:28:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 15:28:18 volumio volumio[1112]: info: Received Get System Info Feb 22 15:28:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:28:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:28:18 volumio volumio[1112]: info: Discovery: Getting this device information Feb 22 15:28:18 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Feb 22 15:28:18 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:28:18 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Feb 22 15:28:18 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:19 volumio volumio[1112]: info: Listing playlists Feb 22 15:28:20 volumio volumio-remote-updater[673]: [2026-02-22 15:28:20] [connect] Successful connection Feb 22 15:28:20 volumio volumio-remote-updater[673]: [2026-02-22 15:28:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771795700 101 Feb 22 15:28:20 volumio volumio[1112]: 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: 10 Feb 22 15:28:20 volumio volumio[1112]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 22 15:28:22 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 22 15:28:22 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 22 15:28:22 volumio systemd[1]: setdatetime-helper.service: Consumed 1.157s CPU time. Feb 22 15:28:23 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Feb 22 15:28:23 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 22 15:28:23 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 22 15:28:23 volumio upmpdcli[1483]: Could not open config: /tmp/upmpdcli.conf Feb 22 15:28:23 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:28:23 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 22 15:28:39 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Feb 22 15:28:39 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 22 15:28:39 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 22 15:28:39 volumio upmpdcli[1512]: Could not open config: /tmp/upmpdcli.conf Feb 22 15:28:39 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:28:39 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 22 15:28:43 volumio volumio[1112]: info: VolumeController::SetAlsaVolume100 Feb 22 15:28:43 volumio volumio[1112]: info: VolumeController::SetAlsaVolume100 Feb 22 15:28:43 volumio volumio[1112]: info: VolumeController::SetAlsaVolume100 Feb 22 15:28:43 volumio volumio[1112]: info: VolumeController::SetAlsaVolume100 Feb 22 15:28:43 volumio volumio[1112]: info: VolumeController::SetAlsaVolume100 Feb 22 15:28:43 volumio volumio[1112]: info: CoreCommandRouter::volumioGetQueue Feb 22 15:28:43 volumio volumio[1112]: info: CoreStateMachine::getQueue Feb 22 15:28:43 volumio volumio[1112]: info: CorePlayQueue::getQueue Feb 22 15:28:43 volumio volumio[1112]: info: CoreStateMachine::pushState Feb 22 15:28:43 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:43 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 15:28:43 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Feb 22 15:28:43 volumio volumio[1112]: info: CoreStateMachine::pushState Feb 22 15:28:43 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:43 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Feb 22 15:28:43 volumio volumio[1112]: info: CoreStateMachine::pushState Feb 22 15:28:43 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:43 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Feb 22 15:28:43 volumio volumio[1112]: info: CoreStateMachine::pushState Feb 22 15:28:43 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:43 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Feb 22 15:28:43 volumio volumio[1112]: info: CoreStateMachine::pushState Feb 22 15:28:43 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:43 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Feb 22 15:28:43 volumio volumio5-onboarding[1362]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Feb 22 15:28:43 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:28:43 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Feb 22 15:28:44 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Feb 22 15:28:44 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 22 15:28:44 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 22 15:28:44 volumio volumio5-onboarding[1518]: time=2026-02-22T15:28:44.350-06:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 22 15:28:44 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 15:28:44 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:28:44 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 15:28:44 volumio volumio[1112]: error: MyVolumio Plugin failed to start in a timely fashion Feb 22 15:28:44 volumio volumio[1112]: [Metrics] CommandRouter: 54s 280.40ms Feb 22 15:28:44 volumio volumio[1112]: info: CoreCommandRouter::volumiosetStartupVolume Feb 22 15:28:44 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:28:44 volumio volumio[1112]: info: VolumeController:: Setting startup Volume 100 Feb 22 15:28:44 volumio volumio[1112]: info: VolumeController::SetAlsaVolume100 Feb 22 15:28:44 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:28:44 volumio volumio[1112]: info: CoreCommandRouter::Close All Modals sent Feb 22 15:28:44 volumio volumio[1112]: info: CoreCommandRouter::Close All Modals sent Feb 22 15:28:45 volumio volumio[1112]: info: CoreStateMachine::pushState Feb 22 15:28:45 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Feb 22 15:28:45 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Feb 22 15:28:45 volumio volumio[1112]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 15:28:45 volumio volumio[1112]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Feb 22 15:28:45 volumio volumio[1112]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Feb 22 15:28:45 volumio volumio[1112]: at new NodeError (node:internal/errors:405:5) Feb 22 15:28:45 volumio volumio[1112]: at assert (node:internal/assert:14:11) Feb 22 15:28:45 volumio volumio[1112]: at internalConnectMultiple (node:net:1118:3) Feb 22 15:28:45 volumio volumio[1112]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Feb 22 15:28:45 volumio volumio[1112]: at listOnTimeout (node:internal/timers:575:11) Feb 22 15:28:45 volumio volumio[1112]: at process.processTimers (node:internal/timers:514:7) { Feb 22 15:28:45 volumio volumio[1112]: code: 'ERR_INTERNAL_ASSERTION' Feb 22 15:28:45 volumio volumio[1112]: } Feb 22 15:28:45 volumio volumio[1112]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 15:28:52 volumio sudo[1560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-22 15:27' Feb 22 15:28:52 volumio sudo[1560]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"