May 30 22:06:01 volumio volumio[995]: Unhandled rejection Error: No sockets available, cannot start. May 30 22:06:01 volumio volumio[995]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) May 30 22:06:01 volumio volumio[995]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) May 30 22:06:01 volumio volumio[995]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 May 30 22:06:01 volumio volumio[995]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) May 30 22:06:01 volumio volumio[995]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) May 30 22:06:01 volumio volumio[995]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) May 30 22:06:01 volumio volumio[995]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) May 30 22:06:01 volumio volumio[995]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) May 30 22:06:01 volumio volumio[995]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) May 30 22:06:01 volumio volumio[995]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) May 30 22:06:01 volumio volumio[995]: at HashMap. (/volumio/app/pluginmanager.js:496:31) May 30 22:06:01 volumio volumio[995]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 30 22:06:01 volumio volumio[995]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 30 22:06:01 volumio volumio[995]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) May 30 22:06:01 volumio volumio[995]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) May 30 22:06:01 volumio volumio[995]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 30 22:06:01 volumio volumio[995]: info: Discovery: adding 79c42b01-dcd6-4e96-a390-f3abaa5fd694 May 30 22:06:01 volumio volumio[995]: info: Discovery: Found device Volumio May 30 22:06:01 volumio volumio[995]: info: CoreCommandRouter::volumioGetState May 30 22:06:01 volumio volumio[995]: info: CorePlayQueue::getTrack 0 May 30 22:06:01 volumio volumio[995]: info: MPD Permissions set May 30 22:06:01 volumio volumio[995]: info: MPD Permissions set May 30 22:06:01 volumio volumio5-onboarding[1215]: time=2026-05-30T22:06:01.582+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist" May 30 22:06:01 volumio volumio5-onboarding[1215]: time=2026-05-30T22:06:01.584+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist" May 30 22:06:01 volumio volumio5-onboarding[1215]: time=2026-05-30T22:06:01.586+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist" May 30 22:06:01 volumio volumio5-onboarding[1215]: time=2026-05-30T22:06:01.588+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist" May 30 22:06:01 volumio volumio5-onboarding[1215]: time=2026-05-30T22:06:01.590+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist" May 30 22:06:01 volumio volumio5-onboarding[1215]: time=2026-05-30T22:06:01.590+08:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" May 30 22:06:01 volumio volumio[995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 May 30 22:06:01 volumio volumio[995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 May 30 22:06:01 volumio volumio[995]: info: Received Get System Info May 30 22:06:01 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 22:06:01 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 22:06:01 volumio volumio[995]: info: Discovery: Getting this device information May 30 22:06:01 volumio volumio[995]: info: CoreCommandRouter::volumioGetState May 30 22:06:01 volumio volumio[995]: info: CorePlayQueue::getTrack 0 May 30 22:06:01 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 22:06:01 volumio volumio5-onboarding[1215]: time=2026-05-30T22:06:01.724+08:00 level=INFO msg="system info for 112a0063cebe35d0d74f7bcba30d01e7" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 May 30 22:06:01 volumio volumio5-onboarding[1215]: time=2026-05-30T22:06:01.728+08:00 level=INFO msg="bootstrapping state" hasInternet=false May 30 22:06:01 volumio volumio[995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 30 22:06:01 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:06:01 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:06:01 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:06:01 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:06:01 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:06:01 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:06:01 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:06:01 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:06:01 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 22:06:01 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:06:02 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:06:02 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:06:02 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:06:02 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:06:02 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:06:02 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:06:02 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:06:02 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:06:02 volumio dhcpcd[770]: eth0: leased 192.168.50.174 for 86400 seconds May 30 22:06:02 volumio sh[754]: eth0: leased 192.168.50.174 for 86400 seconds May 30 22:06:02 volumio avahi-daemon[710]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.50.174. May 30 22:06:02 volumio avahi-daemon[710]: New relevant interface eth0.IPv4 for mDNS. May 30 22:06:02 volumio avahi-daemon[710]: Registering new address record for 192.168.50.174 on eth0.IPv4. May 30 22:06:02 volumio dhcpcd[770]: eth0: adding route to 192.168.50.0/24 May 30 22:06:02 volumio dhcpcd[770]: eth0: adding default route via 192.168.50.1 May 30 22:06:02 volumio sh[754]: eth0: adding route to 192.168.50.0/24 May 30 22:06:02 volumio sh[754]: eth0: adding default route via 192.168.50.1 May 30 22:06:02 volumio systemd[1]: welcome.service: Deactivated successfully. May 30 22:06:02 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 30 22:06:02 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 30 22:06:02 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 22:06:02 volumio sh[754]: forked to background, child pid 766 May 30 22:06:02 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:06:02 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 30 22:06:02 volumio systemd[1]: Started nmbd.service - Samba NMB Daemon. May 30 22:06:02 volumio volumio[995]: info: Received Get System Info May 30 22:06:02 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 22:06:02 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 22:06:02 volumio volumio[995]: info: Discovery: Getting this device information May 30 22:06:02 volumio volumio[995]: info: CoreCommandRouter::volumioGetState May 30 22:06:02 volumio volumio[995]: info: CorePlayQueue::getTrack 0 May 30 22:06:02 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 22:06:02 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... May 30 22:06:02 volumio welcome[1273]: Resolved ip:[1] 192.168.50.174 May 30 22:06:02 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 30 22:06:02 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 30 22:06:02 volumio ifplugd(eth0)[941]: client: ifup: interface eth0 already configured May 30 22:06:02 volumio sh[1312]: eth0=eth0 May 30 22:06:02 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 30 22:06:02 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 30 22:06:02 volumio volumio5-onboarding[1215]: time=2026-05-30T22:06:02.433+08:00 level=INFO msg="enabling local network discovery" May 30 22:06:02 volumio ifplugd(eth0)[941]: Program executed successfully. May 30 22:06:02 volumio volumio[1145]: Starting albumart workers May 30 22:06:02 volumio volumio[995]: info: Starting Shairport Sync May 30 22:06:02 volumio winbindd[1311]: [2026/05/30 22:06:02.707821, 0] ../../source3/winbindd/winbindd.c:1440(main) May 30 22:06:02 volumio winbindd[1311]: winbindd version 4.17.8-Raspbian started. May 30 22:06:02 volumio winbindd[1311]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 30 22:06:02 volumio sudo[1319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 22:06:02 volumio sudo[1319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 22:06:02 volumio winbindd[1311]: [2026/05/30 22:06:02.722159, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) May 30 22:06:02 volumio winbindd[1311]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 30 22:06:02 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. May 30 22:06:02 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... May 30 22:06:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 30 22:06:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 30 22:06:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 22:06:02 volumio systemd[1]: shairport-sync.service: Consumed 1.403s CPU time. May 30 22:06:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 22:06:02 volumio volumio[995]: info: Starting Shairport Sync May 30 22:06:02 volumio sudo[1319]: pam_unix(sudo:session): session closed for user root May 30 22:06:02 volumio volumio[995]: info: Starting Shairport Sync May 30 22:06:03 volumio volumio[995]: info: Received Get System Info May 30 22:06:03 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 22:06:03 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 22:06:03 volumio volumio[995]: info: Discovery: Getting this device information May 30 22:06:03 volumio volumio[995]: info: CoreCommandRouter::volumioGetState May 30 22:06:03 volumio volumio[995]: info: CorePlayQueue::getTrack 0 May 30 22:06:03 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 22:06:03 volumio sudo[1351]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 22:06:03 volumio sudo[1351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 22:06:03 volumio smbd[1356]: [2026/05/30 22:06:03.363011, 0] ../../source3/smbd/server.c:1741(main) May 30 22:06:03 volumio smbd[1356]: smbd version 4.17.8-Raspbian started. May 30 22:06:03 volumio smbd[1356]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 30 22:06:03 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 30 22:06:03 volumio volumio5-onboarding[1215]: time=2026-05-30T22:06:03.384+08:00 level=INFO msg="service successfully established" component=discovery/localnet May 30 22:06:03 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 30 22:06:03 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 22:06:03 volumio ntpd[929]: IO: Listen normally on 3 eth0 192.168.50.174:123 May 30 22:06:03 volumio ntpd[929]: IO: new interface(s) found: waking up resolver May 30 22:06:03 volumio ntpd[929]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 May 30 22:06:03 volumio ntpd[929]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 May 30 22:06:03 volumio ntpd[929]: DNS: Pool taking: 162.159.200.123 May 30 22:06:03 volumio ntpd[929]: DNS: Pool taking: 108.59.2.24 May 30 22:06:03 volumio ntpd[929]: DNS: Pool taking: 116.203.151.74 May 30 22:06:03 volumio ntpd[929]: DNS: Pool taking: 139.199.215.251 May 30 22:06:03 volumio ntpd[929]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 May 30 22:06:03 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 22:06:03 volumio sudo[1351]: pam_unix(sudo:session): session closed for user root May 30 22:06:03 volumio dhcpcd[793]: eth0: leased 192.168.50.174 for 86400 seconds May 30 22:06:03 volumio volumio[1147]: Starting albumart workers May 30 22:06:03 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. May 30 22:06:03 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... May 30 22:06:03 volumio systemd[1]: welcome.service: Deactivated successfully. May 30 22:06:03 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 30 22:06:03 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 30 22:06:03 volumio dhcpcd[793]: eth0: adding route to 192.168.50.0/24 May 30 22:06:03 volumio dhcpcd[793]: eth0: adding default route via 192.168.50.1 May 30 22:06:03 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 30 22:06:03 volumio welcome[1386]: Resolved ip:[1] 192.168.50.174 May 30 22:06:03 volumio sudo[1346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 22:06:03 volumio sudo[1346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 22:06:03 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 30 22:06:03 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 30 22:06:03 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 30 22:06:03 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 30 22:06:03 volumio sudo[1402]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=music,password=Yy061124,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.0 //192.168.50.69/Music /mnt/NAS/music May 30 22:06:03 volumio sudo[1402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 22:06:03 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 30 22:06:03 volumio mpd[1227]: 2026-05-30T22:06:03 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 30 22:06:03 volumio volumio[1148]: Starting albumart workers May 30 22:06:03 volumio sudo[1188]: pam_unix(sudo:session): session closed for user root May 30 22:06:03 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 30 22:06:03 volumio sudo[1200]: pam_unix(sudo:session): session closed for user root May 30 22:06:03 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 30 22:06:03 volumio sudo[1346]: pam_unix(sudo:session): session closed for user root May 30 22:06:03 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 22:06:03 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 22:06:04 volumio kernel: CIFS: Attempting to mount //192.168.50.69/Music May 30 22:06:04 volumio volumio[995]: info: Shairport-Sync Started May 30 22:06:04 volumio volumio[995]: Error adding Membership: Error: addMembership EINVAL May 30 22:06:04 volumio volumio[995]: info: Shairport-Sync Started May 30 22:06:04 volumio volumio[995]: info: Received Get System Info May 30 22:06:04 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 22:06:04 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 22:06:04 volumio volumio[995]: info: Discovery: Getting this device information May 30 22:06:04 volumio volumio[995]: info: CoreCommandRouter::volumioGetState May 30 22:06:04 volumio volumio[995]: info: CorePlayQueue::getTrack 0 May 30 22:06:04 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 22:06:04 volumio volumio[995]: info: CoreCommandRouter::volumioGetState May 30 22:06:04 volumio volumio[995]: info: CorePlayQueue::getTrack 0 May 30 22:06:04 volumio volumio[995]: info: Completed starting Core Plugins May 30 22:06:04 volumio volumio[995]: info: ------------------------------------------- May 30 22:06:04 volumio volumio[995]: info: ----- MyVolumio plugins startup ---- May 30 22:06:04 volumio volumio[995]: info: ------------------------------------------- May 30 22:06:04 volumio volumio[995]: info: [MyVolumio PluginManager] Fetching plans data.... May 30 22:06:04 volumio volumio[995]: info: Shairport-Sync Started May 30 22:06:04 volumio kernel: cryptd: max_cpu_qlen set to 1000 May 30 22:06:04 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 30 22:06:04 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 30 22:06:04 volumio ntpd[929]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 May 30 22:06:04 volumio ntpd[929]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 May 30 22:06:04 volumio ntpd[929]: DNS: Pool taking: 193.182.111.141 May 30 22:06:04 volumio ntpd[929]: DNS: Pool taking: 193.182.111.14 May 30 22:06:04 volumio ntpd[929]: DNS: Pool taking: 210.28.130.4 May 30 22:06:04 volumio ntpd[929]: DNS: Pool taking: 119.28.183.184 May 30 22:06:04 volumio ntpd[929]: DNS: Pool taking: 2001:470:1d:281::123 May 30 22:06:04 volumio ntpd[929]: DNS: Pool taking: 2a12:bec0:168:343::123 May 30 22:06:04 volumio ntpd[929]: DNS: Pool taking: 2a03:8600::ee May 30 22:06:04 volumio ntpd[929]: DNS: Pool taking: 2a03:8600::cc May 30 22:06:04 volumio ntpd[929]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 May 30 22:06:04 volumio sudo[1402]: pam_unix(sudo:session): session closed for user root May 30 22:06:04 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. May 30 22:06:04 volumio systemd[1]: Reached target multi-user.target - Multi-User System. May 30 22:06:04 volumio systemd[1]: Reached target graphical.target - Graphical Interface. May 30 22:06:04 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... May 30 22:06:04 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. May 30 22:06:04 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. May 30 22:06:04 volumio systemd[1]: Startup finished in 12.903s (kernel) + 18.732s (userspace) = 31.636s. May 30 22:06:04 volumio volumio[995]: error: MPD error: The expression evaluated to a falsy value: May 30 22:06:04 volumio volumio[995]: assert.ok(self.idling) May 30 22:06:04 volumio volumio[995]: error: The expression evaluated to a falsy value: May 30 22:06:04 volumio volumio[995]: assert.ok(self.idling) May 30 22:06:05 volumio volumio[995]: info: MPD running with PID1227 May 30 22:06:05 volumio volumio[995]: ,establishing connection May 30 22:06:05 volumio volumio[995]: error: updateQueue error: null May 30 22:06:05 volumio volumio[995]: error: updateQueue error: null May 30 22:06:05 volumio volumio[995]: info: Discovery: this is already registered, 79c42b01-dcd6-4e96-a390-f3abaa5fd694 May 30 22:06:05 volumio volumio[995]: info: Discovery: Found device Volumio May 30 22:06:05 volumio volumio[995]: info: CoreCommandRouter::volumioGetState May 30 22:06:05 volumio volumio[995]: info: CorePlayQueue::getTrack 0 May 30 22:06:05 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. May 30 22:06:05 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 30 22:06:05 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 30 22:06:05 volumio upmpdcli[1472]: Could not open config: /tmp/upmpdcli.conf May 30 22:06:05 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 30 22:06:05 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 30 22:06:05 volumio ntpd[929]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 May 30 22:06:05 volumio ntpd[929]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 May 30 22:06:05 volumio ntpd[929]: DNS: Pool taking: 84.16.67.12 May 30 22:06:05 volumio ntpd[929]: DNS: Pool taking: 84.16.73.33 May 30 22:06:05 volumio ntpd[929]: DNS: Pool skipping: 139.199.215.251 May 30 22:06:05 volumio ntpd[929]: DNS: Pool skipping: 116.203.151.74 May 30 22:06:05 volumio ntpd[929]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 May 30 22:06:05 volumio volumio5-onboarding[1215]: time=2026-05-30T22:06:05.707+08:00 level=INFO msg="service successfully established" component=discovery/localnet May 30 22:06:06 volumio volumio[995]: info: Volumio Network Manager: Network status updated: 1 May 30 22:06:06 volumio sudo[1501]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 22:06:06 volumio sudo[1501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 22:06:06 volumio sudo[1501]: pam_unix(sudo:session): session closed for user root May 30 22:06:06 volumio sudo[1503]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 22:06:06 volumio sudo[1503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 22:06:06 volumio sudo[1503]: pam_unix(sudo:session): session closed for user root May 30 22:06:06 volumio sudo[1509]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 30 22:06:06 volumio sudo[1509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 22:06:06 volumio ntpd[929]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 30 22:06:06 volumio ntpd[929]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 30 22:06:06 volumio ntpd[929]: DNS: Pool taking: 119.28.206.193 May 30 22:06:06 volumio ntpd[929]: DNS: Pool taking: 111.230.189.174 May 30 22:06:06 volumio ntpd[929]: DNS: Pool skipping: 108.59.2.24 May 30 22:06:06 volumio ntpd[929]: DNS: Pool taking: 185.209.85.222 May 30 22:06:06 volumio ntpd[929]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 May 30 22:06:09 volumio volumio[995]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 30 22:06:41 volumio ntpd[929]: CLOCK: time stepped by 30.593932 May 30 22:06:41 volumio ntpd[929]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 30 22:06:42 volumio volumio[995]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.119&uuid=112a0063cebe35d0d74f7bcba30d01e7" http://updates.volumio.org/downloader-v1/track-device May 30 22:06:42 volumio volumio[995]: curl: (6) Could not resolve host: updates.volumio.org May 30 22:06:42 volumio volumio[995]: retrying in 5 seconds, trial 0 May 30 22:06:42 volumio volumio[995]: info: Volumio Calling Home May 30 22:06:42 volumio volumio[995]: info: Volumio called home May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 30 22:06:44 volumio volumio[995]: info: Adding plugin bluetooth to MyMusic Plugins May 30 22:06:44 volumio volumio[995]: info: Adding plugin multiroom to MyMusic Plugins May 30 22:06:44 volumio volumio[995]: info: Adding plugin metavolumio to MyMusic Plugins May 30 22:06:44 volumio volumio[995]: info: Adding plugin cd_controller to MyMusic Plugins May 30 22:06:44 volumio volumio[995]: info: Adding plugin qobuzconnect to MyMusic Plugins May 30 22:06:44 volumio volumio[995]: info: Adding plugin smart_inputs to MyMusic Plugins May 30 22:06:44 volumio volumio[995]: info: Adding plugin tidalconnect to MyMusic Plugins May 30 22:06:44 volumio volumio[995]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 30 22:06:45 volumio volumio[995]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 30 22:06:45 volumio volumio[995]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 30 22:06:45 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 22:06:45 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 22:06:45 volumio volumio[995]: info: Starting MyVolumio Remote Streaming Endpoints May 30 22:06:45 volumio volumio[995]: info: MyVolumio not started May 30 22:06:45 volumio volumio[995]: info: Initializing device activation check May 30 22:06:45 volumio volumio[995]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 30 22:06:45 volumio volumio[995]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 30 22:06:46 volumio volumio[995]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 30 22:06:46 volumio volumio[995]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 30 22:06:46 volumio volumio[995]: info: Streaming services startup May 30 22:06:46 volumio volumio[995]: info: Starting Streaming Daemon May 30 22:06:46 volumio volumio[995]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 30 22:06:46 volumio sudo[1519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 30 22:06:46 volumio sudo[1519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 22:06:46 volumio sudo[1519]: pam_unix(sudo:session): session closed for user root May 30 22:06:46 volumio volumio[995]: error: Cannot start Volumio Streaming Daemon May 30 22:06:46 volumio volumio[995]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 30 22:06:46 volumio volumio[995]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 30 22:06:48 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. May 30 22:06:50 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 30 22:06:50 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. May 30 22:06:50 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 30 22:06:51 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 30 22:06:51 volumio sudo[1509]: pam_unix(sudo:session): session closed for user root May 30 22:06:51 volumio volumio[995]: info: Upmpdcli Daemon Started May 30 22:06:57 volumio upmpdcli[1572]: writing RSA key May 30 22:07:00 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 22:07:00 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 22:07:00 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 30 22:07:00 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 30 22:07:00 volumio volumio[995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 30 22:07:00 volumio volumio[995]: info: CoreCommandRouter::volumioGetState May 30 22:07:00 volumio volumio[995]: info: CorePlayQueue::getTrack 0 May 30 22:07:00 volumio volumio-remote-updater[729]: Test mode disabled May 30 22:07:00 volumio volumio-remote-updater[729]: Alpha mode disabled May 30 22:07:00 volumio volumio-remote-updater[729]: Alpha legacy test mode disabled May 30 22:07:00 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 30 22:07:01 volumio volumio[995]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 30 22:07:01 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 30 22:07:04 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 30 22:07:05 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:07:05 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:07:05 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 30 22:07:05 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 30 22:07:05 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 30 22:07:05 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 30 22:07:05 volumio volumio[995]: info: CoreCommandRouter::volumioGetBrowseSources May 30 22:07:05 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 22:07:06 volumio volumio[995]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 30 22:07:06 volumio volumio[995]: info: Completed starting MyVolumio Plugin May 30 22:07:06 volumio volumio[995]: [Metrics] CommandRouter: 41s 907.34ms May 30 22:07:06 volumio volumio[995]: info: CoreCommandRouter::volumiosetStartupVolume May 30 22:07:06 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 22:07:06 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 22:07:06 volumio volumio[995]: info: CoreCommandRouter::Close All Modals sent May 30 22:07:06 volumio volumio[995]: info: CoreCommandRouter::Close All Modals sent May 30 22:07:06 volumio volumio[995]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 30 22:07:06 volumio volumio[995]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for sndrpihifiberry May 30 22:07:06 volumio volumio[995]: aplay: main:831: audio open error: No such device May 30 22:07:07 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 30 22:07:07 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 30 22:07:07 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 30 22:07:10 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. May 30 22:07:10 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 30 22:07:13 volumio volumio[995]: info: BOOT COMPLETED May 30 22:07:26 volumio volumio[995]: verbose: New Socket.io Connection to 192.168.50.174 from 192.168.50.219 UA: Mozilla/5.0 (Linux; U; Android 16; zh-cn; PGU110 Build/BP2A.250605.015) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/115.0.5790.168 Mobile Safari/537.36 HeyTapBrowser/40.10.16.1 Engine version: 3 Transport: polling Total Clients: 5 May 30 22:07:31 volumio volumio[995]: verbose: New Socket.io Connection to 192.168.50.174 from 192.168.50.219 UA: Mozilla/5.0 (Linux; U; Android 16; zh-cn; PGU110 Build/BP2A.250605.015) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/115.0.5790.168 Mobile Safari/537.36 HeyTapBrowser/40.10.16.1 Engine version: 3 Transport: polling Total Clients: 6 May 30 22:07:31 volumio sudo[1652]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 22:07:31 volumio sudo[1652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 22:07:31 volumio sudo[1652]: pam_unix(sudo:session): session closed for user root May 30 22:07:31 volumio sudo[1654]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 22:07:31 volumio sudo[1654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 22:07:31 volumio sudo[1654]: pam_unix(sudo:session): session closed for user root May 30 22:07:31 volumio volumio[995]: verbose: New Socket.io Connection to 192.168.50.174 from 192.168.50.219 UA: Mozilla/5.0 (Linux; U; Android 16; zh-cn; PGU110 Build/BP2A.250605.015) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/115.0.5790.168 Mobile Safari/537.36 HeyTapBrowser/40.10.16.1 Engine version: 3 Transport: polling Total Clients: 7 May 30 22:07:32 volumio sudo[1660]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 22:07:32 volumio sudo[1660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 22:07:32 volumio sudo[1662]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 22:07:32 volumio sudo[1662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 22:07:32 volumio sudo[1660]: pam_unix(sudo:session): session closed for user root May 30 22:07:32 volumio sudo[1662]: pam_unix(sudo:session): session closed for user root May 30 22:07:32 volumio volumio[995]: verbose: New Socket.io Connection to 192.168.50.174 from 192.168.50.219 UA: Mozilla/5.0 (Linux; U; Android 16; zh-cn; PGU110 Build/BP2A.250605.015) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/115.0.5790.168 Mobile Safari/537.36 HeyTapBrowser/40.10.16.1 Engine version: 3 Transport: polling Total Clients: 8 May 30 22:07:33 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 30 22:07:33 volumio volumio[995]: info: CoreCommandRouter::volumioGetVisibleSources May 30 22:07:33 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 22:07:33 volumio volumio[995]: info: CoreCommandRouter::volumioGetState May 30 22:07:33 volumio volumio[995]: info: CorePlayQueue::getTrack 0 May 30 22:07:33 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 30 22:07:33 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 30 22:07:33 volumio volumio[995]: info: Received Get System Info May 30 22:07:33 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 22:07:33 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 22:07:33 volumio volumio[995]: info: Discovery: Getting this device information May 30 22:07:33 volumio volumio[995]: info: CoreCommandRouter::volumioGetState May 30 22:07:33 volumio volumio[995]: info: CorePlayQueue::getTrack 0 May 30 22:07:33 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 22:07:33 volumio volumio[995]: info: CoreCommandRouter::volumioGetState May 30 22:07:33 volumio volumio[995]: info: CorePlayQueue::getTrack 0 May 30 22:07:33 volumio volumio[995]: info: Listing playlists May 30 22:07:33 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 30 22:07:33 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 30 22:07:33 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 30 22:07:34 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 30 22:07:34 volumio volumio[995]: info: Received Get System Info May 30 22:07:34 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 22:07:34 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 22:07:34 volumio volumio[995]: info: Discovery: Getting this device information May 30 22:07:34 volumio volumio[995]: info: CoreCommandRouter::volumioGetState May 30 22:07:34 volumio volumio[995]: info: CorePlayQueue::getTrack 0 May 30 22:07:34 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 22:07:34 volumio volumio[995]: info: CoreCommandRouter::volumioPlay May 30 22:07:34 volumio volumio[995]: info: CoreStateMachine::play index undefined May 30 22:07:34 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 22:07:34 volumio volumio[995]: info: CorePlayQueue::getTrack 0 May 30 22:07:34 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 30 22:07:38 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue May 30 22:07:38 volumio volumio[995]: info: CoreStateMachine::getQueue May 30 22:07:38 volumio volumio[995]: info: CorePlayQueue::getQueue May 30 22:07:40 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 30 22:07:40 volumio volumio[995]: info: CURURI: music-library May 30 22:07:40 volumio volumio[995]: info: Preload queue cleared May 30 22:07:41 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 30 22:07:41 volumio volumio[995]: info: CURURI: music-library/NAS May 30 22:07:41 volumio volumio[995]: info: Preload queue cleared May 30 22:07:41 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 30 22:07:41 volumio volumio[995]: info: CURURI: music-library/NAS/music May 30 22:07:41 volumio volumio[995]: info: Preload queue cleared May 30 22:07:42 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 30 22:07:42 volumio volumio[995]: info: CURURI: music-library/NAS/music/Music May 30 22:07:42 volumio volumio[995]: error: Cue Parser - Cannot parse NAS/music/Music/区瑞强.-.[民歌味道IV].专辑.(APE).cue May 30 22:07:42 volumio volumio[995]: info: Preload queue cleared May 30 22:07:42 volumio volumio[995]: info: Preloading song: music-library/NAS/music/Music/区瑞强.-.[民歌味道IV].专辑.(APE).ape May 30 22:07:42 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/区瑞强.-.[民歌味道IV].专辑.(APE).ape in service mpd May 30 22:07:42 volumio volumio[995]: info: ALBUMART /albumart?cacheid=437&web=//extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FMusic%2F%E5%8C%BA%E7%91%9E%E5%BC%BA.-.%5B%E6%B0%91%E6%AD%8C%E5%91%B3%E9%81%93IV%5D.%E4%B8%93%E8%BE%91.(APE).ape&metadata=false May 30 22:07:42 volumio volumio[995]: info: URI /mnt/NAS/music/Music/区瑞强.-.[民歌味道IV].专辑.(APE).ape May 30 22:07:42 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 30 22:07:43 volumio volumio[995]: info: Preload queue cleared May 30 22:07:43 volumio volumio[995]: info: CoreCommandRouter::volumioReplaceandPlayItems May 30 22:07:43 volumio volumio[995]: info: CoreStateMachine::ClearQueue May 30 22:07:43 volumio volumio[995]: info: CoreStateMachine::stop May 30 22:07:43 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 22:07:43 volumio volumio[995]: info: CorePlayQueue::clearPlayQueue May 30 22:07:43 volumio volumio[995]: info: CorePlayQueue::saveQueue May 30 22:07:43 volumio volumio[995]: info: CoreCommandRouter::volumioPushQueue May 30 22:07:43 volumio volumio[995]: info: CoreStateMachine::addQueueItems May 30 22:07:43 volumio volumio[995]: info: CorePlayQueue::addQueueItems May 30 22:07:43 volumio volumio[995]: info: Preload queue cleared May 30 22:07:43 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/区瑞强.-.[民歌味道IV].专辑.(APE).ape May 30 22:07:43 volumio volumio[995]: info: Using cached record of: music-library/NAS/music/Music/区瑞强.-.[民歌味道IV].专辑.(APE).ape May 30 22:07:43 volumio volumio[995]: info: CoreCommandRouter::volumioPushQueue May 30 22:07:43 volumio volumio[995]: info: CorePlayQueue::saveQueue May 30 22:07:43 volumio volumio[995]: info: CoreStateMachine::updateTrackBlock May 30 22:07:43 volumio volumio[995]: info: CorePlayQueue::getTrackBlock May 30 22:07:43 volumio volumio[995]: info: CoreCommandRouter::volumioPlay May 30 22:07:43 volumio volumio[995]: info: CoreStateMachine::play index 0 May 30 22:07:43 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 22:07:43 volumio volumio[995]: info: CoreStateMachine::addQueueItems May 30 22:07:43 volumio volumio[995]: info: CorePlayQueue::addQueueItems May 30 22:07:43 volumio volumio[995]: info: Preload queue cleared May 30 22:07:43 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/《2008留声机古典百大系列》APE整轨 May 30 22:07:43 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/《2008留声机古典百大系列》APE整轨 in service mpd May 30 22:07:43 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/《监听发烧人声·磁性女声 DSD》【WAV】 May 30 22:07:43 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/《监听发烧人声·磁性女声 DSD》【WAV】 in service mpd May 30 22:07:43 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/006 久石让(Joe Hisaishi).-.[Piano.Stories.III-Nostalgia].专辑.(APE) May 30 22:07:43 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/006 久石让(Joe Hisaishi).-.[Piano.Stories.III-Nostalgia].专辑.(APE) in service mpd May 30 22:07:43 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/1834 首 无损单曲 -www.cdbao.net May 30 22:07:43 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/1834 首 无损单曲 -www.cdbao.net in service mpd May 30 22:07:45 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/2013-SIAV第21届上海国际高级HIFI演示会纪念CD May 30 22:07:45 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/2013-SIAV第21届上海国际高级HIFI演示会纪念CD in service mpd May 30 22:07:45 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/Disney May 30 22:07:45 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/Disney in service mpd May 30 22:07:45 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/zhubinjie-[试音宝典 试机三十三号][WAV] May 30 22:07:45 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/zhubinjie-[试音宝典 试机三十三号][WAV] in service mpd May 30 22:07:45 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/中国最爱儿歌经典(三) May 30 22:07:45 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/中国最爱儿歌经典(三) in service mpd May 30 22:07:45 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/中国最爱儿歌经典(二) May 30 22:07:45 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/中国最爱儿歌经典(二) in service mpd May 30 22:07:45 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/北京天使合唱团12CD May 30 22:07:45 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/北京天使合唱团12CD in service mpd May 30 22:07:45 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/区瑞强民歌味道 May 30 22:07:45 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/区瑞强民歌味道 in service mpd May 30 22:07:45 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/周华健 周而复始 iTunes Plus AAC May 30 22:07:45 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/周华健 周而复始 iTunes Plus AAC in service mpd May 30 22:07:45 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/喜欢盘01 May 30 22:07:45 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/喜欢盘01 in service mpd May 30 22:07:45 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/天乐唱片《扣人心弦怀旧心曲》 May 30 22:07:45 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/天乐唱片《扣人心弦怀旧心曲》 in service mpd May 30 22:07:45 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/张国荣.-.[告别当年情珍藏版CD1].专辑.(APE) May 30 22:07:45 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/张国荣.-.[告别当年情珍藏版CD1].专辑.(APE) in service mpd May 30 22:07:45 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/张学友《历年获奖金曲》[WAV] May 30 22:07:45 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/张学友《历年获奖金曲》[WAV] in service mpd May 30 22:07:45 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/张学友《友情翻唱》 May 30 22:07:45 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/张学友《友情翻唱》 in service mpd May 30 22:07:45 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/惠威试音天碟Ⅰ-人声试音 May 30 22:07:45 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/惠威试音天碟Ⅰ-人声试音 in service mpd May 30 22:07:46 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/惠威试音天碟Ⅱ-惠威综合试音1 May 30 22:07:46 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/惠威试音天碟Ⅱ-惠威综合试音1 in service mpd May 30 22:07:46 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/惠威试音天碟Ⅲ-惠威综合试音2 May 30 22:07:46 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/惠威试音天碟Ⅲ-惠威综合试音2 in service mpd May 30 22:07:46 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/惠威试音天碟Ⅳ-惠威综合试音3 May 30 22:07:46 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/惠威试音天碟Ⅳ-惠威综合试音3 in service mpd May 30 22:07:46 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/惠威试音天碟Ⅴ-惠威综合试音4 May 30 22:07:46 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/惠威试音天碟Ⅴ-惠威综合试音4 in service mpd May 30 22:07:46 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/李健 May 30 22:07:46 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/李健 in service mpd May 30 22:07:46 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/李健《温暖嗓音 DSD》 May 30 22:07:46 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/李健《温暖嗓音 DSD》 in service mpd May 30 22:07:46 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/李星儿.-.[童声监听王.DTS-ES].专辑 WAV分轨 May 30 22:07:46 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/李星儿.-.[童声监听王.DTS-ES].专辑 WAV分轨 in service mpd May 30 22:07:46 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/民歌100-校园民歌经典珍藏cd1 May 30 22:07:46 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/民歌100-校园民歌经典珍藏cd1 in service mpd May 30 22:07:46 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/民歌100-校园民歌经典珍藏cd2 May 30 22:07:46 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/民歌100-校园民歌经典珍藏cd2 in service mpd May 30 22:07:46 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/民歌100-校园民歌经典珍藏cd3 May 30 22:07:46 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/民歌100-校园民歌经典珍藏cd3 in service mpd May 30 22:07:46 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/民歌100-校园民歌经典珍藏cd4 May 30 22:07:46 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/民歌100-校园民歌经典珍藏cd4 in service mpd May 30 22:07:46 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/民歌100-校园民歌经典珍藏cd5 May 30 22:07:46 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/民歌100-校园民歌经典珍藏cd5 in service mpd May 30 22:07:46 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/港囧 May 30 22:07:46 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/港囧 in service mpd May 30 22:07:46 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/美国乡村-经典民谣22首 May 30 22:07:46 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/美国乡村-经典民谣22首 in service mpd May 30 22:07:46 volumio volumio[995]: info: Adding Item to queue: music-library/NAS/music/Music/群星《 美妙的天籁之音-中外童声合唱经典（3CD）》 May 30 22:07:46 volumio volumio[995]: info: Exploding uri music-library/NAS/music/Music/群星《 美妙的天籁之音-中外童声合唱经典（3CD）》 in service mpd May 30 22:07:46 volumio volumio[995]: info: CoreStateMachine::stop May 30 22:07:46 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 22:07:46 volumio volumio[995]: info: CoreStateMachine::play index undefined May 30 22:07:46 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 22:07:46 volumio volumio[995]: info: CorePlayQueue::getTrack 0 May 30 22:07:46 volumio volumio[995]: info: CoreStateMachine::startPlaybackTimer May 30 22:07:46 volumio volumio[995]: info: CorePlayQueue::getTrack 0 May 30 22:07:46 volumio volumio[995]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/Music/区瑞强.-.[民歌味道IV].专辑.(APE).ape May 30 22:07:46 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand stop May 30 22:07:46 volumio volumio[995]: info: ALBUMART /albumart?cacheid=437&web=//extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FMusic%2F%E3%80%8A2008%E7%95%99%E5%A3%B0%E6%9C%BA%E5%8F%A4%E5%85%B8%E7%99%BE%E5%A4%A7%E7%B3%BB%E5%88%97%E3%80%8BAPE%E6%95%B4%E8%BD%A8%2F76-80%2F%E8%8B%B1%E5%9B%BD%E7%95%99%E5%A3%B0%E6%9C%BA%E6%9D%82%E5%BF%97%E6%8E%A8%E8%8D%90%E5%8E%86%E5%8F%B2100%E6%9C%80%E4%BD%B3%E5%BD%95%E9%9F%B3_100_GREAT_RECORDINGS76-80%2F76%20%5BShostakovich%5D%20String%20Quartets%20%5BDG%20463%20284-2%5D%20Emerson%20Quartets%2FCD1%2FCDImage.ape&metadata=false May 30 22:07:46 volumio volumio[995]: info: URI /mnt/NAS/music/Music/《2008留声机古典百大系列》APE整轨/76-80/英国留声机杂志推荐历史100最佳录音_100_GREAT_RECORDINGS76-80/76 [Shostakovich] String Quartets [DG 463 284-2] Emerson Quartets/CD1/CDImage.ape May 30 22:07:46 volumio volumio[995]: info: ALBUMART /albumart?cacheid=437&web=Emerson%20String%20Quartet/Shostakovich%3A%20The%20String%20Quartets%20(CD%201_Nos.%201-3)/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FMusic%2F%E3%80%8A2008%E7%95%99%E5%A3%B0%E6%9C%BA%E5%8F%A4%E5%85%B8%E7%99%BE%E5%A4%A7%E7%B3%BB%E5%88%97%E3%80%8BAPE%E6%95%B4%E8%BD%A8%2F76-80%2F%E8%8B%B1%E5%9B%BD%E7%95%99%E5%A3%B0%E6%9C%BA%E6%9D%82%E5%BF%97%E6%8E%A8%E8%8D%90%E5%8E%86%E5%8F%B2100%E6%9C%80%E4%BD%B3%E5%BD%95%E9%9F%B3_100_GREAT_RECORDINGS76-80%2F76%20%5BShostakovich%5D%20String%20Quartets%20%5BDG%20463%20284-2%5D%20Emerson%20Quartets%2FCD1%2FCDImage.cue&metadata=false May 30 22:07:46 volumio volumio[995]: info: URI /mnt/NAS/music/Music/《2008留声机古典百大系列》APE整轨/76-80/英国留声机杂志推荐历史100最佳录音_100_GREAT_RECORDINGS76-80/76 [Shostakovich] String Quartets [DG 463 284-2] Emerson Quartets/CD1/CDImage.cue May 30 22:07:46 volumio volumio[995]: info: ALBUMART /albumart?cacheid=437&web=Emerson%20String%20Quartet/Shostakovich%3A%20The%20String%20Quartets%20(CD%201_Nos.%201-3)/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FMusic%2F%E3%80%8A2008%E7%95%99%E5%A3%B0%E6%9C%BA%E5%8F%A4%E5%85%B8%E7%99%BE%E5%A4%A7%E7%B3%BB%E5%88%97%E3%80%8BAPE%E6%95%B4%E8%BD%A8%2F76-80%2F%E8%8B%B1%E5%9B%BD%E7%95%99%E5%A3%B0%E6%9C%BA%E6%9D%82%E5%BF%97%E6%8E%A8%E8%8D%90%E5%8E%86%E5%8F%B2100%E6%9C%80%E4%BD%B3%E5%BD%95%E9%9F%B3_100_GREAT_RECORDINGS76-80%2F76%20%5BShostakovich%5D%20String%20Quartets%20%5BDG%20463%20284-2%5D%20Emerson%20Quartets%2FCD1%2FCDImage.cue&metadata=false May 30 22:07:46 volumio volumio[995]: info: URI /mnt/NAS/music/Music/《2008留声机古典百大系列》APE整轨/76-80/英国留声机杂志推荐历史100最佳录音_100_GREAT_RECORDINGS76-80/76 [Shostakovich] String Quartets [DG 463 284-2] Emerson Quartets/CD1/CDImage.cue May 30 22:07:46 volumio volumio[995]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 22:07:46 volumio volumio[995]: Error: Unable to resolve or reject the same promise twice May 30 22:07:46 volumio volumio[995]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 30 22:07:46 volumio volumio[995]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 May 30 22:07:46 volumio volumio[995]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) May 30 22:07:46 volumio volumio[995]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) May 30 22:07:46 volumio volumio[995]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) May 30 22:07:46 volumio volumio[995]: at Socket.emit (node:events:514:28) May 30 22:07:46 volumio volumio[995]: at addChunk (node:internal/streams/readable:343:12) May 30 22:07:46 volumio volumio[995]: at readableAddChunk (node:internal/streams/readable:312:11) May 30 22:07:46 volumio volumio[995]: at Readable.push (node:internal/streams/readable:253:10) May 30 22:07:46 volumio volumio[995]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) May 30 22:07:46 volumio volumio[995]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 22:07:48 volumio sudo[1711]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-30 22:06' May 30 22:07:48 volumio sudo[1711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"