Mar 25 20:30:22 volumio-keller dhcpcd[855]: eth0: carrier lost Mar 25 20:30:22 volumio-keller dhcpcd[770]: eth0: carrier lost Mar 25 20:30:22 volumio-keller kernel: r8169 0000:02:00.0 eth0: Link is Down Mar 25 20:30:22 volumio-keller avahi-daemon[830]: Withdrawing address record for 192.168.2.120 on eth0. Mar 25 20:30:22 volumio-keller avahi-daemon[830]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.2.120. Mar 25 20:30:22 volumio-keller avahi-daemon[830]: Interface eth0.IPv4 no longer relevant for mDNS. Mar 25 20:30:22 volumio-keller dhcpcd[770]: eth0: deleting route to 192.168.2.0/24 Mar 25 20:30:22 volumio-keller dhcpcd[770]: eth0: deleting default route via 192.168.2.1 Mar 25 20:30:22 volumio-keller volumio[1036]: info: Discovery: A device disappeared from network Mar 25 20:30:22 volumio-keller systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Mar 25 20:30:22 volumio-keller systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Mar 25 20:30:22 volumio-keller systemd[1]: welcome.service: Deactivated successfully. Mar 25 20:30:22 volumio-keller systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 25 20:30:22 volumio-keller systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 25 20:30:22 volumio-keller dhcpcd[855]: eth0: deleting route to 192.168.2.0/24 Mar 25 20:30:22 volumio-keller dhcpcd[855]: eth0: deleting default route via 192.168.2.1 Mar 25 20:30:22 volumio-keller systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 25 20:30:22 volumio-keller welcome[4849]: Resolved ip:[0] Mar 25 20:30:22 volumio-keller systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 25 20:30:22 volumio-keller systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Mar 25 20:30:22 volumio-keller volumio[1036]: info: Received Get System Info Mar 25 20:30:22 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 20:30:22 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 20:30:22 volumio-keller volumio[1036]: info: Discovery: Getting this device information Mar 25 20:30:22 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:30:22 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:30:22 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 20:30:22 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 25 20:30:22 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 25 20:30:22 volumio-keller ifplugd(eth0)[982]: Link beat lost. Mar 25 20:30:23 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:30:23.418+01:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 25 20:30:23 volumio-keller ntpd[973]: IO: Deleting interface #3 eth0, 192.168.2.120#123, interface stats: received=221, sent=221, dropped=0, active_time=465 secs Mar 25 20:30:23 volumio-keller ntpd[973]: PROTO: 81.3.27.46 unlink local addr 192.168.2.120 -> Mar 25 20:30:23 volumio-keller ntpd[973]: PROTO: 212.132.97.26 unlink local addr 192.168.2.120 -> Mar 25 20:30:23 volumio-keller ntpd[973]: PROTO: 162.159.200.1 unlink local addr 192.168.2.120 -> Mar 25 20:30:23 volumio-keller ntpd[973]: PROTO: 176.9.44.212 unlink local addr 192.168.2.120 -> Mar 25 20:30:23 volumio-keller ntpd[973]: PROTO: 202.61.195.221 unlink local addr 192.168.2.120 -> Mar 25 20:30:23 volumio-keller ntpd[973]: PROTO: 152.53.191.142 unlink local addr 192.168.2.120 -> Mar 25 20:30:23 volumio-keller ntpd[973]: PROTO: 185.248.189.10 unlink local addr 192.168.2.120 -> Mar 25 20:30:23 volumio-keller ntpd[973]: PROTO: 77.90.0.148 unlink local addr 192.168.2.120 -> Mar 25 20:30:23 volumio-keller ntpd[973]: PROTO: 176.9.84.209 unlink local addr 192.168.2.120 -> Mar 25 20:30:23 volumio-keller ntpd[973]: PROTO: 93.177.65.20 unlink local addr 192.168.2.120 -> Mar 25 20:30:23 volumio-keller ntpd[973]: PROTO: 188.174.253.188 unlink local addr 192.168.2.120 -> Mar 25 20:30:23 volumio-keller ntpd[973]: PROTO: 185.252.140.126 unlink local addr 192.168.2.120 -> Mar 25 20:30:23 volumio-keller ntpd[973]: PROTO: 141.144.246.224 unlink local addr 192.168.2.120 -> Mar 25 20:30:23 volumio-keller ntpd[973]: PROTO: 193.141.27.6 unlink local addr 192.168.2.120 -> Mar 25 20:30:23 volumio-keller ntpd[973]: PROTO: 168.119.211.223 unlink local addr 192.168.2.120 -> Mar 25 20:30:25 volumio-keller dhcpcd[855]: eth0: carrier acquired Mar 25 20:30:25 volumio-keller dhcpcd[770]: eth0: carrier acquired Mar 25 20:30:25 volumio-keller kernel: r8169 0000:02:00.0 eth0: Link is Up - 1Gbps/Full - flow control off Mar 25 20:30:25 volumio-keller dhcpcd[770]: eth0: IAID 4d:a2:10:bd Mar 25 20:30:25 volumio-keller dhcpcd[855]: eth0: IAID 4d:a2:10:bd Mar 25 20:30:26 volumio-keller dhcpcd[770]: eth0: soliciting an IPv6 router Mar 25 20:30:26 volumio-keller volumio[1036]: info: Received Get System Info Mar 25 20:30:26 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 20:30:26 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 20:30:26 volumio-keller volumio[1036]: info: Discovery: Getting this device information Mar 25 20:30:26 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:30:26 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:30:26 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 20:30:26 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 25 20:30:26 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 25 20:30:26 volumio-keller dhcpcd[855]: eth0: soliciting an IPv6 router Mar 25 20:30:26 volumio-keller ifplugd(eth0)[982]: Link beat detected. Mar 25 20:30:26 volumio-keller kernel: usb 3-5: new SuperSpeed USB device number 2 using xhci_hcd Mar 25 20:30:26 volumio-keller kernel: usb 3-5: New USB device found, idVendor=152d, idProduct=a583, bcdDevice= 2.14 Mar 25 20:30:26 volumio-keller kernel: usb 3-5: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 25 20:30:26 volumio-keller kernel: usb 3-5: Product: External Mar 25 20:30:26 volumio-keller kernel: usb 3-5: Manufacturer: JMicron Mar 25 20:30:26 volumio-keller kernel: usb 3-5: SerialNumber: DD56419883914 Mar 25 20:30:26 volumio-keller kernel: scsi host0: uas Mar 25 20:30:26 volumio-keller kernel: scsi 0:0:0:0: Direct-Access JMicron Tech 0214 PQ: 0 ANSI: 6 Mar 25 20:30:26 volumio-keller kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Mar 25 20:30:27 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:30:27.378+01:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 25 20:30:27 volumio-keller dhcpcd[770]: eth0: rebinding lease of 192.168.2.120 Mar 25 20:30:27 volumio-keller dhcpcd[770]: eth0: probing address 192.168.2.120/24 Mar 25 20:30:27 volumio-keller dhcpcd[855]: eth0: rebinding lease of 192.168.2.120 Mar 25 20:30:27 volumio-keller dhcpcd[855]: eth0: probing address 192.168.2.120/24 Mar 25 20:30:28 volumio-keller kernel: sd 0:0:0:0: [sda] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB) Mar 25 20:30:28 volumio-keller kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Mar 25 20:30:28 volumio-keller kernel: sd 0:0:0:0: [sda] Write Protect is off Mar 25 20:30:28 volumio-keller kernel: sd 0:0:0:0: [sda] Mode Sense: 5f 00 00 08 Mar 25 20:30:28 volumio-keller kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Mar 25 20:30:28 volumio-keller kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 4096 bytes Mar 25 20:30:28 volumio-keller kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Mar 25 20:30:28 volumio-keller kernel: sda: sda1 sda2 Mar 25 20:30:28 volumio-keller kernel: sd 0:0:0:0: [sda] Attached SCSI disk Mar 25 20:30:28 volumio-keller volumio[1036]: info: Mounting Device USB-1TB Mar 25 20:30:28 volumio-keller sudo[4903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/USB-1TB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 25 20:30:28 volumio-keller sudo[4903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 20:30:29 volumio-keller ntfs-3g[4907]: Version 2022.10.3 integrated FUSE 28 Mar 25 20:30:29 volumio-keller ntfs-3g[4907]: Mounted /dev/sda2 (Read-Write, label "USB-1TB", NTFS 3.1) Mar 25 20:30:29 volumio-keller ntfs-3g[4907]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 25 20:30:29 volumio-keller ntfs-3g[4907]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda2,blkdev,blksize=4096 Mar 25 20:30:29 volumio-keller ntfs-3g[4907]: Global ownership and permissions enforced, configuration type 7 Mar 25 20:30:29 volumio-keller sudo[4903]: pam_unix(sudo:session): session closed for user root Mar 25 20:30:29 volumio-keller volumio[1036]: info: Ignoring mount for partition: boot Mar 25 20:30:29 volumio-keller volumio[1036]: info: Scanning new location : "USB/USB-1TB" Mar 25 20:30:29 volumio-keller volumio[1036]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:30:29 volumio-keller volumio[1036]: info: Mar 25 20:30:29 volumio-keller volumio[1036]: ---------------------------- MPD announces state update: update Mar 25 20:30:29 volumio-keller volumio[1036]: info: ControllerMpd::getState Mar 25 20:30:29 volumio-keller volumio[1036]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:30:29 volumio-keller volumio[1036]: info: sendMpdCommand status took 1 milliseconds Mar 25 20:30:29 volumio-keller volumio[1036]: info: sendMpdCommand status took 1 milliseconds Mar 25 20:30:29 volumio-keller volumio[1036]: verbose: ControllerMpd::parseState Mar 25 20:30:29 volumio-keller volumio[1036]: info: Command Router : Notfying DB Updatetrue Mar 25 20:30:29 volumio-keller volumio[1036]: verbose: ControllerMpd::parseState Mar 25 20:30:29 volumio-keller volumio[1036]: info: ControllerMpd::pushState Mar 25 20:30:29 volumio-keller volumio[1036]: info: CoreCommandRouter::servicePushState Mar 25 20:30:29 volumio-keller volumio[1036]: info: CoreStateMachine::pushState Mar 25 20:30:29 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:30:29 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioPushState Mar 25 20:30:29 volumio-keller volumio[1036]: info: MRS: Pushing multiroomSync output update for this device Mar 25 20:30:29 volumio-keller volumio[1036]: info: MRS: Pushing multiroomSync output Mar 25 20:30:29 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:30:29 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:30:29 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:30:29 volumio-keller volumio[1036]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 20:30:29 volumio-keller volumio[1036]: verbose: CURRENT POSITION 0 Mar 25 20:30:29 volumio-keller volumio[1036]: info: CoreStateMachine::syncState stateService stop Mar 25 20:30:29 volumio-keller volumio[1036]: info: CoreStateMachine::syncState currentStatus stop Mar 25 20:30:29 volumio-keller volumio[1036]: info: CoreStateMachine::pushState Mar 25 20:30:29 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:30:29 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:30:29 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioPushState Mar 25 20:30:29 volumio-keller volumio[1036]: info: MRS: Pushing multiroomSync output update for this device Mar 25 20:30:29 volumio-keller volumio[1036]: info: MRS: Pushing multiroomSync output Mar 25 20:30:29 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:30:29 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:30:29 volumio-keller volumio[1036]: info: No code Mar 25 20:30:29 volumio-keller volumio[1036]: info: CoreStateMachine::pushState Mar 25 20:30:29 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:30:29 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioPushState Mar 25 20:30:29 volumio-keller volumio[1036]: info: MRS: Pushing multiroomSync output update for this device Mar 25 20:30:29 volumio-keller volumio[1036]: info: MRS: Pushing multiroomSync output Mar 25 20:30:29 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:30:29 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:30:29 volumio-keller volumio[1036]: info: ------------------------------ 34ms Mar 25 20:30:30 volumio-keller volumio[1036]: info: Volumio Network Manager: Network status updated: 0 Mar 25 20:30:32 volumio-keller dhcpcd[855]: eth0: leased 192.168.2.120 for 864000 seconds Mar 25 20:30:32 volumio-keller dhcpcd[855]: eth0: adding route to 192.168.2.0/24 Mar 25 20:30:32 volumio-keller dhcpcd[855]: eth0: adding default route via 192.168.2.1 Mar 25 20:30:32 volumio-keller avahi-daemon[830]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.120. Mar 25 20:30:32 volumio-keller avahi-daemon[830]: New relevant interface eth0.IPv4 for mDNS. Mar 25 20:30:32 volumio-keller avahi-daemon[830]: Registering new address record for 192.168.2.120 on eth0.IPv4. Mar 25 20:30:32 volumio-keller systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Mar 25 20:30:32 volumio-keller systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Mar 25 20:30:32 volumio-keller systemd[1]: welcome.service: Deactivated successfully. Mar 25 20:30:32 volumio-keller systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 25 20:30:32 volumio-keller systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 25 20:30:32 volumio-keller systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 25 20:30:32 volumio-keller welcome[4952]: Resolved ip:[1] 192.168.2.120 Mar 25 20:30:32 volumio-keller systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 25 20:30:32 volumio-keller dhcpcd[770]: eth0: leased 192.168.2.120 for 864000 seconds Mar 25 20:30:32 volumio-keller systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Mar 25 20:30:32 volumio-keller systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Mar 25 20:30:32 volumio-keller systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Mar 25 20:30:32 volumio-keller systemd[1]: welcome.service: Deactivated successfully. Mar 25 20:30:32 volumio-keller systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 25 20:30:32 volumio-keller systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 25 20:30:32 volumio-keller systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 25 20:30:32 volumio-keller welcome[4986]: Resolved ip:[1] 192.168.2.120 Mar 25 20:30:32 volumio-keller systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 25 20:30:32 volumio-keller systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Mar 25 20:30:32 volumio-keller dhcpcd[770]: eth0: adding route to 192.168.2.0/24 Mar 25 20:30:32 volumio-keller dhcpcd[770]: eth0: adding default route via 192.168.2.1 Mar 25 20:30:33 volumio-keller volumio[1036]: info: Received Get System Info Mar 25 20:30:33 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 20:30:33 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 20:30:33 volumio-keller volumio[1036]: info: Discovery: Getting this device information Mar 25 20:30:33 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:30:33 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:30:33 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 20:30:33 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 25 20:30:33 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 25 20:30:33 volumio-keller volumio[1036]: info: Discovery: adding e0d050ec-a46f-468d-90f8-a8ad6d8a4dc9 Mar 25 20:30:33 volumio-keller volumio[1036]: info: Discovery: Found device Volumio Keller Mar 25 20:30:33 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:30:33 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:30:34 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:30:34.238+01:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 25 20:30:34 volumio-keller ntpd[973]: IO: Listen normally on 4 eth0 192.168.2.120:123 Mar 25 20:30:34 volumio-keller ntpd[973]: IO: new interface(s) found: waking up resolver Mar 25 20:30:37 volumio-keller volumio[1036]: info: CoreCommandRouter::Close All Modals sent Mar 25 20:30:40 volumio-keller volumio[1036]: info: Volumio Network Manager: Network status updated: 1 Mar 25 20:30:45 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 20:30:45 volumio-keller volumio[1036]: info: CURURI: music-library Mar 25 20:30:45 volumio-keller volumio[1036]: info: Preload queue cleared Mar 25 20:30:46 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 20:30:46 volumio-keller volumio[1036]: info: CURURI: music-library/USB Mar 25 20:30:46 volumio-keller volumio[1036]: info: Preload queue cleared Mar 25 20:30:47 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 20:30:47 volumio-keller volumio[1036]: info: CURURI: music-library/USB/USB-1TB Mar 25 20:30:47 volumio-keller volumio[1036]: info: Preload queue cleared Mar 25 20:31:01 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:31:01 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:03 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:31:03.792+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.2.149:46846 Mar 25 20:31:03 volumio-keller volumio[1036]: verbose: New Socket.io Connection to 192.168.2.120 from 192.168.2.149 UA: Mozilla/5.0 (Linux; Android 15; moto g84 5G Build/V1TCS35H.88-16-3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.159 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 25 20:31:03 volumio-keller volumio[1036]: verbose: New Socket.io Connection to 192.168.2.120 from 192.168.2.149 UA: Mozilla/5.0 (Linux; Android 15; moto g84 5G Build/V1TCS35H.88-16-3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.159 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 25 20:31:03 volumio-keller volumio[1036]: verbose: New Socket.io Connection to 192.168.2.120 from 192.168.2.149 UA: Mozilla/5.0 (Linux; Android 15; moto g84 5G Build/V1TCS35H.88-16-3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.159 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 25 20:31:03 volumio-keller volumio[1036]: verbose: New Socket.io Connection to 192.168.2.120 from 192.168.2.149 UA: Mozilla/5.0 (Linux; Android 15; moto g84 5G Build/V1TCS35H.88-16-3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.159 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 25 20:31:04 volumio-keller sudo[5100]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 25 20:31:04 volumio-keller sudo[5100]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 20:31:04 volumio-keller sudo[5100]: pam_unix(sudo:session): session closed for user root Mar 25 20:31:04 volumio-keller sudo[5102]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 25 20:31:04 volumio-keller sudo[5102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 20:31:04 volumio-keller sudo[5102]: pam_unix(sudo:session): session closed for user root Mar 25 20:31:04 volumio-keller volumio[1036]: verbose: New Socket.io Connection to 192.168.2.120 from 192.168.2.149 UA: Mozilla/5.0 (Linux; Android 15; moto g84 5G Build/V1TCS35H.88-16-3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.159 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Mar 25 20:31:04 volumio-keller sudo[5108]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 25 20:31:04 volumio-keller sudo[5108]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 20:31:04 volumio-keller sudo[5110]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 25 20:31:04 volumio-keller sudo[5108]: pam_unix(sudo:session): session closed for user root Mar 25 20:31:04 volumio-keller sudo[5110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 20:31:04 volumio-keller sudo[5110]: pam_unix(sudo:session): session closed for user root Mar 25 20:31:04 volumio-keller volumio[1036]: verbose: New Socket.io Connection to 192.168.2.120 from 192.168.2.149 UA: Mozilla/5.0 (Linux; Android 15; moto g84 5G Build/V1TCS35H.88-16-3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.159 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Mar 25 20:31:04 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 25 20:31:04 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetVisibleSources Mar 25 20:31:04 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 20:31:04 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:31:04 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:04 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 25 20:31:04 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 25 20:31:04 volumio-keller volumio[1036]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 25 20:31:04 volumio-keller volumio[1036]: info: Received Get System Info Mar 25 20:31:04 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 20:31:04 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 20:31:04 volumio-keller volumio[1036]: info: Discovery: Getting this device information Mar 25 20:31:04 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:31:04 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:04 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 20:31:04 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:31:04 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:04 volumio-keller volumio[1036]: info: Listing playlists Mar 25 20:31:04 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 25 20:31:04 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 25 20:31:04 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 25 20:31:05 volumio-keller volumio[1036]: info: MPD Database updated - AlbumList cache refreshed Mar 25 20:31:05 volumio-keller volumio[1036]: info: Mar 25 20:31:05 volumio-keller volumio[1036]: ---------------------------- MPD announces state update: database Mar 25 20:31:05 volumio-keller volumio[1036]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:31:05 volumio-keller volumio[1036]: info: Mar 25 20:31:05 volumio-keller volumio[1036]: ---------------------------- MPD announces state update: update Mar 25 20:31:05 volumio-keller volumio[1036]: info: ControllerMpd::getState Mar 25 20:31:05 volumio-keller volumio[1036]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:31:05 volumio-keller volumio[1036]: info: ControllerMpd::getState Mar 25 20:31:05 volumio-keller volumio[1036]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:31:06 volumio-keller volumio[1036]: Cannot compose Albumart path Mar 25 20:31:06 volumio-keller volumio[1036]: info: sendMpdCommand status took 618 milliseconds Mar 25 20:31:06 volumio-keller volumio[1036]: info: sendMpdCommand status took 618 milliseconds Mar 25 20:31:06 volumio-keller volumio[1036]: info: sendMpdCommand status took 618 milliseconds Mar 25 20:31:06 volumio-keller volumio[1036]: verbose: ControllerMpd::parseState Mar 25 20:31:06 volumio-keller volumio[1036]: info: Command Router : Notfying DB Updatefalse Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::Close All Modals sent Mar 25 20:31:06 volumio-keller volumio[1036]: verbose: ControllerMpd::parseState Mar 25 20:31:06 volumio-keller volumio[1036]: verbose: ControllerMpd::parseState Mar 25 20:31:06 volumio-keller volumio[1036]: info: ControllerMpd::pushState Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::servicePushState Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreStateMachine::pushState Mar 25 20:31:06 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioPushState Mar 25 20:31:06 volumio-keller volumio[1036]: info: MRS: Pushing multiroomSync output update for this device Mar 25 20:31:06 volumio-keller volumio[1036]: info: MRS: Pushing multiroomSync output Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:31:06 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:06 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:06 volumio-keller volumio[1036]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 20:31:06 volumio-keller volumio[1036]: verbose: CURRENT POSITION 0 Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreStateMachine::syncState stateService stop Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreStateMachine::syncState currentStatus stop Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreStateMachine::pushState Mar 25 20:31:06 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioPushState Mar 25 20:31:06 volumio-keller volumio[1036]: info: MRS: Pushing multiroomSync output update for this device Mar 25 20:31:06 volumio-keller volumio[1036]: info: MRS: Pushing multiroomSync output Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:31:06 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:06 volumio-keller volumio[1036]: info: No code Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreStateMachine::pushState Mar 25 20:31:06 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioPushState Mar 25 20:31:06 volumio-keller volumio[1036]: info: MRS: Pushing multiroomSync output update for this device Mar 25 20:31:06 volumio-keller volumio[1036]: info: MRS: Pushing multiroomSync output Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:31:06 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:06 volumio-keller volumio[1036]: info: ControllerMpd::pushState Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::servicePushState Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreStateMachine::pushState Mar 25 20:31:06 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioPushState Mar 25 20:31:06 volumio-keller volumio[1036]: info: MRS: Pushing multiroomSync output update for this device Mar 25 20:31:06 volumio-keller volumio[1036]: info: MRS: Pushing multiroomSync output Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:31:06 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:06 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:06 volumio-keller volumio[1036]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 20:31:06 volumio-keller volumio[1036]: verbose: CURRENT POSITION 0 Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreStateMachine::syncState stateService stop Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreStateMachine::syncState currentStatus stop Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreStateMachine::pushState Mar 25 20:31:06 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioPushState Mar 25 20:31:06 volumio-keller volumio[1036]: info: MRS: Pushing multiroomSync output update for this device Mar 25 20:31:06 volumio-keller volumio[1036]: info: MRS: Pushing multiroomSync output Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:31:06 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:06 volumio-keller volumio[1036]: info: No code Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreStateMachine::pushState Mar 25 20:31:06 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioPushState Mar 25 20:31:06 volumio-keller volumio[1036]: info: MRS: Pushing multiroomSync output update for this device Mar 25 20:31:06 volumio-keller volumio[1036]: info: MRS: Pushing multiroomSync output Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:31:06 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:06 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:31:06.378+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.149:46846 @ 0xc0002b3200" state=STATUS_STOPPED positionMs=0 volume= Mar 25 20:31:06 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:31:06.378+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.149:46846 @ 0xc0002b3200" state=STATUS_STOPPED positionMs=0 volume= Mar 25 20:31:06 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:31:06.378+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.149:46846 @ 0xc0002b3200" id= title= Mar 25 20:31:06 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:31:06.378+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.149:46846 @ 0xc0002b3200" id= title= Mar 25 20:31:06 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:31:06.378+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.149:46846 @ 0xc0002b3200" state=STATUS_STOPPED positionMs=0 volume= Mar 25 20:31:06 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:31:06.378+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.149:46846 @ 0xc0002b3200" id= title= Mar 25 20:31:06 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:31:06.378+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.149:46846 @ 0xc0002b3200" state=STATUS_STOPPED positionMs=0 volume= Mar 25 20:31:06 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:31:06.378+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.149:46846 @ 0xc0002b3200" id= title= Mar 25 20:31:06 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:31:06.379+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.149:46846 @ 0xc0002b3200" state=STATUS_STOPPED positionMs=0 volume= Mar 25 20:31:06 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:31:06.379+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.149:46846 @ 0xc0002b3200" id= title= Mar 25 20:31:06 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:31:06.379+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.149:46846 @ 0xc0002b3200" state=STATUS_STOPPED positionMs=0 volume= Mar 25 20:31:06 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:31:06.379+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.149:46846 @ 0xc0002b3200" id= title= Mar 25 20:31:06 volumio-keller volumio[1036]: info: ------------------------------ 661ms Mar 25 20:31:06 volumio-keller volumio[1036]: info: ------------------------------ 661ms Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 25 20:31:06 volumio-keller volumio[1036]: info: Received Get System Info Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 20:31:06 volumio-keller volumio[1036]: info: Discovery: Getting this device information Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:31:06 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:06 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 20:31:07 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 25 20:31:07 volumio-keller volumio[1036]: info: Received Get System Info Mar 25 20:31:07 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 20:31:07 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 20:31:07 volumio-keller volumio[1036]: info: Discovery: Getting this device information Mar 25 20:31:07 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:31:07 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:07 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 20:31:09 volumio-keller volumio[1036]: verbose: New Socket.io Connection to 192.168.2.120 from 192.168.2.149 UA: Mozilla/5.0 (Linux; Android 15; moto g84 5G Build/V1TCS35H.88-16-3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.159 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Mar 25 20:31:09 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 25 20:31:09 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetVisibleSources Mar 25 20:31:09 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 20:31:09 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:31:09 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:09 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 25 20:31:09 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 25 20:31:09 volumio-keller volumio[1036]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 25 20:31:09 volumio-keller volumio[1036]: info: Received Get System Info Mar 25 20:31:09 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 20:31:09 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 20:31:09 volumio-keller volumio[1036]: info: Discovery: Getting this device information Mar 25 20:31:09 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:31:09 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:09 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 20:31:09 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioGetState Mar 25 20:31:09 volumio-keller volumio[1036]: info: CorePlayQueue::getTrack 0 Mar 25 20:31:09 volumio-keller volumio[1036]: info: Listing playlists Mar 25 20:31:09 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 25 20:31:12 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:31:12.354+01:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.2.149:46846 error="read tcp 192.168.2.120:7331->192.168.2.149:46846: read: connection reset by peer" Mar 25 20:31:12 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:31:12.354+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.2.149:46846 Mar 25 20:31:12 volumio-keller volumio5-onboarding[1421]: time=2026-03-25T20:31:12.354+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.2.149:46846 Mar 25 20:31:12 volumio-keller qobuz-connect[2040]: 20260325 20:31:12.692 [2040.2100] INFO SampleApp: API endpoint invoked: get-display-info Mar 25 20:31:12 volumio-keller qobuz-connect[2040]: 20260325 20:31:12.862 [2040.2100] INFO SampleApp: API endpoint invoked: get-connect-info Mar 25 20:31:32 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 20:31:32 volumio-keller volumio[1036]: info: CURURI: music-library Mar 25 20:31:32 volumio-keller volumio[1036]: info: Preload queue cleared Mar 25 20:31:34 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 20:31:34 volumio-keller volumio[1036]: info: CURURI: music-library/USB Mar 25 20:31:34 volumio-keller volumio[1036]: info: Preload queue cleared Mar 25 20:31:35 volumio-keller volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 20:31:35 volumio-keller volumio[1036]: info: CURURI: music-library/USB/USB-1TB Mar 25 20:31:35 volumio-keller volumio[1036]: info: Preload queue cleared Mar 25 20:31:44 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 20:31:44 volumio-keller volumio[1036]: info: CoreStateMachine::ClearQueue Mar 25 20:31:44 volumio-keller volumio[1036]: info: CoreStateMachine::stop Mar 25 20:31:44 volumio-keller volumio[1036]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:31:44 volumio-keller volumio[1036]: info: CorePlayQueue::clearPlayQueue Mar 25 20:31:44 volumio-keller volumio[1036]: info: CorePlayQueue::saveQueue Mar 25 20:31:44 volumio-keller volumio[1036]: info: CoreCommandRouter::volumioPushQueue Mar 25 20:31:44 volumio-keller volumio[1036]: info: CoreStateMachine::addQueueItems Mar 25 20:31:44 volumio-keller volumio[1036]: info: CorePlayQueue::addQueueItems Mar 25 20:31:44 volumio-keller volumio[1036]: info: Preload queue cleared Mar 25 20:31:44 volumio-keller volumio[1036]: info: Adding Item to queue: music-library/USB/USB-1TB/00_KM Mar 25 20:31:44 volumio-keller volumio[1036]: info: Exploding uri music-library/USB/USB-1TB/00_KM in service mpd Mar 25 20:31:44 volumio-keller volumio[1036]: info: ALBUMART /albumart?cacheid=879&web=Buena%20Vista%20Social%20Club/World%20Circuit%20-%20Nonesuch/extralarge&path=%2Fmnt%2FUSB%2FUSB-1TB%2F00_KM%2FBuena%20Vista%20Social%20Club%2F01%20Chan%20Chan.flac&metadata=false Mar 25 20:31:44 volumio-keller volumio[1036]: info: URI /mnt/USB/USB-1TB/00_KM/Buena Vista Social Club/01 Chan Chan.flac Mar 25 20:31:44 volumio-keller volumio[1036]: info: ALBUMART /albumart?cacheid=879&web=Buena%20Vista%20Social%20Club/World%20Circuit%20-%20Nonesuch/extralarge&path=%2Fmnt%2FUSB%2FUSB-1TB%2F00_KM%2FBuena%20Vista%20Social%20Club%2F02%20De%20Camino%20a%20la%20Vereda.flac&metadata=false Mar 25 20:31:44 volumio-keller volumio[1036]: info: URI /mnt/USB/USB-1TB/00_KM/Buena Vista Social Club/02 De Camino a la Vereda.flac Mar 25 20:31:44 volumio-keller volumio[1036]: info: ALBUMART /albumart?cacheid=879&web=Buena%20Vista%20Social%20Club/World%20Circuit%20-%20Nonesuch/extralarge&path=%2Fmnt%2FUSB%2FUSB-1TB%2F00_KM%2FBuena%20Vista%20Social%20Club%2F03%20El%20Cuarto%20de%20Tula.flac&metadata=false Mar 25 20:31:44 volumio-keller volumio[1036]: info: URI /mnt/USB/USB-1TB/00_KM/Buena Vista Social Club/03 El Cuarto de Tula.flac Mar 25 20:31:44 volumio-keller volumio[1036]: info: ALBUMART /albumart?cacheid=879&web=Buena%20Vista%20Social%20Club/World%20Circuit%20-%20Nonesuch/extralarge&path=%2Fmnt%2FUSB%2FUSB-1TB%2F00_KM%2FBuena%20Vista%20Social%20Club%2F04%20Pueblo%20Nuevo.flac&metadata=false Mar 25 20:31:44 volumio-keller volumio[1036]: info: URI /mnt/USB/USB-1TB/00_KM/Buena Vista Social Club/04 Pueblo Nuevo.flac Mar 25 20:31:44 volumio-keller volumio[1036]: info: ALBUMART /albumart?cacheid=879&web=Buena%20Vista%20Social%20Club/World%20Circuit%20-%20Nonesuch/extralarge&path=%2Fmnt%2FUSB%2FUSB-1TB%2F00_KM%2FBuena%20Vista%20Social%20Club%2F05%20Dos%20Gardenias.flac&metadata=false Mar 25 20:31:44 volumio-keller volumio[1036]: info: URI /mnt/USB/USB-1TB/00_KM/Buena Vista Social Club/05 Dos Gardenias.flac Mar 25 20:31:44 volumio-keller volumio[1036]: info: ALBUMART /albumart?cacheid=879&web=Buena%20Vista%20Social%20Club/World%20Circuit%20-%20Nonesuch/extralarge&path=%2Fmnt%2FUSB%2FUSB-1TB%2F00_KM%2FBuena%20Vista%20Social%20Club%2F06%20%C2%BFY%20Tu%20Que%20Has%20Hecho%20.flac&metadata=false Mar 25 20:31:44 volumio-keller volumio[1036]: info: URI /mnt/USB/USB-1TB/00_KM/Buena Vista Social Club/06 ¿Y Tu Que Has Hecho .flac Mar 25 20:31:44 volumio-keller volumio[1036]: info: ALBUMART /albumart?cacheid=879&web=Buena%20Vista%20Social%20Club/World%20Circuit%20-%20Nonesuch/extralarge&path=%2Fmnt%2FUSB%2FUSB-1TB%2F00_KM%2FBuena%20Vista%20Social%20Club%2F06%20%C2%BFY%20Tu%20Que%20Has%20Hecho.flac&metadata=false Mar 25 20:31:44 volumio-keller volumio[1036]: info: URI /mnt/USB/USB-1TB/00_KM/Buena Vista Social Club/06 ¿Y Tu Que Has Hecho.flac Mar 25 20:31:44 volumio-keller volumio[1036]: info: ALBUMART /albumart?cacheid=879&web=Buena%20Vista%20Social%20Club/World%20Circuit%20-%20Nonesuch/extralarge&path=%2Fmnt%2FUSB%2FUSB-1TB%2F00_KM%2FBuena%20Vista%20Social%20Club%2F07%20Veinte%20Anos.flac&metadata=false Mar 25 20:31:44 volumio-keller volumio[1036]: info: URI /mnt/USB/USB-1TB/00_KM/Buena Vista Social Club/07 Veinte Anos.flac Mar 25 20:31:44 volumio-keller volumio[1036]: info: ALBUMART /albumart?cacheid=879&web=Buena%20Vista%20Social%20Club/World%20Circuit%20-%20Nonesuch/extralarge&path=%2Fmnt%2FUSB%2FUSB-1TB%2F00_KM%2FBuena%20Vista%20Social%20Club%2F08%20El%20Carretero.flac&metadata=false Mar 25 20:31:44 volumio-keller volumio[1036]: info: URI /mnt/USB/USB-1TB/00_KM/Buena Vista Social Club/08 El Carretero.flac Mar 25 20:31:44 volumio-keller volumio[1036]: info: ALBUMART /albumart?cacheid=879&web=Buena%20Vista%20Social%20Club/World%20Circuit%20-%20Nonesuch/extralarge&path=%2Fmnt%2FUSB%2FUSB-1TB%2F00_KM%2FBuena%20Vista%20Social%20Club%2F09%20Candela.flac&metadata=false Mar 25 20:31:44 volumio-keller volumio[1036]: info: URI /mnt/USB/USB-1TB/00_KM/Buena Vista Social Club/09 Candela.flac Mar 25 20:31:44 volumio-keller volumio[1036]: info: ALBUMART /albumart?cacheid=879&web=Buena%20Vista%20Social%20Club/World%20Circuit%20-%20Nonesuch/extralarge&path=%2Fmnt%2FUSB%2FUSB-1TB%2F00_KM%2FBuena%20Vista%20Social%20Club%2F10%20Amor%20de%20Loca%20Juventud.flac&metadata=false Mar 25 20:31:44 volumio-keller volumio[1036]: info: URI /mnt/USB/USB-1TB/00_KM/Buena Vista Social Club/10 Amor de Loca Juventud.flac Mar 25 20:31:44 volumio-keller volumio[1036]: info: ALBUMART /albumart?cacheid=879&web=Buena%20Vista%20Social%20Club/World%20Circuit%20-%20Nonesuch/extralarge&path=%2Fmnt%2FUSB%2FUSB-1TB%2F00_KM%2FBuena%20Vista%20Social%20Club%2F11%20Orgullecida.flac&metadata=false Mar 25 20:31:44 volumio-keller volumio[1036]: info: URI /mnt/USB/USB-1TB/00_KM/Buena Vista Social Club/11 Orgullecida.flac Mar 25 20:31:44 volumio-keller volumio[1036]: info: ALBUMART /albumart?cacheid=879&web=Buena%20Vista%20Social%20Club/World%20Circuit%20-%20Nonesuch/extralarge&path=%2Fmnt%2FUSB%2FUSB-1TB%2F00_KM%2FBuena%20Vista%20Social%20Club%2F12%20Murmullo.flac&metadata=false Mar 25 20:31:44 volumio-keller volumio[1036]: info: URI /mnt/USB/USB-1TB/00_KM/Buena Vista Social Club/12 Murmullo.flac Mar 25 20:31:44 volumio-keller volumio[1036]: info: ALBUMART /albumart?cacheid=879&web=Buena%20Vista%20Social%20Club/World%20Circuit%20-%20Nonesuch/extralarge&path=%2Fmnt%2FUSB%2FUSB-1TB%2F00_KM%2FBuena%20Vista%20Social%20Club%2F13%20Buena%20Vista%20Social%20Club.flac&metadata=false Mar 25 20:31:44 volumio-keller volumio[1036]: info: URI /mnt/USB/USB-1TB/00_KM/Buena Vista Social Club/13 Buena Vista Social Club.flac Mar 25 20:31:44 volumio-keller volumio[1036]: info: ALBUMART /albumart?cacheid=879&web=Buena%20Vista%20Social%20Club/World%20Circuit%20-%20Nonesuch/extralarge&path=%2Fmnt%2FUSB%2FUSB-1TB%2F00_KM%2FBuena%20Vista%20Social%20Club%2F14%20La%20Bayamesa.flac&metadata=false Mar 25 20:31:44 volumio-keller volumio[1036]: info: URI /mnt/USB/USB-1TB/00_KM/Buena Vista Social Club/14 La Bayamesa.flac Mar 25 20:31:44 volumio-keller volumio[1036]: info: ALBUMART /albumart?cacheid=879&web=Buena%20Vista%20Social%20Club/Buena%20Vista%20Social%20Club/extralarge&path=%2Fmnt%2FUSB%2FUSB-1TB%2F00_KM%2FBuena%20Vista%20Social%20Club%2FBuena%20Vista%20Social%20Club.cue&metadata=false Mar 25 20:31:44 volumio-keller volumio[1036]: info: URI /mnt/USB/USB-1TB/00_KM/Buena Vista Social Club/Buena Vista Social Club.cue Mar 25 20:31:44 volumio-keller volumio[1036]: info: ALBUMART /albumart?cacheid=879&web=Buena%20Vista%20Social%20Club/Buena%20Vista%20Social%20Club/extralarge&path=%2Fmnt%2FUSB%2FUSB-1TB%2F00_KM%2FBuena%20Vista%20Social%20Club%2FBuena%20Vista%20Social%20Club.cue&metadata=false Mar 25 20:31:44 volumio-keller volumio[1036]: info: URI /mnt/USB/USB-1TB/00_KM/Buena Vista Social Club/Buena Vista Social Club.cue Mar 25 20:31:44 volumio-keller volumio[1036]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 20:31:44 volumio-keller volumio[1036]: Error: Unable to resolve or reject the same promise twice Mar 25 20:31:44 volumio-keller volumio[1036]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 25 20:31:44 volumio-keller volumio[1036]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Mar 25 20:31:44 volumio-keller volumio[1036]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 25 20:31:44 volumio-keller volumio[1036]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 25 20:31:44 volumio-keller volumio[1036]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 25 20:31:44 volumio-keller volumio[1036]: at Socket.emit (node:events:514:28) Mar 25 20:31:44 volumio-keller volumio[1036]: at addChunk (node:internal/streams/readable:343:12) Mar 25 20:31:44 volumio-keller volumio[1036]: at readableAddChunk (node:internal/streams/readable:312:11) Mar 25 20:31:44 volumio-keller volumio[1036]: at Readable.push (node:internal/streams/readable:253:10) Mar 25 20:31:44 volumio-keller volumio[1036]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Mar 25 20:31:44 volumio-keller volumio[1036]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 20:31:44 volumio-keller sudo[5236]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-25 20:30' Mar 25 20:31:44 volumio-keller sudo[5236]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 17:11:42 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="21a5161aef4cb85a1f93b140ccadc4f1"