Apr 13 09:39:00 volumio volumio[1137]: info: Loading plugin "network"... Apr 13 09:39:00 volumio volumio[1137]: info: Refreshing Cached IP Addresses Apr 13 09:39:00 volumio volumio[1137]: info: Loading plugin "services"... Apr 13 09:39:00 volumio volumio[1137]: info: Loading plugin "volumio5onboarding"... Apr 13 09:39:00 volumio volumio[1137]: info: Loading plugin "alsa_controller"... Apr 13 09:39:00 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 09:39:00 volumio volumio[1137]: info: Loading plugin "wizard"... Apr 13 09:39:00 volumio volumio[1137]: info: Loading plugin "networkfs"... Apr 13 09:39:00 volumio sudo[1367]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 13 09:39:00 volumio sudo[1353]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 13 09:39:00 volumio sudo[1355]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 13 09:39:00 volumio sudo[1353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:00 volumio sudo[1367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:00 volumio sudo[1355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:00 volumio sudo[1353]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:00 volumio sudo[1355]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:00 volumio sudo[1367]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:01 volumio volumio[1137]: info: Starting Udev Watcher for removable devices Apr 13 09:39:01 volumio volumio[1137]: info: Mounting Device 320G Apr 13 09:39:01 volumio sudo[1410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/320G -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 13 09:39:01 volumio sudo[1410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:01 volumio volumio-remote-updater[805]: [2026-04-13 09:39:01] [connect] Successful connection Apr 13 09:39:02 volumio ntfs-3g[1425]: Version 2022.10.3 integrated FUSE 28 Apr 13 09:39:02 volumio ntfs-3g[1425]: Mounted /dev/sda1 (Read-Write, label "320G", NTFS 3.1) Apr 13 09:39:02 volumio ntfs-3g[1425]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 13 09:39:02 volumio ntfs-3g[1425]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Apr 13 09:39:02 volumio ntfs-3g[1425]: Global ownership and permissions enforced, configuration type 7 Apr 13 09:39:02 volumio sudo[1410]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:02 volumio volumio[1137]: info: Ignoring mount for partition: boot Apr 13 09:39:02 volumio volumio[1137]: info: Ignoring mount for partition: volumio Apr 13 09:39:02 volumio volumio[1137]: info: Ignoring mount for partition: volumio_data Apr 13 09:39:02 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 13 09:39:02 volumio volumio[1137]: info: Loading plugin "volumio_command_line_client"... Apr 13 09:39:02 volumio volumio[1137]: info: Loading plugin "upnp"... Apr 13 09:39:02 volumio volumio[1137]: info: [1776044342102] Starting Upmpd Daemon Apr 13 09:39:02 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 13 09:39:02 volumio volumio[1137]: info: Loading plugin "my_music"... Apr 13 09:39:02 volumio volumio[1137]: info: Loading plugin "mpd"... Apr 13 09:39:02 volumio dhcpcd[818]: eth0: carrier acquired Apr 13 09:39:02 volumio kernel: e1000e 0000:00:19.0 eth0: NIC Link is Up 100 Mbps Full Duplex, Flow Control: Rx/Tx Apr 13 09:39:02 volumio dhcpcd[818]: eth0: IAID 56:b6:56:de Apr 13 09:39:02 volumio dhcpcd[818]: eth0: adding address fe80::e64c:fbaa:d315:7af1 Apr 13 09:39:02 volumio dhcpcd[818]: ipv6_addaddr1: Permission denied Apr 13 09:39:02 volumio dhcpcd[818]: eth0: probing address 192.168.0.8/24 Apr 13 09:39:02 volumio ifplugd(eth0)[1071]: Link beat detected. Apr 13 09:39:02 volumio ifplugd(eth0)[1071]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 13 09:39:02 volumio ifplugd(eth0)[1071]: client: ifup: interface eth0 already configured Apr 13 09:39:02 volumio ifplugd(eth0)[1071]: Program executed successfully. Apr 13 09:39:03 volumio volumio[1137]: info: Loading plugin "upnp_browser"... Apr 13 09:39:03 volumio dhcpcd[818]: eth0: soliciting an IPv6 router Apr 13 09:39:03 volumio volumio[1137]: info: Starting UPNP Browser Apr 13 09:39:03 volumio volumio[1137]: info: Loading plugin "alarm-clock"... Apr 13 09:39:04 volumio volumio[1137]: info: Loading plugin "airplay_emulation"... Apr 13 09:39:04 volumio volumio[1137]: info: Starting Shairport Sync Apr 13 09:39:04 volumio volumio[1137]: info: Loading plugin "last_100"... Apr 13 09:39:04 volumio volumio[1137]: info: Loading plugin "webradio"... Apr 13 09:39:04 volumio volumio[1137]: info: Loading plugin "i2s_dacs"... Apr 13 09:39:04 volumio volumio[1137]: info: I2S DAC not set, start Auto-detection Apr 13 09:39:04 volumio volumio[1137]: info: Loading plugin "volumiodiscovery"... Apr 13 09:39:04 volumio volumio[1137]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 13 09:39:04 volumio volumio[1137]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 09:39:04 volumio volumio[1137]: *** WARNING *** For more information see Apr 13 09:39:04 volumio volumio[1137]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 13 09:39:04 volumio volumio[1137]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 09:39:04 volumio volumio[1137]: *** WARNING *** For more information see Apr 13 09:39:04 volumio node[1137]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 13 09:39:04 volumio node[1137]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 09:39:04 volumio node[1137]: *** WARNING *** For more information see Apr 13 09:39:04 volumio node[1137]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 13 09:39:04 volumio node[1137]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 09:39:04 volumio node[1137]: *** WARNING *** For more information see Apr 13 09:39:04 volumio volumio[1137]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 13 09:39:04 volumio volumio[1137]: info: Discovery: Started advertising with name: Volumio Apr 13 09:39:04 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 13 09:39:04 volumio volumio[1137]: info: Loading plugin "outputs"... Apr 13 09:39:04 volumio volumio[1137]: info: Loading plugin "albumart"... Apr 13 09:39:04 volumio volumio[1137]: info: Plugin example_plugin is not enabled Apr 13 09:39:04 volumio volumio[1137]: info: Loading plugin "inputs"... Apr 13 09:39:04 volumio volumio[1137]: info: Loading plugin "updater_comm"... Apr 13 09:39:04 volumio volumio[1455]: Forking 3 albumart workers Apr 13 09:39:04 volumio volumio[1466]: Starting albumart workers Apr 13 09:39:04 volumio volumio[1465]: Starting albumart workers Apr 13 09:39:04 volumio volumio[1467]: Starting albumart workers Apr 13 09:39:04 volumio volumio[1137]: info: Plugin mpdemulation is not enabled Apr 13 09:39:04 volumio volumio[1137]: info: Loading plugin "rest_api"... Apr 13 09:39:04 volumio volumio[1137]: info: Loading plugin "websocket"... Apr 13 09:39:04 volumio volumio[1137]: info: Starting Socket.io Server version 1.7.4 Apr 13 09:39:04 volumio volumio[1137]: info: Loading i18n strings for locale zh Apr 13 09:39:04 volumio volumio[1137]: Updating browse sources language Apr 13 09:39:04 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 09:39:05 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Apr 13 09:39:05 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 13 09:39:05 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 13 09:39:05 volumio upmpdcli[1514]: Could not open config: /tmp/upmpdcli.conf Apr 13 09:39:05 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 13 09:39:05 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 13 09:39:05 volumio dbus-daemon[798]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.7' (uid=0 pid=1332 comm="/usr/lib/chromium/chromium --show-component-extens") Apr 13 09:39:05 volumio systemd[1]: Starting bluetooth.service - Bluetooth service... Apr 13 09:39:05 volumio kernel: Bluetooth: Core ver 2.22 Apr 13 09:39:05 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Apr 13 09:39:05 volumio kernel: Bluetooth: HCI device and connection manager initialized Apr 13 09:39:05 volumio kernel: Bluetooth: HCI socket layer initialized Apr 13 09:39:05 volumio kernel: Bluetooth: L2CAP socket layer initialized Apr 13 09:39:05 volumio kernel: Bluetooth: SCO socket layer initialized Apr 13 09:39:05 volumio bluetoothd[1519]: Bluetooth daemon 5.83 Apr 13 09:39:05 volumio dbus-daemon[798]: [system] Successfully activated service 'org.bluez' Apr 13 09:39:05 volumio systemd[1]: Started bluetooth.service - Bluetooth service. Apr 13 09:39:05 volumio bluetoothd[1519]: Starting SDP server Apr 13 09:39:05 volumio bluetoothd[1519]: Excluding (cli) sap Apr 13 09:39:05 volumio bluetoothd[1519]: Excluding (cli) scanparam Apr 13 09:39:05 volumio bluetoothd[1519]: Excluding (cli) midi Apr 13 09:39:05 volumio bluetoothd[1519]: Excluding (cli) battery Apr 13 09:39:05 volumio dbus-daemon[798]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.10' (uid=0 pid=1519 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Apr 13 09:39:05 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Apr 13 09:39:05 volumio kernel: Bluetooth: BNEP filters: protocol multicast Apr 13 09:39:05 volumio kernel: Bluetooth: BNEP socket layer initialized Apr 13 09:39:05 volumio bluetoothd[1519]: Bluetooth management interface 1.23 initialized Apr 13 09:39:05 volumio systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Apr 13 09:39:05 volumio dbus-daemon[798]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 13 09:39:05 volumio systemd[1]: Started systemd-hostnamed.service - Hostname Service. Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::initPlayerControls Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 09:39:06 volumio volumio[1137]: Express server listening on port 3000 Apr 13 09:39:06 volumio volumio[1137]: [Metrics] WebUI: 10s 29.01ms Apr 13 09:39:06 volumio volumio[1137]: info: Setting Device type: x86 Apr 13 09:39:06 volumio volumio[1137]: info: CoreStateMachine::resetVolumioState Apr 13 09:39:06 volumio volumio[1137]: info: CoreStateMachine::getcurrentVolume Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::volumioRetrievevolume Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 09:39:06 volumio volumio[1137]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 13 09:39:06 volumio volumio[1137]: info: Completed loading Core Plugins Apr 13 09:39:06 volumio volumio[1137]: info: Preparing to generate the ALSA configuration file Apr 13 09:39:06 volumio volumio[1137]: info: Volumio Network Manager: Network status updated: 0 Apr 13 09:39:06 volumio volumio[1137]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 13 09:39:06 volumio volumio[1137]: wlan0 Interface doesn't support scanning. Apr 13 09:39:06 volumio volumio[1137]: info: Cannot use regular scanning, forcing with ap-force Apr 13 09:39:06 volumio sudo[1587]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 13 09:39:06 volumio sudo[1587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:06 volumio sudo[1587]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:06 volumio volumio[1137]: command failed: No such device (-19) Apr 13 09:39:06 volumio volumio[1137]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Apr 13 09:39:06 volumio volumio[1137]: command failed: No such device (-19) Apr 13 09:39:06 volumio volumio[1137]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 13 09:39:06 volumio volumio-remote-updater[805]: [2026-04-13 09:39:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1776044341 101 Apr 13 09:39:06 volumio volumio[1137]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Apr 13 09:39:06 volumio volumio[1137]: info: Asound.conf file unchanged, so no further update is needed Apr 13 09:39:06 volumio volumio[1137]: info: Output device has changed, restarting MPD Apr 13 09:39:06 volumio volumio[1137]: info: Output device has changed, restarting Shairport Sync Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 09:39:06 volumio sudo[1600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 13 09:39:06 volumio sudo[1600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:06 volumio sudo[1602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 13 09:39:06 volumio sudo[1602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:06 volumio sudo[1600]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:06 volumio volumio[1137]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 13 09:39:06 volumio volumio[1137]: info: ___________ START PLUGINS ___________ Apr 13 09:39:06 volumio volumio[1137]: info: ControllerMpd::onStart: Initializing MPD Apr 13 09:39:06 volumio volumio[1137]: info: Creating MPD Configuration file Apr 13 09:39:06 volumio sudo[1610]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Apr 13 09:39:06 volumio sudo[1610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 09:39:06 volumio volumio[1137]: info: [1776044346277] CoreMusicLibrary::Adding element 媒体服务器 Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 09:39:06 volumio sudo[1613]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 13 09:39:06 volumio sudo[1613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:06 volumio volumio[1137]: info: UPNP Browser: Client initialized successfully Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:06 volumio sudo[1613]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 09:39:06 volumio sudo[1616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 13 09:39:06 volumio sudo[1616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:06 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 13 09:39:06 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 13 09:39:06 volumio volumio[1137]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 09:39:06 volumio volumio[1137]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 13 09:39:06 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 13 09:39:06 volumio sudo[1610]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:06 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 09:39:06 volumio volumio[1137]: info: [1776044346305] CoreMusicLibrary::Adding element Last_100 Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 09:39:06 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 09:39:06 volumio volumio[1137]: info: [1776044346306] CoreMusicLibrary::Adding element Webradio Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 09:39:06 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 13 09:39:06 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 09:39:06 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 13 09:39:06 volumio volumio[1137]: info: Initializing BBC Radios Apr 13 09:39:06 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 13 09:39:06 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 13 09:39:06 volumio sudo[1630]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 13 09:39:06 volumio sudo[1630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 13 09:39:06 volumio sudo[1635]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 13 09:39:06 volumio sudo[1630]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 09:39:06 volumio volumio[1137]: info: Volumio Calling Home Apr 13 09:39:06 volumio volumio[1137]: info: CoreStateMachine::setRepeat null single undefined Apr 13 09:39:06 volumio volumio[1137]: info: CoreStateMachine::pushState Apr 13 09:39:06 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Apr 13 09:39:06 volumio volumio[1137]: info: CoreStateMachine::setRandom null Apr 13 09:39:06 volumio volumio[1137]: info: CoreStateMachine::pushState Apr 13 09:39:06 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:39:06 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Apr 13 09:39:06 volumio volumio[1137]: Unhandled rejection Error: No sockets available, cannot start. Apr 13 09:39:06 volumio volumio[1137]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Apr 13 09:39:06 volumio volumio[1137]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Apr 13 09:39:06 volumio volumio[1137]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Apr 13 09:39:06 volumio volumio[1137]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Apr 13 09:39:06 volumio volumio[1137]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Apr 13 09:39:06 volumio volumio[1137]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Apr 13 09:39:06 volumio volumio[1137]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Apr 13 09:39:06 volumio volumio[1137]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Apr 13 09:39:06 volumio volumio[1137]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Apr 13 09:39:06 volumio volumio[1137]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Apr 13 09:39:06 volumio volumio[1137]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Apr 13 09:39:06 volumio volumio[1137]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 13 09:39:06 volumio volumio[1137]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 13 09:39:06 volumio volumio[1137]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Apr 13 09:39:06 volumio volumio[1137]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Apr 13 09:39:06 volumio volumio[1137]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 13 09:39:06 volumio volumio[1137]: info: Reloading queue from file Apr 13 09:39:07 volumio volumio[1137]: info: MPD Permissions set Apr 13 09:39:07 volumio volumio[1137]: info: MPD Permissions set Apr 13 09:39:07 volumio volumio[1137]: info: VolumeController:: Volume=100 Mute =false Apr 13 09:39:07 volumio volumio[1137]: info: CoreStateMachine::pushState Apr 13 09:39:07 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Apr 13 09:39:07 volumio volumio[1137]: info: CoreStateMachine::updateTrackBlock Apr 13 09:39:07 volumio volumio[1137]: info: CorePlayQueue::getTrackBlock Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::volumioRetrievevolume Apr 13 09:39:07 volumio volumio[1137]: info: Discovery: adding d4505b36-8944-46d7-bb03-29bf779cc5cc Apr 13 09:39:07 volumio volumio[1137]: info: Discovery: Found device Volumio Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Apr 13 09:39:07 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:39:07 volumio volumio[1137]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Apr 13 09:39:07 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:07 volumio volumio[1137]: info: VolumeController:: Volume=100 Mute =false Apr 13 09:39:07 volumio volumio[1137]: info: CoreStateMachine::pushState Apr 13 09:39:07 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:39:07 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Apr 13 09:39:07 volumio volumio[1137]: info: Starting Shairport Sync Apr 13 09:39:07 volumio volumio[1137]: info: Starting Shairport Sync Apr 13 09:39:07 volumio volumio[1137]: info: Starting Shairport Sync Apr 13 09:39:07 volumio sudo[1692]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 13 09:39:07 volumio sudo[1690]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 13 09:39:07 volumio sudo[1692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:07 volumio sudo[1690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:07 volumio sudo[1689]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 13 09:39:07 volumio sudo[1689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:07 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 13 09:39:07 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 13 09:39:07 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 13 09:39:07 volumio systemd[1]: shairport-sync.service: Consumed 1.780s CPU time. Apr 13 09:39:07 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 13 09:39:07 volumio sudo[1692]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:07 volumio sudo[1689]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:07 volumio volumio[1137]: info: Shairport-Sync Started Apr 13 09:39:07 volumio volumio[1137]: Error adding Membership: Error: addMembership EINVAL Apr 13 09:39:07 volumio volumio[1137]: info: Shairport-Sync Started Apr 13 09:39:07 volumio sudo[1690]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:07 volumio volumio[1137]: info: Shairport-Sync Started Apr 13 09:39:07 volumio avahi-daemon[797]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.8. Apr 13 09:39:07 volumio dhcpcd[818]: eth0: using static address 192.168.0.8/24 Apr 13 09:39:07 volumio dhcpcd[818]: eth0: adding route to 192.168.0.0/24 Apr 13 09:39:07 volumio dhcpcd[818]: eth0: adding default route via 192.168.0.1 Apr 13 09:39:07 volumio avahi-daemon[797]: New relevant interface eth0.IPv4 for mDNS. Apr 13 09:39:07 volumio avahi-daemon[797]: Registering new address record for 192.168.0.8 on eth0.IPv4. Apr 13 09:39:07 volumio systemd[1]: welcome.service: Deactivated successfully. Apr 13 09:39:07 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Apr 13 09:39:07 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Apr 13 09:39:07 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Apr 13 09:39:07 volumio welcome[1714]: Resolved ip:[1] 192.168.0.8 Apr 13 09:39:07 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Apr 13 09:39:07 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Apr 13 09:39:07 volumio systemd[1]: Started nmbd.service - Samba NMB Daemon. Apr 13 09:39:07 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... Apr 13 09:39:08 volumio volumio5-onboarding[1628]: time=2026-04-13T09:39:08.397+08:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Apr 13 09:39:08 volumio volumio[1137]: 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: 3 Apr 13 09:39:08 volumio volumio[1137]: 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: 3 Apr 13 09:39:08 volumio volumio5-onboarding[1628]: time=2026-04-13T09:39:08.425+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist" Apr 13 09:39:08 volumio volumio5-onboarding[1628]: time=2026-04-13T09:39:08.426+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist" Apr 13 09:39:08 volumio volumio5-onboarding[1628]: time=2026-04-13T09:39:08.426+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist" Apr 13 09:39:08 volumio volumio5-onboarding[1628]: time=2026-04-13T09:39:08.426+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist" Apr 13 09:39:08 volumio volumio5-onboarding[1628]: time=2026-04-13T09:39:08.426+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist" Apr 13 09:39:08 volumio volumio5-onboarding[1628]: time=2026-04-13T09:39:08.426+08:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" Apr 13 09:39:08 volumio volumio[1137]: info: Received Get System Info Apr 13 09:39:08 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 09:39:08 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 09:39:08 volumio volumio[1137]: info: Discovery: Getting this device information Apr 13 09:39:08 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Apr 13 09:39:08 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:39:08 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 09:39:08 volumio volumio5-onboarding[1628]: time=2026-04-13T09:39:08.429+08:00 level=INFO msg="system info for 3cd96d07ed87dfb1d9450dee4d1e7a70" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Apr 13 09:39:08 volumio volumio[1137]: info: Discovery: this is already registered, d4505b36-8944-46d7-bb03-29bf779cc5cc Apr 13 09:39:08 volumio volumio[1137]: info: Discovery: Found device Volumio Apr 13 09:39:08 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Apr 13 09:39:08 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:39:08 volumio volumio5-onboarding[1628]: time=2026-04-13T09:39:08.494+08:00 level=INFO msg="bootstrapping state" hasInternet=true Apr 13 09:39:08 volumio volumio[1137]: info: Received Get System Info Apr 13 09:39:08 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 09:39:08 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 09:39:08 volumio volumio[1137]: info: Discovery: Getting this device information Apr 13 09:39:08 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Apr 13 09:39:08 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:39:08 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 09:39:08 volumio volumio-remote-updater[805]: Test mode disabled Apr 13 09:39:08 volumio volumio-remote-updater[805]: Alpha mode disabled Apr 13 09:39:08 volumio volumio-remote-updater[805]: Alpha legacy test mode disabled Apr 13 09:39:08 volumio winbindd[1740]: [2026/04/13 09:39:08.637197, 0] ../../source3/winbindd/winbindd.c:1440(main) Apr 13 09:39:08 volumio winbindd[1740]: winbindd version 4.17.12-Debian started. Apr 13 09:39:08 volumio winbindd[1740]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Apr 13 09:39:08 volumio winbindd[1740]: [2026/04/13 09:39:08.695583, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) Apr 13 09:39:08 volumio winbindd[1740]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 13 09:39:08 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. Apr 13 09:39:08 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... Apr 13 09:39:09 volumio ntpd[1082]: IO: Listen normally on 3 eth0 192.168.0.8:123 Apr 13 09:39:09 volumio ntpd[1082]: IO: new interface(s) found: waking up resolver Apr 13 09:39:09 volumio ntpd[1082]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 13 09:39:09 volumio ntpd[1082]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Apr 13 09:39:09 volumio ntpd[1082]: DNS: Pool taking: 119.28.183.184 Apr 13 09:39:09 volumio ntpd[1082]: DNS: Pool taking: 95.111.202.5 Apr 13 09:39:09 volumio ntpd[1082]: DNS: Pool taking: 139.199.215.251 Apr 13 09:39:09 volumio ntpd[1082]: DNS: Pool taking: 193.182.111.141 Apr 13 09:39:09 volumio ntpd[1082]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Apr 13 09:39:09 volumio smbd[1764]: [2026/04/13 09:39:09.297352, 0] ../../source3/smbd/server.c:1741(main) Apr 13 09:39:09 volumio smbd[1764]: smbd version 4.17.12-Debian started. Apr 13 09:39:09 volumio smbd[1764]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Apr 13 09:39:09 volumio volumio[1137]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 13 09:39:09 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 13 09:39:09 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 09:39:09 volumio volumio5-onboarding[1628]: time=2026-04-13T09:39:09.301+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" Apr 13 09:39:09 volumio volumio5-onboarding[1628]: time=2026-04-13T09:39:09.301+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" Apr 13 09:39:09 volumio volumio5-onboarding[1628]: time=2026-04-13T09:39:09.301+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" Apr 13 09:39:09 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 09:39:09 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 09:39:09 volumio volumio[1137]: info: Received Get System Info Apr 13 09:39:09 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 09:39:09 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 09:39:09 volumio volumio[1137]: info: Discovery: Getting this device information Apr 13 09:39:09 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Apr 13 09:39:09 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:39:09 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 09:39:09 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 13 09:39:09 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 13 09:39:09 volumio volumio5-onboarding[1628]: time=2026-04-13T09:39:09.305+08:00 level=INFO msg="enabling local network discovery" Apr 13 09:39:09 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Apr 13 09:39:09 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Apr 13 09:39:09 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Apr 13 09:39:09 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Apr 13 09:39:09 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Apr 13 09:39:09 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Apr 13 09:39:10 volumio ntpd[1082]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 13 09:39:10 volumio ntpd[1082]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Apr 13 09:39:10 volumio ntpd[1082]: DNS: Pool taking: 202.118.1.81 Apr 13 09:39:10 volumio ntpd[1082]: DNS: Pool taking: 139.199.214.202 Apr 13 09:39:10 volumio ntpd[1082]: DNS: Pool taking: 84.16.73.33 Apr 13 09:39:10 volumio ntpd[1082]: DNS: Pool taking: 84.16.67.12 Apr 13 09:39:10 volumio ntpd[1082]: DNS: Pool taking: 2001:da8:215:4020::118:123 Apr 13 09:39:10 volumio ntpd[1082]: DNS: Pool taking: 2408:4003:10b1:4000:a72a:af72:9dd2:c44a Apr 13 09:39:10 volumio ntpd[1082]: DNS: Pool taking: 2a03:8600::ff Apr 13 09:39:10 volumio ntpd[1082]: DNS: Pool taking: 2001:41d0:305:2100::3f3e Apr 13 09:39:10 volumio ntpd[1082]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Apr 13 09:39:10 volumio volumio5-onboarding[1628]: time=2026-04-13T09:39:10.261+08:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 13 09:39:10 volumio volumio[1137]: info: Volumio Network Manager: Network status updated: 1 Apr 13 09:39:11 volumio ntpd[1082]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 13 09:39:11 volumio ntpd[1082]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Apr 13 09:39:11 volumio ntpd[1082]: DNS: Pool skipping: 119.28.183.184 Apr 13 09:39:11 volumio ntpd[1082]: DNS: Pool taking: 111.230.189.174 Apr 13 09:39:11 volumio ntpd[1082]: DNS: Pool skipping: 139.199.215.251 Apr 13 09:39:11 volumio ntpd[1082]: DNS: Pool taking: 162.159.200.1 Apr 13 09:39:11 volumio ntpd[1082]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Apr 13 09:39:12 volumio ntpd[1082]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 13 09:39:12 volumio ntpd[1082]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Apr 13 09:39:12 volumio ntpd[1082]: DNS: Pool taking: 193.182.111.142 Apr 13 09:39:12 volumio ntpd[1082]: DNS: Pool taking: 193.182.111.143 Apr 13 09:39:12 volumio ntpd[1082]: DNS: Pool taking: 193.182.111.14 Apr 13 09:39:12 volumio ntpd[1082]: DNS: Pool taking: 114.66.25.29 Apr 13 09:39:12 volumio ntpd[1082]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Apr 13 09:39:12 volumio sudo[1881]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 13 09:39:12 volumio sudo[1881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:12 volumio sudo[1879]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 13 09:39:12 volumio sudo[1879]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:12 volumio sudo[1879]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:12 volumio sudo[1881]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:12 volumio sudo[1887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 13 09:39:12 volumio sudo[1887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:13 volumio volumio[1137]: 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: 4 Apr 13 09:39:14 volumio volumio[1137]: 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: 5 Apr 13 09:39:14 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 13 09:39:14 volumio volumio[1137]: info: CoreCommandRouter::volumioGetVisibleSources Apr 13 09:39:14 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 09:39:14 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Apr 13 09:39:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:39:14 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 13 09:39:14 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 13 09:39:14 volumio volumio[1137]: info: Received Get System Info Apr 13 09:39:14 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 09:39:14 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 09:39:14 volumio volumio[1137]: info: Discovery: Getting this device information Apr 13 09:39:14 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Apr 13 09:39:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:39:14 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 09:39:14 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Apr 13 09:39:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:39:14 volumio volumio[1137]: info: Listing playlists Apr 13 09:39:14 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 13 09:39:14 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 13 09:39:14 volumio mpd[1647]: 2026-04-13T09:39:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 13 09:39:15 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 13 09:39:15 volumio sudo[1602]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:15 volumio sudo[1616]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:15 volumio volumio[1137]: info: Completed starting Core Plugins Apr 13 09:39:15 volumio volumio[1137]: info: ------------------------------------------- Apr 13 09:39:15 volumio volumio[1137]: info: ----- MyVolumio plugins startup ---- Apr 13 09:39:15 volumio volumio[1137]: info: ------------------------------------------- Apr 13 09:39:15 volumio volumio[1137]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 13 09:39:15 volumio volumio[1137]: error: MPD error: The expression evaluated to a falsy value: Apr 13 09:39:15 volumio volumio[1137]: assert.ok(self.idling) Apr 13 09:39:15 volumio volumio[1137]: error: The expression evaluated to a falsy value: Apr 13 09:39:15 volumio volumio[1137]: assert.ok(self.idling) Apr 13 09:39:15 volumio volumio[1137]: info: MPD running with PID1647 Apr 13 09:39:15 volumio volumio[1137]: ,establishing connection Apr 13 09:39:15 volumio ntpd[1082]: CLOCK: time stepped by 0.397301 Apr 13 09:39:15 volumio ntpd[1082]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Apr 13 09:39:15 volumio volumio[1137]: error: MPD error: The expression evaluated to a falsy value: Apr 13 09:39:15 volumio volumio[1137]: assert.ok(self.idling) Apr 13 09:39:15 volumio volumio[1137]: error: The expression evaluated to a falsy value: Apr 13 09:39:15 volumio volumio[1137]: assert.ok(self.idling) Apr 13 09:39:15 volumio volumio[1137]: error: updateQueue error: null Apr 13 09:39:15 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Apr 13 09:39:15 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:39:16 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 09:39:16 volumio volumio[1137]: info: Received Get System Info Apr 13 09:39:16 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 09:39:16 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 09:39:16 volumio volumio[1137]: info: Discovery: Getting this device information Apr 13 09:39:16 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Apr 13 09:39:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:39:16 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 09:39:17 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 13 09:39:17 volumio volumio[1137]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=4.119&uuid=3cd96d07ed87dfb1d9450dee4d1e7a70" http://updates.volumio.org/downloader-v1/track-device Apr 13 09:39:17 volumio volumio[1137]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 13 09:39:17 volumio volumio[1137]: Dload Upload Total Spent Left Speed Apr 13 09:39:17 volumio volumio[1137]: [132B blob data] Apr 13 09:39:17 volumio volumio[1137]: retrying in 5 seconds, trial 0 Apr 13 09:39:17 volumio volumio[1137]: info: Volumio Calling Home Apr 13 09:39:18 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Apr 13 09:39:18 volumio volumio[1137]: info: Volumio called home Apr 13 09:39:20 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Apr 13 09:39:20 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 13 09:39:20 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 13 09:39:20 volumio systemd[1]: Startup finished in 22.390s (kernel) + 34.657s (userspace) = 57.048s. Apr 13 09:39:20 volumio sudo[1887]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:20 volumio volumio[1137]: info: Upmpdcli Daemon Started Apr 13 09:39:24 volumio upmpdcli[1948]: writing RSA key Apr 13 09:39:24 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 13 09:39:24 volumio volumio[1137]: info: Adding plugin bluetooth to MyMusic Plugins Apr 13 09:39:24 volumio volumio[1137]: info: Adding plugin multiroom to MyMusic Plugins Apr 13 09:39:24 volumio volumio[1137]: info: Adding plugin metavolumio to MyMusic Plugins Apr 13 09:39:24 volumio volumio[1137]: info: Adding plugin cd_controller to MyMusic Plugins Apr 13 09:39:24 volumio volumio[1137]: info: Adding plugin qobuzconnect to MyMusic Plugins Apr 13 09:39:24 volumio volumio[1137]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 13 09:39:24 volumio volumio[1137]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 13 09:39:24 volumio volumio[1137]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 13 09:39:25 volumio volumio[1137]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 13 09:39:25 volumio volumio[1137]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 13 09:39:25 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 09:39:25 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 09:39:25 volumio volumio[1137]: info: Starting MyVolumio Remote Streaming Endpoints Apr 13 09:39:25 volumio volumio[1137]: info: MyVolumio not started Apr 13 09:39:25 volumio volumio[1137]: info: Initializing device activation check Apr 13 09:39:25 volumio volumio[1137]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 13 09:39:25 volumio volumio[1137]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 13 09:39:25 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Apr 13 09:39:25 volumio volumio[1137]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 13 09:39:25 volumio volumio[1137]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 13 09:39:25 volumio volumio[1137]: info: Streaming services startup Apr 13 09:39:25 volumio volumio[1137]: info: Starting Streaming Daemon Apr 13 09:39:25 volumio sudo[1973]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 13 09:39:25 volumio sudo[1973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:25 volumio volumio[1137]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 13 09:39:25 volumio sudo[1973]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:25 volumio volumio[1137]: error: Cannot start Volumio Streaming Daemon Apr 13 09:39:25 volumio volumio[1137]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 13 09:39:25 volumio volumio[1137]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 13 09:39:26 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 13 09:39:26 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 13 09:39:36 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Apr 13 09:39:36 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 09:39:36 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 09:39:36 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 13 09:39:40 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 13 09:39:40 volumio volumio[1137]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 13 09:39:40 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Apr 13 09:39:40 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:39:45 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:45 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:45 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 13 09:39:45 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 13 09:39:45 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 13 09:39:45 volumio volumio[1137]: info: CoreCommandRouter::volumioGetBrowseSources Apr 13 09:39:45 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 09:39:45 volumio volumio[1137]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 13 09:39:45 volumio volumio[1137]: info: Completed starting MyVolumio Plugin Apr 13 09:39:45 volumio volumio[1137]: [Metrics] CommandRouter: 48s 217.39ms Apr 13 09:39:45 volumio volumio[1137]: info: CoreCommandRouter::volumiosetStartupVolume Apr 13 09:39:45 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 09:39:45 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 09:39:45 volumio volumio[1137]: info: CoreCommandRouter::Close All Modals sent Apr 13 09:39:45 volumio volumio[1137]: info: CoreCommandRouter::Close All Modals sent Apr 13 09:39:46 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 13 09:39:46 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 13 09:39:46 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 13 09:39:47 volumio volumio[1137]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 13 09:39:47 volumio volumio[1137]: /bin/cat: /sys/class/net/wlan0/flags: No such file or directory Apr 13 09:39:47 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 13 09:39:48 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 13 09:39:48 volumio sudo[2068]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 13 09:39:48 volumio sudo[2068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:48 volumio sudo[2072]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 13 09:39:48 volumio sudo[2072]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:48 volumio sudo[2079]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 13 09:39:48 volumio sudo[2079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:48 volumio sudo[2084]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 13 09:39:48 volumio sudo[2084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:48 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 13 09:39:48 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 13 09:39:48 volumio sudo[2094]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 13 09:39:48 volumio sudo[2092]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 13 09:39:48 volumio sudo[2079]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:48 volumio sudo[2072]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:48 volumio sudo[2092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:48 volumio sudo[2068]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:48 volumio sudo[2094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:48 volumio sudo[2084]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:48 volumio sudo[2092]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:48 volumio sudo[2094]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:48 volumio sudo[2113]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 13 09:39:48 volumio sudo[2113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:48 volumio sudo[2113]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:48 volumio volumio[1137]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 13 09:39:48 volumio volumio[1137]: wlan0 Interface doesn't support scanning. Apr 13 09:39:48 volumio volumio[1137]: info: Cannot use regular scanning, forcing with ap-force Apr 13 09:39:48 volumio sudo[2120]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 13 09:39:48 volumio sudo[2120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 09:39:48 volumio sudo[2120]: pam_unix(sudo:session): session closed for user root Apr 13 09:39:48 volumio volumio[1137]: command failed: No such device (-19) Apr 13 09:39:48 volumio volumio[1137]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Apr 13 09:39:48 volumio volumio[1137]: command failed: No such device (-19) Apr 13 09:39:52 volumio volumio[1137]: info: BOOT COMPLETED Apr 13 09:40:03 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Apr 13 09:40:03 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:05 volumio volumio[1137]: info: CoreCommandRouter::volumioPlay Apr 13 09:40:05 volumio volumio[1137]: info: CoreStateMachine::play index undefined Apr 13 09:40:05 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 09:40:05 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:05 volumio volumio[1137]: info: CoreStateMachine::startPlaybackTimer Apr 13 09:40:05 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand stop Apr 13 09:40:05 volumio volumio[1137]: info: sendMpdCommand stop took 1 milliseconds Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand clear Apr 13 09:40:05 volumio volumio[1137]: info: sendMpdCommand clear took 1 milliseconds Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand load "USB/320G/好薇《赞歌》限量编号24K金碟2021[低速原抓WAV+CUE]/好薇《赞歌》头版限量编号24K金碟.cue" Apr 13 09:40:05 volumio volumio[1137]: info: Apr 13 09:40:05 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Apr 13 09:40:05 volumio volumio[1137]: info: Ignoring MPD Status Update Apr 13 09:40:05 volumio volumio[1137]: info: Apr 13 09:40:05 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Apr 13 09:40:05 volumio volumio[1137]: info: Ignoring MPD Status Update Apr 13 09:40:05 volumio volumio[1137]: info: Apr 13 09:40:05 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Apr 13 09:40:05 volumio volumio[1137]: info: Ignoring MPD Status Update Apr 13 09:40:05 volumio volumio[1137]: info: Apr 13 09:40:05 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Apr 13 09:40:05 volumio volumio[1137]: info: Ignoring MPD Status Update Apr 13 09:40:05 volumio volumio[1137]: info: sendMpdCommand load "USB/320G/好薇《赞歌》限量编号24K金碟2021[低速原抓WAV+CUE]/好薇《赞歌》头版限量编号24K金碟.cue" took 450 milliseconds Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand play Apr 13 09:40:05 volumio volumio[1137]: info: ------------------------------ 452ms Apr 13 09:40:05 volumio volumio[1137]: info: ------------------------------ 451ms Apr 13 09:40:05 volumio volumio[1137]: info: ------------------------------ 451ms Apr 13 09:40:05 volumio volumio[1137]: info: ------------------------------ 3ms Apr 13 09:40:05 volumio volumio[1137]: info: Apr 13 09:40:05 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Apr 13 09:40:05 volumio volumio[1137]: info: Ignoring MPD Status Update Apr 13 09:40:05 volumio volumio[1137]: info: Apr 13 09:40:05 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Apr 13 09:40:05 volumio volumio[1137]: info: Ignoring MPD Status Update Apr 13 09:40:05 volumio volumio[1137]: info: sendMpdCommand play took 4 milliseconds Apr 13 09:40:05 volumio volumio[1137]: info: ------------------------------ 2ms Apr 13 09:40:05 volumio volumio[1137]: info: ------------------------------ 1ms Apr 13 09:40:05 volumio volumio[1137]: info: Apr 13 09:40:05 volumio volumio[1137]: ---------------------------- MPD announces state update: player Apr 13 09:40:05 volumio volumio[1137]: info: ControllerMpd::getState Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Apr 13 09:40:05 volumio volumio[1137]: info: Apr 13 09:40:05 volumio volumio[1137]: ---------------------------- MPD announces state update: player Apr 13 09:40:05 volumio volumio[1137]: info: ControllerMpd::getState Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Apr 13 09:40:05 volumio volumio[1137]: info: Apr 13 09:40:05 volumio volumio[1137]: ---------------------------- MPD announces state update: player Apr 13 09:40:05 volumio volumio[1137]: info: ControllerMpd::getState Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Apr 13 09:40:05 volumio volumio[1137]: info: Apr 13 09:40:05 volumio volumio[1137]: ---------------------------- MPD announces state update: player Apr 13 09:40:05 volumio volumio[1137]: info: sendMpdCommand status took 3 milliseconds Apr 13 09:40:05 volumio volumio[1137]: info: ControllerMpd::getState Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::parseState Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 09:40:05 volumio volumio[1137]: info: Apr 13 09:40:05 volumio volumio[1137]: ---------------------------- MPD announces state update: player Apr 13 09:40:05 volumio volumio[1137]: info: ControllerMpd::getState Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Apr 13 09:40:05 volumio volumio[1137]: info: sendMpdCommand status took 4 milliseconds Apr 13 09:40:05 volumio volumio[1137]: info: sendMpdCommand status took 4 milliseconds Apr 13 09:40:05 volumio volumio[1137]: info: sendMpdCommand status took 2 milliseconds Apr 13 09:40:05 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::parseState Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::parseState Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::parseState Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Apr 13 09:40:05 volumio volumio[1137]: info: ControllerMpd::pushState Apr 13 09:40:05 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Apr 13 09:40:05 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:05 volumio volumio[1137]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"唱支山歌给党听","artist":null,"album":"赞歌 24K金限量签名版","uri":"USB/320G/好薇《赞歌》限量编号24K金碟2021[低速原抓WAV+CUE]/好薇《赞歌》头版限量编号24K金碟.wav","trackType":"wav"} Apr 13 09:40:05 volumio volumio[1137]: verbose: CURRENT POSITION 0 Apr 13 09:40:05 volumio volumio[1137]: info: CoreStateMachine::syncState stateService play Apr 13 09:40:05 volumio volumio[1137]: info: CoreStateMachine::syncState currentStatus stop Apr 13 09:40:05 volumio volumio[1137]: info: ------------------------------ 8ms Apr 13 09:40:05 volumio volumio[1137]: info: Apr 13 09:40:05 volumio volumio[1137]: ---------------------------- MPD announces state update: player Apr 13 09:40:05 volumio volumio[1137]: info: ControllerMpd::getState Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Apr 13 09:40:05 volumio volumio[1137]: info: sendMpdCommand status took 5 milliseconds Apr 13 09:40:05 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 13 09:40:05 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 13 09:40:05 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 13 09:40:05 volumio volumio[1137]: info: sendMpdCommand status took 0 milliseconds Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::parseState Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::parseState Apr 13 09:40:05 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 09:40:05 volumio volumio[1137]: info: ControllerMpd::pushState Apr 13 09:40:05 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Apr 13 09:40:05 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:05 volumio volumio[1137]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"唱支山歌给党听","artist":null,"album":"赞歌 24K金限量签名版","uri":"USB/320G/好薇《赞歌》限量编号24K金碟2021[低速原抓WAV+CUE]/好薇《赞歌》头版限量编号24K金碟.wav","trackType":"wav"} Apr 13 09:40:05 volumio volumio[1137]: verbose: CURRENT POSITION 0 Apr 13 09:40:05 volumio volumio[1137]: info: CoreStateMachine::syncState stateService play Apr 13 09:40:05 volumio volumio[1137]: info: CoreStateMachine::syncState currentStatus play Apr 13 09:40:05 volumio volumio[1137]: info: Received an update from plugin. extracting info from payload Apr 13 09:40:05 volumio volumio[1137]: info: CoreStateMachine::pushState Apr 13 09:40:05 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:05 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 09:40:05 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Apr 13 09:40:05 volumio volumio[1137]: info: CoreStateMachine::pushState Apr 13 09:40:05 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:05 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Apr 13 09:40:05 volumio volumio[1137]: info: ControllerMpd::pushState Apr 13 09:40:05 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Apr 13 09:40:05 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:05 volumio volumio[1137]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"唱支山歌给党听","artist":null,"album":"赞歌 24K金限量签名版","uri":"USB/320G/好薇《赞歌》限量编号24K金碟2021[低速原抓WAV+CUE]/好薇《赞歌》头版限量编号24K金碟.wav","trackType":"wav"} Apr 13 09:40:05 volumio volumio[1137]: verbose: CURRENT POSITION 0 Apr 13 09:40:05 volumio volumio[1137]: info: CoreStateMachine::syncState stateService play Apr 13 09:40:05 volumio volumio[1137]: info: CoreStateMachine::syncState currentStatus play Apr 13 09:40:05 volumio volumio[1137]: info: Received an update from plugin. extracting info from payload Apr 13 09:40:05 volumio volumio[1137]: info: CoreStateMachine::pushState Apr 13 09:40:05 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:05 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Apr 13 09:40:05 volumio volumio[1137]: info: CoreStateMachine::pushState Apr 13 09:40:05 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:05 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Apr 13 09:40:05 volumio volumio[1137]: info: ControllerMpd::pushState Apr 13 09:40:05 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Apr 13 09:40:05 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:05 volumio volumio[1137]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"唱支山歌给党听","artist":null,"album":"赞歌 24K金限量签名版","uri":"USB/320G/好薇《赞歌》限量编号24K金碟2021[低速原抓WAV+CUE]/好薇《赞歌》头版限量编号24K金碟.wav","trackType":"wav"} Apr 13 09:40:05 volumio volumio[1137]: verbose: CURRENT POSITION 0 Apr 13 09:40:05 volumio volumio[1137]: info: CoreStateMachine::syncState stateService play Apr 13 09:40:05 volumio volumio[1137]: info: CoreStateMachine::syncState currentStatus play Apr 13 09:40:05 volumio volumio[1137]: info: Received an update from plugin. extracting info from payload Apr 13 09:40:05 volumio volumio[1137]: info: CoreStateMachine::pushState Apr 13 09:40:05 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:05 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Apr 13 09:40:05 volumio volumio[1137]: info: CoreStateMachine::pushState Apr 13 09:40:05 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:05 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Apr 13 09:40:06 volumio volumio[1137]: info: ------------------------------ 26ms Apr 13 09:40:06 volumio volumio[1137]: info: ------------------------------ 25ms Apr 13 09:40:06 volumio volumio[1137]: info: ------------------------------ 24ms Apr 13 09:40:06 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 17 milliseconds Apr 13 09:40:06 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 13 09:40:06 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Apr 13 09:40:06 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Apr 13 09:40:06 volumio volumio[1137]: info: ControllerMpd::pushState Apr 13 09:40:06 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Apr 13 09:40:06 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:06 volumio volumio[1137]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"唱支山歌给党听","artist":null,"album":"赞歌 24K金限量签名版","uri":"USB/320G/好薇《赞歌》限量编号24K金碟2021[低速原抓WAV+CUE]/好薇《赞歌》头版限量编号24K金碟.wav","trackType":"wav"} Apr 13 09:40:06 volumio volumio[1137]: verbose: CURRENT POSITION 0 Apr 13 09:40:06 volumio volumio[1137]: info: CoreStateMachine::syncState stateService play Apr 13 09:40:06 volumio volumio[1137]: info: CoreStateMachine::syncState currentStatus play Apr 13 09:40:06 volumio volumio[1137]: info: Received an update from plugin. extracting info from payload Apr 13 09:40:06 volumio volumio[1137]: info: CoreStateMachine::pushState Apr 13 09:40:06 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:06 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Apr 13 09:40:06 volumio volumio[1137]: info: CoreStateMachine::pushState Apr 13 09:40:06 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:06 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Apr 13 09:40:06 volumio volumio[1137]: info: ControllerMpd::pushState Apr 13 09:40:06 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Apr 13 09:40:06 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:06 volumio volumio[1137]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"唱支山歌给党听","artist":null,"album":"赞歌 24K金限量签名版","uri":"USB/320G/好薇《赞歌》限量编号24K金碟2021[低速原抓WAV+CUE]/好薇《赞歌》头版限量编号24K金碟.wav","trackType":"wav"} Apr 13 09:40:06 volumio volumio[1137]: verbose: CURRENT POSITION 0 Apr 13 09:40:06 volumio volumio[1137]: info: CoreStateMachine::syncState stateService play Apr 13 09:40:06 volumio volumio[1137]: info: CoreStateMachine::syncState currentStatus play Apr 13 09:40:06 volumio volumio[1137]: info: Received an update from plugin. extracting info from payload Apr 13 09:40:06 volumio volumio[1137]: info: CoreStateMachine::pushState Apr 13 09:40:06 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:06 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Apr 13 09:40:06 volumio volumio[1137]: info: CoreStateMachine::pushState Apr 13 09:40:06 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:06 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Apr 13 09:40:06 volumio volumio[1137]: info: ------------------------------ 33ms Apr 13 09:40:06 volumio volumio[1137]: info: ------------------------------ 30ms Apr 13 09:40:09 volumio volumio[1137]: info: CoreCommandRouter::volumioGetQueue Apr 13 09:40:09 volumio volumio[1137]: info: CoreStateMachine::getQueue Apr 13 09:40:09 volumio volumio[1137]: info: CorePlayQueue::getQueue Apr 13 09:40:14 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 13 09:40:14 volumio volumio[1137]: info: CURURI: music-library Apr 13 09:40:14 volumio volumio[1137]: info: Preload queue cleared Apr 13 09:40:17 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 13 09:40:17 volumio volumio[1137]: info: CURURI: music-library/USB Apr 13 09:40:17 volumio volumio[1137]: info: Preload queue cleared Apr 13 09:40:18 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 13 09:40:18 volumio volumio[1137]: info: CURURI: music-library/USB/320G Apr 13 09:40:18 volumio volumio[1137]: info: Preload queue cleared Apr 13 09:40:28 volumio volumio-remote-updater[805]: Test mode disabled Apr 13 09:40:28 volumio volumio-remote-updater[805]: Alpha mode disabled Apr 13 09:40:28 volumio volumio-remote-updater[805]: Alpha legacy test mode disabled Apr 13 09:40:28 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 13 09:40:28 volumio volumio[1137]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 13 09:40:28 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 13 09:40:40 volumio volumio[1137]: info: Preload queue cleared Apr 13 09:40:40 volumio volumio[1137]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 13 09:40:40 volumio volumio[1137]: info: CoreStateMachine::ClearQueue Apr 13 09:40:40 volumio volumio[1137]: info: CoreStateMachine::stop Apr 13 09:40:40 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 09:40:40 volumio volumio[1137]: info: CoreStateMachine::stPlaybackTimer Apr 13 09:40:40 volumio volumio[1137]: info: CoreStateMachine::updateTrackBlock Apr 13 09:40:40 volumio volumio[1137]: info: CorePlayQueue::getTrackBlock Apr 13 09:40:40 volumio volumio[1137]: info: CoreStateMachine::pushState Apr 13 09:40:40 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:40 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 09:40:40 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Apr 13 09:40:40 volumio volumio[1137]: info: CoreStateMachine::serviceStop Apr 13 09:40:40 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Apr 13 09:40:40 volumio volumio[1137]: info: CoreCommandRouter::serviceStop Apr 13 09:40:40 volumio volumio[1137]: info: ControllerMpd::stop Apr 13 09:40:40 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand stop Apr 13 09:40:40 volumio volumio[1137]: info: CorePlayQueue::clearPlayQueue Apr 13 09:40:40 volumio volumio[1137]: info: CorePlayQueue::saveQueue Apr 13 09:40:40 volumio volumio[1137]: info: CoreCommandRouter::volumioPushQueue Apr 13 09:40:40 volumio volumio[1137]: info: CoreStateMachine::addQueueItems Apr 13 09:40:40 volumio volumio[1137]: info: CorePlayQueue::addQueueItems Apr 13 09:40:40 volumio volumio[1137]: info: Preload queue cleared Apr 13 09:40:40 volumio volumio[1137]: info: Adding Item to queue: music-library/USB/320G/凰逸欣《欣声 HQ2CD》头版限量编号[低速整轨WAV] Apr 13 09:40:40 volumio volumio[1137]: info: Exploding uri music-library/USB/320G/凰逸欣《欣声 HQ2CD》头版限量编号[低速整轨WAV] in service mpd Apr 13 09:40:40 volumio volumio[1137]: info: Apr 13 09:40:40 volumio volumio[1137]: ---------------------------- MPD announces state update: player Apr 13 09:40:40 volumio volumio[1137]: info: ALBUMART /albumart?cacheid=518&web=/%3F%3F%3F%3FHQ2CD/extralarge&path=%2Fmnt%2FUSB%2F320G%2F%E5%87%B0%E9%80%B8%E6%AC%A3%E3%80%8A%E6%AC%A3%E5%A3%B0%20HQ2CD%E3%80%8B%E5%A4%B4%E7%89%88%E9%99%90%E9%87%8F%E7%BC%96%E5%8F%B7%5B%E4%BD%8E%E9%80%9F%E6%95%B4%E8%BD%A8WAV%5D%2FCDImage.cue&metadata=false Apr 13 09:40:40 volumio volumio[1137]: info: URI /mnt/USB/320G/凰逸欣《欣声 HQ2CD》头版限量编号[低速整轨WAV]/CDImage.cue Apr 13 09:40:40 volumio volumio[1137]: info: ALBUMART /albumart?cacheid=518&web=/%3F%3F%3F%3FHQ2CD/extralarge&path=%2Fmnt%2FUSB%2F320G%2F%E5%87%B0%E9%80%B8%E6%AC%A3%E3%80%8A%E6%AC%A3%E5%A3%B0%20HQ2CD%E3%80%8B%E5%A4%B4%E7%89%88%E9%99%90%E9%87%8F%E7%BC%96%E5%8F%B7%5B%E4%BD%8E%E9%80%9F%E6%95%B4%E8%BD%A8WAV%5D%2FCDImage.cue&metadata=false Apr 13 09:40:40 volumio volumio[1137]: info: URI /mnt/USB/320G/凰逸欣《欣声 HQ2CD》头版限量编号[低速整轨WAV]/CDImage.cue Apr 13 09:40:40 volumio volumio[1137]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 09:40:40 volumio volumio[1137]: Error: Unable to resolve or reject the same promise twice Apr 13 09:40:40 volumio volumio[1137]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 13 09:40:40 volumio volumio[1137]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Apr 13 09:40:40 volumio volumio[1137]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 13 09:40:40 volumio volumio[1137]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 13 09:40:40 volumio volumio[1137]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 13 09:40:40 volumio volumio[1137]: at Socket.emit (node:events:514:28) Apr 13 09:40:40 volumio volumio[1137]: at addChunk (node:internal/streams/readable:343:12) Apr 13 09:40:40 volumio volumio[1137]: at readableAddChunk (node:internal/streams/readable:312:11) Apr 13 09:40:40 volumio volumio[1137]: at Readable.push (node:internal/streams/readable:253:10) Apr 13 09:40:40 volumio volumio[1137]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Apr 13 09:40:40 volumio volumio[1137]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 09:40:40 volumio sudo[2350]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-13 09:39' Apr 13 09:40:40 volumio sudo[2350]: 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"