-- Logs begin at Thu 2019-02-14 21:11:58 AEDT, end at Sun 2025-06-15 16:30:46 AEST. -- Jun 15 16:29:01 volumio go-librespot[1288]: time="2025-06-15T16:29:01+10:00" level=trace msg="sent dealer ping" Jun 15 16:29:01 volumio go-librespot[1288]: time="2025-06-15T16:29:01+10:00" level=trace msg="received dealer pong" Jun 15 16:29:09 volumio kernel: usb 1-1.4: USB disconnect, device number 3 Jun 15 16:29:09 volumio thd[587]: Error reading device '/dev/input/event0' Jun 15 16:29:10 volumio kernel: usb 1-1.4: new full-speed USB device number 4 using xhci_hcd Jun 15 16:29:10 volumio kernel: usb 1-1.4: New USB device found, idVendor=0483, idProduct=5750, bcdDevice= 2.00 Jun 15 16:29:10 volumio kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jun 15 16:29:10 volumio kernel: usb 1-1.4: Product: ByQDtech 触控USB鼠标 Jun 15 16:29:10 volumio kernel: usb 1-1.4: Manufacturer: 深圳市全动电子技术有限公司 Jun 15 16:29:10 volumio kernel: usb 1-1.4: SerialNumber: 498318693732 Jun 15 16:29:10 volumio kernel: input: 深圳市全动电子技术有限公司 ByQDtech 触控USB鼠标 as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.0/0003:0483:5750.0002/input/input2 Jun 15 16:29:10 volumio kernel: hid-multitouch 0003:0483:5750.0002: input,hidraw0: USB HID v1.10 Device [深圳市全动电子技术有限公司 ByQDtech 触控USB鼠标] on usb-0000:01:00.0-1.4/input0 Jun 15 16:29:10 volumio systemd-udevd[2735]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 15 16:29:11 volumio systemd-udevd[2736]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 15 16:29:13 volumio volumio-remote-updater[596]: [2025-06-15 16:29:13] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Jun 15 16:29:18 volumio volumio-remote-updater[596]: [2025-06-15 16:29:18] [connect] Successful connection Jun 15 16:29:19 volumio kernel: usb 1-1.4: reset full-speed USB device number 4 using xhci_hcd Jun 15 16:29:21 volumio kernel: usb 1-1.4: device descriptor read/64, error -32 Jun 15 16:29:21 volumio kernel: usb 1-1.4: device descriptor read/64, error -32 Jun 15 16:29:21 volumio kernel: usb 1-1.4: reset full-speed USB device number 4 using xhci_hcd Jun 15 16:29:21 volumio kernel: usb 1-1.4: device descriptor read/64, error -32 Jun 15 16:29:21 volumio kernel: usb 1-1.4: device descriptor read/64, error -32 Jun 15 16:29:21 volumio kernel: usb 1-1.4: reset full-speed USB device number 4 using xhci_hcd Jun 15 16:29:21 volumio kernel: usb 1-1.4: Device not responding to setup address. Jun 15 16:29:21 volumio kernel: usb 1-1.4: Device not responding to setup address. Jun 15 16:29:21 volumio kernel: usb 1-1.4: device not accepting address 4, error -71 Jun 15 16:29:21 volumio kernel: usb 1-1.4: reset full-speed USB device number 4 using xhci_hcd Jun 15 16:29:21 volumio kernel: usb 1-1.4: Device not responding to setup address. Jun 15 16:29:21 volumio kernel: usb 1-1.4: Device not responding to setup address. Jun 15 16:29:21 volumio kernel: usb 1-1.4: device not accepting address 4, error -71 Jun 15 16:29:21 volumio kernel: usb 1-1.4: USB disconnect, device number 4 Jun 15 16:29:21 volumio kernel: usb 1-1.4: new full-speed USB device number 5 using xhci_hcd Jun 15 16:29:21 volumio kernel: usb 1-1.4: device descriptor read/64, error -32 Jun 15 16:29:21 volumio kernel: usb 1-1.4: device descriptor read/64, error -32 Jun 15 16:29:21 volumio kernel: usb 1-1.4: new full-speed USB device number 6 using xhci_hcd Jun 15 16:29:22 volumio kernel: usb 1-1.4: device descriptor read/64, error -32 Jun 15 16:29:22 volumio kernel: usb 1-1.4: device descriptor read/64, error -32 Jun 15 16:29:22 volumio kernel: usb 1-1-port4: attempt power cycle Jun 15 16:29:23 volumio kernel: usb 1-1.4: new full-speed USB device number 7 using xhci_hcd Jun 15 16:29:23 volumio kernel: usb 1-1.4: Device not responding to setup address. Jun 15 16:29:23 volumio kernel: usb 1-1.4: Device not responding to setup address. Jun 15 16:29:23 volumio kernel: usb 1-1.4: device not accepting address 7, error -71 Jun 15 16:29:23 volumio kernel: usb 1-1.4: new full-speed USB device number 8 using xhci_hcd Jun 15 16:29:23 volumio kernel: usb 1-1.4: Device not responding to setup address. Jun 15 16:29:23 volumio kernel: usb 1-1.4: Device not responding to setup address. Jun 15 16:29:24 volumio kernel: usb 1-1.4: device not accepting address 8, error -71 Jun 15 16:29:24 volumio kernel: usb 1-1-port4: unable to enumerate USB device Jun 15 16:29:24 volumio volumio[1094]: info: Checking if install.sh is present Jun 15 16:29:24 volumio volumio[1094]: info: Executing install.sh Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.62 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.60 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.62 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.60 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.62 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.60 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.62 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.60 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jun 15 16:29:24 volumio sudo[2768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/now_playing/install.sh Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.62 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jun 15 16:29:24 volumio sudo[2768]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.60 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.62 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.60 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Jun 15 16:29:24 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 16:29:24 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 16:29:24 volumio volumio[1094]: info: Discovery: Getting this device information Jun 15 16:29:24 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 15 16:29:24 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 15 16:29:24 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 16:29:24 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 16:29:24 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 16:29:24 volumio volumio[1094]: info: Discovery: Getting this device information Jun 15 16:29:24 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 15 16:29:24 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 15 16:29:24 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.62 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.60 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.62 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.60 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.62 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.60 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.62 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 21 Jun 15 16:29:24 volumio volumio[1094]: verbose: New Socket.io Connection to 192.168.20.60 from 192.168.20.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 Jun 15 16:29:24 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 15 16:29:24 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 15 16:29:25 volumio volumio[1094]: info: Received Get System Info Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 16:29:25 volumio volumio[1094]: info: Discovery: Getting this device information Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 15 16:29:25 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 15 16:29:25 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::volumioGetVisibleSources Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 15 16:29:25 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 15 16:29:25 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 15 16:29:25 volumio volumio[1094]: info: Received Get System Info Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 16:29:25 volumio volumio[1094]: info: Discovery: Getting this device information Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 15 16:29:25 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 15 16:29:25 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 15 16:29:25 volumio volumio[1094]: info: Listing playlists Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::volumioGetQueue Jun 15 16:29:25 volumio volumio[1094]: info: CoreStateMachine::getQueue Jun 15 16:29:25 volumio volumio[1094]: info: CorePlayQueue::getQueue Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 15 16:29:25 volumio volumio[1094]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 23 Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 15 16:29:25 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 15 16:29:25 volumio volumio[1094]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 24 Jun 15 16:29:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:29:26 volumio volumio[1094]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Jun 15 16:29:26 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 15 16:29:26 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 15 16:29:26 volumio volumio[1094]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Jun 15 16:29:26 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:29:26 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:29:27 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:29:27 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:29:28 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:29:28 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:29:29 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:29:29 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:29:30 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:29:30 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:29:30 volumio volumio-remote-updater[596]: [2025-06-15 16:29:30] [connect] Successful connection Jun 15 16:29:30 volumio volumio-remote-updater[596]: [2025-06-15 16:29:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749968970 101 Jun 15 16:29:30 volumio volumio[1094]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 26 Jun 15 16:29:31 volumio go-librespot[1288]: time="2025-06-15T16:29:31+10:00" level=trace msg="received accesspoint ping" Jun 15 16:29:31 volumio go-librespot[1288]: time="2025-06-15T16:29:31+10:00" level=trace msg="received accesspoint pong ack" Jun 15 16:29:31 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:29:31 volumio go-librespot[1288]: time="2025-06-15T16:29:31+10:00" level=trace msg="sent dealer ping" Jun 15 16:29:31 volumio go-librespot[1288]: time="2025-06-15T16:29:31+10:00" level=trace msg="received dealer pong" Jun 15 16:29:31 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:29:32 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:29:32 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:29:33 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:29:33 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:29:34 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:29:34 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 16:30:01 volumio go-librespot[1288]: time="2025-06-15T16:30:01+10:00" level=trace msg="sent dealer ping" Jun 15 16:30:01 volumio go-librespot[1288]: time="2025-06-15T16:30:01+10:00" level=trace msg="received dealer pong" Jun 15 16:30:04 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 15 16:30:11 volumio volumio[1094]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/armhf Jun 15 16:30:13 volumio volumio[1094]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/armhf Jun 15 16:30:13 volumio volumio[1094]: info: Folder /tmp/plugins removed Jun 15 16:30:15 volumio volumio[1094]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/armhf Jun 15 16:30:15 volumio volumio[1094]: info: Check plugin dependencies Jun 15 16:30:15 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 15 16:30:15 volumio volumio[1094]: info: Checking if plugin already exists Jun 15 16:30:15 volumio volumio[1094]: info: Rename folder Jun 15 16:30:15 volumio volumio[1094]: info: Folder /tmp/downloaded_plugin.zip removed Jun 15 16:30:15 volumio volumio[1094]: info: Move to category Jun 15 16:30:18 volumio volumio[1094]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/armhf Jun 15 16:30:27 volumio volumio[1094]: info: Folder /tmp/plugins removed Jun 15 16:30:28 volumio volumio[1094]: info: An error occurred installing the plugin. Rolling back config Jun 15 16:30:28 volumio volumio[1094]: info: Plugin folders cleanup Jun 15 16:30:28 volumio volumio[1094]: info: Scanning into folder /volumio/app/plugins/ Jun 15 16:30:28 volumio volumio[1094]: info: Scanning category audio_interface Jun 15 16:30:28 volumio volumio[1094]: info: Scanning category miscellanea Jun 15 16:30:28 volumio volumio[1094]: info: Scanning category music_service Jun 15 16:30:28 volumio volumio[1094]: info: Scanning category plugins.json Jun 15 16:30:28 volumio volumio[1094]: info: Scanning category system_controller Jun 15 16:30:28 volumio volumio[1094]: info: Scanning category user_interface Jun 15 16:30:28 volumio volumio[1094]: info: Scanning into folder /data/plugins/ Jun 15 16:30:28 volumio volumio[1094]: info: Scanning category music_service Jun 15 16:30:28 volumio volumio[1094]: info: Scanning category now_playing Jun 15 16:30:31 volumio go-librespot[1288]: time="2025-06-15T16:30:31+10:00" level=trace msg="sent dealer ping" Jun 15 16:30:31 volumio go-librespot[1288]: time="2025-06-15T16:30:31+10:00" level=trace msg="received dealer pong" Jun 15 16:30:41 volumio volumio[1094]: info: Removing /data/plugins//now_playing/README.md Jun 15 16:30:41 volumio volumio[1094]: info: Removing /data/plugins//now_playing/config.json Jun 15 16:30:41 volumio volumio[1094]: info: Removing /data/plugins//now_playing/eslintignore Jun 15 16:30:41 volumio volumio[1094]: info: Removing /data/plugins//now_playing/eslintrc.yml Jun 15 16:30:43 volumio volumio[1094]: info: Removing /data/plugins//now_playing/fixup.sh Jun 15 16:30:43 volumio volumio[1094]: info: Removing /data/plugins//now_playing/gitignore Jun 15 16:30:43 volumio volumio[1094]: info: Removing /data/plugins//now_playing/install.sh Jun 15 16:30:43 volumio volumio[1094]: info: Removing /data/plugins//now_playing/package-lock.json Jun 15 16:30:45 volumio volumio[1094]: info: Removing /data/plugins//now_playing/package.json Jun 15 16:30:45 volumio volumio[1094]: info: Removing /data/plugins//now_playing/tsconfig.json Jun 15 16:30:45 volumio volumio[1094]: info: Removing /data/plugins//now_playing/uninstall.sh Jun 15 16:30:45 volumio volumio[1094]: info: Scanning category user_interface Jun 15 16:30:45 volumio volumio[1094]: info: Plugin folders cleanup completed Jun 15 16:30:45 volumio volumio[1094]: info: Error: Error Jun 15 16:30:45 volumio volumio[1094]: info: Folder /tmp/downloaded_plugin.zip removed Jun 15 16:30:45 volumio volumio[1094]: info: Folder /tmp/plugins removed Jun 15 16:30:45 volumio volumio[1094]: info: Folder /tmp/downloaded_plugin.zip removed Jun 15 16:30:45 volumio volumio[1094]: info: Folder /data/temp removed Jun 15 16:30:45 volumio volumio[1094]: error: Error moving plugin folder: Error: Command failed: /bin/mv /data/plugins/now_playing /data/plugins/user_interface Jun 15 16:30:45 volumio volumio[1094]: /bin/mv: cannot move '/data/plugins/now_playing' to '/data/plugins/user_interface/now_playing': Directory not empty Jun 15 16:30:45 volumio volumio[1094]: info: An error occurred installing the plugin. Rolling back config Jun 15 16:30:45 volumio volumio[1094]: info: Plugin folders cleanup Jun 15 16:30:45 volumio volumio[1094]: info: Scanning into folder /volumio/app/plugins/ Jun 15 16:30:45 volumio volumio[1094]: info: Scanning category audio_interface Jun 15 16:30:45 volumio volumio[1094]: info: Scanning category miscellanea Jun 15 16:30:45 volumio volumio[1094]: info: Scanning category music_service Jun 15 16:30:45 volumio volumio[1094]: info: Scanning category plugins.json Jun 15 16:30:45 volumio volumio[1094]: info: Scanning category system_controller Jun 15 16:30:45 volumio volumio[1094]: info: Scanning category user_interface Jun 15 16:30:45 volumio volumio[1094]: info: Scanning into folder /data/plugins/ Jun 15 16:30:45 volumio volumio[1094]: info: Scanning category music_service Jun 15 16:30:45 volumio volumio[1094]: info: Scanning category now_playing Jun 15 16:30:45 volumio volumio[1094]: info: Scanning category user_interface Jun 15 16:30:45 volumio volumio[1094]: info: Plugin folders cleanup completed Jun 15 16:30:45 volumio volumio[1094]: info: Error: Error Jun 15 16:30:45 volumio volumio[1094]: info: Folder /data/temp removed Jun 15 16:30:45 volumio volumio[1094]: info: Folder /tmp/plugins removed Jun 15 16:30:45 volumio volumio[1094]: info: Folder /tmp/downloaded_plugin.zip removed Jun 15 16:30:46 volumio volumio[1094]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 15 16:30:46 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 15 16:30:46 volumio volumio[1094]: info: CoreCommandRouter::volumioGetVisibleSources Jun 15 16:30:46 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 16:30:46 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 15 16:30:46 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 15 16:30:46 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 15 16:30:46 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 15 16:30:46 volumio volumio[1094]: info: Received Get System Info Jun 15 16:30:46 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 16:30:46 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 16:30:46 volumio volumio[1094]: info: Discovery: Getting this device information Jun 15 16:30:46 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 15 16:30:46 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 15 16:30:46 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 16:30:46 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 15 16:30:46 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 15 16:30:46 volumio volumio[1094]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 16:30:46 volumio volumio[1094]: TypeError: Cannot read property 'plugin_type' of undefined Jun 15 16:30:46 volumio volumio[1094]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1644:56) Jun 15 16:30:46 volumio volumio[1094]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1540:29) Jun 15 16:30:46 volumio volumio[1094]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1342:45) Jun 15 16:30:46 volumio volumio[1094]: at Socket.emit (events.js:315:20) Jun 15 16:30:46 volumio volumio[1094]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 15 16:30:46 volumio volumio[1094]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 15 16:30:46 volumio volumio[1094]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 16:30:46 volumio sudo[2992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-15 16:29 Jun 15 16:30:46 volumio sudo[2992]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"