Mar 29 23:36:00 volumio dhcpcd[778]: eth0: offered 192.168.8.36 from 192.168.8.1 Mar 29 23:36:00 volumio sh[762]: eth0: offered 192.168.8.36 from 192.168.8.1 Mar 29 23:36:00 volumio dhcpcd[778]: eth0: probing address 192.168.8.36/24 Mar 29 23:36:00 volumio sh[762]: eth0: probing address 192.168.8.36/24 Mar 29 23:36:00 volumio volumio[1118]: info: Loading plugin "network"... Mar 29 23:36:00 volumio volumio[1118]: info: Refreshing Cached IP Addresses Mar 29 23:36:01 volumio volumio[1118]: info: Loading plugin "services"... Mar 29 23:36:01 volumio volumio[1118]: info: Loading plugin "volumio5onboarding"... Mar 29 23:36:01 volumio volumio[1118]: info: Loading plugin "alsa_controller"... Mar 29 23:36:01 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 23:36:01 volumio volumio[1118]: info: Loading plugin "wizard"... Mar 29 23:36:01 volumio volumio[1118]: info: Loading plugin "networkfs"... Mar 29 23:36:01 volumio sudo[1388]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 29 23:36:01 volumio sudo[1377]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 23:36:01 volumio sudo[1375]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 23:36:01 volumio sudo[1377]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:01 volumio sudo[1388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:01 volumio sudo[1375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:01 volumio sudo[1375]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:01 volumio sudo[1388]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:01 volumio sudo[1377]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:01 volumio sudo[1416]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=081273Lok,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.1 //192.168.8.9/music /mnt/NAS/music Mar 29 23:36:01 volumio sudo[1416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:01 volumio volumio[1118]: info: Starting Udev Watcher for removable devices Mar 29 23:36:01 volumio sudo[1419]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hireslok,password=081273Lok,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.1 //192.168.8.44/Music /mnt/NAS/hireslok Mar 29 23:36:01 volumio sudo[1419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:01 volumio volumio[1118]: info: Ignoring mount for partition: boot Mar 29 23:36:01 volumio volumio[1118]: info: Ignoring mount for partition: volumio Mar 29 23:36:01 volumio volumio[1118]: info: Ignoring mount for partition: volumio_data Mar 29 23:36:01 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 29 23:36:01 volumio volumio[1118]: info: Loading plugin "volumio_command_line_client"... Mar 29 23:36:01 volumio volumio[1118]: info: Loading plugin "upnp"... Mar 29 23:36:01 volumio volumio[1118]: info: [1774798561784] Starting Upmpd Daemon Mar 29 23:36:01 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 29 23:36:01 volumio volumio[1118]: info: Loading plugin "my_music"... Mar 29 23:36:01 volumio volumio[1118]: info: Loading plugin "mpd"... Mar 29 23:36:01 volumio kernel: netfs: FS-Cache loaded Mar 29 23:36:01 volumio kernel: Key type dns_resolver registered Mar 29 23:36:02 volumio dhcpcd[793]: eth0: leased 192.168.8.36 for 300 seconds Mar 29 23:36:02 volumio dhcpcd[793]: eth0: adding route to 192.168.8.0/24 Mar 29 23:36:02 volumio avahi-daemon[768]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.8.36. Mar 29 23:36:02 volumio avahi-daemon[768]: New relevant interface eth0.IPv4 for mDNS. Mar 29 23:36:02 volumio avahi-daemon[768]: Registering new address record for 192.168.8.36 on eth0.IPv4. Mar 29 23:36:02 volumio systemd[1]: welcome.service: Deactivated successfully. Mar 29 23:36:02 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 29 23:36:02 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 29 23:36:02 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 29 23:36:02 volumio welcome[1452]: Resolved ip:[1] 192.168.8.36 Mar 29 23:36:02 volumio dhcpcd[793]: eth0: adding default route via 192.168.8.1 Mar 29 23:36:02 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 29 23:36:02 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Mar 29 23:36:02 volumio systemd[1]: Started nmbd.service - Samba NMB Daemon. Mar 29 23:36:02 volumio nmbd[1108]: [2026/03/29 23:36:02.340803, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 29 23:36:02 volumio nmbd[1108]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.8.36 for name WORKGROUP<1d>. Mar 29 23:36:02 volumio nmbd[1108]: This response was from IP 192.168.8.44, reporting an IP address of 192.168.8.44. Mar 29 23:36:02 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... Mar 29 23:36:02 volumio kernel: Key type cifs.spnego registered Mar 29 23:36:02 volumio kernel: Key type cifs.idmap registered Mar 29 23:36:02 volumio kernel: CIFS: Attempting to mount //192.168.8.44/Music Mar 29 23:36:02 volumio volumio-remote-updater[781]: [2026-03-29 23:36:02] [connect] Successful connection Mar 29 23:36:02 volumio sudo[1419]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:02 volumio kernel: CIFS: Attempting to mount //192.168.8.9/music Mar 29 23:36:03 volumio winbindd[1485]: [2026/03/29 23:36:03.077891, 0] ../../source3/winbindd/winbindd.c:1440(main) Mar 29 23:36:03 volumio winbindd[1485]: winbindd version 4.17.12-Debian started. Mar 29 23:36:03 volumio winbindd[1485]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Mar 29 23:36:03 volumio winbindd[1485]: [2026/03/29 23:36:03.113036, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) Mar 29 23:36:03 volumio winbindd[1485]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 29 23:36:03 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. Mar 29 23:36:03 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... Mar 29 23:36:03 volumio ntpd[1069]: IO: Listen normally on 3 eth0 192.168.8.36:123 Mar 29 23:36:03 volumio ntpd[1069]: IO: new interface(s) found: waking up resolver Mar 29 23:36:03 volumio ntpd[1069]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 29 23:36:03 volumio ntpd[1069]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Mar 29 23:36:03 volumio ntpd[1069]: DNS: Pool taking: 118.143.17.83 Mar 29 23:36:03 volumio ntpd[1069]: DNS: Pool taking: 183.179.255.44 Mar 29 23:36:03 volumio ntpd[1069]: DNS: Pool taking: 119.28.230.190 Mar 29 23:36:03 volumio ntpd[1069]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Mar 29 23:36:03 volumio sudo[1416]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:03 volumio volumio[1118]: info: Loading plugin "upnp_browser"... Mar 29 23:36:03 volumio smbd[1512]: [2026/03/29 23:36:03.697007, 0] ../../source3/smbd/server.c:1741(main) Mar 29 23:36:03 volumio smbd[1512]: smbd version 4.17.12-Debian started. Mar 29 23:36:03 volumio smbd[1512]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Mar 29 23:36:04 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Mar 29 23:36:04 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Mar 29 23:36:04 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Mar 29 23:36:04 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Mar 29 23:36:04 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Mar 29 23:36:04 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Mar 29 23:36:04 volumio systemd[1]: Startup finished in 11.650s (firmware) + 11.252s (loader) + 23.236s (kernel) + 17.947s (userspace) = 1min 4.086s. Mar 29 23:36:04 volumio ntpd[1069]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 29 23:36:04 volumio ntpd[1069]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Mar 29 23:36:04 volumio ntpd[1069]: DNS: Pool taking: 162.159.200.123 Mar 29 23:36:04 volumio ntpd[1069]: DNS: Pool taking: 17.253.84.253 Mar 29 23:36:04 volumio ntpd[1069]: DNS: Pool taking: 222.166.0.136 Mar 29 23:36:04 volumio ntpd[1069]: DNS: Pool taking: 2a12:bec0:168:343::123 Mar 29 23:36:04 volumio ntpd[1069]: DNS: Pool taking: 2001:ac8:a:23:0:1:0:26 Mar 29 23:36:04 volumio ntpd[1069]: DNS: Pool taking: 240b:4001:278:8402:0:ffb6:c72b:3963 Mar 29 23:36:04 volumio ntpd[1069]: DNS: Pool taking: 2606:4700:f1::123 Mar 29 23:36:04 volumio ntpd[1069]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Mar 29 23:36:05 volumio volumio[1118]: info: Starting UPNP Browser Mar 29 23:36:05 volumio volumio[1118]: info: Loading plugin "alarm-clock"... Mar 29 23:36:05 volumio volumio[1118]: info: Loading plugin "airplay_emulation"... Mar 29 23:36:05 volumio volumio[1118]: info: Starting Shairport Sync Mar 29 23:36:05 volumio volumio[1118]: info: Loading plugin "last_100"... Mar 29 23:36:05 volumio volumio[1118]: info: Loading plugin "webradio"... Mar 29 23:36:05 volumio ntpd[1069]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 29 23:36:05 volumio ntpd[1069]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Mar 29 23:36:05 volumio ntpd[1069]: DNS: Pool taking: 162.159.200.1 Mar 29 23:36:05 volumio ntpd[1069]: DNS: Pool taking: 103.214.22.128 Mar 29 23:36:05 volumio ntpd[1069]: DNS: Pool taking: 223.255.185.3 Mar 29 23:36:05 volumio ntpd[1069]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Mar 29 23:36:05 volumio volumio[1118]: info: Loading plugin "i2s_dacs"... Mar 29 23:36:05 volumio volumio[1118]: info: I2S DAC not set, start Auto-detection Mar 29 23:36:05 volumio volumio[1118]: info: Loading plugin "volumiodiscovery"... Mar 29 23:36:05 volumio volumio[1118]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 29 23:36:05 volumio volumio[1118]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 29 23:36:05 volumio volumio[1118]: *** WARNING *** For more information see Mar 29 23:36:05 volumio volumio[1118]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 29 23:36:05 volumio volumio[1118]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 29 23:36:05 volumio node[1118]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 29 23:36:05 volumio volumio[1118]: *** WARNING *** For more information see Mar 29 23:36:05 volumio node[1118]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 29 23:36:05 volumio node[1118]: *** WARNING *** For more information see Mar 29 23:36:05 volumio node[1118]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 29 23:36:05 volumio node[1118]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 29 23:36:05 volumio node[1118]: *** WARNING *** For more information see Mar 29 23:36:05 volumio volumio[1118]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 29 23:36:05 volumio volumio[1118]: info: Discovery: Started advertising with name: Volumio Mar 29 23:36:05 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 29 23:36:05 volumio volumio[1118]: info: Loading plugin "outputs"... Mar 29 23:36:05 volumio volumio[1118]: info: Loading plugin "albumart"... Mar 29 23:36:05 volumio volumio[1118]: info: Plugin example_plugin is not enabled Mar 29 23:36:05 volumio volumio[1118]: info: Loading plugin "inputs"... Mar 29 23:36:05 volumio volumio[1118]: info: Loading plugin "updater_comm"... Mar 29 23:36:05 volumio sh[762]: eth0: leased 192.168.8.36 for 300 seconds Mar 29 23:36:05 volumio dhcpcd[778]: eth0: leased 192.168.8.36 for 300 seconds Mar 29 23:36:05 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Mar 29 23:36:05 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 23:36:05 volumio dhcpcd[778]: eth0: adding route to 192.168.8.0/24 Mar 29 23:36:05 volumio dhcpcd[778]: eth0: adding default route via 192.168.8.1 Mar 29 23:36:05 volumio sh[762]: eth0: adding route to 192.168.8.0/24 Mar 29 23:36:05 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 23:36:05 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Mar 29 23:36:05 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Mar 29 23:36:05 volumio systemd[1]: welcome.service: Deactivated successfully. Mar 29 23:36:05 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 29 23:36:05 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 29 23:36:05 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 29 23:36:05 volumio volumio[1549]: Forking 3 albumart workers Mar 29 23:36:05 volumio upmpdcli[1563]: Could not open config: /tmp/upmpdcli.conf Mar 29 23:36:05 volumio welcome[1585]: Resolved ip:[1] 192.168.8.36 Mar 29 23:36:05 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 29 23:36:05 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 29 23:36:05 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 29 23:36:05 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Mar 29 23:36:05 volumio sh[1640]: eth0=eth0 Mar 29 23:36:05 volumio ifplugd(eth0)[1053]: client: ifup: interface eth0 already configured Mar 29 23:36:05 volumio volumio[1118]: info: Plugin mpdemulation is not enabled Mar 29 23:36:05 volumio volumio[1118]: info: Loading plugin "rest_api"... Mar 29 23:36:05 volumio volumio[1118]: info: Loading plugin "websocket"... Mar 29 23:36:05 volumio volumio[1118]: info: Starting Socket.io Server version 1.7.4 Mar 29 23:36:05 volumio volumio[1118]: info: Loading i18n strings for locale en Mar 29 23:36:05 volumio volumio[1118]: Updating browse sources language Mar 29 23:36:05 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 23:36:05 volumio ifplugd(eth0)[1053]: Program executed successfully. Mar 29 23:36:05 volumio volumio[1604]: Starting albumart workers Mar 29 23:36:05 volumio volumio[1600]: Starting albumart workers Mar 29 23:36:05 volumio volumio[1592]: Starting albumart workers Mar 29 23:36:06 volumio ntpd[1069]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 29 23:36:06 volumio ntpd[1069]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Mar 29 23:36:06 volumio ntpd[1069]: DNS: Pool taking: 118.143.17.82 Mar 29 23:36:06 volumio ntpd[1069]: DNS: Pool taking: 47.243.51.23 Mar 29 23:36:06 volumio ntpd[1069]: DNS: Pool taking: 151.236.20.166 Mar 29 23:36:06 volumio ntpd[1069]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Mar 29 23:36:06 volumio dbus-daemon[769]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.8' (uid=0 pid=1335 comm="/usr/lib/chromium/chromium --show-component-extens") Mar 29 23:36:06 volumio systemd[1]: Starting bluetooth.service - Bluetooth service... Mar 29 23:36:06 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:36:06 volumio volumio[1118]: info: CoreCommandRouter::initPlayerControls Mar 29 23:36:06 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:06 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:06 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:06 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:06 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:06 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:06 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:06 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:06 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:36:06 volumio kernel: Bluetooth: Core ver 2.22 Mar 29 23:36:06 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Mar 29 23:36:06 volumio kernel: Bluetooth: HCI device and connection manager initialized Mar 29 23:36:06 volumio kernel: Bluetooth: HCI socket layer initialized Mar 29 23:36:06 volumio kernel: Bluetooth: L2CAP socket layer initialized Mar 29 23:36:06 volumio kernel: Bluetooth: SCO socket layer initialized Mar 29 23:36:06 volumio bluetoothd[1667]: Bluetooth daemon 5.83 Mar 29 23:36:06 volumio volumio[1118]: Express server listening on port 3000 Mar 29 23:36:06 volumio volumio[1118]: [Metrics] WebUI: 11s 248.48ms Mar 29 23:36:06 volumio volumio[1118]: info: Setting Device type: x86 Mar 29 23:36:06 volumio volumio[1118]: info: CoreStateMachine::resetVolumioState Mar 29 23:36:06 volumio volumio[1118]: info: CoreStateMachine::getcurrentVolume Mar 29 23:36:06 volumio volumio[1118]: info: CoreCommandRouter::volumioRetrievevolume Mar 29 23:36:06 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:06 volumio volumio[1118]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 29 23:36:06 volumio volumio[1118]: info: Completed loading Core Plugins Mar 29 23:36:06 volumio volumio[1118]: info: Preparing to generate the ALSA configuration file Mar 29 23:36:06 volumio dbus-daemon[769]: [system] Successfully activated service 'org.bluez' Mar 29 23:36:06 volumio systemd[1]: Started bluetooth.service - Bluetooth service. Mar 29 23:36:06 volumio volumio[1118]: info: Volumio Network Manager: Network status updated: 0 Mar 29 23:36:06 volumio bluetoothd[1667]: Starting SDP server Mar 29 23:36:06 volumio bluetoothd[1667]: Excluding (cli) sap Mar 29 23:36:06 volumio volumio[1118]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 29 23:36:06 volumio volumio[1118]: wlan0 Interface doesn't support scanning. Mar 29 23:36:06 volumio volumio[1118]: info: Cannot use regular scanning, forcing with ap-force Mar 29 23:36:06 volumio bluetoothd[1667]: Excluding (cli) scanparam Mar 29 23:36:06 volumio bluetoothd[1667]: Excluding (cli) midi Mar 29 23:36:06 volumio bluetoothd[1667]: Excluding (cli) battery Mar 29 23:36:06 volumio sudo[1717]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 29 23:36:06 volumio sudo[1717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:07 volumio sudo[1717]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:07 volumio volumio[1118]: command failed: No such device (-19) Mar 29 23:36:07 volumio volumio[1118]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 29 23:36:07 volumio volumio[1118]: command failed: No such device (-19) Mar 29 23:36:07 volumio volumio[1118]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Mar 29 23:36:07 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Mar 29 23:36:07 volumio kernel: Bluetooth: BNEP filters: protocol multicast Mar 29 23:36:07 volumio kernel: Bluetooth: BNEP socket layer initialized Mar 29 23:36:07 volumio bluetoothd[1667]: Bluetooth management interface 1.23 initialized Mar 29 23:36:07 volumio dbus-daemon[769]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.11' (uid=0 pid=1667 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 29 23:36:07 volumio systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Mar 29 23:36:07 volumio volumio[1118]: verbose: New Socket.io Connection to 192.168.8.36 from 192.168.8.42 UA: Mozilla/5.0 (Linux; Android 6.0.1; SM-P350 Build/MMB29M; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/106.0.5249.126 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Mar 29 23:36:07 volumio volumio[1118]: info: Reloading queue from file Mar 29 23:36:07 volumio volumio-remote-updater[781]: [2026-03-29 23:36:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1774798562 101 Mar 29 23:36:07 volumio volumio[1118]: 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: 2 Mar 29 23:36:07 volumio volumio[1118]: info: VolumeController:: Volume=100 Mute =false Mar 29 23:36:07 volumio volumio[1118]: info: CoreStateMachine::pushState Mar 29 23:36:07 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::volumioPushState Mar 29 23:36:07 volumio volumio[1118]: info: CoreStateMachine::updateTrackBlock Mar 29 23:36:07 volumio volumio[1118]: info: CorePlayQueue::getTrackBlock Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::volumioRetrievevolume Mar 29 23:36:07 volumio volumio[1118]: info: CoreStateMachine::setRepeat null single undefined Mar 29 23:36:07 volumio volumio[1118]: info: CoreStateMachine::pushState Mar 29 23:36:07 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::volumioPushState Mar 29 23:36:07 volumio volumio[1118]: info: CoreStateMachine::setRandom null Mar 29 23:36:07 volumio volumio[1118]: info: CoreStateMachine::pushState Mar 29 23:36:07 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::volumioPushState Mar 29 23:36:07 volumio volumio[1118]: verbose: New Socket.io Connection to 192.168.8.36:3000 from 192.168.8.42 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Mar 29 23:36:07 volumio volumio[1118]: info: Asound.conf file unchanged, so no further update is needed Mar 29 23:36:07 volumio volumio[1118]: info: Output device has changed, restarting MPD Mar 29 23:36:07 volumio volumio[1118]: info: Output device has changed, restarting Shairport Sync Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:07 volumio sudo[1740]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 23:36:07 volumio sudo[1740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:07 volumio sudo[1737]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 23:36:07 volumio sudo[1737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:07 volumio sudo[1737]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:07 volumio volumio[1118]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 23:36:07 volumio volumio[1118]: info: ___________ START PLUGINS ___________ Mar 29 23:36:07 volumio volumio[1118]: info: ControllerMpd::onStart: Initializing MPD Mar 29 23:36:07 volumio volumio[1118]: info: Creating MPD Configuration file Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 23:36:07 volumio volumio[1118]: info: [1774798567204] CoreMusicLibrary::Adding element Media Servers Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 23:36:07 volumio dbus-daemon[769]: [system] Successfully activated service 'org.freedesktop.hostname1' Mar 29 23:36:07 volumio sudo[1749]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Mar 29 23:36:07 volumio sudo[1751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 23:36:07 volumio sudo[1751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:07 volumio sudo[1749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:07 volumio sudo[1751]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:07 volumio sudo[1753]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 23:36:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 29 23:36:07 volumio sudo[1753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:07 volumio volumio[1118]: info: UPNP Browser: Client initialized successfully Mar 29 23:36:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 29 23:36:07 volumio systemd[1]: Started systemd-hostnamed.service - Hostname Service. Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:07 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 29 23:36:07 volumio sudo[1749]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:07 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 29 23:36:07 volumio volumio[1118]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:07 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 29 23:36:07 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 29 23:36:07 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 29 23:36:07 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 29 23:36:07 volumio volumio[1118]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 23:36:07 volumio volumio[1118]: info: [1774798567277] CoreMusicLibrary::Adding element Last_100 Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 23:36:07 volumio volumio[1118]: info: [1774798567279] CoreMusicLibrary::Adding element Webradio Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 23:36:07 volumio volumio[1118]: info: Initializing BBC Radios Mar 29 23:36:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 29 23:36:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: Volumio Calling Home Mar 29 23:36:07 volumio sudo[1771]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 29 23:36:07 volumio sudo[1771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 29 23:36:07 volumio sudo[1785]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 29 23:36:07 volumio sudo[1771]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:07 volumio volumio[1118]: verbose: New Socket.io Connection to 192.168.8.36 from 192.168.8.42 UA: Mozilla/5.0 (Linux; Android 6.0.1; SM-P350 Build/MMB29M; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/106.0.5249.126 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Mar 29 23:36:07 volumio volumio[1118]: info: MPD Permissions set Mar 29 23:36:07 volumio volumio[1118]: info: MPD Permissions set Mar 29 23:36:07 volumio volumio[1118]: info: VolumeController:: Volume=100 Mute =false Mar 29 23:36:07 volumio volumio[1118]: info: CoreStateMachine::pushState Mar 29 23:36:07 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::volumioPushState Mar 29 23:36:07 volumio volumio[1118]: info: Discovery: adding 849b51c3-caba-4f52-b002-e85477c26512 Mar 29 23:36:07 volumio volumio[1118]: info: Discovery: Found device Volumio Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:07 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:07 volumio volumio[1118]: info: Discovery: this is already registered, 849b51c3-caba-4f52-b002-e85477c26512 Mar 29 23:36:07 volumio volumio[1118]: info: Discovery: Found device Volumio Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:07 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:07 volumio volumio[1118]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 29 23:36:07 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:07 volumio volumio[1118]: info: Volumio called home Mar 29 23:36:07 volumio volumio[1118]: info: Starting Shairport Sync Mar 29 23:36:07 volumio volumio[1118]: info: Starting Shairport Sync Mar 29 23:36:07 volumio volumio[1118]: info: Starting Shairport Sync Mar 29 23:36:07 volumio sudo[1826]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 23:36:07 volumio sudo[1826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:07 volumio sudo[1824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 23:36:07 volumio sudo[1824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:07 volumio sudo[1822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 23:36:07 volumio sudo[1822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:07 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 29 23:36:07 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 29 23:36:07 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 23:36:07 volumio systemd[1]: shairport-sync.service: Consumed 1.932s CPU time. Mar 29 23:36:08 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:08 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:08 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 23:36:08 volumio sudo[1824]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:08 volumio sudo[1826]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:08 volumio sudo[1822]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:08 volumio volumio[1118]: info: Shairport-Sync Started Mar 29 23:36:08 volumio volumio[1118]: Error adding Membership: Error: addMembership EINVAL Mar 29 23:36:08 volumio volumio[1118]: info: Shairport-Sync Started Mar 29 23:36:08 volumio volumio[1118]: info: Shairport-Sync Started Mar 29 23:36:08 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:08.605+08:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Mar 29 23:36:08 volumio volumio[1118]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Mar 29 23:36:08 volumio volumio[1118]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Mar 29 23:36:08 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:08.632+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist" Mar 29 23:36:08 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:08.633+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist" Mar 29 23:36:08 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:08.634+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist" Mar 29 23:36:08 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:08.634+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist" Mar 29 23:36:08 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:08.635+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist" Mar 29 23:36:08 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:08.635+08:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" Mar 29 23:36:08 volumio volumio[1118]: info: Received Get System Info Mar 29 23:36:08 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:36:08 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:36:08 volumio volumio[1118]: info: Discovery: Getting this device information Mar 29 23:36:08 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:08 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:08 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:36:08 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:08.640+08:00 level=INFO msg="system info for 14c62783222c716ca9b0d4f7cd2f51ac" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Mar 29 23:36:08 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:08.724+08:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 29 23:36:08 volumio volumio[1118]: info: Received Get System Info Mar 29 23:36:08 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:36:08 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:36:08 volumio volumio[1118]: info: Discovery: Getting this device information Mar 29 23:36:08 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:08 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:08 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:36:08 volumio volumio-remote-updater[781]: Test mode disabled Mar 29 23:36:08 volumio volumio-remote-updater[781]: Alpha mode disabled Mar 29 23:36:08 volumio volumio-remote-updater[781]: Alpha legacy test mode disabled Mar 29 23:36:09 volumio volumio[1118]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 29 23:36:09 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 29 23:36:09 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 23:36:09 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:09.152+08:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Mar 29 23:36:09 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:09.152+08:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Mar 29 23:36:09 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:09.152+08:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Mar 29 23:36:09 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 23:36:09 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 23:36:09 volumio volumio[1118]: info: Received Get System Info Mar 29 23:36:09 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:36:09 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:36:09 volumio volumio[1118]: info: Discovery: Getting this device information Mar 29 23:36:09 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:09 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:09 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:36:09 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 23:36:09 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 23:36:09 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:09.162+08:00 level=INFO msg="enabling local network discovery" Mar 29 23:36:09 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:09.500+08:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.8.3:47172 Mar 29 23:36:10 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:10.066+08:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 29 23:36:10 volumio volumio[1118]: verbose: New Socket.io Connection to 192.168.8.36 from 192.168.8.42 UA: Mozilla/5.0 (Linux; Android 6.0.1; SM-P350 Build/MMB29M; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/106.0.5249.126 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 29 23:36:10 volumio ntpd[1069]: CLOCK: time stepped by 0.391727 Mar 29 23:36:10 volumio ntpd[1069]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Mar 29 23:36:11 volumio volumio[1118]: info: Volumio Network Manager: Network status updated: 1 Mar 29 23:36:12 volumio sudo[1940]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 23:36:12 volumio sudo[1938]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 23:36:12 volumio sudo[1940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:12 volumio sudo[1938]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:12 volumio sudo[1938]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:12 volumio sudo[1940]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:12 volumio sudo[1957]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 29 23:36:12 volumio sudo[1957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:36:15 volumio volumio[1118]: info: Discovery: Getting this device information Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:15 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:36:15 volumio volumio[1118]: verbose: New Socket.io Connection to 192.168.8.36:3000 from 192.168.8.42 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:15 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:15 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:15 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::volumioGetQueue Mar 29 23:36:15 volumio volumio[1118]: info: CoreStateMachine::getQueue Mar 29 23:36:15 volumio volumio[1118]: info: CorePlayQueue::getQueue Mar 29 23:36:15 volumio volumio[1118]: info: Listing playlists Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 23:36:15 volumio volumio[1118]: info: Received Get System Info Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:36:15 volumio volumio[1118]: info: Discovery: Getting this device information Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:15 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:36:15 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:15 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:16 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:16.739+08:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.8.3:32968 Mar 29 23:36:18 volumio volumio[1118]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 29 23:36:18 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Mar 29 23:36:19 volumio mpd[1786]: 2026-03-29T23:36:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 29 23:36:19 volumio volumio[1118]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 29 23:36:20 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 29 23:36:20 volumio sudo[1753]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:20 volumio sudo[1740]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:20 volumio volumio[1118]: info: Completed starting Core Plugins Mar 29 23:36:20 volumio volumio[1118]: info: ------------------------------------------- Mar 29 23:36:20 volumio volumio[1118]: info: ----- MyVolumio plugins startup ---- Mar 29 23:36:20 volumio volumio[1118]: info: ------------------------------------------- Mar 29 23:36:20 volumio volumio[1118]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 29 23:36:20 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 23:36:20 volumio volumio[1118]: info: MPD running with PID1786 Mar 29 23:36:20 volumio volumio[1118]: ,establishing connection Mar 29 23:36:20 volumio volumio[1118]: error: MPD error: The expression evaluated to a falsy value: Mar 29 23:36:20 volumio volumio[1118]: assert.ok(self.idling) Mar 29 23:36:20 volumio volumio[1118]: error: The expression evaluated to a falsy value: Mar 29 23:36:20 volumio volumio[1118]: assert.ok(self.idling) Mar 29 23:36:20 volumio volumio[1118]: error: MPD error: The expression evaluated to a falsy value: Mar 29 23:36:20 volumio volumio[1118]: assert.ok(self.idling) Mar 29 23:36:20 volumio volumio[1118]: error: The expression evaluated to a falsy value: Mar 29 23:36:20 volumio volumio[1118]: assert.ok(self.idling) Mar 29 23:36:20 volumio volumio[1118]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 23:36:20 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 23:36:20 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:20 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:20 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 23:36:20 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 23:36:20 volumio volumio[1118]: info: Received Get System Info Mar 29 23:36:20 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:36:20 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:36:20 volumio volumio[1118]: info: Discovery: Getting this device information Mar 29 23:36:20 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:20 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:20 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:36:20 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:20 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:20 volumio volumio[1118]: info: Listing playlists Mar 29 23:36:20 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 23:36:20 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 23:36:20 volumio volumio[1118]: error: updateQueue error: null Mar 29 23:36:21 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Mar 29 23:36:21 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 23:36:21 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 23:36:21 volumio sudo[1957]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:21 volumio volumio[1118]: info: Upmpdcli Daemon Started Mar 29 23:36:24 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:24 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:24 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 23:36:24 volumio volumio[1118]: info: Received Get System Info Mar 29 23:36:24 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:36:24 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:36:24 volumio volumio[1118]: info: Discovery: Getting this device information Mar 29 23:36:24 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:24 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:24 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:36:25 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 29 23:36:28 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 23:36:28 volumio volumio[1118]: info: Received Get System Info Mar 29 23:36:28 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:36:28 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:36:28 volumio volumio[1118]: info: Discovery: Getting this device information Mar 29 23:36:28 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:28 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:28 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 29 23:36:28 volumio volumio[1118]: info: Adding plugin bluetooth to MyMusic Plugins Mar 29 23:36:28 volumio volumio[1118]: info: Adding plugin multiroom to MyMusic Plugins Mar 29 23:36:28 volumio volumio[1118]: info: Adding plugin metavolumio to MyMusic Plugins Mar 29 23:36:28 volumio volumio[1118]: info: Adding plugin cd_controller to MyMusic Plugins Mar 29 23:36:28 volumio volumio[1118]: info: Adding plugin qobuzconnect to MyMusic Plugins Mar 29 23:36:28 volumio volumio[1118]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 29 23:36:28 volumio volumio[1118]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 29 23:36:28 volumio volumio[1118]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 29 23:36:29 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 29 23:36:29 volumio volumio[1118]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 29 23:36:29 volumio volumio[1118]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 29 23:36:29 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:29 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:29 volumio volumio[1118]: info: Starting MyVolumio Remote Streaming Endpoints Mar 29 23:36:29 volumio volumio[1118]: info: MyVolumio login type: Token Mar 29 23:36:29 volumio volumio[1118]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 29 23:36:29 volumio volumio[1118]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 29 23:36:30 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 29 23:36:30 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 29 23:36:30 volumio volumio[1118]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 29 23:36:30 volumio volumio[1118]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 29 23:36:30 volumio volumio[1118]: info: Streaming services startup Mar 29 23:36:30 volumio volumio[1118]: info: Starting Streaming Daemon Mar 29 23:36:30 volumio sudo[2055]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 29 23:36:30 volumio sudo[2055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:30 volumio volumio[1118]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 29 23:36:30 volumio sudo[2055]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:30 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 29 23:36:30 volumio volumio[1118]: info: MyVolumio login type: Token Mar 29 23:36:30 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 23:36:30 volumio volumio[1118]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 29 23:36:30 volumio volumio[1118]: error: Cannot start Volumio Streaming Daemon Mar 29 23:36:30 volumio volumio[1118]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 29 23:36:30 volumio volumio[1118]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 29 23:36:30 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 23:36:33 volumio volumio[1118]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 29 23:36:33 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 23:36:33 volumio volumio[1118]: info: Received Get System Info Mar 29 23:36:33 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:36:33 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:36:33 volumio volumio[1118]: info: Discovery: Getting this device information Mar 29 23:36:33 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:33 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:33 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:36:34 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 23:36:34 volumio volumio[1118]: info: Received Get System Info Mar 29 23:36:34 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:36:34 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:36:34 volumio volumio[1118]: info: Discovery: Getting this device information Mar 29 23:36:34 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:34 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:34 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:36:34 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 23:36:34 volumio volumio[1118]: info: Received Get System Info Mar 29 23:36:34 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:36:34 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:36:34 volumio volumio[1118]: info: Discovery: Getting this device information Mar 29 23:36:34 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:34 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:34 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:36:35 volumio upmpdcli[2080]: writing RSA key Mar 29 23:36:37 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Mar 29 23:36:38 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 23:36:38 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:38 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 23:36:38 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:36:38 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:36:38 volumio volumio[1118]: info: Discovery: Getting this device information Mar 29 23:36:38 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:38 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:38 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:36:38 volumio volumio-remote-updater[781]: Test mode disabled Mar 29 23:36:38 volumio volumio-remote-updater[781]: Alpha mode disabled Mar 29 23:36:38 volumio volumio-remote-updater[781]: Alpha legacy test mode disabled Mar 29 23:36:38 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 29 23:36:38 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 23:36:38 volumio volumio[1118]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 23:36:38 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 23:36:38 volumio volumio[1118]: info: Received Get System Version Mar 29 23:36:38 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 23:36:38 volumio volumio[1118]: info: Received Get System Info Mar 29 23:36:38 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:36:38 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:36:38 volumio volumio[1118]: info: Discovery: Getting this device information Mar 29 23:36:38 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:38 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:38 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:36:39 volumio volumio[1118]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 29 23:36:39 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 29 23:36:41 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 23:36:41 volumio volumio[1118]: info: CURURI: music-library Mar 29 23:36:41 volumio volumio[1118]: info: MyVolumio token set successfully Mar 29 23:36:41 volumio volumio[1118]: info: MYVOLUMIO: Adding device Mar 29 23:36:41 volumio volumio[1118]: info: MYVOLUMIO: Evaluating Server Mar 29 23:36:44 volumio volumio[1118]: info: MyVolumio status changed Mar 29 23:36:44 volumio volumio[1118]: info: Streaming services startup Mar 29 23:36:44 volumio volumio[1118]: info: Starting Streaming Daemon Mar 29 23:36:44 volumio volumio[1118]: info: Removing browser output: myVolumio user plan is not superstar Mar 29 23:36:44 volumio volumio[1118]: info: Removing audio output: Mar 29 23:36:44 volumio volumio[1118]: info: Stoppping Tunnel 1 Mar 29 23:36:44 volumio sudo[2139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 29 23:36:44 volumio sudo[2139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:44 volumio sudo[2141]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 29 23:36:44 volumio sudo[2141]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:36:44 volumio sudo[2139]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:44 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. Mar 29 23:36:44 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. Mar 29 23:36:44 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. Mar 29 23:36:44 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. Mar 29 23:36:44 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. Mar 29 23:36:44 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. Mar 29 23:36:44 volumio sudo[2141]: pam_unix(sudo:session): session closed for user root Mar 29 23:36:45 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 23:36:45 volumio volumio[1118]: info: Setting Geolocation for MyVolumio to as1 Mar 29 23:36:45 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:45 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:45 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:45 volumio volumio[1118]: info: Remote SSH Stopped Mar 29 23:36:45 volumio volumio[1118]: error: Cannot start Volumio Streaming Daemon Mar 29 23:36:46 volumio volumio[1118]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 29 23:36:46 volumio volumio[1118]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 29 23:36:47 volumio volumio[1118]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 29 23:36:47 volumio volumio[1118]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 29 23:36:47 volumio volumio[1118]: info: Updating MyVolumio device info Mar 29 23:36:47 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:47 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:47 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:47 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:47.655+08:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.8.3:32966 Mar 29 23:36:49 volumio volumio[1118]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 29 23:36:49 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:49 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:49 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 29 23:36:49 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 29 23:36:49 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 23:36:49 volumio volumio[1118]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 23:36:49 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 23:36:50 volumio volumio[1118]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 29 23:36:50 volumio volumio[1118]: info: Completed starting MyVolumio Plugin Mar 29 23:36:50 volumio volumio[1118]: [Metrics] CommandRouter: 53s 543.41ms Mar 29 23:36:50 volumio volumio[1118]: info: CoreCommandRouter::volumiosetStartupVolume Mar 29 23:36:50 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:36:50 volumio volumio[1118]: info: VolumeController:: Setting startup Volume 100 Mar 29 23:36:50 volumio volumio[1118]: info: VolumeController::SetAlsaVolume100 Mar 29 23:36:50 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:50 volumio volumio[1118]: info: CoreCommandRouter::Close All Modals sent Mar 29 23:36:50 volumio volumio[1118]: info: CoreCommandRouter::Close All Modals sent Mar 29 23:36:50 volumio volumio[1118]: info: CoreStateMachine::pushState Mar 29 23:36:50 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:50 volumio volumio[1118]: info: CoreCommandRouter::volumioPushState Mar 29 23:36:50 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:50.632+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.3:32968 @ 0xc0004b2090" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 23:36:50 volumio volumio5-onboarding[1762]: time=2026-03-29T23:36:50.634+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.3:32968 @ 0xc0004b2090" id= title= Mar 29 23:36:51 volumio volumio[1118]: info: MYVOLUMIO: Adding device Mar 29 23:36:51 volumio volumio[1118]: info: MYVOLUMIO: Evaluating Server Mar 29 23:36:52 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 29 23:36:52 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 23:36:52 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 29 23:36:53 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:36:53 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:36:54 volumio volumio[1118]: info: Setting Geolocation for MyVolumio to as1 Mar 29 23:36:54 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:54 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:54 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:56 volumio volumio[1118]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 29 23:36:56 volumio volumio[1118]: info: Updating MyVolumio device info Mar 29 23:36:56 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:56 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:56 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:36:58 volumio volumio[1118]: info: BOOT COMPLETED Mar 29 23:37:00 volumio volumio[1118]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 29 23:37:11 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:37:11 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:37:11 volumio volumio[1118]: info: Discovery: Getting this device information Mar 29 23:37:11 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:37:11 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:37:11 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:37:19 volumio volumio[1118]: Cannot compose Albumart path Mar 29 23:37:22 volumio volumio[1118]: info: Preload queue cleared Mar 29 23:37:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:37:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:37:37 volumio volumio[1118]: info: Discovery: Getting this device information Mar 29 23:37:37 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 29 23:37:37 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 29 23:37:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:37:39 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 23:37:39 volumio volumio[1118]: info: CURURI: music-library/NAS Mar 29 23:37:39 volumio volumio[1118]: info: Preload queue cleared Mar 29 23:37:41 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 23:37:41 volumio volumio[1118]: info: CURURI: music-library/NAS/hireslok Mar 29 23:37:41 volumio volumio[1118]: info: Preload queue cleared Mar 29 23:37:42 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 23:37:42 volumio volumio[1118]: info: CURURI: music-library/NAS/hireslok/01.爵士546张HiRes（565G） Mar 29 23:37:42 volumio volumio[1118]: info: Preload queue cleared Mar 29 23:37:58 volumio volumio[1118]: info: Preload queue cleared Mar 29 23:37:58 volumio volumio[1118]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 23:37:58 volumio volumio[1118]: info: CoreStateMachine::ClearQueue Mar 29 23:37:58 volumio volumio[1118]: info: CoreStateMachine::stop Mar 29 23:37:58 volumio volumio[1118]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 23:37:58 volumio volumio[1118]: info: CorePlayQueue::clearPlayQueue Mar 29 23:37:58 volumio volumio[1118]: info: CorePlayQueue::saveQueue Mar 29 23:37:58 volumio volumio[1118]: info: CoreCommandRouter::volumioPushQueue Mar 29 23:37:58 volumio volumio[1118]: info: CoreStateMachine::addQueueItems Mar 29 23:37:58 volumio volumio[1118]: info: CorePlayQueue::addQueueItems Mar 29 23:37:58 volumio volumio[1118]: info: Preload queue cleared Mar 29 23:37:58 volumio volumio[1118]: info: Adding Item to queue: music-library/NAS/hireslok/01.爵士546张HiRes（565G）/1957 - John Coltrane - Blue Train (Classic Records HDAD rips, 24-192) Mar 29 23:37:58 volumio volumio[1118]: info: Exploding uri music-library/NAS/hireslok/01.爵士546张HiRes（565G）/1957 - John Coltrane - Blue Train (Classic Records HDAD rips, 24-192) in service mpd Mar 29 23:37:58 volumio volumio[1118]: info: ALBUMART /albumart?cacheid=482&web=John%20Coltrane/Blue%20Train%20-%20DVD-A%20rip/extralarge&path=%2Fmnt%2FNAS%2Fhireslok%2F01.%E7%88%B5%E5%A3%AB546%E5%BC%A0HiRes%EF%BC%88565G%EF%BC%89%2F1957%20-%20John%20Coltrane%20-%20Blue%20Train%20(Classic%20Records%20HDAD%20rips%2C%2024-192)%2F01%20-%20Blue%20Train.flac&metadata=false Mar 29 23:37:58 volumio volumio[1118]: info: URI /mnt/NAS/hireslok/01.爵士546张HiRes（565G）/1957 - John Coltrane - Blue Train (Classic Records HDAD rips, 24-192)/01 - Blue Train.flac Mar 29 23:37:58 volumio volumio[1118]: info: ALBUMART /albumart?cacheid=482&web=John%20Coltrane/Blue%20Train%20-%20DVD-A%20rip/extralarge&path=%2Fmnt%2FNAS%2Fhireslok%2F01.%E7%88%B5%E5%A3%AB546%E5%BC%A0HiRes%EF%BC%88565G%EF%BC%89%2F1957%20-%20John%20Coltrane%20-%20Blue%20Train%20(Classic%20Records%20HDAD%20rips%2C%2024-192)%2F02%20-%20Moment's%20Notice.flac&metadata=false Mar 29 23:37:58 volumio volumio[1118]: info: URI /mnt/NAS/hireslok/01.爵士546张HiRes（565G）/1957 - John Coltrane - Blue Train (Classic Records HDAD rips, 24-192)/02 - Moment's Notice.flac Mar 29 23:37:58 volumio volumio[1118]: info: ALBUMART /albumart?cacheid=482&web=John%20Coltrane/Blue%20Train%20-%20DVD-A%20rip/extralarge&path=%2Fmnt%2FNAS%2Fhireslok%2F01.%E7%88%B5%E5%A3%AB546%E5%BC%A0HiRes%EF%BC%88565G%EF%BC%89%2F1957%20-%20John%20Coltrane%20-%20Blue%20Train%20(Classic%20Records%20HDAD%20rips%2C%2024-192)%2F03%20-%20Locomotion.flac&metadata=false Mar 29 23:37:58 volumio volumio[1118]: info: URI /mnt/NAS/hireslok/01.爵士546张HiRes（565G）/1957 - John Coltrane - Blue Train (Classic Records HDAD rips, 24-192)/03 - Locomotion.flac Mar 29 23:37:58 volumio volumio[1118]: info: ALBUMART /albumart?cacheid=482&web=John%20Coltrane/Blue%20Train%20-%20DVD-A%20rip/extralarge&path=%2Fmnt%2FNAS%2Fhireslok%2F01.%E7%88%B5%E5%A3%AB546%E5%BC%A0HiRes%EF%BC%88565G%EF%BC%89%2F1957%20-%20John%20Coltrane%20-%20Blue%20Train%20(Classic%20Records%20HDAD%20rips%2C%2024-192)%2F04%20-%20I'm%20Old%20Fashioned.flac&metadata=false Mar 29 23:37:58 volumio volumio[1118]: info: URI /mnt/NAS/hireslok/01.爵士546张HiRes（565G）/1957 - John Coltrane - Blue Train (Classic Records HDAD rips, 24-192)/04 - I'm Old Fashioned.flac Mar 29 23:37:58 volumio volumio[1118]: info: ALBUMART /albumart?cacheid=482&web=John%20Coltrane/Blue%20Train%20-%20DVD-A%20rip/extralarge&path=%2Fmnt%2FNAS%2Fhireslok%2F01.%E7%88%B5%E5%A3%AB546%E5%BC%A0HiRes%EF%BC%88565G%EF%BC%89%2F1957%20-%20John%20Coltrane%20-%20Blue%20Train%20(Classic%20Records%20HDAD%20rips%2C%2024-192)%2F05%20-%20Lazy%20Bird.flac&metadata=false Mar 29 23:37:58 volumio volumio[1118]: info: URI /mnt/NAS/hireslok/01.爵士546张HiRes（565G）/1957 - John Coltrane - Blue Train (Classic Records HDAD rips, 24-192)/05 - Lazy Bird.flac Mar 29 23:37:58 volumio volumio[1118]: info: ALBUMART /albumart?cacheid=482&web=John%20Coltrane/Blue%20Train/extralarge&path=%2Fmnt%2FNAS%2Fhireslok%2F01.%E7%88%B5%E5%A3%AB546%E5%BC%A0HiRes%EF%BC%88565G%EF%BC%89%2F1957%20-%20John%20Coltrane%20-%20Blue%20Train%20(Classic%20Records%20HDAD%20rips%2C%2024-192)%2F1957%20-%20John%20Coltrane%20-%20Blue%20Train.cue&metadata=false Mar 29 23:37:58 volumio volumio[1118]: info: URI /mnt/NAS/hireslok/01.爵士546张HiRes（565G）/1957 - John Coltrane - Blue Train (Classic Records HDAD rips, 24-192)/1957 - John Coltrane - Blue Train.cue Mar 29 23:37:58 volumio volumio[1118]: info: ALBUMART /albumart?cacheid=482&web=John%20Coltrane/Blue%20Train/extralarge&path=%2Fmnt%2FNAS%2Fhireslok%2F01.%E7%88%B5%E5%A3%AB546%E5%BC%A0HiRes%EF%BC%88565G%EF%BC%89%2F1957%20-%20John%20Coltrane%20-%20Blue%20Train%20(Classic%20Records%20HDAD%20rips%2C%2024-192)%2F1957%20-%20John%20Coltrane%20-%20Blue%20Train.cue&metadata=false Mar 29 23:37:58 volumio volumio[1118]: info: URI /mnt/NAS/hireslok/01.爵士546张HiRes（565G）/1957 - John Coltrane - Blue Train (Classic Records HDAD rips, 24-192)/1957 - John Coltrane - Blue Train.cue Mar 29 23:37:58 volumio volumio[1118]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 23:37:58 volumio volumio[1118]: Error: Unable to resolve or reject the same promise twice Mar 29 23:37:58 volumio volumio[1118]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 29 23:37:58 volumio volumio[1118]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Mar 29 23:37:58 volumio volumio[1118]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 29 23:37:58 volumio volumio[1118]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 29 23:37:58 volumio volumio[1118]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 29 23:37:58 volumio volumio[1118]: at Socket.emit (node:events:514:28) Mar 29 23:37:58 volumio volumio[1118]: at addChunk (node:internal/streams/readable:343:12) Mar 29 23:37:58 volumio volumio[1118]: at readableAddChunk (node:internal/streams/readable:312:11) Mar 29 23:37:58 volumio volumio[1118]: at Readable.push (node:internal/streams/readable:253:10) Mar 29 23:37:58 volumio volumio[1118]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Mar 29 23:37:58 volumio volumio[1118]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 23:37:58 volumio sudo[2367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-29 23:36' Mar 29 23:37:58 volumio sudo[2367]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"