Feb 16 15:38:08 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 9662. Feb 16 15:38:08 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 16 15:38:08 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 16 15:38:09 volumio upmpdcli[391790]: Could not open config: /tmp/upmpdcli.conf Feb 16 15:38:09 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 16 15:38:09 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 16 15:38:24 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 9663. Feb 16 15:38:24 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 16 15:38:24 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 16 15:38:24 volumio upmpdcli[391821]: Could not open config: /tmp/upmpdcli.conf Feb 16 15:38:24 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 16 15:38:24 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 16 15:38:27 volumio dhcpcd[826]: eth0: carrier lost Feb 16 15:38:27 volumio kernel: e1000e 0000:00:19.0 eth0: NIC Link is Down Feb 16 15:38:27 volumio avahi-daemon[755]: Withdrawing address record for 192.168.1.138 on eth0. Feb 16 15:38:27 volumio avahi-daemon[755]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.138. Feb 16 15:38:27 volumio avahi-daemon[755]: Interface eth0.IPv4 no longer relevant for mDNS. Feb 16 15:38:27 volumio dhcpcd[826]: eth0: deleting route to 192.168.1.0/24 Feb 16 15:38:27 volumio dhcpcd[826]: eth0: deleting default route via 192.168.1.1 Feb 16 15:38:27 volumio volumio[145682]: info: Discovery: A device disappeared from network Feb 16 15:38:27 volumio volumio[145682]: info: Discovery: Device volumio disappeared from network Feb 16 15:38:27 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Feb 16 15:38:27 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Feb 16 15:38:27 volumio systemd[1]: welcome.service: Deactivated successfully. Feb 16 15:38:27 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 16 15:38:27 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 16 15:38:27 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 16 15:38:27 volumio welcome[391865]: Resolved ip:[0] Feb 16 15:38:27 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 16 15:38:27 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 16 15:38:27 volumio ifplugd(eth0)[986]: Link beat lost. Feb 16 15:38:28 volumio ntpd[922]: IO: Deleting interface #4 eth0, 192.168.1.138#123, interface stats: received=619, sent=622, dropped=0, active_time=93885 secs Feb 16 15:38:28 volumio ntpd[922]: PROTO: 23.95.49.216 unlink local addr 192.168.1.138 -> Feb 16 15:38:28 volumio ntpd[922]: PROTO: 104.234.61.117 unlink local addr 192.168.1.138 -> Feb 16 15:38:28 volumio ntpd[922]: PROTO: 149.248.12.167 unlink local addr 192.168.1.138 -> Feb 16 15:38:28 volumio ntpd[922]: PROTO: 155.248.196.28 unlink local addr 192.168.1.138 -> Feb 16 15:38:28 volumio ntpd[922]: PROTO: 129.146.193.200 unlink local addr 192.168.1.138 -> Feb 16 15:38:28 volumio ntpd[922]: PROTO: 23.159.16.194 unlink local addr 192.168.1.138 -> Feb 16 15:38:28 volumio ntpd[922]: PROTO: 141.11.89.193 unlink local addr 192.168.1.138 -> Feb 16 15:38:35 volumio volumio[145682]: info: Volumio Network Manager: Network status updated: 0 Feb 16 15:38:37 volumio ifplugd(eth0)[986]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Feb 16 15:38:37 volumio dhcpcd[391916]: dhcpcd not running Feb 16 15:38:37 volumio ifplugd(eth0)[986]: client: dhcpcd not running Feb 16 15:38:38 volumio kernel: e1000e 0000:00:19.0 eth0: NIC Link is Down Feb 16 15:38:38 volumio ifplugd(eth0)[986]: Program executed successfully. Feb 16 15:38:39 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 9664. Feb 16 15:38:39 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 16 15:38:39 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 16 15:38:39 volumio upmpdcli[391931]: Could not open config: /tmp/upmpdcli.conf Feb 16 15:38:39 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 16 15:38:39 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 16 15:38:41 volumio dhcpcd[826]: eth0: carrier acquired Feb 16 15:38:41 volumio kernel: e1000e 0000:00:19.0 eth0: NIC Link is Up 1000 Mbps Full Duplex, Flow Control: None Feb 16 15:38:41 volumio dhcpcd[826]: eth0: IAID f2:ec:7d:5b Feb 16 15:38:41 volumio dhcpcd[826]: eth0: soliciting an IPv6 router Feb 16 15:38:41 volumio ifplugd(eth0)[986]: Link beat detected. Feb 16 15:38:41 volumio ifplugd(eth0)[986]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 16 15:38:41 volumio ifplugd(eth0)[986]: client: command failed: No such device (-19) Feb 16 15:38:41 volumio ifplugd(eth0)[986]: client: sending commands to dhcpcd process Feb 16 15:38:41 volumio dhcpcd[826]: ps_ctl_dispatch: cannot handle another client Feb 16 15:38:41 volumio dhcpcd[826]: control_free: No such file or directory Feb 16 15:38:41 volumio dhcpcd[826]: eth0: rebinding lease of 192.168.1.138 Feb 16 15:38:41 volumio ifplugd(eth0)[986]: Program executed successfully. Feb 16 15:38:45 volumio dhcpcd[826]: eth0: carrier lost Feb 16 15:38:45 volumio kernel: e1000e 0000:00:19.0 eth0: NIC Link is Down Feb 16 15:38:45 volumio volumio[145682]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:38:45 volumio volumio[145682]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 16 15:38:45 volumio volumio[145682]: at doSend (node:dgram:717:16) Feb 16 15:38:45 volumio volumio[145682]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Feb 16 15:38:45 volumio volumio[145682]: at afterDns (node:dgram:663:5) Feb 16 15:38:45 volumio volumio[145682]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Feb 16 15:38:45 volumio volumio[145682]: errno: -101, Feb 16 15:38:45 volumio volumio[145682]: code: 'ENETUNREACH', Feb 16 15:38:45 volumio volumio[145682]: syscall: 'send', Feb 16 15:38:45 volumio volumio[145682]: address: '255.255.255.255', Feb 16 15:38:45 volumio volumio[145682]: port: 3483 Feb 16 15:38:45 volumio volumio[145682]: } Feb 16 15:38:45 volumio volumio[145682]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:38:45 volumio sudo[392020]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-16 15:37' Feb 16 15:38:45 volumio sudo[392020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:45 volumio sudo[392020]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:45 volumio go-librespot[145907]: time="2026-02-16T15:38:45-06:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Feb 16 15:38:45 volumio volumio-remote-updater[763]: [2026-02-16 15:38:45] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 16 15:38:45 volumio volumio-remote-updater[763]: [2026-02-16 15:38:45] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 16 15:38:45 volumio volumio5-onboarding[1422]: time=2026-02-16T15:38:45.509-06:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 16 15:38:45 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 16 15:38:45 volumio volumio5-onboarding[1422]: time=2026-02-16T15:38:45.510-06:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 16 15:38:45 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 16 15:38:45 volumio systemd[1]: volumio.service: Consumed 3min 54.658s CPU time. Feb 16 15:38:45 volumio ifplugd(eth0)[986]: Link beat lost. Feb 16 15:38:45 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 16 15:38:45 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 16 15:38:45 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 22. Feb 16 15:38:45 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 16 15:38:45 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 16 15:38:45 volumio systemd[1]: volumio.service: Consumed 3min 54.658s CPU time. Feb 16 15:38:45 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 16 15:38:45 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 16 15:38:46 volumio volumio[392074]: info: ------------------------------------------- Feb 16 15:38:46 volumio volumio[392074]: info: ----- Volumio3 ---- Feb 16 15:38:46 volumio volumio[392074]: info: ------------------------------------------- Feb 16 15:38:46 volumio volumio[392074]: info: ----- System startup ---- Feb 16 15:38:46 volumio volumio[392074]: info: ------------------------------------------- Feb 16 15:38:46 volumio volumio[392074]: info: MYVOLUMIO Environment detected Feb 16 15:38:46 volumio volumio[392074]: info: Plugin folders cleanup Feb 16 15:38:46 volumio volumio[392074]: info: Scanning into folder /volumio/app/plugins/ Feb 16 15:38:46 volumio volumio[392074]: info: Scanning category audio_interface Feb 16 15:38:46 volumio volumio[392074]: info: Scanning category miscellanea Feb 16 15:38:46 volumio volumio[392074]: info: Scanning category music_service Feb 16 15:38:46 volumio volumio[392074]: info: Scanning category plugins.json Feb 16 15:38:46 volumio volumio[392074]: info: Scanning category system_controller Feb 16 15:38:46 volumio volumio[392074]: info: Scanning category user_interface Feb 16 15:38:46 volumio volumio[392074]: info: Scanning into folder /data/plugins/ Feb 16 15:38:46 volumio volumio[392074]: info: Scanning category music_service Feb 16 15:38:46 volumio volumio[392074]: info: Plugin folders cleanup completed Feb 16 15:38:46 volumio volumio[392074]: info: ------------------------------------------- Feb 16 15:38:46 volumio volumio[392074]: info: ----- Core plugins startup ---- Feb 16 15:38:46 volumio volumio[392074]: info: ------------------------------------------- Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 15:38:46 volumio volumio[392074]: info: Adding plugin upnp to MyMusic Plugins Feb 16 15:38:46 volumio volumio[392074]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 15:38:46 volumio volumio[392074]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugins from folder /data/plugins/ Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugin "system"... Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugin "appearance"... Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugin "network"... Feb 16 15:38:46 volumio volumio[392074]: info: Refreshing Cached IP Addresses Feb 16 15:38:46 volumio sudo[392102]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 15:38:46 volumio sudo[392102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:46 volumio sudo[392102]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:46 volumio sudo[392104]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 15:38:46 volumio sudo[392104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugin "services"... Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugin "volumio5onboarding"... Feb 16 15:38:46 volumio sudo[392113]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 16 15:38:46 volumio sudo[392104]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:46 volumio sudo[392113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugin "alsa_controller"... Feb 16 15:38:46 volumio sudo[392113]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:46 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugin "wizard"... Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugin "networkfs"... Feb 16 15:38:46 volumio volumio[392074]: info: Starting Udev Watcher for removable devices Feb 16 15:38:46 volumio volumio[392074]: info: Ignoring mount for partition: boot Feb 16 15:38:46 volumio volumio[392074]: info: Ignoring mount for partition: volumio Feb 16 15:38:46 volumio volumio[392074]: info: Ignoring mount for partition: volumio_data Feb 16 15:38:46 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugin "volumio_command_line_client"... Feb 16 15:38:46 volumio volumio[392074]: info: Plugin upnp is not enabled Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugin "my_music"... Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugin "mpd"... Feb 16 15:38:46 volumio volumio[392074]: info: Plugin upnp_browser is not enabled Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugin "alarm-clock"... Feb 16 15:38:46 volumio volumio[392074]: info: Plugin airplay_emulation is not enabled Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugin "last_100"... Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugin "webradio"... Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugin "i2s_dacs"... Feb 16 15:38:46 volumio volumio[392074]: info: I2S DAC not set, start Auto-detection Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugin "volumiodiscovery"... Feb 16 15:38:46 volumio volumio[392074]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:46 volumio volumio[392074]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:46 volumio volumio[392074]: *** WARNING *** For more information see Feb 16 15:38:46 volumio volumio[392074]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:46 volumio volumio[392074]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:46 volumio volumio[392074]: *** WARNING *** For more information see Feb 16 15:38:46 volumio node[392074]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:46 volumio node[392074]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:46 volumio node[392074]: *** WARNING *** For more information see Feb 16 15:38:46 volumio node[392074]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:46 volumio node[392074]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:46 volumio node[392074]: *** WARNING *** For more information see Feb 16 15:38:46 volumio volumio[392074]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 15:38:46 volumio volumio[392074]: info: Discovery: Started advertising with name: Volumio Feb 16 15:38:46 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:38:46 volumio volumio[392074]: info: Loading plugin "spop"... Feb 16 15:38:47 volumio volumio[392074]: info: Loading plugin "squeezelite_mc"... Feb 16 15:38:47 volumio volumio[392074]: info: Loading plugin "outputs"... Feb 16 15:38:47 volumio volumio[392074]: info: Loading plugin "albumart"... Feb 16 15:38:47 volumio volumio[392074]: info: Plugin example_plugin is not enabled Feb 16 15:38:47 volumio volumio[392074]: info: Loading plugin "inputs"... Feb 16 15:38:47 volumio volumio[392074]: info: Loading plugin "updater_comm"... Feb 16 15:38:47 volumio volumio[392074]: info: Plugin mpdemulation is not enabled Feb 16 15:38:47 volumio volumio[392074]: info: Loading plugin "rest_api"... Feb 16 15:38:47 volumio volumio[392074]: info: Loading plugin "websocket"... Feb 16 15:38:47 volumio volumio[392074]: info: Starting Socket.io Server version 1.7.4 Feb 16 15:38:47 volumio volumio[392074]: info: Loading plugin "RoonBridge"... Feb 16 15:38:47 volumio volumio[392139]: Forking 3 albumart workers Feb 16 15:38:47 volumio volumio[392074]: info: Applying required configuration parameters for plugin RoonBridge Feb 16 15:38:47 volumio volumio[392074]: info: Loading i18n strings for locale en Feb 16 15:38:47 volumio volumio[392074]: Updating browse sources language Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::initPlayerControls Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:38:47 volumio volumio[392074]: Express server listening on port 3000 Feb 16 15:38:47 volumio volumio[392074]: [Metrics] WebUI: 1s 341.35ms Feb 16 15:38:47 volumio volumio[392074]: info: Setting Device type: x86 Feb 16 15:38:47 volumio volumio[392074]: info: CoreStateMachine::resetVolumioState Feb 16 15:38:47 volumio volumio[392074]: info: CoreStateMachine::getcurrentVolume Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:38:47 volumio volumio[392074]: info: CoreStateMachine::pushState Feb 16 15:38:47 volumio volumio[392074]: info: CorePlayQueue::getTrack 0 Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::volumioPushState Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:38:47 volumio volumio[392074]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 16 15:38:47 volumio volumio[392074]: info: Completed loading Core Plugins Feb 16 15:38:47 volumio volumio[392074]: info: Preparing to generate the ALSA configuration file Feb 16 15:38:47 volumio volumio[392074]: info: Volumio Network Manager: Network status updated: 0 Feb 16 15:38:47 volumio volumio[392074]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 16 15:38:47 volumio volumio[392074]: wlan0 Interface doesn't support scanning. Feb 16 15:38:47 volumio volumio[392074]: info: Cannot use regular scanning, forcing with ap-force Feb 16 15:38:47 volumio sudo[392182]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 16 15:38:47 volumio sudo[392182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:47 volumio volumio[392150]: Starting albumart workers Feb 16 15:38:47 volumio sudo[392182]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:47 volumio volumio[392074]: command failed: No such device (-19) Feb 16 15:38:47 volumio volumio[392074]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 16 15:38:47 volumio volumio[392074]: command failed: No such device (-19) Feb 16 15:38:47 volumio volumio[392074]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 16 15:38:47 volumio volumio[392151]: Starting albumart workers Feb 16 15:38:47 volumio volumio[392074]: 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: 1 Feb 16 15:38:47 volumio volumio[392149]: Starting albumart workers Feb 16 15:38:47 volumio volumio[392074]: info: Reloading queue from file Feb 16 15:38:47 volumio volumio[392074]: 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: 1 Feb 16 15:38:47 volumio volumio[392074]: info: Asound.conf file unchanged, so no further update is needed Feb 16 15:38:47 volumio volumio[392074]: info: Output device has changed, restarting MPD Feb 16 15:38:47 volumio volumio[392074]: info: ___________ START PLUGINS ___________ Feb 16 15:38:47 volumio sudo[392192]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:38:47 volumio sudo[392192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:47 volumio volumio[392074]: info: ControllerMpd::onStart: Initializing MPD Feb 16 15:38:47 volumio volumio[392074]: info: Creating MPD Configuration file Feb 16 15:38:47 volumio sudo[392192]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:47 volumio sudo[392195]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 16 15:38:47 volumio sudo[392194]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:38:47 volumio sudo[392194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:47 volumio sudo[392195]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:38:47 volumio volumio[392074]: info: [1771277927490] CoreMusicLibrary::Adding element Last_100 Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:38:47 volumio volumio[392074]: info: [1771277927491] CoreMusicLibrary::Adding element Webradio Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:38:47 volumio sudo[392199]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:38:47 volumio volumio[392074]: info: Initializing BBC Radios Feb 16 15:38:47 volumio sudo[392199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:47 volumio sudo[392203]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:38:47 volumio sudo[392203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:47 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 16 15:38:47 volumio sudo[392199]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:47 volumio sudo[392195]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:47 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 16 15:38:47 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 15:38:47 volumio systemd[1]: mpd.service: Consumed 1.429s CPU time. Feb 16 15:38:47 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 16 15:38:47 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 15:38:47 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:38:47 volumio volumio[392074]: info: Creating Spotify config file Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:47 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 15:38:47 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 15:38:47 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 16 15:38:47 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 15:38:47 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 16 15:38:47 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 15:38:47 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 15:38:47 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 15:38:47 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 15:38:47 volumio volumio[392074]: info: [squeezelite_mc] Starting proxy server... Feb 16 15:38:47 volumio sudo[392222]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 15:38:47 volumio sudo[392222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 16 15:38:47 volumio sudo[392222]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:47 volumio volumio[392074]: info: Volumio Calling Home Feb 16 15:38:47 volumio sudo[392238]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 16 15:38:47 volumio sudo[392238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:47 volumio volumio[392074]: info: [squeezelite_mc] Proxy server started on port 41335 Feb 16 15:38:47 volumio sudo[392238]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:38:47 volumio volumio[392074]: info: CoreStateMachine::pushState Feb 16 15:38:47 volumio volumio[392074]: info: CorePlayQueue::getTrack 0 Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::volumioPushState Feb 16 15:38:47 volumio volumio[392074]: info: [squeezelite_mc] Server discovery started Feb 16 15:38:47 volumio volumio[392074]: info: [squeezelite_mc] Player finder started Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:47 volumio volumio[392074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:38:47 volumio volumio[392074]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 16 15:38:47 volumio volumio[392074]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:38:47 volumio volumio[392074]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 16 15:38:47 volumio volumio[392074]: at doSend (node:dgram:717:16) Feb 16 15:38:47 volumio volumio[392074]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Feb 16 15:38:47 volumio volumio[392074]: at afterDns (node:dgram:663:5) Feb 16 15:38:47 volumio volumio[392074]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Feb 16 15:38:47 volumio volumio[392074]: errno: -101, Feb 16 15:38:47 volumio volumio[392074]: code: 'ENETUNREACH', Feb 16 15:38:47 volumio volumio[392074]: syscall: 'send', Feb 16 15:38:47 volumio volumio[392074]: address: '255.255.255.255', Feb 16 15:38:47 volumio volumio[392074]: port: 3483 Feb 16 15:38:47 volumio volumio[392074]: } Feb 16 15:38:47 volumio volumio[392074]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:38:47 volumio sudo[392265]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-16 15:37' Feb 16 15:38:47 volumio sudo[392265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:47 volumio sudo[392265]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:47 volumio volumio5-onboarding[1422]: time=2026-02-16T15:38:47.859-06:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:58350->127.0.0.1:3000: read: connection reset by peer" Feb 16 15:38:47 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 16 15:38:47 volumio sudo[392194]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:47 volumio sudo[392203]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:47 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 16 15:38:47 volumio systemd[1]: volumio.service: Consumed 4.118s CPU time. Feb 16 15:38:47 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 16 15:38:47 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 16 15:38:47 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 23. Feb 16 15:38:48 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 16 15:38:48 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 16 15:38:48 volumio systemd[1]: volumio.service: Consumed 4.118s CPU time. Feb 16 15:38:48 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 16 15:38:48 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 16 15:38:48 volumio mpd[392237]: 2026-02-16T15:38:48 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 16 15:38:48 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 16 15:38:48 volumio volumio[392277]: info: ------------------------------------------- Feb 16 15:38:48 volumio volumio[392277]: info: ----- Volumio3 ---- Feb 16 15:38:48 volumio volumio[392277]: info: ------------------------------------------- Feb 16 15:38:48 volumio volumio[392277]: info: ----- System startup ---- Feb 16 15:38:48 volumio volumio[392277]: info: ------------------------------------------- Feb 16 15:38:48 volumio volumio[392277]: info: MYVOLUMIO Environment detected Feb 16 15:38:48 volumio volumio[392277]: info: Plugin folders cleanup Feb 16 15:38:48 volumio volumio[392277]: info: Scanning into folder /volumio/app/plugins/ Feb 16 15:38:48 volumio volumio[392277]: info: Scanning category audio_interface Feb 16 15:38:48 volumio volumio[392277]: info: Scanning category miscellanea Feb 16 15:38:48 volumio volumio[392277]: info: Scanning category music_service Feb 16 15:38:48 volumio volumio[392277]: info: Scanning category plugins.json Feb 16 15:38:48 volumio volumio[392277]: info: Scanning category system_controller Feb 16 15:38:48 volumio volumio[392277]: info: Scanning category user_interface Feb 16 15:38:48 volumio volumio[392277]: info: Scanning into folder /data/plugins/ Feb 16 15:38:48 volumio volumio[392277]: info: Scanning category music_service Feb 16 15:38:48 volumio volumio[392277]: info: Plugin folders cleanup completed Feb 16 15:38:48 volumio volumio[392277]: info: ------------------------------------------- Feb 16 15:38:48 volumio volumio[392277]: info: ----- Core plugins startup ---- Feb 16 15:38:48 volumio volumio[392277]: info: ------------------------------------------- Feb 16 15:38:48 volumio volumio[392277]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 15:38:48 volumio volumio[392277]: info: Adding plugin upnp to MyMusic Plugins Feb 16 15:38:48 volumio volumio[392277]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 15:38:48 volumio volumio[392277]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 15:38:48 volumio volumio[392277]: info: Loading plugins from folder /data/plugins/ Feb 16 15:38:48 volumio volumio[392277]: info: Loading plugin "system"... Feb 16 15:38:48 volumio volumio[392277]: info: Loading plugin "appearance"... Feb 16 15:38:48 volumio volumio[392277]: info: Loading plugin "network"... Feb 16 15:38:48 volumio volumio[392277]: info: Refreshing Cached IP Addresses Feb 16 15:38:48 volumio sudo[392308]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 15:38:48 volumio sudo[392308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:48 volumio sudo[392308]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:48 volumio sudo[392310]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 15:38:48 volumio sudo[392310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:48 volumio volumio[392277]: info: Loading plugin "services"... Feb 16 15:38:48 volumio volumio[392277]: info: Loading plugin "volumio5onboarding"... Feb 16 15:38:48 volumio sudo[392318]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 16 15:38:48 volumio sudo[392318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:48 volumio sudo[392310]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:48 volumio volumio[392277]: info: Loading plugin "alsa_controller"... Feb 16 15:38:48 volumio sudo[392318]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:48 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:38:48 volumio volumio[392277]: info: Loading plugin "wizard"... Feb 16 15:38:48 volumio volumio[392277]: info: Loading plugin "networkfs"... Feb 16 15:38:48 volumio volumio[392277]: info: Starting Udev Watcher for removable devices Feb 16 15:38:48 volumio volumio[392277]: info: Ignoring mount for partition: boot Feb 16 15:38:48 volumio volumio[392277]: info: Ignoring mount for partition: volumio Feb 16 15:38:48 volumio volumio[392277]: info: Ignoring mount for partition: volumio_data Feb 16 15:38:48 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:38:48 volumio volumio[392277]: info: Loading plugin "volumio_command_line_client"... Feb 16 15:38:48 volumio volumio[392277]: info: Plugin upnp is not enabled Feb 16 15:38:48 volumio volumio[392277]: info: Loading plugin "my_music"... Feb 16 15:38:48 volumio volumio[392277]: info: Loading plugin "mpd"... Feb 16 15:38:48 volumio volumio[392277]: info: Plugin upnp_browser is not enabled Feb 16 15:38:48 volumio volumio[392277]: info: Loading plugin "alarm-clock"... Feb 16 15:38:48 volumio volumio[392277]: info: Plugin airplay_emulation is not enabled Feb 16 15:38:48 volumio volumio[392277]: info: Loading plugin "last_100"... Feb 16 15:38:48 volumio volumio[392277]: info: Loading plugin "webradio"... Feb 16 15:38:49 volumio volumio[392277]: info: Loading plugin "i2s_dacs"... Feb 16 15:38:49 volumio volumio[392277]: info: I2S DAC not set, start Auto-detection Feb 16 15:38:49 volumio volumio[392277]: info: Loading plugin "volumiodiscovery"... Feb 16 15:38:49 volumio volumio[392277]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:49 volumio volumio[392277]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:49 volumio volumio[392277]: *** WARNING *** For more information see Feb 16 15:38:49 volumio volumio[392277]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:49 volumio volumio[392277]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:49 volumio volumio[392277]: *** WARNING *** For more information see Feb 16 15:38:49 volumio node[392277]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:49 volumio node[392277]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:49 volumio node[392277]: *** WARNING *** For more information see Feb 16 15:38:49 volumio node[392277]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:49 volumio node[392277]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:49 volumio node[392277]: *** WARNING *** For more information see Feb 16 15:38:49 volumio volumio[392277]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 15:38:49 volumio volumio[392277]: info: Discovery: Started advertising with name: Volumio Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:38:49 volumio volumio[392277]: info: Loading plugin "spop"... Feb 16 15:38:49 volumio volumio[392277]: info: Loading plugin "squeezelite_mc"... Feb 16 15:38:49 volumio volumio[392277]: info: Loading plugin "outputs"... Feb 16 15:38:49 volumio volumio[392277]: info: Loading plugin "albumart"... Feb 16 15:38:49 volumio volumio[392277]: info: Plugin example_plugin is not enabled Feb 16 15:38:49 volumio volumio[392277]: info: Loading plugin "inputs"... Feb 16 15:38:49 volumio volumio[392277]: info: Loading plugin "updater_comm"... Feb 16 15:38:49 volumio volumio[392277]: info: Plugin mpdemulation is not enabled Feb 16 15:38:49 volumio volumio[392277]: info: Loading plugin "rest_api"... Feb 16 15:38:49 volumio volumio[392277]: info: Loading plugin "websocket"... Feb 16 15:38:49 volumio volumio[392277]: info: Starting Socket.io Server version 1.7.4 Feb 16 15:38:49 volumio volumio[392277]: info: Loading plugin "RoonBridge"... Feb 16 15:38:49 volumio volumio[392344]: Forking 3 albumart workers Feb 16 15:38:49 volumio volumio[392277]: info: Applying required configuration parameters for plugin RoonBridge Feb 16 15:38:49 volumio volumio[392277]: info: Loading i18n strings for locale en Feb 16 15:38:49 volumio volumio[392277]: Updating browse sources language Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::initPlayerControls Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:38:49 volumio volumio[392277]: Express server listening on port 3000 Feb 16 15:38:49 volumio volumio[392277]: [Metrics] WebUI: 1s 349.83ms Feb 16 15:38:49 volumio volumio[392277]: info: Setting Device type: x86 Feb 16 15:38:49 volumio volumio[392277]: info: CoreStateMachine::resetVolumioState Feb 16 15:38:49 volumio volumio[392277]: info: CoreStateMachine::getcurrentVolume Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:38:49 volumio volumio[392277]: info: CoreStateMachine::pushState Feb 16 15:38:49 volumio volumio[392277]: info: CorePlayQueue::getTrack 0 Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::volumioPushState Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:38:49 volumio volumio[392277]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 16 15:38:49 volumio volumio[392277]: info: Completed loading Core Plugins Feb 16 15:38:49 volumio volumio[392277]: info: Preparing to generate the ALSA configuration file Feb 16 15:38:49 volumio volumio[392277]: info: Volumio Network Manager: Network status updated: 0 Feb 16 15:38:49 volumio volumio[392277]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 16 15:38:49 volumio volumio[392277]: wlan0 Interface doesn't support scanning. Feb 16 15:38:49 volumio volumio[392277]: info: Cannot use regular scanning, forcing with ap-force Feb 16 15:38:49 volumio sudo[392387]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 16 15:38:49 volumio sudo[392387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:49 volumio sudo[392387]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:49 volumio volumio[392277]: command failed: No such device (-19) Feb 16 15:38:49 volumio volumio[392277]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 16 15:38:49 volumio volumio[392277]: command failed: No such device (-19) Feb 16 15:38:49 volumio volumio[392277]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 16 15:38:49 volumio volumio[392355]: Starting albumart workers Feb 16 15:38:49 volumio volumio[392356]: Starting albumart workers Feb 16 15:38:49 volumio volumio[392354]: Starting albumart workers Feb 16 15:38:49 volumio volumio[392277]: 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: 1 Feb 16 15:38:49 volumio volumio[392277]: info: Reloading queue from file Feb 16 15:38:49 volumio volumio[392277]: 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: 1 Feb 16 15:38:49 volumio volumio[392277]: info: Asound.conf file unchanged, so no further update is needed Feb 16 15:38:49 volumio volumio[392277]: info: Output device has changed, restarting MPD Feb 16 15:38:49 volumio volumio[392277]: info: ___________ START PLUGINS ___________ Feb 16 15:38:49 volumio sudo[392398]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:38:49 volumio sudo[392398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:49 volumio sudo[392396]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:38:49 volumio volumio[392277]: info: ControllerMpd::onStart: Initializing MPD Feb 16 15:38:49 volumio volumio[392277]: info: Creating MPD Configuration file Feb 16 15:38:49 volumio sudo[392396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:49 volumio sudo[392396]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:38:49 volumio volumio[392277]: info: [1771277929763] CoreMusicLibrary::Adding element Last_100 Feb 16 15:38:49 volumio sudo[392404]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:38:49 volumio volumio[392277]: info: [1771277929768] CoreMusicLibrary::Adding element Webradio Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:38:49 volumio sudo[392401]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:38:49 volumio sudo[392401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:49 volumio sudo[392404]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:49 volumio volumio[392277]: info: Initializing BBC Radios Feb 16 15:38:49 volumio sudo[392404]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:49 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 16 15:38:49 volumio sudo[392406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:38:49 volumio sudo[392406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:49 volumio sudo[392401]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:49 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 16 15:38:49 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 15:38:49 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 16 15:38:49 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 15:38:49 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 15:38:49 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:38:49 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 15:38:49 volumio volumio[392277]: info: Creating Spotify config file Feb 16 15:38:49 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:49 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 16 15:38:49 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 15:38:49 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 16 15:38:49 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 15:38:49 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 15:38:49 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 15:38:49 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 15:38:49 volumio sudo[392426]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 15:38:49 volumio sudo[392426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 16 15:38:49 volumio sudo[392426]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:49 volumio volumio[392277]: info: [squeezelite_mc] Starting proxy server... Feb 16 15:38:49 volumio volumio[392277]: info: Volumio Calling Home Feb 16 15:38:49 volumio sudo[392448]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 16 15:38:49 volumio sudo[392448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:49 volumio volumio[392277]: info: [squeezelite_mc] Proxy server started on port 37369 Feb 16 15:38:49 volumio sudo[392448]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:50 volumio volumio[392277]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:38:50 volumio volumio[392277]: info: CoreStateMachine::pushState Feb 16 15:38:50 volumio volumio[392277]: info: CorePlayQueue::getTrack 0 Feb 16 15:38:50 volumio volumio[392277]: info: CoreCommandRouter::volumioPushState Feb 16 15:38:50 volumio volumio[392277]: info: [squeezelite_mc] Server discovery started Feb 16 15:38:50 volumio volumio[392277]: info: [squeezelite_mc] Player finder started Feb 16 15:38:50 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:50 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:50 volumio volumio[392277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:38:50 volumio volumio[392277]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 16 15:38:50 volumio volumio[392277]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:38:50 volumio volumio[392277]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 16 15:38:50 volumio volumio[392277]: at doSend (node:dgram:717:16) Feb 16 15:38:50 volumio volumio[392277]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Feb 16 15:38:50 volumio volumio[392277]: at afterDns (node:dgram:663:5) Feb 16 15:38:50 volumio volumio[392277]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Feb 16 15:38:50 volumio volumio[392277]: errno: -101, Feb 16 15:38:50 volumio volumio[392277]: code: 'ENETUNREACH', Feb 16 15:38:50 volumio volumio[392277]: syscall: 'send', Feb 16 15:38:50 volumio volumio[392277]: address: '255.255.255.255', Feb 16 15:38:50 volumio volumio[392277]: port: 3483 Feb 16 15:38:50 volumio volumio[392277]: } Feb 16 15:38:50 volumio volumio[392277]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:38:50 volumio sudo[392469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-16 15:37' Feb 16 15:38:50 volumio sudo[392469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:50 volumio sudo[392469]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:50 volumio volumio5-onboarding[1422]: time=2026-02-16T15:38:50.151-06:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:58380->127.0.0.1:3000: read: connection reset by peer" Feb 16 15:38:50 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 16 15:38:50 volumio sudo[392398]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:50 volumio sudo[392406]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:50 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 16 15:38:50 volumio systemd[1]: volumio.service: Consumed 4.214s CPU time. Feb 16 15:38:50 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 16 15:38:50 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 16 15:38:50 volumio mpd[392440]: 2026-02-16T15:38:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 16 15:38:50 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 24. Feb 16 15:38:50 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 16 15:38:50 volumio systemd[1]: dynamicswap.service: Start request repeated too quickly. Feb 16 15:38:50 volumio systemd[1]: dynamicswap.service: Failed with result 'start-limit-hit'. Feb 16 15:38:50 volumio systemd[1]: Failed to start dynamicswap.service - dynamicswap service. Feb 16 15:38:50 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 16 15:38:50 volumio systemd[1]: volumio.service: Consumed 4.214s CPU time. Feb 16 15:38:50 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 16 15:38:50 volumio volumio-remote-updater[763]: [2026-02-16 15:38:50] [info] asio async_connect error: asio.system:111 (Connection refused) Feb 16 15:38:50 volumio volumio-remote-updater[763]: [2026-02-16 15:38:50] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Feb 16 15:38:50 volumio volumio-remote-updater[763]: [2026-02-16 15:38:50] [error] handle_connect error: Connection refused Feb 16 15:38:50 volumio volumio[392482]: info: ------------------------------------------- Feb 16 15:38:50 volumio volumio[392482]: info: ----- Volumio3 ---- Feb 16 15:38:50 volumio volumio[392482]: info: ------------------------------------------- Feb 16 15:38:50 volumio volumio[392482]: info: ----- System startup ---- Feb 16 15:38:50 volumio volumio[392482]: info: ------------------------------------------- Feb 16 15:38:50 volumio volumio[392482]: info: MYVOLUMIO Environment detected Feb 16 15:38:50 volumio volumio[392482]: info: Plugin folders cleanup Feb 16 15:38:50 volumio volumio[392482]: info: Scanning into folder /volumio/app/plugins/ Feb 16 15:38:50 volumio volumio[392482]: info: Scanning category audio_interface Feb 16 15:38:50 volumio volumio[392482]: info: Scanning category miscellanea Feb 16 15:38:50 volumio volumio[392482]: info: Scanning category music_service Feb 16 15:38:50 volumio volumio[392482]: info: Scanning category plugins.json Feb 16 15:38:50 volumio volumio[392482]: info: Scanning category system_controller Feb 16 15:38:50 volumio volumio[392482]: info: Scanning category user_interface Feb 16 15:38:50 volumio volumio[392482]: info: Scanning into folder /data/plugins/ Feb 16 15:38:50 volumio volumio[392482]: info: Scanning category music_service Feb 16 15:38:50 volumio volumio[392482]: info: Plugin folders cleanup completed Feb 16 15:38:50 volumio volumio[392482]: info: ------------------------------------------- Feb 16 15:38:50 volumio volumio[392482]: info: ----- Core plugins startup ---- Feb 16 15:38:50 volumio volumio[392482]: info: ------------------------------------------- Feb 16 15:38:50 volumio volumio[392482]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 15:38:50 volumio volumio[392482]: info: Adding plugin upnp to MyMusic Plugins Feb 16 15:38:50 volumio volumio[392482]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 15:38:50 volumio volumio[392482]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 15:38:50 volumio volumio[392482]: info: Loading plugins from folder /data/plugins/ Feb 16 15:38:50 volumio volumio[392482]: info: Loading plugin "system"... Feb 16 15:38:50 volumio volumio[392482]: info: Loading plugin "appearance"... Feb 16 15:38:50 volumio volumio[392482]: info: Loading plugin "network"... Feb 16 15:38:50 volumio volumio[392482]: info: Refreshing Cached IP Addresses Feb 16 15:38:50 volumio sudo[392506]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 15:38:50 volumio sudo[392506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:50 volumio sudo[392508]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 15:38:50 volumio sudo[392508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:50 volumio sudo[392506]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:50 volumio volumio[392482]: info: Loading plugin "services"... Feb 16 15:38:50 volumio volumio[392482]: info: Loading plugin "volumio5onboarding"... Feb 16 15:38:50 volumio sudo[392508]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "alsa_controller"... Feb 16 15:38:51 volumio sudo[392514]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 16 15:38:51 volumio sudo[392514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:51 volumio sudo[392514]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "wizard"... Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "networkfs"... Feb 16 15:38:51 volumio volumio[392482]: info: Starting Udev Watcher for removable devices Feb 16 15:38:51 volumio volumio[392482]: info: Ignoring mount for partition: boot Feb 16 15:38:51 volumio volumio[392482]: info: Ignoring mount for partition: volumio Feb 16 15:38:51 volumio volumio[392482]: info: Ignoring mount for partition: volumio_data Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "volumio_command_line_client"... Feb 16 15:38:51 volumio volumio[392482]: info: Plugin upnp is not enabled Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "my_music"... Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "mpd"... Feb 16 15:38:51 volumio volumio[392482]: info: Plugin upnp_browser is not enabled Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "alarm-clock"... Feb 16 15:38:51 volumio volumio[392482]: info: Plugin airplay_emulation is not enabled Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "last_100"... Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "webradio"... Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "i2s_dacs"... Feb 16 15:38:51 volumio volumio[392482]: info: I2S DAC not set, start Auto-detection Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "volumiodiscovery"... Feb 16 15:38:51 volumio volumio[392482]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:51 volumio volumio[392482]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:51 volumio volumio[392482]: *** WARNING *** For more information see Feb 16 15:38:51 volumio volumio[392482]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:51 volumio volumio[392482]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:51 volumio volumio[392482]: *** WARNING *** For more information see Feb 16 15:38:51 volumio node[392482]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:51 volumio node[392482]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:51 volumio node[392482]: *** WARNING *** For more information see Feb 16 15:38:51 volumio node[392482]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:51 volumio node[392482]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:51 volumio node[392482]: *** WARNING *** For more information see Feb 16 15:38:51 volumio volumio[392482]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 15:38:51 volumio volumio[392482]: info: Discovery: Started advertising with name: Volumio Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "spop"... Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "squeezelite_mc"... Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "outputs"... Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "albumart"... Feb 16 15:38:51 volumio volumio[392482]: info: Plugin example_plugin is not enabled Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "inputs"... Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "updater_comm"... Feb 16 15:38:51 volumio volumio[392482]: info: Plugin mpdemulation is not enabled Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "rest_api"... Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "websocket"... Feb 16 15:38:51 volumio volumio[392482]: info: Starting Socket.io Server version 1.7.4 Feb 16 15:38:51 volumio volumio[392482]: info: Loading plugin "RoonBridge"... Feb 16 15:38:51 volumio volumio[392543]: Forking 3 albumart workers Feb 16 15:38:51 volumio volumio[392482]: info: Applying required configuration parameters for plugin RoonBridge Feb 16 15:38:51 volumio volumio[392482]: info: Loading i18n strings for locale en Feb 16 15:38:51 volumio volumio[392482]: Updating browse sources language Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::initPlayerControls Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:38:51 volumio volumio[392482]: Express server listening on port 3000 Feb 16 15:38:51 volumio volumio[392482]: [Metrics] WebUI: 1s 322.50ms Feb 16 15:38:51 volumio volumio[392482]: info: Setting Device type: x86 Feb 16 15:38:51 volumio volumio[392482]: info: CoreStateMachine::resetVolumioState Feb 16 15:38:51 volumio volumio[392482]: info: CoreStateMachine::getcurrentVolume Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:38:51 volumio volumio[392482]: info: CoreStateMachine::pushState Feb 16 15:38:51 volumio volumio[392482]: info: CorePlayQueue::getTrack 0 Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::volumioPushState Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:38:51 volumio volumio[392482]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 16 15:38:51 volumio volumio[392482]: info: Completed loading Core Plugins Feb 16 15:38:51 volumio volumio[392482]: info: Preparing to generate the ALSA configuration file Feb 16 15:38:51 volumio volumio[392482]: info: Volumio Network Manager: Network status updated: 0 Feb 16 15:38:51 volumio volumio[392482]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 16 15:38:51 volumio volumio[392482]: wlan0 Interface doesn't support scanning. Feb 16 15:38:51 volumio volumio[392482]: info: Cannot use regular scanning, forcing with ap-force Feb 16 15:38:51 volumio sudo[392585]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 16 15:38:51 volumio sudo[392585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:51 volumio sudo[392585]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:51 volumio volumio[392482]: command failed: No such device (-19) Feb 16 15:38:51 volumio volumio[392482]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 16 15:38:51 volumio volumio[392482]: command failed: No such device (-19) Feb 16 15:38:51 volumio volumio[392482]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 16 15:38:51 volumio volumio[392482]: 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: 1 Feb 16 15:38:51 volumio volumio[392482]: info: Reloading queue from file Feb 16 15:38:51 volumio volumio[392482]: 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: 1 Feb 16 15:38:51 volumio volumio[392482]: info: Asound.conf file unchanged, so no further update is needed Feb 16 15:38:51 volumio volumio[392482]: info: Output device has changed, restarting MPD Feb 16 15:38:51 volumio volumio[392482]: info: ___________ START PLUGINS ___________ Feb 16 15:38:51 volumio sudo[392594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:38:51 volumio sudo[392594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:51 volumio volumio[392482]: info: ControllerMpd::onStart: Initializing MPD Feb 16 15:38:51 volumio volumio[392482]: info: Creating MPD Configuration file Feb 16 15:38:51 volumio sudo[392594]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:51 volumio sudo[392596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:38:51 volumio sudo[392596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:51 volumio volumio[392553]: Starting albumart workers Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:38:51 volumio volumio[392482]: info: [1771277931988] CoreMusicLibrary::Adding element Last_100 Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:38:51 volumio volumio[392482]: info: [1771277931989] CoreMusicLibrary::Adding element Webradio Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:38:51 volumio volumio[392555]: Starting albumart workers Feb 16 15:38:51 volumio sudo[392600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 16 15:38:51 volumio sudo[392600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:51 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 16 15:38:51 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:38:51 volumio volumio[392482]: info: Initializing BBC Radios Feb 16 15:38:52 volumio sudo[392604]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:38:52 volumio sudo[392604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:52 volumio sudo[392601]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:38:52 volumio sudo[392601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:52 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 16 15:38:52 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 15:38:52 volumio sudo[392601]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:52 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 16 15:38:52 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 15:38:52 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 15:38:52 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:38:52 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:38:52 volumio volumio[392554]: Starting albumart workers Feb 16 15:38:52 volumio volumio[392482]: info: Creating Spotify config file Feb 16 15:38:52 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:52 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 15:38:52 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 15:38:52 volumio sudo[392600]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:52 volumio sudo[392623]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 15:38:52 volumio sudo[392623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 16 15:38:52 volumio sudo[392623]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:52 volumio volumio[392482]: info: [squeezelite_mc] Starting proxy server... Feb 16 15:38:52 volumio volumio[392482]: info: Volumio Calling Home Feb 16 15:38:52 volumio sudo[392636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 16 15:38:52 volumio sudo[392636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:52 volumio volumio[392482]: info: [squeezelite_mc] Proxy server started on port 37103 Feb 16 15:38:52 volumio sudo[392636]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:52 volumio volumio[392482]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:38:52 volumio volumio[392482]: info: CoreStateMachine::pushState Feb 16 15:38:52 volumio volumio[392482]: info: CorePlayQueue::getTrack 0 Feb 16 15:38:52 volumio volumio[392482]: info: CoreCommandRouter::volumioPushState Feb 16 15:38:52 volumio volumio[392482]: info: [squeezelite_mc] Server discovery started Feb 16 15:38:52 volumio volumio[392482]: info: [squeezelite_mc] Player finder started Feb 16 15:38:52 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:52 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:52 volumio volumio[392482]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:38:52 volumio volumio[392482]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 16 15:38:52 volumio volumio[392482]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:38:52 volumio volumio[392482]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 16 15:38:52 volumio volumio[392482]: at doSend (node:dgram:717:16) Feb 16 15:38:52 volumio volumio[392482]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Feb 16 15:38:52 volumio volumio[392482]: at afterDns (node:dgram:663:5) Feb 16 15:38:52 volumio volumio[392482]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Feb 16 15:38:52 volumio volumio[392482]: errno: -101, Feb 16 15:38:52 volumio volumio[392482]: code: 'ENETUNREACH', Feb 16 15:38:52 volumio volumio[392482]: syscall: 'send', Feb 16 15:38:52 volumio volumio[392482]: address: '255.255.255.255', Feb 16 15:38:52 volumio volumio[392482]: port: 3483 Feb 16 15:38:52 volumio volumio[392482]: } Feb 16 15:38:52 volumio volumio[392482]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:38:52 volumio sudo[392666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-16 15:37' Feb 16 15:38:52 volumio sudo[392666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:52 volumio sudo[392666]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:52 volumio volumio5-onboarding[1422]: time=2026-02-16T15:38:52.431-06:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:38390->127.0.0.1:3000: read: connection reset by peer" Feb 16 15:38:52 volumio sudo[392596]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:52 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 16 15:38:52 volumio sudo[392604]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:52 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 16 15:38:52 volumio systemd[1]: volumio.service: Consumed 4.093s CPU time. Feb 16 15:38:52 volumio systemd[1]: dynamicswap.service: Start request repeated too quickly. Feb 16 15:38:52 volumio systemd[1]: dynamicswap.service: Failed with result 'start-limit-hit'. Feb 16 15:38:52 volumio systemd[1]: Failed to start dynamicswap.service - dynamicswap service. Feb 16 15:38:52 volumio mpd[392625]: 2026-02-16T15:38:52 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 16 15:38:52 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 25. Feb 16 15:38:52 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 16 15:38:52 volumio systemd[1]: dynamicswap.service: Start request repeated too quickly. Feb 16 15:38:52 volumio systemd[1]: dynamicswap.service: Failed with result 'start-limit-hit'. Feb 16 15:38:52 volumio systemd[1]: Failed to start dynamicswap.service - dynamicswap service. Feb 16 15:38:52 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 16 15:38:52 volumio systemd[1]: volumio.service: Consumed 4.093s CPU time. Feb 16 15:38:52 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 16 15:38:52 volumio volumio[392674]: info: ------------------------------------------- Feb 16 15:38:52 volumio volumio[392674]: info: ----- Volumio3 ---- Feb 16 15:38:52 volumio volumio[392674]: info: ------------------------------------------- Feb 16 15:38:52 volumio volumio[392674]: info: ----- System startup ---- Feb 16 15:38:52 volumio volumio[392674]: info: ------------------------------------------- Feb 16 15:38:53 volumio volumio[392674]: info: MYVOLUMIO Environment detected Feb 16 15:38:53 volumio volumio[392674]: info: Plugin folders cleanup Feb 16 15:38:53 volumio volumio[392674]: info: Scanning into folder /volumio/app/plugins/ Feb 16 15:38:53 volumio volumio[392674]: info: Scanning category audio_interface Feb 16 15:38:53 volumio volumio[392674]: info: Scanning category miscellanea Feb 16 15:38:53 volumio volumio[392674]: info: Scanning category music_service Feb 16 15:38:53 volumio volumio[392674]: info: Scanning category plugins.json Feb 16 15:38:53 volumio volumio[392674]: info: Scanning category system_controller Feb 16 15:38:53 volumio volumio[392674]: info: Scanning category user_interface Feb 16 15:38:53 volumio volumio[392674]: info: Scanning into folder /data/plugins/ Feb 16 15:38:53 volumio volumio[392674]: info: Scanning category music_service Feb 16 15:38:53 volumio volumio[392674]: info: Plugin folders cleanup completed Feb 16 15:38:53 volumio volumio[392674]: info: ------------------------------------------- Feb 16 15:38:53 volumio volumio[392674]: info: ----- Core plugins startup ---- Feb 16 15:38:53 volumio volumio[392674]: info: ------------------------------------------- Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 15:38:53 volumio volumio[392674]: info: Adding plugin upnp to MyMusic Plugins Feb 16 15:38:53 volumio volumio[392674]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 15:38:53 volumio volumio[392674]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugins from folder /data/plugins/ Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "system"... Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "appearance"... Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "network"... Feb 16 15:38:53 volumio volumio[392674]: info: Refreshing Cached IP Addresses Feb 16 15:38:53 volumio sudo[392697]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 15:38:53 volumio sudo[392697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:53 volumio sudo[392697]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:53 volumio sudo[392699]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 15:38:53 volumio sudo[392699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "services"... Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "volumio5onboarding"... Feb 16 15:38:53 volumio sudo[392707]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 16 15:38:53 volumio sudo[392707]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:53 volumio sudo[392699]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "alsa_controller"... Feb 16 15:38:53 volumio sudo[392707]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:53 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "wizard"... Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "networkfs"... Feb 16 15:38:53 volumio volumio[392674]: info: Starting Udev Watcher for removable devices Feb 16 15:38:53 volumio volumio[392674]: info: Ignoring mount for partition: boot Feb 16 15:38:53 volumio volumio[392674]: info: Ignoring mount for partition: volumio Feb 16 15:38:53 volumio volumio[392674]: info: Ignoring mount for partition: volumio_data Feb 16 15:38:53 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "volumio_command_line_client"... Feb 16 15:38:53 volumio volumio[392674]: info: Plugin upnp is not enabled Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "my_music"... Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "mpd"... Feb 16 15:38:53 volumio volumio[392674]: info: Plugin upnp_browser is not enabled Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "alarm-clock"... Feb 16 15:38:53 volumio volumio[392674]: info: Plugin airplay_emulation is not enabled Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "last_100"... Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "webradio"... Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "i2s_dacs"... Feb 16 15:38:53 volumio volumio[392674]: info: I2S DAC not set, start Auto-detection Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "volumiodiscovery"... Feb 16 15:38:53 volumio volumio[392674]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:53 volumio volumio[392674]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:53 volumio volumio[392674]: *** WARNING *** For more information see Feb 16 15:38:53 volumio volumio[392674]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:53 volumio volumio[392674]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:53 volumio volumio[392674]: *** WARNING *** For more information see Feb 16 15:38:53 volumio node[392674]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:53 volumio node[392674]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:53 volumio node[392674]: *** WARNING *** For more information see Feb 16 15:38:53 volumio node[392674]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:53 volumio node[392674]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:53 volumio node[392674]: *** WARNING *** For more information see Feb 16 15:38:53 volumio volumio[392674]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 15:38:53 volumio volumio[392674]: info: Discovery: Started advertising with name: Volumio Feb 16 15:38:53 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "spop"... Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "squeezelite_mc"... Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "outputs"... Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "albumart"... Feb 16 15:38:53 volumio volumio[392674]: info: Plugin example_plugin is not enabled Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "inputs"... Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "updater_comm"... Feb 16 15:38:53 volumio volumio[392674]: info: Plugin mpdemulation is not enabled Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "rest_api"... Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "websocket"... Feb 16 15:38:53 volumio volumio[392674]: info: Starting Socket.io Server version 1.7.4 Feb 16 15:38:53 volumio volumio[392674]: info: Loading plugin "RoonBridge"... Feb 16 15:38:54 volumio volumio[392734]: Forking 3 albumart workers Feb 16 15:38:54 volumio volumio[392674]: info: Applying required configuration parameters for plugin RoonBridge Feb 16 15:38:54 volumio volumio[392674]: info: Loading i18n strings for locale en Feb 16 15:38:54 volumio volumio[392674]: Updating browse sources language Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::initPlayerControls Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:38:54 volumio volumio[392674]: Express server listening on port 3000 Feb 16 15:38:54 volumio volumio[392674]: [Metrics] WebUI: 1s 353.72ms Feb 16 15:38:54 volumio volumio[392674]: info: Setting Device type: x86 Feb 16 15:38:54 volumio volumio[392674]: info: CoreStateMachine::resetVolumioState Feb 16 15:38:54 volumio volumio[392674]: info: CoreStateMachine::getcurrentVolume Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:38:54 volumio volumio[392674]: info: CoreStateMachine::pushState Feb 16 15:38:54 volumio volumio[392674]: info: CorePlayQueue::getTrack 0 Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::volumioPushState Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:38:54 volumio volumio[392674]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 16 15:38:54 volumio volumio[392674]: info: Completed loading Core Plugins Feb 16 15:38:54 volumio volumio[392674]: info: Preparing to generate the ALSA configuration file Feb 16 15:38:54 volumio volumio[392674]: info: Volumio Network Manager: Network status updated: 0 Feb 16 15:38:54 volumio volumio[392674]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 16 15:38:54 volumio volumio[392674]: wlan0 Interface doesn't support scanning. Feb 16 15:38:54 volumio volumio[392674]: info: Cannot use regular scanning, forcing with ap-force Feb 16 15:38:54 volumio sudo[392776]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 16 15:38:54 volumio sudo[392776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:54 volumio volumio[392744]: Starting albumart workers Feb 16 15:38:54 volumio sudo[392776]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:54 volumio volumio[392674]: command failed: No such device (-19) Feb 16 15:38:54 volumio volumio[392674]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 16 15:38:54 volumio volumio[392674]: command failed: No such device (-19) Feb 16 15:38:54 volumio volumio[392674]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 16 15:38:54 volumio volumio[392745]: Starting albumart workers Feb 16 15:38:54 volumio volumio[392746]: Starting albumart workers Feb 16 15:38:54 volumio volumio[392674]: 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: 1 Feb 16 15:38:54 volumio volumio[392674]: info: Reloading queue from file Feb 16 15:38:54 volumio volumio[392674]: 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: 1 Feb 16 15:38:54 volumio volumio[392674]: info: Asound.conf file unchanged, so no further update is needed Feb 16 15:38:54 volumio volumio[392674]: info: Output device has changed, restarting MPD Feb 16 15:38:54 volumio volumio[392674]: info: ___________ START PLUGINS ___________ Feb 16 15:38:54 volumio sudo[392787]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:38:54 volumio volumio[392674]: info: ControllerMpd::onStart: Initializing MPD Feb 16 15:38:54 volumio sudo[392785]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:38:54 volumio sudo[392787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:54 volumio volumio[392674]: info: Creating MPD Configuration file Feb 16 15:38:54 volumio sudo[392789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 16 15:38:54 volumio sudo[392785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:54 volumio sudo[392789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:54 volumio sudo[392785]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:38:54 volumio volumio[392674]: info: [1771277934330] CoreMusicLibrary::Adding element Last_100 Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:38:54 volumio volumio[392674]: info: [1771277934331] CoreMusicLibrary::Adding element Webradio Feb 16 15:38:54 volumio sudo[392794]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:38:54 volumio sudo[392794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:38:54 volumio volumio[392674]: info: Initializing BBC Radios Feb 16 15:38:54 volumio sudo[392794]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:54 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 16 15:38:54 volumio sudo[392789]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:54 volumio sudo[392796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:38:54 volumio sudo[392796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:54 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 16 15:38:54 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 15:38:54 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 16 15:38:54 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 15:38:54 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:38:54 volumio volumio[392674]: info: Creating Spotify config file Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:54 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 15:38:54 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 15:38:54 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 16 15:38:54 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 15:38:54 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 16 15:38:54 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 15:38:54 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 15:38:54 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 15:38:54 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 15:38:54 volumio sudo[392815]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 15:38:54 volumio sudo[392815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 16 15:38:54 volumio sudo[392815]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:54 volumio volumio[392674]: info: [squeezelite_mc] Starting proxy server... Feb 16 15:38:54 volumio volumio[392674]: info: Volumio Calling Home Feb 16 15:38:54 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 9665. Feb 16 15:38:54 volumio sudo[392838]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 16 15:38:54 volumio sudo[392838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:54 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 16 15:38:54 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 16 15:38:54 volumio sudo[392838]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:54 volumio upmpdcli[392842]: Could not open config: /tmp/upmpdcli.conf Feb 16 15:38:54 volumio volumio[392674]: info: [squeezelite_mc] Proxy server started on port 33647 Feb 16 15:38:54 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 16 15:38:54 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:38:54 volumio volumio[392674]: info: CoreStateMachine::pushState Feb 16 15:38:54 volumio volumio[392674]: info: CorePlayQueue::getTrack 0 Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::volumioPushState Feb 16 15:38:54 volumio volumio[392674]: info: [squeezelite_mc] Server discovery started Feb 16 15:38:54 volumio volumio[392674]: info: [squeezelite_mc] Player finder started Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:54 volumio volumio[392674]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:38:54 volumio volumio[392674]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 16 15:38:54 volumio volumio[392674]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:38:54 volumio volumio[392674]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 16 15:38:54 volumio volumio[392674]: at doSend (node:dgram:717:16) Feb 16 15:38:54 volumio volumio[392674]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Feb 16 15:38:54 volumio volumio[392674]: at afterDns (node:dgram:663:5) Feb 16 15:38:54 volumio volumio[392674]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Feb 16 15:38:54 volumio volumio[392674]: errno: -101, Feb 16 15:38:54 volumio volumio[392674]: code: 'ENETUNREACH', Feb 16 15:38:54 volumio volumio[392674]: syscall: 'send', Feb 16 15:38:54 volumio volumio[392674]: address: '255.255.255.255', Feb 16 15:38:54 volumio volumio[392674]: port: 3483 Feb 16 15:38:54 volumio volumio[392674]: } Feb 16 15:38:54 volumio volumio[392674]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:38:54 volumio sudo[392860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-16 15:37' Feb 16 15:38:54 volumio sudo[392860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:54 volumio sudo[392860]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:54 volumio volumio5-onboarding[1422]: time=2026-02-16T15:38:54.710-06:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:38414->127.0.0.1:3000: read: connection reset by peer" Feb 16 15:38:54 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 16 15:38:54 volumio sudo[392787]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:54 volumio sudo[392796]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:54 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 16 15:38:54 volumio systemd[1]: volumio.service: Consumed 4.181s CPU time. Feb 16 15:38:54 volumio systemd[1]: dynamicswap.service: Start request repeated too quickly. Feb 16 15:38:54 volumio systemd[1]: dynamicswap.service: Failed with result 'start-limit-hit'. Feb 16 15:38:54 volumio systemd[1]: Failed to start dynamicswap.service - dynamicswap service. Feb 16 15:38:54 volumio mpd[392835]: 2026-02-16T15:38:54 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 16 15:38:54 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 26. Feb 16 15:38:54 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 16 15:38:54 volumio systemd[1]: dynamicswap.service: Start request repeated too quickly. Feb 16 15:38:54 volumio systemd[1]: dynamicswap.service: Failed with result 'start-limit-hit'. Feb 16 15:38:54 volumio systemd[1]: Failed to start dynamicswap.service - dynamicswap service. Feb 16 15:38:54 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 16 15:38:54 volumio systemd[1]: volumio.service: Consumed 4.181s CPU time. Feb 16 15:38:54 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 16 15:38:55 volumio volumio[392867]: info: ------------------------------------------- Feb 16 15:38:55 volumio volumio[392867]: info: ----- Volumio3 ---- Feb 16 15:38:55 volumio volumio[392867]: info: ------------------------------------------- Feb 16 15:38:55 volumio volumio[392867]: info: ----- System startup ---- Feb 16 15:38:55 volumio volumio[392867]: info: ------------------------------------------- Feb 16 15:38:55 volumio volumio[392867]: info: MYVOLUMIO Environment detected Feb 16 15:38:55 volumio volumio[392867]: info: Plugin folders cleanup Feb 16 15:38:55 volumio volumio[392867]: info: Scanning into folder /volumio/app/plugins/ Feb 16 15:38:55 volumio volumio[392867]: info: Scanning category audio_interface Feb 16 15:38:55 volumio volumio[392867]: info: Scanning category miscellanea Feb 16 15:38:55 volumio volumio[392867]: info: Scanning category music_service Feb 16 15:38:55 volumio volumio[392867]: info: Scanning category plugins.json Feb 16 15:38:55 volumio volumio[392867]: info: Scanning category system_controller Feb 16 15:38:55 volumio volumio[392867]: info: Scanning category user_interface Feb 16 15:38:55 volumio volumio[392867]: info: Scanning into folder /data/plugins/ Feb 16 15:38:55 volumio volumio[392867]: info: Scanning category music_service Feb 16 15:38:55 volumio volumio[392867]: info: Plugin folders cleanup completed Feb 16 15:38:55 volumio volumio[392867]: info: ------------------------------------------- Feb 16 15:38:55 volumio volumio[392867]: info: ----- Core plugins startup ---- Feb 16 15:38:55 volumio volumio[392867]: info: ------------------------------------------- Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 15:38:55 volumio volumio[392867]: info: Adding plugin upnp to MyMusic Plugins Feb 16 15:38:55 volumio volumio[392867]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 15:38:55 volumio volumio[392867]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugins from folder /data/plugins/ Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugin "system"... Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugin "appearance"... Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugin "network"... Feb 16 15:38:55 volumio volumio[392867]: info: Refreshing Cached IP Addresses Feb 16 15:38:55 volumio sudo[392891]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 15:38:55 volumio sudo[392891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:55 volumio sudo[392893]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 15:38:55 volumio sudo[392891]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:55 volumio sudo[392893]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugin "services"... Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugin "volumio5onboarding"... Feb 16 15:38:55 volumio sudo[392893]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:55 volumio sudo[392901]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 16 15:38:55 volumio sudo[392901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugin "alsa_controller"... Feb 16 15:38:55 volumio sudo[392901]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:55 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugin "wizard"... Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugin "networkfs"... Feb 16 15:38:55 volumio volumio[392867]: info: Starting Udev Watcher for removable devices Feb 16 15:38:55 volumio volumio[392867]: info: Ignoring mount for partition: boot Feb 16 15:38:55 volumio volumio[392867]: info: Ignoring mount for partition: volumio Feb 16 15:38:55 volumio volumio[392867]: info: Ignoring mount for partition: volumio_data Feb 16 15:38:55 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugin "volumio_command_line_client"... Feb 16 15:38:55 volumio volumio[392867]: info: Plugin upnp is not enabled Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugin "my_music"... Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugin "mpd"... Feb 16 15:38:55 volumio ifplugd(eth0)[986]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Feb 16 15:38:55 volumio ifplugd(eth0)[986]: client: dhcpcd not running Feb 16 15:38:55 volumio dhcpcd[392935]: dhcpcd not running Feb 16 15:38:55 volumio volumio[392867]: info: Plugin upnp_browser is not enabled Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugin "alarm-clock"... Feb 16 15:38:55 volumio volumio[392867]: info: Plugin airplay_emulation is not enabled Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugin "last_100"... Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugin "webradio"... Feb 16 15:38:55 volumio kernel: e1000e 0000:00:19.0 eth0: NIC Link is Down Feb 16 15:38:55 volumio ifplugd(eth0)[986]: Program executed successfully. Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugin "i2s_dacs"... Feb 16 15:38:55 volumio volumio[392867]: info: I2S DAC not set, start Auto-detection Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugin "volumiodiscovery"... Feb 16 15:38:55 volumio volumio[392867]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:55 volumio volumio[392867]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:55 volumio volumio[392867]: *** WARNING *** For more information see Feb 16 15:38:55 volumio volumio[392867]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:55 volumio volumio[392867]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:55 volumio volumio[392867]: *** WARNING *** For more information see Feb 16 15:38:55 volumio node[392867]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:55 volumio node[392867]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:55 volumio node[392867]: *** WARNING *** For more information see Feb 16 15:38:55 volumio node[392867]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:55 volumio node[392867]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:55 volumio node[392867]: *** WARNING *** For more information see Feb 16 15:38:55 volumio volumio[392867]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 15:38:55 volumio volumio[392867]: info: Discovery: Started advertising with name: Volumio Feb 16 15:38:55 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:38:55 volumio volumio[392867]: info: Loading plugin "spop"... Feb 16 15:38:56 volumio volumio[392867]: info: Loading plugin "squeezelite_mc"... Feb 16 15:38:56 volumio volumio[392867]: info: Loading plugin "outputs"... Feb 16 15:38:56 volumio volumio[392867]: info: Loading plugin "albumart"... Feb 16 15:38:56 volumio volumio[392867]: info: Plugin example_plugin is not enabled Feb 16 15:38:56 volumio volumio[392867]: info: Loading plugin "inputs"... Feb 16 15:38:56 volumio volumio[392867]: info: Loading plugin "updater_comm"... Feb 16 15:38:56 volumio volumio[392867]: info: Plugin mpdemulation is not enabled Feb 16 15:38:56 volumio volumio[392867]: info: Loading plugin "rest_api"... Feb 16 15:38:56 volumio volumio[392867]: info: Loading plugin "websocket"... Feb 16 15:38:56 volumio volumio[392867]: info: Starting Socket.io Server version 1.7.4 Feb 16 15:38:56 volumio volumio[392867]: info: Loading plugin "RoonBridge"... Feb 16 15:38:56 volumio volumio[392950]: Forking 3 albumart workers Feb 16 15:38:56 volumio volumio[392867]: info: Applying required configuration parameters for plugin RoonBridge Feb 16 15:38:56 volumio volumio[392867]: info: Loading i18n strings for locale en Feb 16 15:38:56 volumio volumio[392867]: Updating browse sources language Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::initPlayerControls Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:38:56 volumio volumio[392867]: Express server listening on port 3000 Feb 16 15:38:56 volumio volumio[392867]: [Metrics] WebUI: 1s 353.52ms Feb 16 15:38:56 volumio volumio[392867]: info: Setting Device type: x86 Feb 16 15:38:56 volumio volumio[392867]: info: CoreStateMachine::resetVolumioState Feb 16 15:38:56 volumio volumio[392867]: info: CoreStateMachine::getcurrentVolume Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:38:56 volumio volumio[392867]: info: CoreStateMachine::pushState Feb 16 15:38:56 volumio volumio[392867]: info: CorePlayQueue::getTrack 0 Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::volumioPushState Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:38:56 volumio volumio[392867]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 16 15:38:56 volumio volumio[392867]: info: Completed loading Core Plugins Feb 16 15:38:56 volumio volumio[392867]: info: Preparing to generate the ALSA configuration file Feb 16 15:38:56 volumio volumio[392867]: info: Volumio Network Manager: Network status updated: 0 Feb 16 15:38:56 volumio volumio[392867]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 16 15:38:56 volumio volumio[392867]: wlan0 Interface doesn't support scanning. Feb 16 15:38:56 volumio volumio[392867]: info: Cannot use regular scanning, forcing with ap-force Feb 16 15:38:56 volumio sudo[392992]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 16 15:38:56 volumio sudo[392992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:56 volumio sudo[392992]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:56 volumio volumio[392867]: command failed: No such device (-19) Feb 16 15:38:56 volumio volumio[392867]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 16 15:38:56 volumio volumio[392867]: command failed: No such device (-19) Feb 16 15:38:56 volumio volumio[392867]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 16 15:38:56 volumio volumio[392867]: 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: 1 Feb 16 15:38:56 volumio volumio[392962]: Starting albumart workers Feb 16 15:38:56 volumio volumio[392867]: info: Reloading queue from file Feb 16 15:38:56 volumio volumio[392961]: Starting albumart workers Feb 16 15:38:56 volumio volumio[392867]: 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: 1 Feb 16 15:38:56 volumio volumio[392867]: info: Asound.conf file unchanged, so no further update is needed Feb 16 15:38:56 volumio volumio[392867]: info: Output device has changed, restarting MPD Feb 16 15:38:56 volumio volumio[392960]: Starting albumart workers Feb 16 15:38:56 volumio sudo[393002]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:38:56 volumio sudo[393002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:56 volumio volumio[392867]: info: ___________ START PLUGINS ___________ Feb 16 15:38:56 volumio sudo[393002]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:56 volumio sudo[393005]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:38:56 volumio volumio[392867]: info: ControllerMpd::onStart: Initializing MPD Feb 16 15:38:56 volumio volumio[392867]: info: Creating MPD Configuration file Feb 16 15:38:56 volumio sudo[393007]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 16 15:38:56 volumio sudo[393007]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:56 volumio sudo[393005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:56 volumio sudo[393012]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:38:56 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:38:56 volumio volumio[392867]: info: [1771277936627] CoreMusicLibrary::Adding element Last_100 Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:38:56 volumio sudo[393007]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:56 volumio volumio[392867]: info: [1771277936630] CoreMusicLibrary::Adding element Webradio Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:38:56 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 16 15:38:56 volumio sudo[393012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:56 volumio sudo[393013]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:38:56 volumio sudo[393013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:56 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 15:38:56 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 16 15:38:56 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 15:38:56 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 15:38:56 volumio volumio[392867]: info: Initializing BBC Radios Feb 16 15:38:56 volumio sudo[393012]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:56 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 15:38:56 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 15:38:56 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 16 15:38:56 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 15:38:56 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 16 15:38:56 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 15:38:56 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 15:38:56 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 15:38:56 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:38:56 volumio volumio[392867]: info: Creating Spotify config file Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:56 volumio sudo[393032]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 15:38:56 volumio sudo[393032]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 16 15:38:56 volumio sudo[393032]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:56 volumio volumio[392867]: info: [squeezelite_mc] Starting proxy server... Feb 16 15:38:56 volumio volumio[392867]: info: Volumio Calling Home Feb 16 15:38:56 volumio sudo[393054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 16 15:38:56 volumio sudo[393054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:56 volumio volumio[392867]: info: [squeezelite_mc] Proxy server started on port 45709 Feb 16 15:38:56 volumio sudo[393054]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:38:56 volumio volumio[392867]: info: CoreStateMachine::pushState Feb 16 15:38:56 volumio volumio[392867]: info: CorePlayQueue::getTrack 0 Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::volumioPushState Feb 16 15:38:56 volumio volumio[392867]: info: [squeezelite_mc] Server discovery started Feb 16 15:38:56 volumio volumio[392867]: info: [squeezelite_mc] Player finder started Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:56 volumio volumio[392867]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:38:56 volumio volumio[392867]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 16 15:38:56 volumio volumio[392867]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:38:56 volumio volumio[392867]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 16 15:38:56 volumio volumio[392867]: at doSend (node:dgram:717:16) Feb 16 15:38:56 volumio volumio[392867]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Feb 16 15:38:56 volumio volumio[392867]: at afterDns (node:dgram:663:5) Feb 16 15:38:56 volumio volumio[392867]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Feb 16 15:38:56 volumio volumio[392867]: errno: -101, Feb 16 15:38:56 volumio volumio[392867]: code: 'ENETUNREACH', Feb 16 15:38:56 volumio volumio[392867]: syscall: 'send', Feb 16 15:38:56 volumio volumio[392867]: address: '255.255.255.255', Feb 16 15:38:56 volumio volumio[392867]: port: 3483 Feb 16 15:38:56 volumio volumio[392867]: } Feb 16 15:38:56 volumio volumio[392867]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:38:56 volumio sudo[393075]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-16 15:37' Feb 16 15:38:56 volumio sudo[393075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:57 volumio sudo[393075]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:57 volumio volumio5-onboarding[1422]: time=2026-02-16T15:38:57.040-06:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:38450->127.0.0.1:3000: read: connection reset by peer" Feb 16 15:38:57 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 16 15:38:57 volumio sudo[393013]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:57 volumio sudo[393005]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:57 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 16 15:38:57 volumio systemd[1]: volumio.service: Consumed 4.186s CPU time. Feb 16 15:38:57 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 16 15:38:57 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 16 15:38:57 volumio mpd[393044]: 2026-02-16T15:38:57 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 16 15:38:57 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 27. Feb 16 15:38:57 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 16 15:38:57 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 16 15:38:57 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 16 15:38:57 volumio systemd[1]: volumio.service: Consumed 4.186s CPU time. Feb 16 15:38:57 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 16 15:38:57 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 16 15:38:57 volumio volumio[393089]: info: ------------------------------------------- Feb 16 15:38:57 volumio volumio[393089]: info: ----- Volumio3 ---- Feb 16 15:38:57 volumio volumio[393089]: info: ------------------------------------------- Feb 16 15:38:57 volumio volumio[393089]: info: ----- System startup ---- Feb 16 15:38:57 volumio volumio[393089]: info: ------------------------------------------- Feb 16 15:38:57 volumio volumio[393089]: info: MYVOLUMIO Environment detected Feb 16 15:38:57 volumio volumio[393089]: info: Plugin folders cleanup Feb 16 15:38:57 volumio volumio[393089]: info: Scanning into folder /volumio/app/plugins/ Feb 16 15:38:57 volumio volumio[393089]: info: Scanning category audio_interface Feb 16 15:38:57 volumio volumio[393089]: info: Scanning category miscellanea Feb 16 15:38:57 volumio volumio[393089]: info: Scanning category music_service Feb 16 15:38:57 volumio volumio[393089]: info: Scanning category plugins.json Feb 16 15:38:57 volumio volumio[393089]: info: Scanning category system_controller Feb 16 15:38:57 volumio volumio[393089]: info: Scanning category user_interface Feb 16 15:38:57 volumio volumio[393089]: info: Scanning into folder /data/plugins/ Feb 16 15:38:57 volumio volumio[393089]: info: Scanning category music_service Feb 16 15:38:57 volumio volumio[393089]: info: Plugin folders cleanup completed Feb 16 15:38:57 volumio volumio[393089]: info: ------------------------------------------- Feb 16 15:38:57 volumio volumio[393089]: info: ----- Core plugins startup ---- Feb 16 15:38:57 volumio volumio[393089]: info: ------------------------------------------- Feb 16 15:38:57 volumio volumio[393089]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 15:38:57 volumio volumio[393089]: info: Adding plugin upnp to MyMusic Plugins Feb 16 15:38:57 volumio volumio[393089]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 15:38:57 volumio volumio[393089]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 15:38:57 volumio volumio[393089]: info: Loading plugins from folder /data/plugins/ Feb 16 15:38:57 volumio volumio[393089]: info: Loading plugin "system"... Feb 16 15:38:57 volumio volumio[393089]: info: Loading plugin "appearance"... Feb 16 15:38:57 volumio volumio[393089]: info: Loading plugin "network"... Feb 16 15:38:57 volumio volumio[393089]: info: Refreshing Cached IP Addresses Feb 16 15:38:57 volumio sudo[393118]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 15:38:57 volumio sudo[393118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:57 volumio sudo[393120]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 15:38:57 volumio sudo[393120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:57 volumio sudo[393118]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:57 volumio volumio[393089]: info: Loading plugin "services"... Feb 16 15:38:57 volumio sudo[393120]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:57 volumio volumio[393089]: info: Loading plugin "volumio5onboarding"... Feb 16 15:38:57 volumio sudo[393131]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 16 15:38:57 volumio volumio[393089]: info: Loading plugin "alsa_controller"... Feb 16 15:38:57 volumio sudo[393131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:57 volumio sudo[393131]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:57 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:38:57 volumio volumio[393089]: info: Loading plugin "wizard"... Feb 16 15:38:57 volumio volumio[393089]: info: Loading plugin "networkfs"... Feb 16 15:38:57 volumio volumio[393089]: info: Starting Udev Watcher for removable devices Feb 16 15:38:57 volumio volumio[393089]: info: Ignoring mount for partition: boot Feb 16 15:38:57 volumio volumio[393089]: info: Ignoring mount for partition: volumio Feb 16 15:38:57 volumio volumio[393089]: info: Ignoring mount for partition: volumio_data Feb 16 15:38:57 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:38:57 volumio volumio[393089]: info: Loading plugin "volumio_command_line_client"... Feb 16 15:38:57 volumio volumio[393089]: info: Plugin upnp is not enabled Feb 16 15:38:57 volumio volumio[393089]: info: Loading plugin "my_music"... Feb 16 15:38:57 volumio volumio[393089]: info: Loading plugin "mpd"... Feb 16 15:38:57 volumio volumio[393089]: info: Plugin upnp_browser is not enabled Feb 16 15:38:57 volumio volumio[393089]: info: Loading plugin "alarm-clock"... Feb 16 15:38:57 volumio volumio[393089]: info: Plugin airplay_emulation is not enabled Feb 16 15:38:57 volumio volumio[393089]: info: Loading plugin "last_100"... Feb 16 15:38:57 volumio volumio[393089]: info: Loading plugin "webradio"... Feb 16 15:38:58 volumio volumio-remote-updater[763]: [2026-02-16 15:38:58] [connect] Successful connection Feb 16 15:38:58 volumio volumio[393089]: info: Loading plugin "i2s_dacs"... Feb 16 15:38:58 volumio volumio[393089]: info: I2S DAC not set, start Auto-detection Feb 16 15:38:58 volumio volumio[393089]: info: Loading plugin "volumiodiscovery"... Feb 16 15:38:58 volumio volumio[393089]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:58 volumio volumio[393089]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:58 volumio volumio[393089]: *** WARNING *** For more information see Feb 16 15:38:58 volumio volumio[393089]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:58 volumio volumio[393089]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:58 volumio volumio[393089]: *** WARNING *** For more information see Feb 16 15:38:58 volumio node[393089]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:58 volumio node[393089]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:58 volumio node[393089]: *** WARNING *** For more information see Feb 16 15:38:58 volumio node[393089]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:38:58 volumio node[393089]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:38:58 volumio node[393089]: *** WARNING *** For more information see Feb 16 15:38:58 volumio volumio[393089]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 15:38:58 volumio volumio[393089]: info: Discovery: Started advertising with name: Volumio Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:38:58 volumio volumio[393089]: info: Loading plugin "spop"... Feb 16 15:38:58 volumio volumio[393089]: info: Loading plugin "squeezelite_mc"... Feb 16 15:38:58 volumio volumio[393089]: info: Loading plugin "outputs"... Feb 16 15:38:58 volumio volumio[393089]: info: Loading plugin "albumart"... Feb 16 15:38:58 volumio volumio[393089]: info: Plugin example_plugin is not enabled Feb 16 15:38:58 volumio volumio[393089]: info: Loading plugin "inputs"... Feb 16 15:38:58 volumio volumio[393089]: info: Loading plugin "updater_comm"... Feb 16 15:38:58 volumio volumio[393089]: info: Plugin mpdemulation is not enabled Feb 16 15:38:58 volumio volumio[393089]: info: Loading plugin "rest_api"... Feb 16 15:38:58 volumio volumio[393089]: info: Loading plugin "websocket"... Feb 16 15:38:58 volumio volumio[393089]: info: Starting Socket.io Server version 1.7.4 Feb 16 15:38:58 volumio volumio[393089]: info: Loading plugin "RoonBridge"... Feb 16 15:38:58 volumio volumio[393155]: Forking 3 albumart workers Feb 16 15:38:58 volumio volumio[393089]: info: Applying required configuration parameters for plugin RoonBridge Feb 16 15:38:58 volumio volumio[393089]: info: Loading i18n strings for locale en Feb 16 15:38:58 volumio volumio[393089]: Updating browse sources language Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::initPlayerControls Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:38:58 volumio volumio[393089]: Express server listening on port 3000 Feb 16 15:38:58 volumio volumio[393089]: [Metrics] WebUI: 1s 343.70ms Feb 16 15:38:58 volumio volumio[393089]: info: Setting Device type: x86 Feb 16 15:38:58 volumio volumio[393089]: info: CoreStateMachine::resetVolumioState Feb 16 15:38:58 volumio volumio[393089]: info: CoreStateMachine::getcurrentVolume Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:38:58 volumio volumio[393089]: info: CoreStateMachine::pushState Feb 16 15:38:58 volumio volumio[393089]: info: CorePlayQueue::getTrack 0 Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::volumioPushState Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:38:58 volumio volumio[393089]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 16 15:38:58 volumio volumio[393089]: info: Completed loading Core Plugins Feb 16 15:38:58 volumio volumio[393089]: info: Preparing to generate the ALSA configuration file Feb 16 15:38:58 volumio volumio[393089]: info: Volumio Network Manager: Network status updated: 0 Feb 16 15:38:58 volumio volumio[393089]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 16 15:38:58 volumio volumio[393089]: wlan0 Interface doesn't support scanning. Feb 16 15:38:58 volumio volumio[393089]: info: Cannot use regular scanning, forcing with ap-force Feb 16 15:38:58 volumio sudo[393197]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 16 15:38:58 volumio sudo[393197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:58 volumio sudo[393197]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:58 volumio volumio[393165]: Starting albumart workers Feb 16 15:38:58 volumio volumio[393089]: command failed: No such device (-19) Feb 16 15:38:58 volumio volumio[393089]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 16 15:38:58 volumio volumio[393089]: command failed: No such device (-19) Feb 16 15:38:58 volumio volumio[393166]: Starting albumart workers Feb 16 15:38:58 volumio volumio[393089]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 16 15:38:58 volumio volumio[393167]: Starting albumart workers Feb 16 15:38:58 volumio volumio-remote-updater[763]: [2026-02-16 15:38:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771277938 101 Feb 16 15:38:58 volumio volumio[393089]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Feb 16 15:38:58 volumio volumio[393089]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 16 15:38:58 volumio volumio[393089]: info: Reloading queue from file Feb 16 15:38:58 volumio volumio[393089]: info: Asound.conf file unchanged, so no further update is needed Feb 16 15:38:58 volumio volumio[393089]: info: Output device has changed, restarting MPD Feb 16 15:38:58 volumio volumio[393089]: info: ___________ START PLUGINS ___________ Feb 16 15:38:58 volumio sudo[393206]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:38:58 volumio sudo[393206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:58 volumio sudo[393206]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:58 volumio volumio[393089]: info: ControllerMpd::onStart: Initializing MPD Feb 16 15:38:58 volumio volumio[393089]: info: Creating MPD Configuration file Feb 16 15:38:58 volumio sudo[393208]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:38:58 volumio sudo[393208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:38:58 volumio volumio[393089]: info: [1771277938932] CoreMusicLibrary::Adding element Last_100 Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:38:58 volumio sudo[393211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 16 15:38:58 volumio sudo[393211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:58 volumio sudo[393213]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:38:58 volumio volumio[393089]: info: [1771277938939] CoreMusicLibrary::Adding element Webradio Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:38:58 volumio volumio[393089]: info: Initializing BBC Radios Feb 16 15:38:58 volumio sudo[393213]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:58 volumio sudo[393217]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:38:58 volumio sudo[393217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:58 volumio sudo[393213]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:58 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 16 15:38:58 volumio sudo[393211]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:58 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 16 15:38:58 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 15:38:58 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 16 15:38:58 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 15:38:58 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:38:58 volumio volumio[393089]: info: Creating Spotify config file Feb 16 15:38:58 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:59 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 15:38:59 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 15:38:59 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 16 15:38:59 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 15:38:59 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 16 15:38:59 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 15:38:59 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 15:38:59 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 15:38:59 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 15:38:59 volumio sudo[393248]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 15:38:59 volumio sudo[393248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 16 15:38:59 volumio sudo[393248]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:59 volumio volumio[393089]: info: [squeezelite_mc] Starting proxy server... Feb 16 15:38:59 volumio volumio[393089]: info: Volumio Calling Home Feb 16 15:38:59 volumio sudo[393258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 16 15:38:59 volumio sudo[393258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:59 volumio sudo[393258]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:59 volumio volumio[393089]: info: [squeezelite_mc] Proxy server started on port 40421 Feb 16 15:38:59 volumio volumio[393089]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:38:59 volumio volumio[393089]: info: CoreStateMachine::pushState Feb 16 15:38:59 volumio volumio[393089]: info: CorePlayQueue::getTrack 0 Feb 16 15:38:59 volumio volumio[393089]: info: CoreCommandRouter::volumioPushState Feb 16 15:38:59 volumio volumio[393089]: info: [squeezelite_mc] Server discovery started Feb 16 15:38:59 volumio volumio[393089]: info: [squeezelite_mc] Player finder started Feb 16 15:38:59 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:59 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:38:59 volumio volumio[393089]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:38:59 volumio volumio[393089]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 16 15:38:59 volumio volumio[393089]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:38:59 volumio volumio[393089]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 16 15:38:59 volumio volumio[393089]: at doSend (node:dgram:717:16) Feb 16 15:38:59 volumio volumio[393089]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Feb 16 15:38:59 volumio volumio[393089]: at afterDns (node:dgram:663:5) Feb 16 15:38:59 volumio volumio[393089]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Feb 16 15:38:59 volumio volumio[393089]: errno: -101, Feb 16 15:38:59 volumio volumio[393089]: code: 'ENETUNREACH', Feb 16 15:38:59 volumio volumio[393089]: syscall: 'send', Feb 16 15:38:59 volumio volumio[393089]: address: '255.255.255.255', Feb 16 15:38:59 volumio volumio[393089]: port: 3483 Feb 16 15:38:59 volumio volumio[393089]: } Feb 16 15:38:59 volumio volumio[393089]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:38:59 volumio sudo[393279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-16 15:37' Feb 16 15:38:59 volumio sudo[393279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:38:59 volumio sudo[393279]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:59 volumio volumio-remote-updater[763]: [2026-02-16 15:38:59] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 16 15:38:59 volumio volumio-remote-updater[763]: [2026-02-16 15:38:59] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 16 15:38:59 volumio volumio5-onboarding[1422]: time=2026-02-16T15:38:59.310-06:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:38484->127.0.0.1:3000: read: connection reset by peer" Feb 16 15:38:59 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 16 15:38:59 volumio sudo[393217]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:59 volumio sudo[393208]: pam_unix(sudo:session): session closed for user root Feb 16 15:38:59 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 16 15:38:59 volumio systemd[1]: volumio.service: Consumed 4.169s CPU time. Feb 16 15:38:59 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 16 15:38:59 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 16 15:38:59 volumio mpd[393256]: 2026-02-16T15:38:59 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 16 15:38:59 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 28. Feb 16 15:38:59 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 16 15:38:59 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 16 15:38:59 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 16 15:38:59 volumio systemd[1]: volumio.service: Consumed 4.169s CPU time. Feb 16 15:38:59 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 16 15:38:59 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 16 15:38:59 volumio volumio[393293]: info: ------------------------------------------- Feb 16 15:38:59 volumio volumio[393293]: info: ----- Volumio3 ---- Feb 16 15:38:59 volumio volumio[393293]: info: ------------------------------------------- Feb 16 15:38:59 volumio volumio[393293]: info: ----- System startup ---- Feb 16 15:38:59 volumio volumio[393293]: info: ------------------------------------------- Feb 16 15:38:59 volumio volumio[393293]: info: MYVOLUMIO Environment detected Feb 16 15:38:59 volumio volumio[393293]: info: Plugin folders cleanup Feb 16 15:38:59 volumio volumio[393293]: info: Scanning into folder /volumio/app/plugins/ Feb 16 15:38:59 volumio volumio[393293]: info: Scanning category audio_interface Feb 16 15:38:59 volumio volumio[393293]: info: Scanning category miscellanea Feb 16 15:38:59 volumio volumio[393293]: info: Scanning category music_service Feb 16 15:38:59 volumio volumio[393293]: info: Scanning category plugins.json Feb 16 15:38:59 volumio volumio[393293]: info: Scanning category system_controller Feb 16 15:38:59 volumio volumio[393293]: info: Scanning category user_interface Feb 16 15:38:59 volumio volumio[393293]: info: Scanning into folder /data/plugins/ Feb 16 15:38:59 volumio volumio[393293]: info: Scanning category music_service Feb 16 15:38:59 volumio volumio[393293]: info: Plugin folders cleanup completed Feb 16 15:38:59 volumio volumio[393293]: info: ------------------------------------------- Feb 16 15:38:59 volumio volumio[393293]: info: ----- Core plugins startup ---- Feb 16 15:38:59 volumio volumio[393293]: info: ------------------------------------------- Feb 16 15:38:59 volumio volumio[393293]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 15:38:59 volumio volumio[393293]: info: Adding plugin upnp to MyMusic Plugins Feb 16 15:38:59 volumio volumio[393293]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 15:38:59 volumio volumio[393293]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 15:38:59 volumio volumio[393293]: info: Loading plugins from folder /data/plugins/ Feb 16 15:38:59 volumio volumio[393293]: info: Loading plugin "system"... Feb 16 15:38:59 volumio volumio[393293]: info: Loading plugin "appearance"... Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "network"... Feb 16 15:39:00 volumio volumio[393293]: info: Refreshing Cached IP Addresses Feb 16 15:39:00 volumio sudo[393322]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 15:39:00 volumio sudo[393322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:00 volumio sudo[393324]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 15:39:00 volumio sudo[393322]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:00 volumio sudo[393324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "services"... Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "volumio5onboarding"... Feb 16 15:39:00 volumio sudo[393324]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:00 volumio sudo[393334]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 16 15:39:00 volumio sudo[393334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "alsa_controller"... Feb 16 15:39:00 volumio sudo[393334]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:00 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "wizard"... Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "networkfs"... Feb 16 15:39:00 volumio volumio[393293]: info: Starting Udev Watcher for removable devices Feb 16 15:39:00 volumio volumio[393293]: info: Ignoring mount for partition: boot Feb 16 15:39:00 volumio volumio[393293]: info: Ignoring mount for partition: volumio Feb 16 15:39:00 volumio volumio[393293]: info: Ignoring mount for partition: volumio_data Feb 16 15:39:00 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "volumio_command_line_client"... Feb 16 15:39:00 volumio volumio[393293]: info: Plugin upnp is not enabled Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "my_music"... Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "mpd"... Feb 16 15:39:00 volumio dhcpcd[826]: eth0: carrier acquired Feb 16 15:39:00 volumio kernel: e1000e 0000:00:19.0 eth0: NIC Link is Up 1000 Mbps Full Duplex, Flow Control: Rx/Tx Feb 16 15:39:00 volumio dhcpcd[826]: eth0: IAID f2:ec:7d:5b Feb 16 15:39:00 volumio volumio[393293]: info: Plugin upnp_browser is not enabled Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "alarm-clock"... Feb 16 15:39:00 volumio volumio[393293]: info: Plugin airplay_emulation is not enabled Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "last_100"... Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "webradio"... Feb 16 15:39:00 volumio dhcpcd[826]: eth0: soliciting an IPv6 router Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "i2s_dacs"... Feb 16 15:39:00 volumio volumio[393293]: info: I2S DAC not set, start Auto-detection Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "volumiodiscovery"... Feb 16 15:39:00 volumio volumio[393293]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:39:00 volumio volumio[393293]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:39:00 volumio volumio[393293]: *** WARNING *** For more information see Feb 16 15:39:00 volumio volumio[393293]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:39:00 volumio volumio[393293]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:39:00 volumio volumio[393293]: *** WARNING *** For more information see Feb 16 15:39:00 volumio node[393293]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:39:00 volumio node[393293]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:39:00 volumio node[393293]: *** WARNING *** For more information see Feb 16 15:39:00 volumio node[393293]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:39:00 volumio node[393293]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:39:00 volumio node[393293]: *** WARNING *** For more information see Feb 16 15:39:00 volumio volumio[393293]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 15:39:00 volumio volumio[393293]: info: Discovery: Started advertising with name: Volumio Feb 16 15:39:00 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "spop"... Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "squeezelite_mc"... Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "outputs"... Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "albumart"... Feb 16 15:39:00 volumio volumio[393293]: info: Plugin example_plugin is not enabled Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "inputs"... Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "updater_comm"... Feb 16 15:39:00 volumio volumio[393293]: info: Plugin mpdemulation is not enabled Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "rest_api"... Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "websocket"... Feb 16 15:39:00 volumio volumio[393293]: info: Starting Socket.io Server version 1.7.4 Feb 16 15:39:00 volumio volumio[393293]: info: Loading plugin "RoonBridge"... Feb 16 15:39:00 volumio volumio[393361]: Forking 3 albumart workers Feb 16 15:39:00 volumio volumio[393293]: info: Applying required configuration parameters for plugin RoonBridge Feb 16 15:39:00 volumio volumio[393293]: info: Loading i18n strings for locale en Feb 16 15:39:00 volumio volumio[393293]: Updating browse sources language Feb 16 15:39:00 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:39:00 volumio ifplugd(eth0)[986]: Link beat detected. Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::initPlayerControls Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:39:01 volumio volumio[393293]: Express server listening on port 3000 Feb 16 15:39:01 volumio volumio[393293]: [Metrics] WebUI: 1s 322.05ms Feb 16 15:39:01 volumio volumio[393293]: info: Setting Device type: x86 Feb 16 15:39:01 volumio volumio[393293]: info: CoreStateMachine::resetVolumioState Feb 16 15:39:01 volumio volumio[393293]: info: CoreStateMachine::getcurrentVolume Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:39:01 volumio volumio[393293]: info: CoreStateMachine::pushState Feb 16 15:39:01 volumio volumio[393293]: info: CorePlayQueue::getTrack 0 Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::volumioPushState Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:01 volumio volumio[393293]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 16 15:39:01 volumio volumio[393293]: info: Completed loading Core Plugins Feb 16 15:39:01 volumio volumio[393293]: info: Preparing to generate the ALSA configuration file Feb 16 15:39:01 volumio volumio[393293]: info: Volumio Network Manager: Network status updated: 0 Feb 16 15:39:01 volumio volumio[393293]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 16 15:39:01 volumio volumio[393293]: wlan0 Interface doesn't support scanning. Feb 16 15:39:01 volumio volumio[393293]: info: Cannot use regular scanning, forcing with ap-force Feb 16 15:39:01 volumio ifplugd(eth0)[986]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 16 15:39:01 volumio sudo[393403]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 16 15:39:01 volumio sudo[393403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:01 volumio sudo[393403]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:01 volumio volumio[393293]: command failed: No such device (-19) Feb 16 15:39:01 volumio volumio[393293]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 16 15:39:01 volumio volumio[393293]: command failed: No such device (-19) Feb 16 15:39:01 volumio volumio[393293]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 16 15:39:01 volumio ifplugd(eth0)[986]: client: command failed: No such device (-19) Feb 16 15:39:01 volumio volumio[393293]: 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: 1 Feb 16 15:39:01 volumio ifplugd(eth0)[986]: client: sending commands to dhcpcd process Feb 16 15:39:01 volumio dhcpcd[826]: ps_ctl_dispatch: cannot handle another client Feb 16 15:39:01 volumio dhcpcd[826]: control_free: No such file or directory Feb 16 15:39:01 volumio volumio[393373]: Starting albumart workers Feb 16 15:39:01 volumio volumio[393371]: Starting albumart workers Feb 16 15:39:01 volumio volumio[393293]: 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: 1 Feb 16 15:39:01 volumio volumio[393293]: 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: 1 Feb 16 15:39:01 volumio volumio[393293]: info: Reloading queue from file Feb 16 15:39:01 volumio volumio[393293]: info: Asound.conf file unchanged, so no further update is needed Feb 16 15:39:01 volumio volumio[393293]: info: Output device has changed, restarting MPD Feb 16 15:39:01 volumio sudo[393454]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:39:01 volumio sudo[393454]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:01 volumio volumio[393372]: Starting albumart workers Feb 16 15:39:01 volumio sudo[393454]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:01 volumio volumio[393293]: info: ___________ START PLUGINS ___________ Feb 16 15:39:01 volumio sudo[393463]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 16 15:39:01 volumio volumio[393293]: info: ControllerMpd::onStart: Initializing MPD Feb 16 15:39:01 volumio sudo[393463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:01 volumio volumio[393293]: info: Creating MPD Configuration file Feb 16 15:39:01 volumio sudo[393466]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:39:01 volumio sudo[393466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:39:01 volumio volumio[393293]: info: [1771277941227] CoreMusicLibrary::Adding element Last_100 Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:39:01 volumio volumio[393293]: info: [1771277941229] CoreMusicLibrary::Adding element Webradio Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:39:01 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 16 15:39:01 volumio sudo[393474]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:39:01 volumio sudo[393472]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:39:01 volumio sudo[393474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:01 volumio sudo[393472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:01 volumio volumio[393293]: info: Initializing BBC Radios Feb 16 15:39:01 volumio sudo[393463]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:01 volumio sudo[393472]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:01 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 16 15:39:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 15:39:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 16 15:39:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 15:39:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 15:39:01 volumio dhcpcd[826]: eth0: rebinding lease of 192.168.1.138 Feb 16 15:39:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 15:39:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 15:39:01 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 16 15:39:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 15:39:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 16 15:39:01 volumio dhcpcd[826]: eth0: probing address 192.168.1.138/24 Feb 16 15:39:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 15:39:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 15:39:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 15:39:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 15:39:01 volumio ifplugd(eth0)[986]: Program executed successfully. Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:01 volumio volumio[393293]: info: Creating Spotify config file Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:01 volumio sudo[393491]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 15:39:01 volumio sudo[393491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 16 15:39:01 volumio sudo[393491]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:01 volumio volumio[393293]: info: [squeezelite_mc] Starting proxy server... Feb 16 15:39:01 volumio volumio[393293]: info: Volumio Calling Home Feb 16 15:39:01 volumio sudo[393517]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 16 15:39:01 volumio sudo[393517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:01 volumio sudo[393517]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:01 volumio volumio[393293]: info: [squeezelite_mc] Proxy server started on port 36977 Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:39:01 volumio volumio[393293]: info: CoreStateMachine::pushState Feb 16 15:39:01 volumio volumio[393293]: info: CorePlayQueue::getTrack 0 Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::volumioPushState Feb 16 15:39:01 volumio volumio[393293]: info: [squeezelite_mc] Server discovery started Feb 16 15:39:01 volumio volumio[393293]: info: [squeezelite_mc] Player finder started Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:01 volumio volumio[393293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:39:01 volumio volumio[393293]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 16 15:39:01 volumio volumio[393293]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:39:01 volumio volumio[393293]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 16 15:39:01 volumio volumio[393293]: at doSend (node:dgram:717:16) Feb 16 15:39:01 volumio volumio[393293]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Feb 16 15:39:01 volumio volumio[393293]: at afterDns (node:dgram:663:5) Feb 16 15:39:01 volumio volumio[393293]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Feb 16 15:39:01 volumio volumio[393293]: errno: -101, Feb 16 15:39:01 volumio volumio[393293]: code: 'ENETUNREACH', Feb 16 15:39:01 volumio volumio[393293]: syscall: 'send', Feb 16 15:39:01 volumio volumio[393293]: address: '255.255.255.255', Feb 16 15:39:01 volumio volumio[393293]: port: 3483 Feb 16 15:39:01 volumio volumio[393293]: } Feb 16 15:39:01 volumio volumio[393293]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:39:01 volumio sudo[393539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-16 15:38' Feb 16 15:39:01 volumio sudo[393539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:01 volumio sudo[393539]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:01 volumio volumio5-onboarding[1422]: time=2026-02-16T15:39:01.673-06:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 16 15:39:01 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 16 15:39:01 volumio volumio5-onboarding[1422]: time=2026-02-16T15:39:01.676-06:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 16 15:39:01 volumio sudo[393474]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:01 volumio sudo[393466]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:01 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 16 15:39:01 volumio systemd[1]: volumio.service: Consumed 4.215s CPU time. Feb 16 15:39:01 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 16 15:39:01 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 16 15:39:01 volumio mpd[393497]: 2026-02-16T15:39:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 16 15:39:01 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 16 15:39:01 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 29. Feb 16 15:39:01 volumio systemd[1]: dynamicswap.service: Start request repeated too quickly. Feb 16 15:39:01 volumio systemd[1]: dynamicswap.service: Failed with result 'start-limit-hit'. Feb 16 15:39:01 volumio systemd[1]: Failed to start dynamicswap.service - dynamicswap service. Feb 16 15:39:01 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 16 15:39:01 volumio systemd[1]: volumio.service: Consumed 4.215s CPU time. Feb 16 15:39:02 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 16 15:39:02 volumio volumio[393552]: info: ------------------------------------------- Feb 16 15:39:02 volumio volumio[393552]: info: ----- Volumio3 ---- Feb 16 15:39:02 volumio volumio[393552]: info: ------------------------------------------- Feb 16 15:39:02 volumio volumio[393552]: info: ----- System startup ---- Feb 16 15:39:02 volumio volumio[393552]: info: ------------------------------------------- Feb 16 15:39:02 volumio volumio[393552]: info: MYVOLUMIO Environment detected Feb 16 15:39:02 volumio volumio[393552]: info: Plugin folders cleanup Feb 16 15:39:02 volumio volumio[393552]: info: Scanning into folder /volumio/app/plugins/ Feb 16 15:39:02 volumio volumio[393552]: info: Scanning category audio_interface Feb 16 15:39:02 volumio volumio[393552]: info: Scanning category miscellanea Feb 16 15:39:02 volumio volumio[393552]: info: Scanning category music_service Feb 16 15:39:02 volumio volumio[393552]: info: Scanning category plugins.json Feb 16 15:39:02 volumio volumio[393552]: info: Scanning category system_controller Feb 16 15:39:02 volumio volumio[393552]: info: Scanning category user_interface Feb 16 15:39:02 volumio volumio[393552]: info: Scanning into folder /data/plugins/ Feb 16 15:39:02 volumio volumio[393552]: info: Scanning category music_service Feb 16 15:39:02 volumio volumio[393552]: info: Plugin folders cleanup completed Feb 16 15:39:02 volumio volumio[393552]: info: ------------------------------------------- Feb 16 15:39:02 volumio volumio[393552]: info: ----- Core plugins startup ---- Feb 16 15:39:02 volumio volumio[393552]: info: ------------------------------------------- Feb 16 15:39:02 volumio volumio[393552]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 15:39:02 volumio volumio[393552]: info: Adding plugin upnp to MyMusic Plugins Feb 16 15:39:02 volumio volumio[393552]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 15:39:02 volumio volumio[393552]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 15:39:02 volumio volumio[393552]: info: Loading plugins from folder /data/plugins/ Feb 16 15:39:02 volumio volumio[393552]: info: Loading plugin "system"... Feb 16 15:39:02 volumio volumio[393552]: info: Loading plugin "appearance"... Feb 16 15:39:02 volumio volumio[393552]: info: Loading plugin "network"... Feb 16 15:39:02 volumio volumio[393552]: info: Refreshing Cached IP Addresses Feb 16 15:39:02 volumio sudo[393576]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 15:39:02 volumio sudo[393576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:02 volumio sudo[393578]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 15:39:02 volumio sudo[393578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:02 volumio sudo[393576]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:02 volumio volumio[393552]: info: Loading plugin "services"... Feb 16 15:39:02 volumio volumio[393552]: info: Loading plugin "volumio5onboarding"... Feb 16 15:39:02 volumio volumio[393552]: info: Loading plugin "alsa_controller"... Feb 16 15:39:02 volumio sudo[393587]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 16 15:39:02 volumio sudo[393587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:02 volumio sudo[393578]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:02 volumio sudo[393587]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:02 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:39:02 volumio volumio[393552]: info: Loading plugin "wizard"... Feb 16 15:39:02 volumio volumio[393552]: info: Loading plugin "networkfs"... Feb 16 15:39:02 volumio volumio[393552]: info: Starting Udev Watcher for removable devices Feb 16 15:39:02 volumio volumio[393552]: info: Ignoring mount for partition: boot Feb 16 15:39:02 volumio volumio[393552]: info: Ignoring mount for partition: volumio Feb 16 15:39:02 volumio volumio[393552]: info: Ignoring mount for partition: volumio_data Feb 16 15:39:02 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:39:02 volumio volumio[393552]: info: Loading plugin "volumio_command_line_client"... Feb 16 15:39:02 volumio volumio[393552]: info: Plugin upnp is not enabled Feb 16 15:39:02 volumio volumio[393552]: info: Loading plugin "my_music"... Feb 16 15:39:02 volumio volumio[393552]: info: Loading plugin "mpd"... Feb 16 15:39:02 volumio volumio[393552]: info: Plugin upnp_browser is not enabled Feb 16 15:39:02 volumio volumio[393552]: info: Loading plugin "alarm-clock"... Feb 16 15:39:02 volumio volumio[393552]: info: Plugin airplay_emulation is not enabled Feb 16 15:39:02 volumio volumio[393552]: info: Loading plugin "last_100"... Feb 16 15:39:02 volumio volumio[393552]: info: Loading plugin "webradio"... Feb 16 15:39:03 volumio volumio[393552]: info: Loading plugin "i2s_dacs"... Feb 16 15:39:03 volumio volumio[393552]: info: I2S DAC not set, start Auto-detection Feb 16 15:39:03 volumio volumio[393552]: info: Loading plugin "volumiodiscovery"... Feb 16 15:39:03 volumio volumio[393552]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:39:03 volumio volumio[393552]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:39:03 volumio volumio[393552]: *** WARNING *** For more information see Feb 16 15:39:03 volumio volumio[393552]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:39:03 volumio volumio[393552]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:39:03 volumio volumio[393552]: *** WARNING *** For more information see Feb 16 15:39:03 volumio node[393552]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:39:03 volumio node[393552]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:39:03 volumio node[393552]: *** WARNING *** For more information see Feb 16 15:39:03 volumio node[393552]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:39:03 volumio node[393552]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:39:03 volumio node[393552]: *** WARNING *** For more information see Feb 16 15:39:03 volumio volumio[393552]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 15:39:03 volumio volumio[393552]: info: Discovery: Started advertising with name: Volumio Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:39:03 volumio volumio[393552]: info: Loading plugin "spop"... Feb 16 15:39:03 volumio volumio[393552]: info: Loading plugin "squeezelite_mc"... Feb 16 15:39:03 volumio volumio[393552]: info: Loading plugin "outputs"... Feb 16 15:39:03 volumio volumio[393552]: info: Loading plugin "albumart"... Feb 16 15:39:03 volumio volumio[393552]: info: Plugin example_plugin is not enabled Feb 16 15:39:03 volumio volumio[393552]: info: Loading plugin "inputs"... Feb 16 15:39:03 volumio volumio[393552]: info: Loading plugin "updater_comm"... Feb 16 15:39:03 volumio volumio[393552]: info: Plugin mpdemulation is not enabled Feb 16 15:39:03 volumio volumio[393552]: info: Loading plugin "rest_api"... Feb 16 15:39:03 volumio volumio[393552]: info: Loading plugin "websocket"... Feb 16 15:39:03 volumio volumio[393552]: info: Starting Socket.io Server version 1.7.4 Feb 16 15:39:03 volumio volumio[393552]: info: Loading plugin "RoonBridge"... Feb 16 15:39:03 volumio volumio[393612]: Forking 3 albumart workers Feb 16 15:39:03 volumio volumio[393552]: info: Applying required configuration parameters for plugin RoonBridge Feb 16 15:39:03 volumio volumio[393552]: info: Loading i18n strings for locale en Feb 16 15:39:03 volumio volumio[393552]: Updating browse sources language Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::initPlayerControls Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:39:03 volumio volumio[393552]: Express server listening on port 3000 Feb 16 15:39:03 volumio volumio[393552]: [Metrics] WebUI: 1s 328.87ms Feb 16 15:39:03 volumio volumio[393552]: info: Setting Device type: x86 Feb 16 15:39:03 volumio volumio[393552]: info: CoreStateMachine::resetVolumioState Feb 16 15:39:03 volumio volumio[393552]: info: CoreStateMachine::getcurrentVolume Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:39:03 volumio volumio[393552]: info: CoreStateMachine::pushState Feb 16 15:39:03 volumio volumio[393552]: info: CorePlayQueue::getTrack 0 Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::volumioPushState Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:03 volumio volumio[393552]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 16 15:39:03 volumio volumio[393552]: info: Completed loading Core Plugins Feb 16 15:39:03 volumio volumio[393552]: info: Preparing to generate the ALSA configuration file Feb 16 15:39:03 volumio volumio[393552]: info: Volumio Network Manager: Network status updated: 0 Feb 16 15:39:03 volumio volumio[393552]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 16 15:39:03 volumio volumio[393552]: wlan0 Interface doesn't support scanning. Feb 16 15:39:03 volumio volumio[393552]: info: Cannot use regular scanning, forcing with ap-force Feb 16 15:39:03 volumio sudo[393655]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 16 15:39:03 volumio sudo[393655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:03 volumio sudo[393655]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:03 volumio volumio[393552]: command failed: No such device (-19) Feb 16 15:39:03 volumio volumio[393552]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 16 15:39:03 volumio volumio[393552]: command failed: No such device (-19) Feb 16 15:39:03 volumio volumio[393552]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 16 15:39:03 volumio volumio[393552]: 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: 1 Feb 16 15:39:03 volumio volumio[393552]: info: Reloading queue from file Feb 16 15:39:03 volumio volumio[393623]: Starting albumart workers Feb 16 15:39:03 volumio volumio[393622]: Starting albumart workers Feb 16 15:39:03 volumio volumio[393552]: 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: 1 Feb 16 15:39:03 volumio volumio[393552]: info: Asound.conf file unchanged, so no further update is needed Feb 16 15:39:03 volumio volumio[393552]: info: Output device has changed, restarting MPD Feb 16 15:39:03 volumio volumio[393552]: info: ___________ START PLUGINS ___________ Feb 16 15:39:03 volumio sudo[393665]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:39:03 volumio sudo[393665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:03 volumio volumio[393552]: info: ControllerMpd::onStart: Initializing MPD Feb 16 15:39:03 volumio volumio[393552]: info: Creating MPD Configuration file Feb 16 15:39:03 volumio sudo[393665]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:03 volumio sudo[393668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 16 15:39:03 volumio sudo[393668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:03 volumio sudo[393667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:39:03 volumio sudo[393667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:39:03 volumio volumio[393552]: info: [1771277943688] CoreMusicLibrary::Adding element Last_100 Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:39:03 volumio volumio[393552]: info: [1771277943689] CoreMusicLibrary::Adding element Webradio Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:39:03 volumio sudo[393675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:39:03 volumio sudo[393675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:39:03 volumio volumio[393552]: info: Initializing BBC Radios Feb 16 15:39:03 volumio sudo[393671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:39:03 volumio sudo[393671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:03 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 16 15:39:03 volumio sudo[393671]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:03 volumio sudo[393668]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:03 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 16 15:39:03 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 15:39:03 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 16 15:39:03 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 15:39:03 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:03 volumio volumio[393624]: Starting albumart workers Feb 16 15:39:03 volumio volumio[393552]: info: Creating Spotify config file Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:03 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 15:39:03 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 15:39:03 volumio sudo[393692]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 15:39:03 volumio sudo[393692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 16 15:39:03 volumio sudo[393692]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:03 volumio volumio[393552]: info: [squeezelite_mc] Starting proxy server... Feb 16 15:39:03 volumio volumio[393552]: info: Volumio Calling Home Feb 16 15:39:03 volumio sudo[393708]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 16 15:39:03 volumio sudo[393708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:03 volumio sudo[393708]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:03 volumio volumio[393552]: info: [squeezelite_mc] Proxy server started on port 44013 Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:39:03 volumio volumio[393552]: info: CoreStateMachine::pushState Feb 16 15:39:03 volumio volumio[393552]: info: CorePlayQueue::getTrack 0 Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::volumioPushState Feb 16 15:39:03 volumio volumio[393552]: info: [squeezelite_mc] Server discovery started Feb 16 15:39:03 volumio volumio[393552]: info: [squeezelite_mc] Player finder started Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:03 volumio volumio[393552]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:39:03 volumio volumio[393552]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 16 15:39:03 volumio volumio[393552]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:39:03 volumio volumio[393552]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 16 15:39:03 volumio volumio[393552]: at doSend (node:dgram:717:16) Feb 16 15:39:03 volumio volumio[393552]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Feb 16 15:39:03 volumio volumio[393552]: at afterDns (node:dgram:663:5) Feb 16 15:39:03 volumio volumio[393552]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Feb 16 15:39:03 volumio volumio[393552]: errno: -101, Feb 16 15:39:03 volumio volumio[393552]: code: 'ENETUNREACH', Feb 16 15:39:03 volumio volumio[393552]: syscall: 'send', Feb 16 15:39:03 volumio volumio[393552]: address: '255.255.255.255', Feb 16 15:39:03 volumio volumio[393552]: port: 3483 Feb 16 15:39:03 volumio volumio[393552]: } Feb 16 15:39:03 volumio volumio[393552]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:39:04 volumio sudo[393735]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-16 15:38' Feb 16 15:39:04 volumio sudo[393735]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:04 volumio sudo[393735]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:04 volumio volumio5-onboarding[1422]: time=2026-02-16T15:39:04.138-06:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:35932->127.0.0.1:3000: read: connection reset by peer" Feb 16 15:39:04 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 16 15:39:04 volumio sudo[393675]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:04 volumio sudo[393667]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:04 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 16 15:39:04 volumio systemd[1]: volumio.service: Consumed 4.169s CPU time. Feb 16 15:39:04 volumio systemd[1]: dynamicswap.service: Start request repeated too quickly. Feb 16 15:39:04 volumio systemd[1]: dynamicswap.service: Failed with result 'start-limit-hit'. Feb 16 15:39:04 volumio systemd[1]: Failed to start dynamicswap.service - dynamicswap service. Feb 16 15:39:04 volumio mpd[393700]: 2026-02-16T15:39:04 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 16 15:39:04 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 30. Feb 16 15:39:04 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 16 15:39:04 volumio systemd[1]: dynamicswap.service: Start request repeated too quickly. Feb 16 15:39:04 volumio systemd[1]: dynamicswap.service: Failed with result 'start-limit-hit'. Feb 16 15:39:04 volumio systemd[1]: Failed to start dynamicswap.service - dynamicswap service. Feb 16 15:39:04 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 16 15:39:04 volumio systemd[1]: volumio.service: Consumed 4.169s CPU time. Feb 16 15:39:04 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 16 15:39:04 volumio volumio-remote-updater[763]: [2026-02-16 15:39:04] [info] asio async_connect error: asio.system:111 (Connection refused) Feb 16 15:39:04 volumio volumio-remote-updater[763]: [2026-02-16 15:39:04] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Feb 16 15:39:04 volumio volumio-remote-updater[763]: [2026-02-16 15:39:04] [error] handle_connect error: Connection refused Feb 16 15:39:04 volumio volumio[393742]: info: ------------------------------------------- Feb 16 15:39:04 volumio volumio[393742]: info: ----- Volumio3 ---- Feb 16 15:39:04 volumio volumio[393742]: info: ------------------------------------------- Feb 16 15:39:04 volumio volumio[393742]: info: ----- System startup ---- Feb 16 15:39:04 volumio volumio[393742]: info: ------------------------------------------- Feb 16 15:39:04 volumio volumio[393742]: info: MYVOLUMIO Environment detected Feb 16 15:39:04 volumio volumio[393742]: info: Plugin folders cleanup Feb 16 15:39:04 volumio volumio[393742]: info: Scanning into folder /volumio/app/plugins/ Feb 16 15:39:04 volumio volumio[393742]: info: Scanning category audio_interface Feb 16 15:39:04 volumio volumio[393742]: info: Scanning category miscellanea Feb 16 15:39:04 volumio volumio[393742]: info: Scanning category music_service Feb 16 15:39:04 volumio volumio[393742]: info: Scanning category plugins.json Feb 16 15:39:04 volumio volumio[393742]: info: Scanning category system_controller Feb 16 15:39:04 volumio volumio[393742]: info: Scanning category user_interface Feb 16 15:39:04 volumio volumio[393742]: info: Scanning into folder /data/plugins/ Feb 16 15:39:04 volumio volumio[393742]: info: Scanning category music_service Feb 16 15:39:04 volumio volumio[393742]: info: Plugin folders cleanup completed Feb 16 15:39:04 volumio volumio[393742]: info: ------------------------------------------- Feb 16 15:39:04 volumio volumio[393742]: info: ----- Core plugins startup ---- Feb 16 15:39:04 volumio volumio[393742]: info: ------------------------------------------- Feb 16 15:39:04 volumio volumio[393742]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 15:39:04 volumio volumio[393742]: info: Adding plugin upnp to MyMusic Plugins Feb 16 15:39:04 volumio volumio[393742]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 15:39:04 volumio volumio[393742]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 15:39:04 volumio volumio[393742]: info: Loading plugins from folder /data/plugins/ Feb 16 15:39:04 volumio volumio[393742]: info: Loading plugin "system"... Feb 16 15:39:04 volumio volumio[393742]: info: Loading plugin "appearance"... Feb 16 15:39:04 volumio volumio[393742]: info: Loading plugin "network"... Feb 16 15:39:04 volumio volumio[393742]: info: Refreshing Cached IP Addresses Feb 16 15:39:04 volumio sudo[393766]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 15:39:04 volumio sudo[393766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:04 volumio sudo[393768]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 15:39:04 volumio sudo[393768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:04 volumio sudo[393766]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:04 volumio volumio[393742]: info: Loading plugin "services"... Feb 16 15:39:04 volumio volumio[393742]: info: Loading plugin "volumio5onboarding"... Feb 16 15:39:04 volumio sudo[393768]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:04 volumio sudo[393775]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 16 15:39:04 volumio sudo[393775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:04 volumio volumio[393742]: info: Loading plugin "alsa_controller"... Feb 16 15:39:04 volumio sudo[393775]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:04 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:39:04 volumio volumio[393742]: info: Loading plugin "wizard"... Feb 16 15:39:04 volumio volumio[393742]: info: Loading plugin "networkfs"... Feb 16 15:39:04 volumio volumio[393742]: info: Starting Udev Watcher for removable devices Feb 16 15:39:04 volumio volumio[393742]: info: Ignoring mount for partition: boot Feb 16 15:39:04 volumio volumio[393742]: info: Ignoring mount for partition: volumio Feb 16 15:39:04 volumio volumio[393742]: info: Ignoring mount for partition: volumio_data Feb 16 15:39:04 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:39:04 volumio volumio[393742]: info: Loading plugin "volumio_command_line_client"... Feb 16 15:39:04 volumio volumio[393742]: info: Plugin upnp is not enabled Feb 16 15:39:04 volumio volumio[393742]: info: Loading plugin "my_music"... Feb 16 15:39:04 volumio volumio[393742]: info: Loading plugin "mpd"... Feb 16 15:39:05 volumio volumio[393742]: info: Plugin upnp_browser is not enabled Feb 16 15:39:05 volumio volumio[393742]: info: Loading plugin "alarm-clock"... Feb 16 15:39:05 volumio volumio[393742]: info: Plugin airplay_emulation is not enabled Feb 16 15:39:05 volumio volumio[393742]: info: Loading plugin "last_100"... Feb 16 15:39:05 volumio volumio[393742]: info: Loading plugin "webradio"... Feb 16 15:39:05 volumio volumio[393742]: info: Loading plugin "i2s_dacs"... Feb 16 15:39:05 volumio volumio[393742]: info: I2S DAC not set, start Auto-detection Feb 16 15:39:05 volumio volumio[393742]: info: Loading plugin "volumiodiscovery"... Feb 16 15:39:05 volumio volumio[393742]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:39:05 volumio volumio[393742]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:39:05 volumio volumio[393742]: *** WARNING *** For more information see Feb 16 15:39:05 volumio volumio[393742]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:39:05 volumio volumio[393742]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:39:05 volumio volumio[393742]: *** WARNING *** For more information see Feb 16 15:39:05 volumio node[393742]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:39:05 volumio node[393742]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:39:05 volumio node[393742]: *** WARNING *** For more information see Feb 16 15:39:05 volumio node[393742]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:39:05 volumio node[393742]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:39:05 volumio node[393742]: *** WARNING *** For more information see Feb 16 15:39:05 volumio volumio[393742]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 15:39:05 volumio volumio[393742]: info: Discovery: Started advertising with name: Volumio Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:39:05 volumio volumio[393742]: info: Loading plugin "spop"... Feb 16 15:39:05 volumio volumio[393742]: info: Loading plugin "squeezelite_mc"... Feb 16 15:39:05 volumio volumio[393742]: info: Loading plugin "outputs"... Feb 16 15:39:05 volumio volumio[393742]: info: Loading plugin "albumart"... Feb 16 15:39:05 volumio volumio[393742]: info: Plugin example_plugin is not enabled Feb 16 15:39:05 volumio volumio[393742]: info: Loading plugin "inputs"... Feb 16 15:39:05 volumio volumio[393742]: info: Loading plugin "updater_comm"... Feb 16 15:39:05 volumio volumio[393742]: info: Plugin mpdemulation is not enabled Feb 16 15:39:05 volumio volumio[393742]: info: Loading plugin "rest_api"... Feb 16 15:39:05 volumio volumio[393742]: info: Loading plugin "websocket"... Feb 16 15:39:05 volumio volumio[393742]: info: Starting Socket.io Server version 1.7.4 Feb 16 15:39:05 volumio volumio[393742]: info: Loading plugin "RoonBridge"... Feb 16 15:39:05 volumio volumio[393803]: Forking 3 albumart workers Feb 16 15:39:05 volumio volumio[393742]: info: Applying required configuration parameters for plugin RoonBridge Feb 16 15:39:05 volumio volumio[393742]: info: Loading i18n strings for locale en Feb 16 15:39:05 volumio volumio[393742]: Updating browse sources language Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::initPlayerControls Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:39:05 volumio volumio[393742]: Express server listening on port 3000 Feb 16 15:39:05 volumio volumio[393742]: [Metrics] WebUI: 1s 319.14ms Feb 16 15:39:05 volumio volumio[393742]: info: Setting Device type: x86 Feb 16 15:39:05 volumio volumio[393742]: info: CoreStateMachine::resetVolumioState Feb 16 15:39:05 volumio volumio[393742]: info: CoreStateMachine::getcurrentVolume Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:39:05 volumio volumio[393742]: info: CoreStateMachine::pushState Feb 16 15:39:05 volumio volumio[393742]: info: CorePlayQueue::getTrack 0 Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::volumioPushState Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:05 volumio volumio[393742]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 16 15:39:05 volumio volumio[393742]: info: Completed loading Core Plugins Feb 16 15:39:05 volumio volumio[393742]: info: Preparing to generate the ALSA configuration file Feb 16 15:39:05 volumio volumio[393742]: info: Volumio Network Manager: Network status updated: 0 Feb 16 15:39:05 volumio volumio[393742]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 16 15:39:05 volumio volumio[393742]: wlan0 Interface doesn't support scanning. Feb 16 15:39:05 volumio volumio[393742]: info: Cannot use regular scanning, forcing with ap-force Feb 16 15:39:05 volumio sudo[393846]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 16 15:39:05 volumio sudo[393846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:05 volumio sudo[393846]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:05 volumio volumio[393742]: command failed: No such device (-19) Feb 16 15:39:05 volumio volumio[393742]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 16 15:39:05 volumio volumio[393742]: command failed: No such device (-19) Feb 16 15:39:05 volumio volumio[393742]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 16 15:39:05 volumio volumio[393742]: 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: 1 Feb 16 15:39:05 volumio volumio[393742]: info: Reloading queue from file Feb 16 15:39:05 volumio volumio[393814]: Starting albumart workers Feb 16 15:39:05 volumio volumio[393742]: 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: 1 Feb 16 15:39:05 volumio volumio[393742]: info: Asound.conf file unchanged, so no further update is needed Feb 16 15:39:05 volumio volumio[393742]: info: Output device has changed, restarting MPD Feb 16 15:39:05 volumio volumio[393813]: Starting albumart workers Feb 16 15:39:05 volumio volumio[393742]: info: ___________ START PLUGINS ___________ Feb 16 15:39:05 volumio sudo[393855]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:39:05 volumio sudo[393855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:05 volumio sudo[393855]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:05 volumio sudo[393857]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:39:05 volumio volumio[393815]: Starting albumart workers Feb 16 15:39:05 volumio volumio[393742]: info: ControllerMpd::onStart: Initializing MPD Feb 16 15:39:05 volumio volumio[393742]: info: Creating MPD Configuration file Feb 16 15:39:05 volumio sudo[393857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:05 volumio sudo[393860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 16 15:39:05 volumio sudo[393860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:39:05 volumio volumio[393742]: info: [1771277945964] CoreMusicLibrary::Adding element Last_100 Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:39:05 volumio volumio[393742]: info: [1771277945965] CoreMusicLibrary::Adding element Webradio Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:39:05 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:39:05 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 16 15:39:05 volumio volumio[393742]: info: Initializing BBC Radios Feb 16 15:39:05 volumio sudo[393863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:39:05 volumio sudo[393863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:05 volumio sudo[393865]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:39:05 volumio sudo[393865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:05 volumio sudo[393863]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:05 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 16 15:39:05 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 15:39:05 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 16 15:39:05 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 15:39:05 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 15:39:06 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 15:39:06 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:39:06 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:06 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 15:39:06 volumio volumio[393742]: info: Creating Spotify config file Feb 16 15:39:06 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:06 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 16 15:39:06 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 15:39:06 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 16 15:39:06 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 15:39:06 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 15:39:06 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 15:39:06 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 15:39:06 volumio sudo[393860]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:06 volumio sudo[393885]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 15:39:06 volumio sudo[393885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 16 15:39:06 volumio sudo[393885]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:06 volumio volumio[393742]: info: [squeezelite_mc] Starting proxy server... Feb 16 15:39:06 volumio volumio[393742]: info: Volumio Calling Home Feb 16 15:39:06 volumio volumio[393742]: info: [squeezelite_mc] Proxy server started on port 42093 Feb 16 15:39:06 volumio volumio[393742]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:39:06 volumio volumio[393742]: info: CoreStateMachine::pushState Feb 16 15:39:06 volumio volumio[393742]: info: CorePlayQueue::getTrack 0 Feb 16 15:39:06 volumio volumio[393742]: info: CoreCommandRouter::volumioPushState Feb 16 15:39:06 volumio sudo[393896]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 16 15:39:06 volumio sudo[393896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:06 volumio volumio[393742]: info: [squeezelite_mc] Server discovery started Feb 16 15:39:06 volumio volumio[393742]: info: [squeezelite_mc] Player finder started Feb 16 15:39:06 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:06 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:06 volumio volumio[393742]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:39:06 volumio volumio[393742]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 16 15:39:06 volumio volumio[393742]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:39:06 volumio sudo[393896]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:06 volumio volumio[393742]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 16 15:39:06 volumio volumio[393742]: at doSend (node:dgram:717:16) Feb 16 15:39:06 volumio volumio[393742]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Feb 16 15:39:06 volumio volumio[393742]: at afterDns (node:dgram:663:5) Feb 16 15:39:06 volumio volumio[393742]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Feb 16 15:39:06 volumio volumio[393742]: errno: -101, Feb 16 15:39:06 volumio volumio[393742]: code: 'ENETUNREACH', Feb 16 15:39:06 volumio volumio[393742]: syscall: 'send', Feb 16 15:39:06 volumio volumio[393742]: address: '255.255.255.255', Feb 16 15:39:06 volumio volumio[393742]: port: 3483 Feb 16 15:39:06 volumio volumio[393742]: } Feb 16 15:39:06 volumio volumio[393742]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:39:06 volumio sudo[393928]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-16 15:38' Feb 16 15:39:06 volumio sudo[393928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:06 volumio sudo[393928]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:06 volumio volumio5-onboarding[1422]: time=2026-02-16T15:39:06.390-06:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:35972->127.0.0.1:3000: read: connection reset by peer" Feb 16 15:39:06 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 16 15:39:06 volumio sudo[393865]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:06 volumio sudo[393857]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:06 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 16 15:39:06 volumio systemd[1]: volumio.service: Consumed 4.133s CPU time. Feb 16 15:39:06 volumio systemd[1]: dynamicswap.service: Start request repeated too quickly. Feb 16 15:39:06 volumio systemd[1]: dynamicswap.service: Failed with result 'start-limit-hit'. Feb 16 15:39:06 volumio systemd[1]: Failed to start dynamicswap.service - dynamicswap service. Feb 16 15:39:06 volumio dhcpcd[826]: eth0: leased 192.168.1.138 for 86400 seconds Feb 16 15:39:06 volumio avahi-daemon[755]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.138. Feb 16 15:39:06 volumio avahi-daemon[755]: New relevant interface eth0.IPv4 for mDNS. Feb 16 15:39:06 volumio avahi-daemon[755]: Registering new address record for 192.168.1.138 on eth0.IPv4. Feb 16 15:39:06 volumio dhcpcd[826]: eth0: adding route to 192.168.1.0/24 Feb 16 15:39:06 volumio mpd[393887]: 2026-02-16T15:39:06 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 16 15:39:06 volumio dhcpcd[826]: eth0: adding default route via 192.168.1.1 Feb 16 15:39:06 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 31. Feb 16 15:39:06 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 16 15:39:06 volumio systemd[1]: dynamicswap.service: Start request repeated too quickly. Feb 16 15:39:06 volumio systemd[1]: dynamicswap.service: Failed with result 'start-limit-hit'. Feb 16 15:39:06 volumio systemd[1]: Failed to start dynamicswap.service - dynamicswap service. Feb 16 15:39:06 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 16 15:39:06 volumio systemd[1]: volumio.service: Consumed 4.133s CPU time. Feb 16 15:39:06 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 16 15:39:06 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Feb 16 15:39:06 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Feb 16 15:39:06 volumio systemd[1]: welcome.service: Deactivated successfully. Feb 16 15:39:06 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 16 15:39:06 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 16 15:39:06 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 16 15:39:06 volumio welcome[393956]: Resolved ip:[1] 192.168.1.138 Feb 16 15:39:06 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 16 15:39:06 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 16 15:39:06 volumio volumio[393944]: info: ------------------------------------------- Feb 16 15:39:06 volumio volumio[393944]: info: ----- Volumio3 ---- Feb 16 15:39:06 volumio volumio[393944]: info: ------------------------------------------- Feb 16 15:39:06 volumio volumio[393944]: info: ----- System startup ---- Feb 16 15:39:06 volumio volumio[393944]: info: ------------------------------------------- Feb 16 15:39:06 volumio volumio[393944]: info: MYVOLUMIO Environment detected Feb 16 15:39:07 volumio volumio[393944]: info: Plugin folders cleanup Feb 16 15:39:07 volumio volumio[393944]: info: Scanning into folder /volumio/app/plugins/ Feb 16 15:39:07 volumio volumio[393944]: info: Scanning category audio_interface Feb 16 15:39:07 volumio volumio[393944]: info: Scanning category miscellanea Feb 16 15:39:07 volumio volumio[393944]: info: Scanning category music_service Feb 16 15:39:07 volumio volumio[393944]: info: Scanning category plugins.json Feb 16 15:39:07 volumio volumio[393944]: info: Scanning category system_controller Feb 16 15:39:07 volumio volumio[393944]: info: Scanning category user_interface Feb 16 15:39:07 volumio volumio[393944]: info: Scanning into folder /data/plugins/ Feb 16 15:39:07 volumio volumio[393944]: info: Scanning category music_service Feb 16 15:39:07 volumio volumio[393944]: info: Plugin folders cleanup completed Feb 16 15:39:07 volumio volumio[393944]: info: ------------------------------------------- Feb 16 15:39:07 volumio volumio[393944]: info: ----- Core plugins startup ---- Feb 16 15:39:07 volumio volumio[393944]: info: ------------------------------------------- Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 15:39:07 volumio volumio[393944]: info: Adding plugin upnp to MyMusic Plugins Feb 16 15:39:07 volumio volumio[393944]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 15:39:07 volumio volumio[393944]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugins from folder /data/plugins/ Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "system"... Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "appearance"... Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "network"... Feb 16 15:39:07 volumio volumio[393944]: info: Refreshing Cached IP Addresses Feb 16 15:39:07 volumio sudo[393984]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 15:39:07 volumio sudo[393984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:07 volumio sudo[393986]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 15:39:07 volumio sudo[393986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "services"... Feb 16 15:39:07 volumio sudo[393984]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "volumio5onboarding"... Feb 16 15:39:07 volumio sudo[393989]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 16 15:39:07 volumio sudo[393989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "alsa_controller"... Feb 16 15:39:07 volumio sudo[393986]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:07 volumio sudo[393989]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:07 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "wizard"... Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "networkfs"... Feb 16 15:39:07 volumio volumio[393944]: info: Starting Udev Watcher for removable devices Feb 16 15:39:07 volumio volumio[393944]: info: Ignoring mount for partition: boot Feb 16 15:39:07 volumio volumio[393944]: info: Ignoring mount for partition: volumio Feb 16 15:39:07 volumio volumio[393944]: info: Ignoring mount for partition: volumio_data Feb 16 15:39:07 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "volumio_command_line_client"... Feb 16 15:39:07 volumio volumio[393944]: info: Plugin upnp is not enabled Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "my_music"... Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "mpd"... Feb 16 15:39:07 volumio volumio[393944]: info: Plugin upnp_browser is not enabled Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "alarm-clock"... Feb 16 15:39:07 volumio volumio[393944]: info: Plugin airplay_emulation is not enabled Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "last_100"... Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "webradio"... Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "i2s_dacs"... Feb 16 15:39:07 volumio volumio[393944]: info: I2S DAC not set, start Auto-detection Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "volumiodiscovery"... Feb 16 15:39:07 volumio volumio[393944]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:39:07 volumio volumio[393944]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:39:07 volumio volumio[393944]: *** WARNING *** For more information see Feb 16 15:39:07 volumio volumio[393944]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:39:07 volumio volumio[393944]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:39:07 volumio volumio[393944]: *** WARNING *** For more information see Feb 16 15:39:07 volumio node[393944]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 15:39:07 volumio node[393944]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:39:07 volumio node[393944]: *** WARNING *** For more information see Feb 16 15:39:07 volumio node[393944]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 15:39:07 volumio node[393944]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 15:39:07 volumio node[393944]: *** WARNING *** For more information see Feb 16 15:39:07 volumio volumio[393944]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 15:39:07 volumio volumio[393944]: info: Discovery: Started advertising with name: Volumio Feb 16 15:39:07 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "spop"... Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "squeezelite_mc"... Feb 16 15:39:07 volumio ntpd[922]: IO: Listen normally on 5 eth0 192.168.1.138:123 Feb 16 15:39:07 volumio ntpd[922]: IO: new interface(s) found: waking up resolver Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "outputs"... Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "albumart"... Feb 16 15:39:07 volumio volumio[393944]: info: Plugin example_plugin is not enabled Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "inputs"... Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "updater_comm"... Feb 16 15:39:07 volumio volumio[393944]: info: Plugin mpdemulation is not enabled Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "rest_api"... Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "websocket"... Feb 16 15:39:07 volumio volumio[393944]: info: Starting Socket.io Server version 1.7.4 Feb 16 15:39:07 volumio volumio[393944]: info: Loading plugin "RoonBridge"... Feb 16 15:39:07 volumio volumio[394021]: Forking 3 albumart workers Feb 16 15:39:08 volumio volumio[393944]: info: Applying required configuration parameters for plugin RoonBridge Feb 16 15:39:08 volumio volumio[393944]: info: Loading i18n strings for locale en Feb 16 15:39:08 volumio volumio[393944]: Updating browse sources language Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::initPlayerControls Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: Express server listening on port 3000 Feb 16 15:39:08 volumio volumio[393944]: [Metrics] WebUI: 1s 333.83ms Feb 16 15:39:08 volumio volumio[393944]: info: Setting Device type: x86 Feb 16 15:39:08 volumio volumio[393944]: info: CoreStateMachine::resetVolumioState Feb 16 15:39:08 volumio volumio[393944]: info: CoreStateMachine::getcurrentVolume Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:39:08 volumio volumio[393944]: info: CoreStateMachine::pushState Feb 16 15:39:08 volumio volumio[393944]: info: CorePlayQueue::getTrack 0 Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::volumioPushState Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 16 15:39:08 volumio volumio[393944]: info: Completed loading Core Plugins Feb 16 15:39:08 volumio volumio[393944]: info: Preparing to generate the ALSA configuration file Feb 16 15:39:08 volumio volumio[393944]: info: Volumio Network Manager: Network status updated: 1 Feb 16 15:39:08 volumio volumio[393944]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 16 15:39:08 volumio volumio[393944]: wlan0 Interface doesn't support scanning. Feb 16 15:39:08 volumio volumio[393944]: info: Cannot use regular scanning, forcing with ap-force Feb 16 15:39:08 volumio sudo[394063]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 16 15:39:08 volumio sudo[394063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:08 volumio sudo[394063]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:08 volumio volumio[393944]: command failed: No such device (-19) Feb 16 15:39:08 volumio volumio[393944]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 16 15:39:08 volumio volumio[393944]: command failed: No such device (-19) Feb 16 15:39:08 volumio volumio[393944]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 16 15:39:08 volumio volumio[394032]: Starting albumart workers Feb 16 15:39:08 volumio volumio[394033]: Starting albumart workers Feb 16 15:39:08 volumio volumio[393944]: 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: 1 Feb 16 15:39:08 volumio volumio[394031]: Starting albumart workers Feb 16 15:39:08 volumio volumio[393944]: 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: 1 Feb 16 15:39:08 volumio volumio[393944]: info: Reloading queue from file Feb 16 15:39:08 volumio volumio[393944]: info: Asound.conf file unchanged, so no further update is needed Feb 16 15:39:08 volumio volumio[393944]: info: Output device has changed, restarting MPD Feb 16 15:39:08 volumio volumio[393944]: info: ___________ START PLUGINS ___________ Feb 16 15:39:08 volumio sudo[394073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:39:08 volumio sudo[394073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:08 volumio volumio[393944]: info: ControllerMpd::onStart: Initializing MPD Feb 16 15:39:08 volumio sudo[394075]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:39:08 volumio volumio[393944]: info: Creating MPD Configuration file Feb 16 15:39:08 volumio sudo[394075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:08 volumio sudo[394073]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:08 volumio sudo[394078]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 16 15:39:08 volumio sudo[394078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:39:08 volumio volumio[393944]: info: [1771277948307] CoreMusicLibrary::Adding element Last_100 Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 15:39:08 volumio volumio[393944]: info: [1771277948311] CoreMusicLibrary::Adding element Webradio Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:39:08 volumio volumio[393944]: info: Initializing BBC Radios Feb 16 15:39:08 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 16 15:39:08 volumio sudo[394081]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:39:08 volumio sudo[394081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:08 volumio sudo[394078]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:08 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 16 15:39:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 15:39:08 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 16 15:39:08 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 15:39:08 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 15:39:08 volumio sudo[394081]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:08 volumio sudo[394083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:39:08 volumio sudo[394083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 15:39:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:08 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 16 15:39:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 15:39:08 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 16 15:39:08 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 15:39:08 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 15:39:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 15:39:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 15:39:08 volumio volumio[393944]: info: Creating Spotify config file Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio sudo[394103]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 15:39:08 volumio sudo[394103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 16 15:39:08 volumio sudo[394103]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:08 volumio volumio[393944]: info: [squeezelite_mc] Starting proxy server... Feb 16 15:39:08 volumio volumio[393944]: info: Volumio Calling Home Feb 16 15:39:08 volumio sudo[394125]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 16 15:39:08 volumio sudo[394125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:08 volumio sudo[394125]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:08 volumio volumio[393944]: info: [squeezelite_mc] Proxy server started on port 33525 Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 15:39:08 volumio volumio[393944]: info: CoreStateMachine::pushState Feb 16 15:39:08 volumio volumio[393944]: info: CorePlayQueue::getTrack 0 Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::volumioPushState Feb 16 15:39:08 volumio volumio[393944]: info: [squeezelite_mc] Server discovery started Feb 16 15:39:08 volumio volumio[393944]: info: [squeezelite_mc] Player finder started Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 16 15:39:08 volumio volumio[393944]: info: CoreStateMachine::setRepeat null single undefined Feb 16 15:39:08 volumio volumio[393944]: info: CoreStateMachine::pushState Feb 16 15:39:08 volumio volumio[393944]: info: CorePlayQueue::getTrack 0 Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::volumioPushState Feb 16 15:39:08 volumio volumio[393944]: info: CoreStateMachine::setRandom null Feb 16 15:39:08 volumio volumio[393944]: info: CoreStateMachine::pushState Feb 16 15:39:08 volumio volumio[393944]: info: CorePlayQueue::getTrack 0 Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::volumioPushState Feb 16 15:39:08 volumio volumio[393944]: 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: 1 Feb 16 15:39:08 volumio volumio[393944]: info: [squeezelite_mc] Card 5 supports the following ALSA formats: ["S32_LE","SPECIAL","DSD_U32_BE"] Feb 16 15:39:08 volumio volumio[393944]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":"DSD_U32_BE","card":"5","mixerType":"None","mixer":null} Feb 16 15:39:08 volumio volumio[393944]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 16 15:39:08 volumio volumio[393944]: info: MPD Permissions set Feb 16 15:39:08 volumio volumio[393944]: info: MPD Permissions set Feb 16 15:39:08 volumio volumio[393944]: info: An error occurred while refreshing Spotify Token Error: connect ENETUNREACH 10.0.0.1:443 Feb 16 15:39:08 volumio sudo[394135]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 16 15:39:08 volumio sudo[394135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:08 volumio volumio[393944]: 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: 1 Feb 16 15:39:08 volumio sudo[394135]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:08 volumio volumio[393944]: info: Spotify config file written Feb 16 15:39:08 volumio volumio[393944]: info: Discovery: adding 886fd5ca-d83f-4ee8-8d30-aeadf4ea8fc5 Feb 16 15:39:08 volumio volumio[393944]: info: Discovery: Found device Volumio Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::volumioGetState Feb 16 15:39:08 volumio volumio[393944]: info: CorePlayQueue::getTrack 0 Feb 16 15:39:08 volumio sudo[394139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 16 15:39:08 volumio sudo[394139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:08 volumio volumio[393944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 16 15:39:08 volumio volumio[393944]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio sudo[394143]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Feb 16 15:39:08 volumio sudo[394143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 16 15:39:08 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 16 15:39:08 volumio sudo[394143]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:08 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 16 15:39:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 16 15:39:08 volumio systemd[1]: go-librespot-daemon.service: Consumed 16.880s CPU time. Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:08 volumio volumio[393944]: info: No need to fix Spotify hosts Feb 16 15:39:08 volumio go-librespot[394153]: go-librespot daemon starting... Feb 16 15:39:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 16 15:39:08 volumio sudo[394139]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:08 volumio go-librespot[394162]: time="2026-02-16T15:39:08-06:00" level=info msg="running go-librespot 0.4.0" Feb 16 15:39:08 volumio go-librespot[394162]: time="2026-02-16T15:39:08-06:00" level=debug msg="app state loaded" Feb 16 15:39:08 volumio go-librespot[394162]: time="2026-02-16T15:39:08-06:00" level=debug msg="stored credentials not found" Feb 16 15:39:08 volumio go-librespot[394162]: time="2026-02-16T15:39:08-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 16 15:39:08 volumio volumio[393944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 16 15:39:08 volumio volumio[393944]: info: Discovery: this is already registered, 886fd5ca-d83f-4ee8-8d30-aeadf4ea8fc5 Feb 16 15:39:08 volumio volumio[393944]: info: Discovery: Found device Volumio Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::volumioGetState Feb 16 15:39:08 volumio volumio[393944]: info: CorePlayQueue::getTrack 0 Feb 16 15:39:08 volumio volumio[393944]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Feb 16 15:39:08 volumio volumio[393944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 16 15:39:08 volumio volumio[393944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 16 15:39:08 volumio sudo[394170]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Feb 16 15:39:08 volumio sudo[394170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:08 volumio sudo[394170]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:08 volumio volumio[393944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 16 15:39:08 volumio volumio[393944]: info: CoreCommandRouter::volumioGetState Feb 16 15:39:08 volumio volumio[393944]: info: CorePlayQueue::getTrack 0 Feb 16 15:39:08 volumio volumio[393944]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Feb 16 15:39:08 volumio volumio[393944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 16 15:39:08 volumio sudo[394173]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 16 15:39:08 volumio sudo[394173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:08 volumio volumio[393944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 16 15:39:08 volumio volumio[393944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 16 15:39:08 volumio systemd[1]: Reloading. Feb 16 15:39:08 volumio volumio[393944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 16 15:39:08 volumio volumio[393944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 16 15:39:08 volumio volumio[393944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 16 15:39:08 volumio volumio[393944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 16 15:39:08 volumio volumio[393944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 16 15:39:08 volumio volumio[393944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 16 15:39:08 volumio systemd-gpt-auto-generator[394182]: EFI loader partition unknown, exiting. Feb 16 15:39:08 volumio systemd-gpt-auto-generator[394182]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Feb 16 15:39:08 volumio mpd[394117]: 2026-02-16T15:39:08 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 16 15:39:08 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 16 15:39:08 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 16 15:39:08 volumio systemd[1]: /lib/systemd/system/roonbridge.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 16 15:39:08 volumio systemd[1]: /lib/systemd/system/roonbridge.service:8: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 16 15:39:09 volumio sudo[394173]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:09 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 16 15:39:09 volumio sudo[394083]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:09 volumio volumio[393944]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 16 15:39:09 volumio sudo[394075]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:09 volumio sudo[394193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 16 15:39:09 volumio sudo[394193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:09 volumio volumio[393944]: error: MPD error: The expression evaluated to a falsy value: Feb 16 15:39:09 volumio volumio[393944]: assert.ok(self.idling) Feb 16 15:39:09 volumio volumio[393944]: error: The expression evaluated to a falsy value: Feb 16 15:39:09 volumio volumio[393944]: assert.ok(self.idling) Feb 16 15:39:09 volumio volumio[393944]: error: updateQueue error: null Feb 16 15:39:09 volumio volumio[393944]: info: MPD running with PID394117 Feb 16 15:39:09 volumio volumio[393944]: ,establishing connection Feb 16 15:39:09 volumio volumio[393944]: error: updateQueue error: null Feb 16 15:39:09 volumio sudo[394193]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:09 volumio volumio[393944]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Feb 16 15:39:09 volumio sudo[394198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Feb 16 15:39:09 volumio sudo[394198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:09 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 16 15:39:09 volumio systemd[1]: squeezelite.service: Deactivated successfully. Feb 16 15:39:09 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 16 15:39:09 volumio systemd[1]: squeezelite.service: Consumed 56.612s CPU time. Feb 16 15:39:09 volumio sudo[394198]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:09 volumio volumio[393944]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 16 15:39:09 volumio sudo[394207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 16 15:39:09 volumio sudo[394207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:09 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 9666. Feb 16 15:39:09 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 16 15:39:09 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 16 15:39:09 volumio sudo[394207]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:09 volumio upmpdcli[394209]: Could not open config: /tmp/upmpdcli.conf Feb 16 15:39:09 volumio volumio[393944]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Feb 16 15:39:09 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 16 15:39:09 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 16 15:39:09 volumio sudo[394212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Feb 16 15:39:09 volumio sudo[394212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:09 volumio sudo[394212]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:09 volumio volumio[393944]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Feb 16 15:39:09 volumio sudo[394215]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Feb 16 15:39:09 volumio sudo[394215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:09 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 16 15:39:09 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 16 15:39:09 volumio sudo[394215]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:10 volumio volumio[393944]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 16 15:39:10 volumio sudo[394222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 16 15:39:10 volumio sudo[394222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:10 volumio sudo[394222]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:10 volumio volumio[393944]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 16 15:39:10 volumio sudo[394226]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 16 15:39:10 volumio sudo[394226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:10 volumio sudo[394226]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:11 volumio volumio[393944]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 16 15:39:11 volumio sudo[394229]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 16 15:39:11 volumio sudo[394229]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:11 volumio sudo[394229]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:11 volumio volumio[393944]: info: go-librespot daemon successfully initialized Feb 16 15:39:11 volumio volumio-remote-updater[763]: [2026-02-16 15:39:11] [connect] Successful connection Feb 16 15:39:11 volumio volumio-remote-updater[763]: [2026-02-16 15:39:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771277951 101 Feb 16 15:39:11 volumio volumio[393944]: 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: 3 Feb 16 15:39:11 volumio volumio[393944]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 16 15:39:11 volumio sudo[394233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 16 15:39:11 volumio sudo[394233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:11 volumio sudo[394233]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:12 volumio volumio[393944]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 16 15:39:12 volumio sudo[394236]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 16 15:39:12 volumio sudo[394236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:12 volumio sudo[394236]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:12 volumio volumio[393944]: info: Completed starting Core Plugins Feb 16 15:39:12 volumio volumio[393944]: info: ------------------------------------------- Feb 16 15:39:12 volumio volumio[393944]: info: ----- MyVolumio plugins startup ---- Feb 16 15:39:12 volumio volumio[393944]: info: ------------------------------------------- Feb 16 15:39:12 volumio volumio[393944]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 16 15:39:14 volumio volumio[393944]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 16 15:39:14 volumio volumio[393944]: info: Initializing connection to go-librespot Websocket Feb 16 15:39:14 volumio go-librespot[394162]: time="2026-02-16T15:39:14-06:00" level=debug msg="new websocket client" Feb 16 15:39:14 volumio volumio[393944]: info: Connection to go-librespot Websocket established Feb 16 15:39:17 volumio volumio[393944]: info: Getting Spotify volume Feb 16 15:39:17 volumio volumio[393944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 16 15:39:17 volumio volumio[393944]: info: CoreCommandRouter::volumioGetState Feb 16 15:39:17 volumio volumio[393944]: info: CorePlayQueue::getTrack 0 Feb 16 15:39:18 volumio volumio[393944]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=4.096&uuid=244d6b835235da967f7ac36747963d3a" http://updates.volumio.org/downloader-v1/track-device Feb 16 15:39:18 volumio volumio[393944]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 16 15:39:18 volumio volumio[393944]: Dload Upload Total Spent Left Speed Feb 16 15:39:18 volumio volumio[393944]: [158B blob data] Feb 16 15:39:18 volumio volumio[393944]: curl: (52) Empty reply from server Feb 16 15:39:18 volumio volumio[393944]: retrying in 5 seconds, trial 0 Feb 16 15:39:18 volumio volumio[393944]: info: Volumio Calling Home Feb 16 15:39:24 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 9667. Feb 16 15:39:24 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 16 15:39:24 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 16 15:39:24 volumio upmpdcli[394273]: Could not open config: /tmp/upmpdcli.conf Feb 16 15:39:24 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 16 15:39:24 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 16 15:39:28 volumio volumio[393944]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=4.096&uuid=244d6b835235da967f7ac36747963d3a" http://updates.volumio.org/downloader-v1/track-device Feb 16 15:39:28 volumio volumio[393944]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 16 15:39:28 volumio volumio[393944]: Dload Upload Total Spent Left Speed Feb 16 15:39:28 volumio volumio[393944]: [158B blob data] Feb 16 15:39:28 volumio volumio[393944]: curl: (52) Empty reply from server Feb 16 15:39:28 volumio volumio[393944]: retrying in 5 seconds, trial 1 Feb 16 15:39:28 volumio volumio[393944]: info: Volumio Calling Home Feb 16 15:39:32 volumio volumio[393944]: error: MyVolumio Plugin failed to start in a timely fashion Feb 16 15:39:32 volumio volumio[393944]: [Metrics] CommandRouter: 25s 524.07ms Feb 16 15:39:32 volumio volumio[393944]: info: CoreCommandRouter::volumiosetStartupVolume Feb 16 15:39:32 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:39:32 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:32 volumio volumio[393944]: info: CoreCommandRouter::Close All Modals sent Feb 16 15:39:32 volumio volumio[393944]: info: CoreCommandRouter::Close All Modals sent Feb 16 15:39:33 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 16 15:39:33 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 16 15:39:33 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 16 15:39:33 volumio volumio[393944]: info: Volumio called home Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 16 15:39:33 volumio volumio[393944]: info: Adding plugin bluetooth to MyMusic Plugins Feb 16 15:39:33 volumio volumio[393944]: info: Adding plugin multiroom to MyMusic Plugins Feb 16 15:39:33 volumio volumio[393944]: info: Adding plugin metavolumio to MyMusic Plugins Feb 16 15:39:33 volumio volumio[393944]: info: Adding plugin cd_controller to MyMusic Plugins Feb 16 15:39:33 volumio volumio[393944]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 16 15:39:33 volumio volumio[393944]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 16 15:39:33 volumio volumio[393944]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 16 15:39:33 volumio volumio[393944]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 16 15:39:34 volumio volumio[393944]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 16 15:39:34 volumio volumio[393944]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 16 15:39:34 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:34 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:34 volumio volumio[393944]: info: Starting MyVolumio Remote Streaming Endpoints Feb 16 15:39:34 volumio volumio[393944]: info: MyVolumio login type: Token Feb 16 15:39:34 volumio volumio[393944]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 16 15:39:34 volumio volumio[393944]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 16 15:39:34 volumio volumio[393944]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 16 15:39:34 volumio volumio[393944]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 16 15:39:34 volumio volumio[393944]: info: Streaming services startup Feb 16 15:39:34 volumio volumio[393944]: info: Starting Streaming Daemon Feb 16 15:39:34 volumio sudo[394308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 16 15:39:34 volumio sudo[394308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:34 volumio volumio[393944]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 16 15:39:34 volumio sudo[394308]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:34 volumio volumio[393944]: error: Cannot start Volumio Streaming Daemon Feb 16 15:39:34 volumio volumio[393944]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 16 15:39:34 volumio volumio[393944]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 16 15:39:34 volumio volumio[393944]: error: MyVolumio Custom Token format not valid, refreshing it Feb 16 15:39:34 volumio volumio[393944]: info: MyVolumio login type: Token Feb 16 15:39:34 volumio volumio[393944]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 16 15:39:35 volumio volumio[393944]: info: MyVolumio token set successfully Feb 16 15:39:35 volumio volumio[393944]: info: MYVOLUMIO: Adding device Feb 16 15:39:35 volumio volumio[393944]: info: MYVOLUMIO: Evaluating Server Feb 16 15:39:35 volumio volumio[393944]: info: MyVolumio status changed Feb 16 15:39:35 volumio volumio[393944]: info: Streaming services startup Feb 16 15:39:35 volumio volumio[393944]: info: Starting Streaming Daemon Feb 16 15:39:35 volumio volumio[393944]: info: Removing browser output: myVolumio user plan is not superstar Feb 16 15:39:35 volumio volumio[393944]: info: Removing audio output: Feb 16 15:39:35 volumio volumio[393944]: info: Stoppping Tunnel 1 Feb 16 15:39:35 volumio sudo[394336]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 16 15:39:35 volumio sudo[394336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:35 volumio sudo[394336]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:35 volumio volumio[393944]: error: Cannot start Volumio Streaming Daemon Feb 16 15:39:35 volumio volumio[393944]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 16 15:39:35 volumio volumio[393944]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 16 15:39:35 volumio sudo[394339]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 16 15:39:35 volumio sudo[394339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 15:39:35 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 16 15:39:35 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 16 15:39:35 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 16 15:39:35 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 16 15:39:35 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 16 15:39:35 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 16 15:39:35 volumio sudo[394339]: pam_unix(sudo:session): session closed for user root Feb 16 15:39:35 volumio volumio[393944]: info: Remote SSH Stopped Feb 16 15:39:35 volumio volumio[393944]: info: Setting Geolocation for MyVolumio to us1 Feb 16 15:39:35 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:35 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:35 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:35 volumio volumio[393944]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 16 15:39:36 volumio volumio[393944]: info: Updating MyVolumio device info Feb 16 15:39:36 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:36 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:36 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:36 volumio volumio[393944]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 16 15:39:38 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 15:39:38 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:39:38 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 16 15:39:38 volumio volumio-remote-updater[763]: Test mode disabled Feb 16 15:39:38 volumio volumio-remote-updater[763]: Alpha mode disabled Feb 16 15:39:38 volumio volumio-remote-updater[763]: Alpha legacy test mode disabled Feb 16 15:39:38 volumio volumio[393944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 16 15:39:38 volumio go-librespot[394162]: time="2026-02-16T15:39:38-06:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" Feb 16 15:39:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 16 15:39:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 16 15:39:38 volumio volumio[393944]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:39:38 volumio volumio[393944]: Error: socket hang up Feb 16 15:39:38 volumio volumio[393944]: at connResetException (node:internal/errors:720:14) Feb 16 15:39:38 volumio volumio[393944]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 16 15:39:38 volumio volumio[393944]: at Socket.emit (node:events:526:35) Feb 16 15:39:38 volumio volumio[393944]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 16 15:39:38 volumio volumio[393944]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 16 15:39:38 volumio volumio[393944]: code: 'ECONNRESET', Feb 16 15:39:38 volumio volumio[393944]: response: undefined Feb 16 15:39:38 volumio volumio[393944]: } Feb 16 15:39:38 volumio volumio[393944]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:39:38 volumio sudo[394379]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-16 15:38' Feb 16 15:39:38 volumio sudo[394379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"