Feb 18 17:17:10 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully.
Feb 18 17:17:10 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Feb 18 17:17:10 volumio systemd[1]: setdatetime-helper.service: Consumed 1.849s CPU time.
Feb 18 17:17:10 volumio sh[1234]: eth0=eth0
Feb 18 17:17:10 volumio ifplugd(eth0)[877]: client: ifup: interface eth0 already configured
Feb 18 17:17:10 volumio ifplugd(eth0)[877]: Program executed successfully.
Feb 18 17:17:10 volumio ifplugd[801]: Network Interface Plugging Daemon...start eth0...done.
Feb 18 17:17:10 volumio systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically.
Feb 18 17:17:10 volumio ntpd[859]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101
Feb 18 17:17:10 volumio ntpd[859]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101
Feb 18 17:17:10 volumio ntpd[859]: DNS: Pool taking: 162.159.200.123
Feb 18 17:17:10 volumio ntpd[859]: DNS: Pool taking: 5.250.184.159
Feb 18 17:17:10 volumio ntpd[859]: DNS: Pool taking: 92.113.12.78
Feb 18 17:17:10 volumio ntpd[859]: DNS: Pool taking: 94.143.139.219
Feb 18 17:17:10 volumio ntpd[859]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8
Feb 18 17:17:11 volumio ntpd[859]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101
Feb 18 17:17:11 volumio ntpd[859]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101
Feb 18 17:17:11 volumio ntpd[859]: DNS: Pool taking: 194.164.164.175
Feb 18 17:17:11 volumio ntpd[859]: DNS: Pool taking: 195.95.153.43
Feb 18 17:17:11 volumio ntpd[859]: DNS: Pool taking: 172.233.111.111
Feb 18 17:17:11 volumio ntpd[859]: DNS: Pool taking: 92.113.12.77
Feb 18 17:17:11 volumio ntpd[859]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8
Feb 18 17:17:11 volumio systemd[1]: Started smbd.service - Samba SMB Daemon.
Feb 18 17:17:11 volumio systemd[1]: Reached target multi-user.target - Multi-User System.
Feb 18 17:17:11 volumio systemd[1]: Reached target graphical.target - Graphical Interface.
Feb 18 17:17:11 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP...
Feb 18 17:17:11 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully.
Feb 18 17:17:11 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP.
Feb 18 17:17:11 volumio systemd[1]: Startup finished in 15.658s (kernel) + 18.632s (userspace) = 34.291s.
Feb 18 17:17:11 volumio volumio-remote-updater[673]: [2026-02-18 17:17:11] [info] asio async_connect error: asio.system:111 (Connection refused)
Feb 18 17:17:11 volumio volumio-remote-updater[673]: [2026-02-18 17:17:11] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected)
Feb 18 17:17:11 volumio volumio-remote-updater[673]: [2026-02-18 17:17:11] [error] handle_connect error: Connection refused
Feb 18 17:17:12 volumio ntpd[859]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101
Feb 18 17:17:12 volumio ntpd[859]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101
Feb 18 17:17:12 volumio ntpd[859]: DNS: Pool taking: 89.140.186.3
Feb 18 17:17:12 volumio ntpd[859]: DNS: Pool skipping: 94.143.139.219
Feb 18 17:17:12 volumio ntpd[859]: DNS: Pool skipping: 92.113.12.78
Feb 18 17:17:12 volumio ntpd[859]: DNS: Pool taking: 85.49.251.75
Feb 18 17:17:12 volumio ntpd[859]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8
Feb 18 17:17:14 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1.
Feb 18 17:17:14 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 18 17:17:15 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 18 17:17:15 volumio upmpdcli[1244]: Could not open config: /tmp/upmpdcli.conf
Feb 18 17:17:15 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Feb 18 17:17:15 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Feb 18 17:17:17 volumio ntpd[859]: CLOCK: time stepped by 1.138023
Feb 18 17:17:17 volumio ntpd[859]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes
Feb 18 17:17:17 volumio volumio[1141]: info: -------------------------------------------
Feb 18 17:17:17 volumio volumio[1141]: info: ----- Volumio3 ----
Feb 18 17:17:17 volumio volumio[1141]: info: -------------------------------------------
Feb 18 17:17:17 volumio volumio[1141]: info: ----- System startup ----
Feb 18 17:17:17 volumio volumio[1141]: info: -------------------------------------------
Feb 18 17:17:19 volumio volumio[1141]: info: MYVOLUMIO Environment detected
Feb 18 17:17:19 volumio volumio[1141]: info: Plugin folders cleanup
Feb 18 17:17:19 volumio volumio[1141]: info: Scanning into folder /volumio/app/plugins/
Feb 18 17:17:19 volumio volumio[1141]: info: Scanning category audio_interface
Feb 18 17:17:19 volumio volumio[1141]: info: Scanning category miscellanea
Feb 18 17:17:19 volumio volumio[1141]: info: Scanning category music_service
Feb 18 17:17:19 volumio volumio[1141]: info: Scanning category plugins.json
Feb 18 17:17:19 volumio volumio[1141]: info: Scanning category system_controller
Feb 18 17:17:19 volumio volumio[1141]: info: Scanning category user_interface
Feb 18 17:17:19 volumio volumio[1141]: info: Scanning into folder /data/plugins/
Feb 18 17:17:19 volumio volumio[1141]: info: Scanning category music_service
Feb 18 17:17:19 volumio volumio[1141]: info: Scanning category user_interface
Feb 18 17:17:19 volumio volumio[1141]: info: Plugin folders cleanup completed
Feb 18 17:17:19 volumio volumio[1141]: info: -------------------------------------------
Feb 18 17:17:19 volumio volumio[1141]: info: ----- Core plugins startup ----
Feb 18 17:17:19 volumio volumio[1141]: info: -------------------------------------------
Feb 18 17:17:19 volumio volumio[1141]: info: Loading plugins from folder /volumio/app/plugins/
Feb 18 17:17:19 volumio volumio[1141]: info: Adding plugin upnp to MyMusic Plugins
Feb 18 17:17:19 volumio volumio[1141]: info: Adding plugin airplay_emulation to MyMusic Plugins
Feb 18 17:17:19 volumio volumio[1141]: info: Adding plugin upnp_browser to MyMusic Plugins
Feb 18 17:17:19 volumio volumio[1141]: info: Loading plugins from folder /data/plugins/
Feb 18 17:17:19 volumio volumio[1141]: info: Adding plugin rtlsdr_radio to MyMusic Plugins
Feb 18 17:17:19 volumio volumio[1141]: info: Loading plugin "system"...
Feb 18 17:17:19 volumio volumio[1141]: info: Loading plugin "appearance"...
Feb 18 17:17:21 volumio volumio[1141]: info: Loading plugin "network"...
Feb 18 17:17:21 volumio volumio[1141]: info: Refreshing Cached IP Addresses
Feb 18 17:17:21 volumio volumio[1141]: info: Loading plugin "services"...
Feb 18 17:17:21 volumio volumio[1141]: info: Loading plugin "volumio5onboarding"...
Feb 18 17:17:21 volumio sudo[1258]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 18 17:17:21 volumio sudo[1258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 17:17:21 volumio sudo[1258]: pam_unix(sudo:session): session closed for user root
Feb 18 17:17:22 volumio volumio[1141]: info: Loading plugin "alsa_controller"...
Feb 18 17:17:22 volumio sudo[1260]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 18 17:17:22 volumio sudo[1260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 17:17:22 volumio sudo[1271]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Feb 18 17:17:22 volumio sudo[1260]: pam_unix(sudo:session): session closed for user root
Feb 18 17:17:22 volumio sudo[1271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 17:17:22 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 18 17:17:22 volumio volumio[1141]: info: Loading plugin "wizard"...
Feb 18 17:17:22 volumio volumio[1141]: info: Loading plugin "networkfs"...
Feb 18 17:17:22 volumio volumio[1141]: info: Starting Udev Watcher for removable devices
Feb 18 17:17:22 volumio sudo[1289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Admin,password=Qlfta16030924,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.20/music /mnt/NAS/Nas
Feb 18 17:17:22 volumio sudo[1289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 17:17:22 volumio volumio[1141]: info: Ignoring mount for partition: boot
Feb 18 17:17:22 volumio volumio[1141]: info: Ignoring mount for partition: volumio
Feb 18 17:17:22 volumio volumio[1141]: info: Ignoring mount for partition: volumio_data
Feb 18 17:17:22 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Feb 18 17:17:22 volumio volumio[1141]: info: Loading plugin "volumio_command_line_client"...
Feb 18 17:17:22 volumio volumio[1141]: info: Loading plugin "upnp"...
Feb 18 17:17:22 volumio volumio[1141]: info: [1771431442426] Starting Upmpd Daemon
Feb 18 17:17:22 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Feb 18 17:17:22 volumio volumio[1141]: info: Loading plugin "my_music"...
Feb 18 17:17:22 volumio volumio[1141]: info: Loading plugin "mpd"...
Feb 18 17:17:22 volumio kernel: netfs: FS-Cache loaded
Feb 18 17:17:22 volumio kernel: Key type cifs.spnego registered
Feb 18 17:17:22 volumio kernel: Key type cifs.idmap registered
Feb 18 17:17:22 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 18 17:17:22 volumio kernel: CIFS: Attempting to mount //192.168.0.20/music
Feb 18 17:17:22 volumio volumio-remote-updater[673]: [2026-02-18 17:17:22] [connect] Successful connection
Feb 18 17:17:23 volumio sudo[1289]: pam_unix(sudo:session): session closed for user root
Feb 18 17:17:23 volumio volumio[1141]: info: Loading plugin "upnp_browser"...
Feb 18 17:17:24 volumio sudo[1271]: pam_unix(sudo:session): session closed for user root
Feb 18 17:17:25 volumio volumio[1141]: info: Starting UPNP Browser
Feb 18 17:17:25 volumio volumio[1141]: info: Loading plugin "alarm-clock"...
Feb 18 17:17:26 volumio volumio[1141]: info: Loading plugin "airplay_emulation"...
Feb 18 17:17:26 volumio volumio[1141]: info: Starting Shairport Sync
Feb 18 17:17:26 volumio volumio[1141]: info: Loading plugin "last_100"...
Feb 18 17:17:26 volumio volumio[1141]: info: Loading plugin "webradio"...
Feb 18 17:17:26 volumio volumio[1141]: info: Loading plugin "i2s_dacs"...
Feb 18 17:17:26 volumio volumio[1141]: info: Loading plugin "volumiodiscovery"...
Feb 18 17:17:26 volumio volumio[1141]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Feb 18 17:17:26 volumio node[1141]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Feb 18 17:17:26 volumio volumio[1141]: *** WARNING *** Please fix your application to use the native API of Avahi!
Feb 18 17:17:26 volumio volumio[1141]: *** WARNING *** For more information see
Feb 18 17:17:26 volumio volumio[1141]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Feb 18 17:17:26 volumio volumio[1141]: *** WARNING *** Please fix your application to use the native API of Avahi!
Feb 18 17:17:26 volumio volumio[1141]: *** WARNING *** For more information see
Feb 18 17:17:26 volumio node[1141]: *** WARNING *** Please fix your application to use the native API of Avahi!
Feb 18 17:17:26 volumio node[1141]: *** WARNING *** For more information see
Feb 18 17:17:26 volumio node[1141]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Feb 18 17:17:26 volumio node[1141]: *** WARNING *** Please fix your application to use the native API of Avahi!
Feb 18 17:17:26 volumio node[1141]: *** WARNING *** For more information see
Feb 18 17:17:26 volumio volumio[1141]: info: Applying required configuration parameters for plugin volumiodiscovery
Feb 18 17:17:26 volumio volumio[1141]: info: Discovery: Started advertising with name: Volumio
Feb 18 17:17:26 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Feb 18 17:17:26 volumio volumio[1141]: info: Loading plugin "spop"...
Feb 18 17:17:27 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully.
Feb 18 17:17:29 volumio volumio[1141]: info: Loading plugin "outputs"...
Feb 18 17:17:29 volumio volumio[1141]: info: Loading plugin "albumart"...
Feb 18 17:17:30 volumio volumio[1141]: info: Plugin example_plugin is not enabled
Feb 18 17:17:30 volumio volumio[1141]: info: Loading plugin "inputs"...
Feb 18 17:17:30 volumio volumio[1141]: info: Loading plugin "updater_comm"...
Feb 18 17:17:30 volumio volumio[1141]: info: Plugin mpdemulation is not enabled
Feb 18 17:17:30 volumio volumio[1141]: info: Loading plugin "rest_api"...
Feb 18 17:17:30 volumio volumio[1141]: info: Loading plugin "websocket"...
Feb 18 17:17:30 volumio volumio[1141]: info: Starting Socket.io Server version 1.7.4
Feb 18 17:17:30 volumio volumio[1141]: info: Loading plugin "podcast"...
Feb 18 17:17:31 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2.
Feb 18 17:17:31 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 18 17:17:31 volumio volumio[1141]: info: ControllerPodcast::constructor
Feb 18 17:17:31 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 18 17:17:31 volumio upmpdcli[1338]: Could not open config: /tmp/upmpdcli.conf
Feb 18 17:17:31 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Feb 18 17:17:31 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Feb 18 17:17:31 volumio volumio[1328]: Forking 3 albumart workers
Feb 18 17:17:32 volumio volumio[1141]: info: Loading plugin "rtlsdr_radio"...
Feb 18 17:17:32 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Feb 18 17:17:37 volumio volumio-remote-updater[673]: [2026-02-18 17:17:37] [connect] Successful connection
Feb 18 17:17:39 volumio volumio[1141]: info: [RTL-SDR Radio] Loaded FM region data
Feb 18 17:17:39 volumio volumio[1141]: info: Loading plugin "Systeminfo"...
Feb 18 17:17:41 volumio volumio[1341]: Starting albumart workers
Feb 18 17:17:41 volumio volumio[1339]: Starting albumart workers
Feb 18 17:17:41 volumio volumio[1340]: Starting albumart workers
Feb 18 17:17:43 volumio volumio[1141]: info: Loading i18n strings for locale es
Feb 18 17:17:43 volumio volumio[1141]: Updating browse sources language
Feb 18 17:17:43 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 18 17:17:44 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 17:17:44 volumio volumio[1141]: info: CoreCommandRouter::initPlayerControls
Feb 18 17:17:45 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:17:45 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:17:45 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:17:45 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:17:45 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:17:45 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:17:45 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:17:45 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:17:45 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 17:17:45 volumio volumio[1141]: Express server listening on port 3000
Feb 18 17:17:45 volumio volumio[1141]: [Metrics] WebUI: 29s 351.58ms
Feb 18 17:17:45 volumio volumio[1141]: info: CoreStateMachine::resetVolumioState
Feb 18 17:17:45 volumio volumio[1141]: info: CoreStateMachine::getcurrentVolume
Feb 18 17:17:45 volumio volumio[1141]: info: CoreCommandRouter::volumioRetrievevolume
Feb 18 17:17:45 volumio volumio[1141]: info: CoreStateMachine::pushState
Feb 18 17:17:45 volumio volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 18 17:17:45 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 18 17:17:45 volumio volumio[1141]: info: CoreCommandRouter::volumioPushState
Feb 18 17:17:45 volumio sudo[1404]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 18 17:17:45 volumio sudo[1404]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 17:17:45 volumio sudo[1404]: pam_unix(sudo:session): session closed for user root
Feb 18 17:17:45 volumio sudo[1406]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 18 17:17:45 volumio sudo[1406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 17:17:45 volumio sudo[1406]: pam_unix(sudo:session): session closed for user root
Feb 18 17:17:45 volumio volumio[1141]: info: Volumio Network Manager: Network status updated: 1
Feb 18 17:17:46 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Feb 18 17:17:46 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3.
Feb 18 17:17:46 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 18 17:17:46 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 18 17:17:46 volumio volumio[1141]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.55 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1
Feb 18 17:17:46 volumio upmpdcli[1439]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.0.70 port 49152 IPV6 port 49152
Feb 18 17:17:46 volumio upmpdcli[1439]: :3:../src/mediaserver/cdplugins/plgwithslave.cxx:238::PlgWithSlave: starting streaming proxy on port 49149
Feb 18 17:17:46 volumio upmpdcli[1439]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Cannot assign requested address
Feb 18 17:17:46 volumio upmpdcli[1439]: :2:../src/main.cxx:728::MPD connection failed
Feb 18 17:17:47 volumio volumio[1141]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.55 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2
Feb 18 17:17:47 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 18 17:17:47 volumio volumio[1141]: info: Executing start script for DAC Volumio Zero ES9039 DAC
Feb 18 17:17:47 volumio volumio[1141]: info: Reloading queue from file
Feb 18 17:17:47 volumio sudo[1468]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x48 0x00 0x02
Feb 18 17:17:47 volumio sudo[1468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 17:17:47 volumio sudo[1468]: pam_unix(sudo:session): session closed for user root
Feb 18 17:17:47 volumio volumio[1141]: info: Setting Device type: Raspberry PI
Feb 18 17:17:47 volumio volumio[1141]: info: CoreStateMachine::setRepeat null single undefined
Feb 18 17:17:47 volumio volumio[1141]: info: CoreStateMachine::pushState
Feb 18 17:17:47 volumio volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 18 17:17:47 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully.
Feb 18 17:17:47 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Feb 18 17:17:47 volumio systemd[1]: setdatetime-helper.service: Consumed 1.008s CPU time.
Feb 18 17:17:47 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 18 17:17:47 volumio volumio[1141]: info: CoreCommandRouter::volumioPushState
Feb 18 17:17:47 volumio volumio[1141]: info: CoreStateMachine::setRandom null
Feb 18 17:17:47 volumio volumio[1141]: info: CoreStateMachine::pushState
Feb 18 17:17:47 volumio volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 18 17:17:47 volumio volumio[1141]: info: CoreCommandRouter::volumioPushState
Feb 18 17:17:47 volumio volumio[1141]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.55 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3
Feb 18 17:17:48 volumio volumio[1141]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.55 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4
Feb 18 17:17:48 volumio volumio[1141]: info: DAC script executed
Feb 18 17:17:48 volumio volumio[1141]: info: Completed loading Core Plugins
Feb 18 17:17:48 volumio volumio[1141]: info: Preparing to generate the ALSA configuration file
Feb 18 17:17:48 volumio volumio[1141]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.55 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
Feb 18 17:17:48 volumio volumio[1141]: info: CoreCommandRouter::volumioGetState
Feb 18 17:17:48 volumio volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 18 17:17:48 volumio sudo[1478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Feb 18 17:17:48 volumio sudo[1478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 17:17:48 volumio volumio[1141]: info: CoreCommandRouter::volumioGetQueue
Feb 18 17:17:48 volumio volumio[1141]: info: CoreStateMachine::getQueue
Feb 18 17:17:48 volumio sudo[1478]: pam_unix(sudo:session): session closed for user root
Feb 18 17:17:48 volumio volumio[1141]: info: CorePlayQueue::getQueue
Feb 18 17:17:48 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid
Feb 18 17:17:48 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel
Feb 18 17:17:48 volumio volumio[1141]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object]
Feb 18 17:17:48 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction
Feb 18 17:17:48 volumio volumio[1141]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs
Feb 18 17:17:48 volumio volumio[1141]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object]
Feb 18 17:17:48 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection
Feb 18 17:17:48 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 18 17:17:48 volumio upmpdcli[1439]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Cannot assign requested address
Feb 18 17:17:48 volumio upmpdcli[1439]: :2:../src/main.cxx:728::MPD connection failed
Feb 18 17:17:48 volumio volumio[1141]: info: CoreCommandRouter::volumioGetVisibleSources
Feb 18 17:17:48 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 18 17:17:48 volumio volumio[1141]: info: CoreCommandRouter::volumioGetState
Feb 18 17:17:49 volumio volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 18 17:17:49 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Feb 18 17:17:49 volumio volumio[1141]: info: CoreCommandRouter::volumioGetQueue
Feb 18 17:17:49 volumio volumio[1141]: info: CoreStateMachine::getQueue
Feb 18 17:17:49 volumio volumio[1141]: info: CorePlayQueue::getQueue
Feb 18 17:17:49 volumio volumio[1141]: info: Listing playlists
Feb 18 17:17:49 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Feb 18 17:17:49 volumio volumio[1141]: info: Received Get System Info
Feb 18 17:17:49 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 18 17:17:49 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 18 17:17:49 volumio volumio[1141]: info: Discovery: Getting this device information
Feb 18 17:17:49 volumio volumio[1141]: info: CoreCommandRouter::volumioGetState
Feb 18 17:17:49 volumio volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 18 17:17:49 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 18 17:17:49 volumio volumio[1141]: info: CoreCommandRouter::volumioGetState
Feb 18 17:17:49 volumio volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 18 17:17:49 volumio volumio[1141]: info: Upmpdcli Daemon Started
Feb 18 17:17:49 volumio volumio[1141]: info: Asound.conf file unchanged, so no further update is needed
Feb 18 17:17:49 volumio volumio[1141]: info: Output device has changed, restarting MPD
Feb 18 17:17:49 volumio volumio[1141]: info: Output device has changed, restarting Shairport Sync
Feb 18 17:17:49 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:17:49 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 18 17:17:49 volumio sudo[1481]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 18 17:17:49 volumio sudo[1481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 17:17:49 volumio sudo[1481]: pam_unix(sudo:session): session closed for user root
Feb 18 17:17:49 volumio volumio[1141]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 18 17:17:49 volumio sudo[1483]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 18 17:17:49 volumio sudo[1483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 17:17:49 volumio volumio[1141]: info: ___________ START PLUGINS ___________
Feb 18 17:17:49 volumio volumio[1141]: info: ControllerMpd::onStart: Initializing MPD
Feb 18 17:17:49 volumio volumio[1141]: info: Creating MPD Configuration file
Feb 18 17:17:49 volumio sudo[1491]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service
Feb 18 17:17:49 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 18 17:17:49 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 18 17:17:49 volumio sudo[1491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 17:17:49 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 17:17:49 volumio volumio[1141]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 18 17:17:49 volumio volumio[1141]: info: [1771431469934] CoreMusicLibrary::Adding element Media Servers
Feb 18 17:17:49 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 18 17:17:49 volumio sudo[1494]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 18 17:17:49 volumio sudo[1494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 17:17:50 volumio sudo[1498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 18 17:17:50 volumio sudo[1498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 17:17:50 volumio volumio[1141]: info: UPNP Browser: Client initialized successfully
Feb 18 17:17:50 volumio sudo[1494]: pam_unix(sudo:session): session closed for user root
Feb 18 17:17:50 volumio sudo[1495]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 18 17:17:50 volumio sudo[1495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 18 17:17:50 volumio sudo[1502]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Feb 18 17:17:50 volumio sudo[1495]: pam_unix(sudo:session): session closed for user root
Feb 18 17:17:50 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:17:50 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 18 17:17:50 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server.
Feb 18 17:17:50 volumio sudo[1491]: pam_unix(sudo:session): session closed for user root
Feb 18 17:17:50 volumio systemd[1]: mpd.service: Deactivated successfully.
Feb 18 17:17:50 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 18 17:17:50 volumio systemd[1]: mpd.socket: Deactivated successfully.
Feb 18 17:17:50 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 18 17:17:50 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 18 17:17:50 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 18 17:17:50 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 18 17:17:50 volumio volumio[1141]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 18 17:17:50 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:17:50 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 18 17:17:50 volumio sudo[1507]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 18 17:17:50 volumio sudo[1507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 18 17:17:50 volumio sudo[1520]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Feb 18 17:17:50 volumio sudo[1507]: pam_unix(sudo:session): session closed for user root
Feb 18 17:17:50 volumio volumio[1141]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 18 17:17:50 volumio volumio[1141]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 18 17:17:50 volumio volumio[1141]: info: [1771431470754] CoreMusicLibrary::Adding element Last_100
Feb 18 17:17:50 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 18 17:17:50 volumio volumio[1141]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 18 17:17:50 volumio volumio[1141]: info: [1771431470864] CoreMusicLibrary::Adding element Webradio
Feb 18 17:17:50 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 18 17:17:50 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 18 17:17:50 volumio volumio[1141]: info: Initializing BBC Radios
Feb 18 17:17:51 volumio volumio5-onboarding[1501]: time=2026-02-18T17:17:51.056+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z
Feb 18 17:17:51 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 18 17:17:51 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 18 17:17:51 volumio volumio[1141]: info: Creating Spotify config file
Feb 18 17:17:51 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:17:52 volumio volumio-remote-updater[673]: [2026-02-18 17:17:52] [connect] Successful connection
Feb 18 17:17:53 volumio volumio[1141]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 18 17:17:53 volumio volumio[1141]: info: [1771431473885] CoreMusicLibrary::Adding element Podcast
Feb 18 17:17:53 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 18 17:17:53 volumio volumio[1141]: Cannot find translation for source Podcast
Feb 18 17:17:53 volumio volumio[1141]: info: [RTL-SDR Radio] Starting plugin
Feb 18 17:17:54 volumio volumio[1141]: info: [RTL-SDR Radio] Loaded i18n strings for language: es
Feb 18 17:17:54 volumio volumio[1141]: info: Volumio Calling Home
Feb 18 17:17:54 volumio upmpdcli[1439]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout
Feb 18 17:17:54 volumio upmpdcli[1439]: :2:../src/main.cxx:728::MPD connection failed
Feb 18 17:17:54 volumio volumio[1141]: info: [RTL-SDR Radio] snd-aloop already loaded
Feb 18 17:17:54 volumio volumio[1141]: info: [RTL-SDR Radio] No stations database found, creating v2
Feb 18 17:17:54 volumio volumio[1141]: info: [RTL-SDR Radio] Database loaded at: 2026-02-18T16:17:54.991Z
Feb 18 17:17:55 volumio volumio[1141]: info: [RTL-SDR Radio] Loading blocklist from: /data/plugins/music_service/rtlsdr_radio/blocklist.json
Feb 18 17:17:55 volumio volumio[1141]: info: [RTL-SDR Radio] Got 21 phrases from file
Feb 18 17:17:55 volumio volumio[1141]: info: [RTL-SDR Radio] Updated metadata blocklist
Feb 18 17:17:55 volumio volumio[1141]: info: [RTL-SDR Radio] Metadata module now has 21 phrases
Feb 18 17:17:57 volumio volumio[1141]: info: [RTL-SDR Radio] Management server started on port 3456
Feb 18 17:17:57 volumio volumio[1141]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 18 17:17:57 volumio volumio[1141]: info: [1771431477036] CoreMusicLibrary::Adding element FM/DAB Radio
Feb 18 17:17:57 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 18 17:17:57 volumio volumio[1141]: Cannot find translation for source Podcast
Feb 18 17:17:57 volumio volumio[1141]: Cannot find translation for source FM/DAB Radio
Feb 18 17:17:57 volumio volumio[1141]: info: [RTL-SDR Radio] Plugin started successfully
Feb 18 17:17:59 volumio mpd[1521]: 2026-02-18T17:17:59 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 18 17:17:59 volumio volumio[1141]: info: MPD Permissions set
Feb 18 17:17:59 volumio volumio[1141]: info: MPD Permissions set
Feb 18 17:17:59 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Feb 18 17:17:59 volumio sudo[1483]: pam_unix(sudo:session): session closed for user root
Feb 18 17:17:59 volumio sudo[1498]: pam_unix(sudo:session): session closed for user root
Feb 18 17:17:59 volumio volumio[1141]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6
Feb 18 17:17:59 volumio volumio[1141]: info: Volumio called home
Feb 18 17:17:59 volumio volumio[1141]: info: Spotify config file written
Feb 18 17:18:00 volumio volumio[1141]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6
Feb 18 17:18:00 volumio volumio[1141]: info: Received Get System Info
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 18 17:18:00 volumio volumio[1141]: info: Discovery: Getting this device information
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::volumioGetState
Feb 18 17:18:00 volumio volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 18 17:18:00 volumio volumio5-onboarding[1501]: time=2026-02-18T17:18:00.089+01:00 level=INFO msg="system info for 6b86dc4be29839ca7e62fbacebb9c40e" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.096
Feb 18 17:18:00 volumio sudo[1564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Feb 18 17:18:00 volumio sudo[1564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 17:18:00 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 18 17:18:00 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 18 17:18:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 18 17:18:00 volumio go-librespot[1566]: go-librespot daemon starting...
Feb 18 17:18:00 volumio sudo[1564]: pam_unix(sudo:session): session closed for user root
Feb 18 17:18:00 volumio volumio[1141]: info: Completed starting Core Plugins
Feb 18 17:18:00 volumio volumio[1141]: info: -------------------------------------------
Feb 18 17:18:00 volumio volumio[1141]: info: ----- MyVolumio plugins startup ----
Feb 18 17:18:00 volumio volumio[1141]: info: -------------------------------------------
Feb 18 17:18:00 volumio volumio[1141]: info: [MyVolumio PluginManager] Fetching plans data....
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:00 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:01 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 18 17:18:01 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:01 volumio volumio[1141]: info: No need to fix Spotify hosts
Feb 18 17:18:01 volumio volumio[1141]: info: Received Get System Info
Feb 18 17:18:01 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 18 17:18:01 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 18 17:18:01 volumio volumio[1141]: info: Discovery: Getting this device information
Feb 18 17:18:01 volumio volumio[1141]: info: CoreCommandRouter::volumioGetState
Feb 18 17:18:01 volumio volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 18 17:18:01 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 18 17:18:01 volumio go-librespot[1567]: time="2026-02-18T17:18:01+01:00" level=info msg="running go-librespot 0.6.2"
Feb 18 17:18:01 volumio go-librespot[1567]: time="2026-02-18T17:18:01+01:00" level=debug msg="app state loaded"
Feb 18 17:18:01 volumio volumio[1141]: info: MPD running with PID1521
Feb 18 17:18:01 volumio volumio[1141]: ,establishing connection
Feb 18 17:18:01 volumio go-librespot[1567]: time="2026-02-18T17:18:01+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 18 17:18:01 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 18 17:18:01 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 18 17:18:01 volumio volumio5-onboarding[1501]: time=2026-02-18T17:18:01.514+01:00 level=INFO msg="enabling local network discovery"
Feb 18 17:18:01 volumio volumio5-onboarding[1501]: time=2026-02-18T17:18:01.561+01:00 level=INFO msg="bootstrapping state" hasInternet=true
Feb 18 17:18:01 volumio go-librespot[1567]: time="2026-02-18T17:18:01+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Feb 18 17:18:01 volumio go-librespot[1567]: time="2026-02-18T17:18:01+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Feb 18 17:18:01 volumio go-librespot[1567]: time="2026-02-18T17:18:01+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Feb 18 17:18:01 volumio go-librespot[1567]: time="2026-02-18T17:18:01+01:00" level=info msg="zeroconf server listening on port 45733"
Feb 18 17:18:01 volumio go-librespot[1567]: time="2026-02-18T17:18:01+01:00" level=debug msg="obtained new client token: AACfV/ul8JYsrbyscuXvARzMfFAFQRTwncTXm4qw2pia9Di52YbiXqpHBo3WoGedRIu51D45QmGmWdtcQt9HQQzyLv2xLp2QPcgApeu8GW03w7d0gnKzuOOn+blX6Oq/AJuNUqAfux5SwqaRabOFN5jiy5Ejx8feA68kahXxiCa8BnsoOPi7gZbFNVXaEtKZvJk/atIDUmCgy/GqgsJlH2PyOagbodQJG7pz6AICOczVsRXDdYpPXbO2gQ=="
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused"
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=debug msg="connected to ap-gew1.spotify.com:443"
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=debug msg="completed keyexchange"
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=debug msg="completed challenge"
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=info msg="authenticated AP" username="od*ur"
Feb 18 17:18:02 volumio volumio[1141]: error: MPD error: The expression evaluated to a falsy value:
Feb 18 17:18:02 volumio volumio[1141]: assert.ok(self.idling)
Feb 18 17:18:02 volumio volumio[1141]: error: The expression evaluated to a falsy value:
Feb 18 17:18:02 volumio volumio[1141]: assert.ok(self.idling)
Feb 18 17:18:02 volumio volumio[1141]: error: MPD error: The expression evaluated to a falsy value:
Feb 18 17:18:02 volumio volumio[1141]: assert.ok(self.idling)
Feb 18 17:18:02 volumio volumio[1141]: error: The expression evaluated to a falsy value:
Feb 18 17:18:02 volumio volumio[1141]: assert.ok(self.idling)
Feb 18 17:18:02 volumio volumio[1141]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.55 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7
Feb 18 17:18:02 volumio volumio[1141]: info: Received Get System Info
Feb 18 17:18:02 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 18 17:18:02 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 18 17:18:02 volumio volumio[1141]: info: Discovery: Getting this device information
Feb 18 17:18:02 volumio volumio[1141]: info: CoreCommandRouter::volumioGetState
Feb 18 17:18:02 volumio volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 18 17:18:02 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=info msg="authenticated Login5" username="od*ur"
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=debug msg="initializing zeroconf session" username="od*ur"
Feb 18 17:18:02 volumio volumio[1141]: info: Starting Shairport Sync
Feb 18 17:18:02 volumio volumio[1141]: info: Starting Shairport Sync
Feb 18 17:18:02 volumio volumio[1141]: info: Starting Shairport Sync
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=debug msg="dealer connection opened"
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=trace msg="starting accesspoint recv loop"
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=trace msg="starting dealer recv loop"
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=trace msg="received accesspoint ping"
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=debug msg="received connection id: MzMwYTlkYzItZmYw...ODRFMEFGODY2OQ=="
Feb 18 17:18:02 volumio sudo[1592]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 18 17:18:02 volumio sudo[1592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 17:18:02 volumio sudo[1594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 18 17:18:02 volumio sudo[1594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=debug msg="put connect state because NEW_DEVICE"
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=debug msg="update volume requested to 65535/65535"
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481"
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=trace msg="received accesspoint pong ack"
Feb 18 17:18:02 volumio volumio[1141]: info: CoreCommandRouter::volumioGetState
Feb 18 17:18:02 volumio volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 18 17:18:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 18 17:18:02 volumio volumio[1141]: error: updateQueue error: null
Feb 18 17:18:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 18 17:18:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 18 17:18:02 volumio systemd[1]: shairport-sync.service: Consumed 2.137s CPU time.
Feb 18 17:18:02 volumio sudo[1597]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 18 17:18:02 volumio sudo[1597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 17:18:02 volumio volumio[1141]: info: CoreCommandRouter::volumioGetQueue
Feb 18 17:18:02 volumio volumio[1141]: info: CoreStateMachine::getQueue
Feb 18 17:18:02 volumio volumio[1141]: info: CorePlayQueue::getQueue
Feb 18 17:18:02 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid
Feb 18 17:18:02 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel
Feb 18 17:18:02 volumio volumio[1141]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object]
Feb 18 17:18:02 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction
Feb 18 17:18:02 volumio volumio[1141]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs
Feb 18 17:18:02 volumio volumio[1141]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object]
Feb 18 17:18:02 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection
Feb 18 17:18:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=debug msg="put connect state because VOLUME_CHANGED"
Feb 18 17:18:02 volumio go-librespot[1567]: time="2026-02-18T17:18:02+01:00" level=trace msg="emitting websocket event: volume"
Feb 18 17:18:02 volumio sudo[1592]: pam_unix(sudo:session): session closed for user root
Feb 18 17:18:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 18 17:18:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 18 17:18:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 18 17:18:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 18 17:18:02 volumio sudo[1594]: pam_unix(sudo:session): session closed for user root
Feb 18 17:18:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 18 17:18:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 18 17:18:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 18 17:18:03 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 18 17:18:03 volumio sudo[1597]: pam_unix(sudo:session): session closed for user root
Feb 18 17:18:03 volumio volumio[1141]: info: Shairport-Sync Started
Feb 18 17:18:03 volumio volumio[1141]: Error adding Membership: Error: addMembership EINVAL
Feb 18 17:18:03 volumio volumio[1141]: info: Shairport-Sync Started
Feb 18 17:18:03 volumio volumio[1141]: info: Shairport-Sync Started
Feb 18 17:18:04 volumio volumio[1141]: info: go-librespot daemon successfully initialized
Feb 18 17:18:04 volumio volumio[1141]: info: Discovery: adding 9747f86f-d2e8-47e2-a09b-14f0dfd83934
Feb 18 17:18:04 volumio volumio[1141]: info: Discovery: Found device Volumio
Feb 18 17:18:04 volumio volumio[1141]: info: CoreCommandRouter::volumioGetState
Feb 18 17:18:04 volumio volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 18 17:18:04 volumio volumio[1141]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Feb 18 17:18:04 volumio volumio[1141]: info: CoreCommandRouter::volumioGetState
Feb 18 17:18:04 volumio volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 18 17:18:04 volumio volumio[1141]: info: New Spotify access tokenBQD-Up_sgg...
Feb 18 17:18:04 volumio volumio[1141]: info: Spotify credentials grant success - running version from March 24, 2019
Feb 18 17:18:05 volumio volumio[1141]: info: Discovery: this is already registered, 9747f86f-d2e8-47e2-a09b-14f0dfd83934
Feb 18 17:18:05 volumio volumio[1141]: info: Discovery: Found device Volumio
Feb 18 17:18:05 volumio volumio[1141]: info: CoreCommandRouter::volumioGetState
Feb 18 17:18:05 volumio volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 18 17:18:05 volumio volumio[1141]: SPOTIFY: User informations: {"country":"ES","display_name":"Vicente Iborra Llamas","email":"viborra@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/odiur"},"followers":{"href":null,"total":7},"href":"https://api.spotify.com/v1/users/odiur","id":"odiur","images":[{"height":300,"url":"https://scontent-ord5-1.xx.fbcdn.net/v/t39.30808-1/465783128_10225297531936044_6306274745852072800_n.jpg?stp=dst-jpg_s320x320_tt6&_nc_cat=108&ccb=1-7&_nc_sid=08baa4&_nc_ohc=9stlJOcfSUUQ7kNvwHouxpb&_nc_oc=AdmH_tQmXVwxeIwqFwWyD7nhZUSE0svTh5jVqQIvRQfZlaruVpCo6mQaC-yYWCBhRWSQUtzbT2Gu2JzJ6Lk_cpMw&_nc_zt=24&_nc_ht=scontent-ord5-1.xx&edm=AP4hL3IEAAAA&_nc_gid=jcvso0zn0p5UCt2VIjotIQ&_nc_tpa=Q5bMBQGQK5ze7svzIsNX3zemUF6JwnqGyc4V3JyCu4vFUBS-5dltv79JZrhKIbmd9f1jA3PozwPb&oh=00_AfscltovMuTJR8D4whO5C1kZRQ7xHFGvrZxWQDCotljiQw&oe=699BA88C","width":300},{"height":64,"url":"https://scontent-ord5-1.xx.fbcdn.net/v/t39.30808-1/465783128_10225297531936044_6306274745852072800_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=108&ccb=1-7&_nc_sid=28885b&_nc_ohc=9stlJOcfSUUQ7kNvwHouxpb&_nc_oc=AdmH_tQmXVwxeIwqFwWyD7nhZUSE0svTh5jVqQIvRQfZlaruVpCo6mQaC-yYWCBhRWSQUtzbT2Gu2JzJ6Lk_cpMw&_nc_zt=24&_nc_ht=scontent-ord5-1.xx&edm=AP4hL3IEAAAA&_nc_gid=jcvso0zn0p5UCt2VIjotIQ&_nc_tpa=Q5bMBQGISSSGbTnEoKQGacjnZKNj7QlHjRWsSLHzzFcC8XDfsQ_U1u1ZPOi_7bOmCgbkMjQJJ7J7&oh=00_Afs1XNJL_EH14PVQlL6o0QPF--0MQWJrHPIksN98B3sfew&oe=699BA88C","width":64}],"product":"free","type":"user","uri":"spotify:user:odiur"}
Feb 18 17:18:05 volumio volumio[1141]: info: Spotify Successfully logged in
Feb 18 17:18:05 volumio volumio[1141]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 18 17:18:05 volumio volumio[1141]: info: [1771431485687] CoreMusicLibrary::Adding element Spotify
Feb 18 17:18:05 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 18 17:18:05 volumio volumio[1141]: Cannot find translation for source Podcast
Feb 18 17:18:05 volumio volumio[1141]: Cannot find translation for source FM/DAB Radio
Feb 18 17:18:05 volumio volumio[1141]: Cannot find translation for source Spotify
Feb 18 17:18:07 volumio volumio[1141]: info: Initializing connection to go-librespot Websocket
Feb 18 17:18:07 volumio go-librespot[1567]: time="2026-02-18T17:18:07+01:00" level=debug msg="new websocket client"
Feb 18 17:18:07 volumio volumio[1141]: info: Connection to go-librespot Websocket established
Feb 18 17:18:07 volumio volumio-remote-updater[673]: [2026-02-18 17:18:07] [connect] Successful connection
Feb 18 17:18:07 volumio volumio-remote-updater[673]: [2026-02-18 17:18:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771431487 101
Feb 18 17:18:07 volumio volumio[1141]: 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: 9
Feb 18 17:18:08 volumio volumio[1141]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Feb 18 17:18:13 volumio volumio[1141]: info: Getting Spotify volume
Feb 18 17:18:24 volumio upmpdcli[1661]: writing RSA key
Feb 18 17:18:25 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 18 17:18:25 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 18 17:18:25 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 18 17:18:25 volumio volumio[1141]: error: MyVolumio Plugin failed to start in a timely fashion
Feb 18 17:18:26 volumio volumio[1141]: [Metrics] CommandRouter: 68s 470.43ms
Feb 18 17:18:26 volumio volumio[1141]: info: CoreCommandRouter::volumiosetStartupVolume
Feb 18 17:18:26 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 17:18:26 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 18 17:18:26 volumio volumio[1141]: info: CoreCommandRouter::Close All Modals sent
Feb 18 17:18:26 volumio volumio[1141]: info: CoreCommandRouter::Close All Modals sent
Feb 18 17:18:26 volumio volumio[1141]: info: Spotify volume: 100
Feb 18 17:18:31 volumio volumio5-onboarding[1501]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded
Feb 18 17:18:31 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE
Feb 18 17:18:31 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'.
Feb 18 17:18:31 volumio volumio[1141]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10
Feb 18 17:18:31 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Feb 18 17:18:31 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 18 17:18:31 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Feb 18 17:18:31 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1.
Feb 18 17:18:31 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server.
Feb 18 17:18:31 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server.
Feb 18 17:18:32 volumio volumio5-onboarding[1706]: time=2026-02-18T17:18:32.054+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z
Feb 18 17:18:32 volumio go-librespot[1567]: time="2026-02-18T17:18:32+01:00" level=trace msg="sent dealer ping"
Feb 18 17:18:32 volumio go-librespot[1567]: time="2026-02-18T17:18:32+01:00" level=trace msg="received dealer pong"
Feb 18 17:18:33 volumio volumio[1141]: info: BOOT COMPLETED
Feb 18 17:18:33 volumio volumio[1141]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 18 17:18:33 volumio volumio[1141]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals.
Feb 18 17:18:33 volumio volumio[1141]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues
Feb 18 17:18:33 volumio volumio[1141]: at new NodeError (node:internal/errors:405:5)
Feb 18 17:18:33 volumio volumio[1141]: at assert (node:internal/assert:14:11)
Feb 18 17:18:33 volumio volumio[1141]: at internalConnectMultiple (node:net:1118:3)
Feb 18 17:18:33 volumio volumio[1141]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3)
Feb 18 17:18:33 volumio volumio[1141]: at listOnTimeout (node:internal/timers:575:11)
Feb 18 17:18:33 volumio volumio[1141]: at process.processTimers (node:internal/timers:514:7) {
Feb 18 17:18:33 volumio volumio[1141]: code: 'ERR_INTERNAL_ASSERTION'
Feb 18 17:18:33 volumio volumio[1141]: }
Feb 18 17:18:33 volumio volumio[1141]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 18 17:18:39 volumio sudo[1741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-18 17:17'
Feb 18 17:18:39 volumio sudo[1741]: 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"