-- Logs begin at Wed 2025-05-07 12:51:23 CEST, end at Wed 2025-05-07 13:14:59 CEST. -- May 07 13:13:08 volumio-bermuda volumio-remote-updater[625]: [2025-05-07 13:13:08] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] May 07 13:13:09 volumio-bermuda volumio[1077]: info: Checking if install.sh is present May 07 13:13:09 volumio-bermuda volumio[1077]: info: Executing install.sh May 07 13:13:09 volumio-bermuda volumio[1077]: 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: 2 May 07 13:13:09 volumio-bermuda volumio[1077]: 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: 3 May 07 13:13:09 volumio-bermuda sudo[3931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/peppyspectrum/install.sh May 07 13:13:09 volumio-bermuda sudo[3931]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 13:13:09 volumio-bermuda volumio[1077]: info: Installing peppyalsa plugin dependencies May 07 13:13:09 volumio-bermuda sudo[3935]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 07 13:13:09 volumio-bermuda sudo[3935]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 13:13:09 volumio-bermuda systemd[1]: Starting Cleanup of Temporary Directories... May 07 13:13:09 volumio-bermuda systemd[1]: Reloading. May 07 13:13:09 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 13:13:09 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioGetVisibleSources May 07 13:13:09 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 13:13:09 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioGetState May 07 13:13:09 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:13:09 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 07 13:13:09 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 13:13:09 volumio-bermuda volumio[1077]: info: Received Get System Info May 07 13:13:09 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 13:13:09 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 13:13:09 volumio-bermuda volumio[1077]: info: Discovery: Getting this device information May 07 13:13:09 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioGetState May 07 13:13:09 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:13:09 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 13:13:09 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioGetState May 07 13:13:09 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:13:09 volumio-bermuda volumio[1077]: info: Listing playlists May 07 13:13:09 volumio-bermuda systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 07 13:13:09 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 07 13:13:10 volumio-bermuda systemd[1]: systemd-tmpfiles-clean.service: Succeeded. May 07 13:13:10 volumio-bermuda systemd[1]: Started Cleanup of Temporary Directories. May 07 13:13:10 volumio-bermuda sudo[3935]: pam_unix(sudo:session): session closed for user root May 07 13:13:10 volumio-bermuda sudo[3952]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get update May 07 13:13:10 volumio-bermuda sudo[3952]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 13:13:10 volumio-bermuda volumio[1077]: 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 07 13:13:10 volumio-bermuda volumio[1077]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 07 13:13:10 volumio-bermuda volumio[1077]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 07 13:13:10 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioGetState May 07 13:13:10 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:13:10 volumio-bermuda volumio[1077]: info: Hit:1 http://archive.raspberrypi.org/debian buster InRelease May 07 13:13:10 volumio-bermuda volumio[1077]: info: Get:2 http://raspbian.raspberrypi.org/raspbian buster InRelease [15.0 kB] May 07 13:13:10 volumio-bermuda volumio[1077]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease May 07 13:13:11 volumio-bermuda volumio[1077]: info: Fetched 15.0 kB in 1s (15.0 kB/s) May 07 13:13:13 volumio-bermuda volumio-remote-updater[625]: [2025-05-07 13:13:13] [connect] Successful connection May 07 13:13:13 volumio-bermuda volumio-remote-updater[625]: [2025-05-07 13:13:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746616393 101 May 07 13:13:13 volumio-bermuda volumio[1077]: 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: 7 May 07 13:13:15 volumio-bermuda kernel: usb 1-1.3: USB disconnect, device number 4 May 07 13:13:16 volumio-bermuda volumio[1077]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/peppyspectrum/volumio/buster/armhf May 07 13:13:17 volumio-bermuda volumio[1077]: info: Reading package lists... May 07 13:13:17 volumio-bermuda sudo[3952]: pam_unix(sudo:session): session closed for user root May 07 13:13:17 volumio-bermuda volumio[1077]: info: cloning peppyspectrum repo May 07 13:13:18 volumio-bermuda volumio[1077]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/peppyspectrum/volumio/buster/armhf May 07 13:13:18 volumio-bermuda volumio[1077]: info: Folder /tmp/plugins removed May 07 13:13:22 volumio-bermuda kernel: usb 1-1.3: new high-speed USB device number 5 using xhci_hcd May 07 13:13:22 volumio-bermuda kernel: usb 1-1.3: New USB device found, idVendor=058f, idProduct=6387, bcdDevice= 1.04 May 07 13:13:22 volumio-bermuda kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 07 13:13:22 volumio-bermuda kernel: usb 1-1.3: Product: Zerobasic USB Device May 07 13:13:22 volumio-bermuda kernel: usb 1-1.3: Manufacturer: Zerobasic May 07 13:13:22 volumio-bermuda kernel: usb 1-1.3: SerialNumber: 655868B8 May 07 13:13:22 volumio-bermuda kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected May 07 13:13:22 volumio-bermuda kernel: scsi host0: usb-storage 1-1.3:1.0 May 07 13:13:23 volumio-bermuda kernel: scsi 0:0:0:0: Direct-Access Zerobasic 8.07 PQ: 0 ANSI: 2 May 07 13:13:23 volumio-bermuda kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 May 07 13:13:23 volumio-bermuda kernel: sd 0:0:0:0: [sda] 7831552 512-byte logical blocks: (4.01 GB/3.73 GiB) May 07 13:13:23 volumio-bermuda kernel: sd 0:0:0:0: [sda] Write Protect is off May 07 13:13:23 volumio-bermuda kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 May 07 13:13:23 volumio-bermuda kernel: sd 0:0:0:0: [sda] No Caching mode page found May 07 13:13:23 volumio-bermuda kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through May 07 13:13:23 volumio-bermuda kernel: sda: sda1 May 07 13:13:23 volumio-bermuda kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk May 07 13:13:23 volumio-bermuda volumio[1077]: info: Mounting Device 4A3E-B83C May 07 13:13:25 volumio-bermuda sudo[4229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/4A3E-B83C -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 07 13:13:25 volumio-bermuda sudo[4229]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 13:13:25 volumio-bermuda sudo[4229]: pam_unix(sudo:session): session closed for user root May 07 13:13:25 volumio-bermuda kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! May 07 13:13:25 volumio-bermuda kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. May 07 13:13:25 volumio-bermuda volumio[1077]: info: Scanning new location : "USB/4A3E-B83C" May 07 13:13:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:13:25 volumio-bermuda volumio[1077]: info: May 07 13:13:25 volumio-bermuda volumio[1077]: ---------------------------- MPD announces state update: update May 07 13:13:25 volumio-bermuda volumio[1077]: info: ControllerMpd::getState May 07 13:13:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:13:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:13:25 volumio-bermuda volumio[1077]: info: May 07 13:13:25 volumio-bermuda volumio[1077]: ---------------------------- MPD announces state update: update May 07 13:13:25 volumio-bermuda volumio[1077]: info: ControllerMpd::getState May 07 13:13:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:13:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:13:25 volumio-bermuda volumio[1077]: info: May 07 13:13:25 volumio-bermuda volumio[1077]: ---------------------------- MPD announces state update: update May 07 13:13:25 volumio-bermuda volumio[1077]: info: ControllerMpd::getState May 07 13:13:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:13:25 volumio-bermuda volumio[1077]: info: sendMpdCommand status took 8 milliseconds May 07 13:13:25 volumio-bermuda volumio[1077]: info: sendMpdCommand status took 6 milliseconds May 07 13:13:25 volumio-bermuda volumio[1077]: info: sendMpdCommand status took 5 milliseconds May 07 13:13:25 volumio-bermuda volumio[1077]: info: sendMpdCommand status took 4 milliseconds May 07 13:13:25 volumio-bermuda volumio[1077]: info: sendMpdCommand status took 4 milliseconds May 07 13:13:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseState May 07 13:13:43 volumio-bermuda volumio-remote-updater[625]: [2025-05-07 13:13:43] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] May 07 13:13:47 volumio-bermuda volumio[1077]: info: Command Router : Notfying DB Updatetrue May 07 13:13:47 volumio-bermuda volumio[1077]: info: CoreCommandRouter::Close All Modals sent May 07 13:13:47 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseState May 07 13:13:47 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseState May 07 13:13:48 volumio-bermuda volumio-remote-updater[625]: [2025-05-07 13:13:48] [connect] Successful connection May 07 13:13:55 volumio-bermuda volumio[1077]: info: Command Router : Notfying DB Updatetrue May 07 13:13:55 volumio-bermuda volumio[1077]: info: CoreCommandRouter::Close All Modals sent May 07 13:13:55 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseState May 07 13:13:55 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseState May 07 13:13:58 volumio-bermuda volumio[1077]: info: Command Router : Notfying DB Updatetrue May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreCommandRouter::Close All Modals sent May 07 13:13:58 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseState May 07 13:13:58 volumio-bermuda volumio[1077]: info: ControllerMpd::pushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreCommandRouter::servicePushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:13:58 volumio-bermuda volumio[1077]: 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} May 07 13:13:58 volumio-bermuda volumio[1077]: verbose: CURRENT POSITION 0 May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState stateService stop May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState currentStatus stop May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: No code May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: ControllerMpd::pushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreCommandRouter::servicePushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:13:58 volumio-bermuda volumio[1077]: 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} May 07 13:13:58 volumio-bermuda volumio[1077]: verbose: CURRENT POSITION 0 May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState stateService stop May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState currentStatus stop May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: No code May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: ControllerMpd::pushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreCommandRouter::servicePushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:13:58 volumio-bermuda volumio[1077]: 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} May 07 13:13:58 volumio-bermuda volumio[1077]: verbose: CURRENT POSITION 0 May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState stateService stop May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState currentStatus stop May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: No code May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:13:58 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:13:58 volumio-bermuda volumio[1077]: info: ------------------------------ 33477ms May 07 13:13:58 volumio-bermuda volumio[1077]: info: ------------------------------ 33476ms May 07 13:13:58 volumio-bermuda volumio[1077]: info: ------------------------------ 33476ms May 07 13:13:58 volumio-bermuda volumio[1077]: info: MPD Database updated - AlbumList cache refreshed May 07 13:13:58 volumio-bermuda volumio[1077]: info: May 07 13:13:58 volumio-bermuda volumio[1077]: ---------------------------- MPD announces state update: database May 07 13:13:58 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:13:58 volumio-bermuda volumio[1077]: info: May 07 13:13:58 volumio-bermuda volumio[1077]: ---------------------------- MPD announces state update: update May 07 13:13:58 volumio-bermuda volumio[1077]: info: ControllerMpd::getState May 07 13:13:58 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:13:58 volumio-bermuda volumio[1077]: info: ControllerMpd::getState May 07 13:13:58 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:13:58 volumio-bermuda volumio[1077]: info: MPD Database updated - AlbumList cache refreshed May 07 13:13:58 volumio-bermuda volumio[1077]: info: May 07 13:13:58 volumio-bermuda volumio[1077]: ---------------------------- MPD announces state update: database May 07 13:13:58 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:13:58 volumio-bermuda volumio[1077]: info: May 07 13:13:58 volumio-bermuda volumio[1077]: ---------------------------- MPD announces state update: update May 07 13:13:58 volumio-bermuda volumio[1077]: info: ControllerMpd::getState May 07 13:13:58 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:13:58 volumio-bermuda volumio[1077]: info: ControllerMpd::getState May 07 13:13:58 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:13:58 volumio-bermuda volumio[1077]: info: MPD Database updated - AlbumList cache refreshed May 07 13:13:58 volumio-bermuda volumio[1077]: info: May 07 13:13:58 volumio-bermuda volumio[1077]: ---------------------------- MPD announces state update: database May 07 13:13:58 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:13:58 volumio-bermuda volumio[1077]: info: May 07 13:13:58 volumio-bermuda volumio[1077]: ---------------------------- MPD announces state update: update May 07 13:13:58 volumio-bermuda volumio[1077]: info: ControllerMpd::getState May 07 13:13:58 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:13:58 volumio-bermuda volumio[1077]: info: ControllerMpd::getState May 07 13:13:58 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:13:59 volumio-bermuda volumio[1077]: info: Check plugin dependencies May 07 13:13:59 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 13:13:59 volumio-bermuda volumio[1077]: info: Checking if plugin already exists May 07 13:13:59 volumio-bermuda volumio[1077]: info: Rename folder May 07 13:13:59 volumio-bermuda volumio[1077]: info: sendMpdCommand status took 121 milliseconds May 07 13:13:59 volumio-bermuda volumio[1077]: info: sendMpdCommand status took 119 milliseconds May 07 13:13:59 volumio-bermuda volumio[1077]: info: sendMpdCommand status took 119 milliseconds May 07 13:13:59 volumio-bermuda volumio[1077]: info: sendMpdCommand status took 117 milliseconds May 07 13:13:59 volumio-bermuda volumio[1077]: info: sendMpdCommand status took 115 milliseconds May 07 13:13:59 volumio-bermuda volumio[1077]: info: sendMpdCommand status took 114 milliseconds May 07 13:13:59 volumio-bermuda volumio[1077]: info: sendMpdCommand status took 113 milliseconds May 07 13:13:59 volumio-bermuda volumio[1077]: info: sendMpdCommand status took 111 milliseconds May 07 13:13:59 volumio-bermuda volumio[1077]: info: sendMpdCommand status took 110 milliseconds May 07 13:13:59 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseState May 07 13:14:00 volumio-bermuda volumio[1077]: info: Command Router : Notfying DB Updatefalse May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::Close All Modals sent May 07 13:14:00 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseState May 07 13:14:00 volumio-bermuda volumio[1077]: info: Command Router : Notfying DB Updatefalse May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::Close All Modals sent May 07 13:14:00 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseState May 07 13:14:00 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseState May 07 13:14:00 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseState May 07 13:14:00 volumio-bermuda volumio[1077]: info: Command Router : Notfying DB Updatefalse May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::Close All Modals sent May 07 13:14:00 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseState May 07 13:14:00 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseState May 07 13:14:00 volumio-bermuda volumio[1077]: info: ControllerMpd::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::servicePushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: 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} May 07 13:14:00 volumio-bermuda volumio[1077]: verbose: CURRENT POSITION 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState stateService stop May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState currentStatus stop May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: No code May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: ControllerMpd::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::servicePushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: 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} May 07 13:14:00 volumio-bermuda volumio[1077]: verbose: CURRENT POSITION 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState stateService stop May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState currentStatus stop May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: No code May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: ControllerMpd::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::servicePushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: 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} May 07 13:14:00 volumio-bermuda volumio[1077]: verbose: CURRENT POSITION 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState stateService stop May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState currentStatus stop May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: No code May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: ControllerMpd::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::servicePushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: 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} May 07 13:14:00 volumio-bermuda volumio[1077]: verbose: CURRENT POSITION 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState stateService stop May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState currentStatus stop May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: No code May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: ControllerMpd::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::servicePushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: 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} May 07 13:14:00 volumio-bermuda volumio[1077]: verbose: CURRENT POSITION 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState stateService stop May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState currentStatus stop May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: No code May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: ControllerMpd::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::servicePushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: 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} May 07 13:14:00 volumio-bermuda volumio[1077]: verbose: CURRENT POSITION 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState stateService stop May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState currentStatus stop May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: No code May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:00 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:00 volumio-bermuda volumio[1077]: info: ------------------------------ 1495ms May 07 13:14:00 volumio-bermuda volumio[1077]: info: ------------------------------ 1496ms May 07 13:14:00 volumio-bermuda volumio[1077]: info: ------------------------------ 1491ms May 07 13:14:00 volumio-bermuda volumio[1077]: info: ------------------------------ 1492ms May 07 13:14:00 volumio-bermuda volumio[1077]: info: ------------------------------ 1488ms May 07 13:14:00 volumio-bermuda volumio[1077]: info: ------------------------------ 1488ms May 07 13:14:00 volumio-bermuda volumio[1077]: 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: 1 May 07 13:14:00 volumio-bermuda volumio[1077]: 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: 2 May 07 13:14:00 volumio-bermuda volumio[1077]: 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: 3 May 07 13:14:00 volumio-bermuda volumio[1077]: 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: 4 May 07 13:14:00 volumio-bermuda volumio[1077]: 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: 5 May 07 13:14:00 volumio-bermuda volumio[1077]: info: Folder /tmp/downloaded_plugin.zip removed May 07 13:14:00 volumio-bermuda volumio[1077]: info: Move to category May 07 13:14:00 volumio-bermuda volumio[1077]: error: Error moving plugin folder: Error: Command failed: /bin/mv /data/plugins/peppyspectrum /data/plugins/user_interface May 07 13:14:00 volumio-bermuda volumio[1077]: /bin/mv: cannot move '/data/plugins/peppyspectrum' to '/data/plugins/user_interface/peppyspectrum': Directory not empty May 07 13:14:00 volumio-bermuda volumio[1077]: info: An error occurred installing the plugin. Rolling back config May 07 13:14:00 volumio-bermuda volumio[1077]: info: Plugin folders cleanup May 07 13:14:00 volumio-bermuda volumio[1077]: info: Scanning into folder /volumio/app/plugins/ May 07 13:14:00 volumio-bermuda volumio[1077]: info: Scanning category audio_interface May 07 13:14:00 volumio-bermuda volumio-remote-updater[625]: [2025-05-07 13:14:00] [connect] Successful connection May 07 13:14:00 volumio-bermuda volumio[1077]: info: Scanning category miscellanea May 07 13:14:00 volumio-bermuda volumio[1077]: info: Scanning category music_service May 07 13:14:00 volumio-bermuda volumio[1077]: info: Scanning category plugins.json May 07 13:14:00 volumio-bermuda volumio[1077]: info: Scanning category system_controller May 07 13:14:00 volumio-bermuda volumio[1077]: info: Scanning category user_interface May 07 13:14:00 volumio-bermuda volumio[1077]: info: Scanning into folder /data/plugins/ May 07 13:14:00 volumio-bermuda volumio[1077]: info: Scanning category peppyspectrum May 07 13:14:00 volumio-bermuda volumio[1077]: info: Removing /data/plugins//peppyspectrum/800x480+99-99bars.zip.r May 07 13:14:00 volumio-bermuda volumio[1077]: info: Removing /data/plugins//peppyspectrum/UIConfig.json May 07 13:14:00 volumio-bermuda volumio[1077]: info: Removing /data/plugins//peppyspectrum/config.json May 07 13:14:00 volumio-bermuda volumio[1077]: info: Removing /data/plugins//peppyspectrum/config.txt.tmpl May 07 13:14:00 volumio-bermuda volumio[1077]: info: Removing /data/plugins//peppyspectrum/gitignore May 07 13:14:00 volumio-bermuda volumio[1077]: info: Removing /data/plugins//peppyspectrum/index.js May 07 13:14:00 volumio-bermuda volumio[1077]: info: Removing /data/plugins//peppyspectrum/install.sh May 07 13:14:00 volumio-bermuda volumio[1077]: info: Removing /data/plugins//peppyspectrum/package-lock.json May 07 13:14:00 volumio-bermuda volumio[1077]: info: Removing /data/plugins//peppyspectrum/package.json May 07 13:14:00 volumio-bermuda volumio[1077]: info: Removing /data/plugins//peppyspectrum/peppy_in.peppy_out.6.conf.tmpl May 07 13:14:00 volumio-bermuda volumio[1077]: info: Removing /data/plugins//peppyspectrum/peppyspectrum.service.tar May 07 13:14:00 volumio-bermuda volumio[1077]: info: Removing /data/plugins//peppyspectrum/readme.md May 07 13:14:00 volumio-bermuda volumio[1077]: info: Removing /data/plugins//peppyspectrum/spectrumslist.txt May 07 13:14:00 volumio-bermuda volumio[1077]: info: Removing /data/plugins//peppyspectrum/startpeppyspectrum.sh May 07 13:14:00 volumio-bermuda volumio[1077]: info: Removing /data/plugins//peppyspectrum/uninstall.sh May 07 13:14:00 volumio-bermuda volumio[1077]: info: Scanning category system_hardware May 07 13:14:00 volumio-bermuda volumio[1077]: info: Scanning category user_interface May 07 13:14:00 volumio-bermuda volumio[1077]: info: Plugin folders cleanup completed May 07 13:14:00 volumio-bermuda volumio[1077]: info: Error: Error May 07 13:14:00 volumio-bermuda volumio[1077]: info: Folder /tmp/plugins removed May 07 13:14:00 volumio-bermuda volumio[1077]: info: Folder /tmp/downloaded_plugin.zip removed May 07 13:14:00 volumio-bermuda volumio[1077]: info: Folder /data/temp removed May 07 13:14:00 volumio-bermuda volumio-remote-updater[625]: [2025-05-07 13:14:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746616440 101 May 07 13:14:00 volumio-bermuda volumio[1077]: 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: 6 May 07 13:14:00 volumio-bermuda sudo[4328]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown -R volumio /data/plugins/user_interface/peppyspectrum /data/INTERNAL/PeppySpectrum/Templates May 07 13:14:00 volumio-bermuda sudo[4328]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 13:14:00 volumio-bermuda sudo[4328]: pam_unix(sudo:session): session closed for user root May 07 13:14:00 volumio-bermuda sudo[4330]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chgrp -R volumio /data/plugins/user_interface/peppyspectrum /data/INTERNAL/PeppySpectrum/Templates May 07 13:14:00 volumio-bermuda sudo[4330]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 13:14:00 volumio-bermuda sudo[4330]: pam_unix(sudo:session): session closed for user root May 07 13:14:00 volumio-bermuda volumio[1077]: info: installing apt packages May 07 13:14:00 volumio-bermuda sudo[4332]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get -y install python3-pygame python3-pip python3-dev libjpeg-dev zlib1g-dev May 07 13:14:00 volumio-bermuda sudo[4332]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 13:14:00 volumio-bermuda volumio[1077]: info: Reading package lists... May 07 13:14:01 volumio-bermuda volumio[1077]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 07 13:14:01 volumio-bermuda volumio[1077]: info: Building dependency tree... May 07 13:14:01 volumio-bermuda volumio[1077]: info: Reading state information... May 07 13:14:01 volumio-bermuda volumio[1077]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 07 13:14:01 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioGetState May 07 13:14:01 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:01 volumio-bermuda volumio[1077]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 07 13:14:01 volumio-bermuda volumio[1077]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 07 13:14:02 volumio-bermuda volumio[1077]: 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: 11 May 07 13:14:02 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 13:14:02 volumio-bermuda volumio[1077]: info: zlib1g-dev is already the newest version (1:1.2.11.dfsg-1+deb10u2). May 07 13:14:02 volumio-bermuda volumio[1077]: info: zlib1g-dev set to manually installed. May 07 13:14:02 volumio-bermuda volumio[1077]: info: The following additional packages will be installed: May 07 13:14:02 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioGetVisibleSources May 07 13:14:02 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 13:14:02 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioGetState May 07 13:14:02 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:02 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 07 13:14:02 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 13:14:02 volumio-bermuda volumio[1077]: info: Received Get System Info May 07 13:14:02 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 13:14:02 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 13:14:02 volumio-bermuda volumio[1077]: info: Discovery: Getting this device information May 07 13:14:02 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioGetState May 07 13:14:02 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:02 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 13:14:02 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioGetState May 07 13:14:02 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:02 volumio-bermuda volumio[1077]: info: Listing playlists May 07 13:14:02 volumio-bermuda volumio[1077]: info: build-essential bzip2 dh-python dpkg-dev fakeroot fonts-freefont-ttf g++ May 07 13:14:02 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 07 13:14:02 volumio-bermuda volumio[1077]: info: g++-8 gcc gcc-8 libalgorithm-diff-perl libalgorithm-diff-xs-perl May 07 13:14:02 volumio-bermuda volumio[1077]: info: libalgorithm-merge-perl libasan5 libblas3 libcc1-0 libexpat1-dev libfakeroot May 07 13:14:02 volumio-bermuda volumio[1077]: info: libgcc-8-dev libgfortran5 libjpeg62-turbo-dev liblapack3 libportmidi0 May 07 13:14:02 volumio-bermuda volumio[1077]: info: libpython3-dev libpython3.7 libpython3.7-dev libsdl-image1.2 libsdl-mixer1.2 May 07 13:14:02 volumio-bermuda volumio[1077]: info: libsdl-ttf2.0-0 libsdl1.2debian libstdc++-8-dev libubsan1 make patch May 07 13:14:02 volumio-bermuda volumio[1077]: info: python-pip-whl python3-asn1crypto python3-cffi-backend python3-crypto May 07 13:14:02 volumio-bermuda volumio[1077]: info: python3-cryptography python3-dbus python3-entrypoints python3-gi May 07 13:14:02 volumio-bermuda volumio[1077]: info: python3-keyring python3-keyrings.alt python3-numpy python3-secretstorage May 07 13:14:02 volumio-bermuda volumio[1077]: info: python3-setuptools python3-wheel python3-xdg python3.7-dev May 07 13:14:02 volumio-bermuda volumio[1077]: info: timgm6mb-soundfont May 07 13:14:02 volumio-bermuda volumio[1077]: info: Suggested packages: May 07 13:14:02 volumio-bermuda volumio[1077]: info: bzip2-doc debian-keyring gcc-8-doc libstdc++6-8-dbg gcc-multilib autoconf May 07 13:14:02 volumio-bermuda volumio[1077]: info: automake libtool flex bison gdb gcc-doc gcc-8-locales libgcc1-dbg May 07 13:14:02 volumio-bermuda volumio[1077]: info: libgomp1-dbg libitm1-dbg libatomic1-dbg libasan5-dbg liblsan0-dbg May 07 13:14:02 volumio-bermuda volumio[1077]: info: libtsan0-dbg libubsan1-dbg libmpx2-dbg libquadmath0-dbg libstdc++-8-doc May 07 13:14:02 volumio-bermuda volumio[1077]: info: make-doc ed diffutils-doc python-crypto-doc python-cryptography-doc May 07 13:14:02 volumio-bermuda volumio[1077]: info: python3-cryptography-vectors python-dbus-doc python3-dbus-dbg gnome-keyring May 07 13:14:02 volumio-bermuda volumio[1077]: info: libkf5wallet-bin gir1.2-gnomekeyring-1.0 gfortran python-numpy-doc May 07 13:14:02 volumio-bermuda volumio[1077]: info: python3-pytest python3-numpy-dbg python-pygame-doc timidity May 07 13:14:02 volumio-bermuda volumio[1077]: info: python-secretstorage-doc python-setuptools-doc fluid-soundfont-gm fluidsynth May 07 13:14:02 volumio-bermuda volumio[1077]: info: musescore May 07 13:14:03 volumio-bermuda volumio[1077]: info: The following NEW packages will be installed: May 07 13:14:03 volumio-bermuda volumio[1077]: info: build-essential bzip2 dh-python dpkg-dev fakeroot fonts-freefont-ttf g++ May 07 13:14:03 volumio-bermuda volumio[1077]: info: g++-8 gcc gcc-8 libalgorithm-diff-perl libalgorithm-diff-xs-perl May 07 13:14:03 volumio-bermuda volumio[1077]: info: libalgorithm-merge-perl libasan5 libblas3 libcc1-0 libexpat1-dev libfakeroot May 07 13:14:03 volumio-bermuda volumio[1077]: info: libgcc-8-dev libgfortran5 libjpeg-dev libjpeg62-turbo-dev liblapack3 May 07 13:14:03 volumio-bermuda volumio[1077]: info: libportmidi0 libpython3-dev libpython3.7 libpython3.7-dev libsdl-image1.2 May 07 13:14:03 volumio-bermuda volumio[1077]: info: libsdl-mixer1.2 libsdl-ttf2.0-0 libsdl1.2debian libstdc++-8-dev libubsan1 May 07 13:14:03 volumio-bermuda volumio[1077]: info: make patch python-pip-whl python3-asn1crypto python3-cffi-backend May 07 13:14:03 volumio-bermuda volumio[1077]: info: python3-crypto python3-cryptography python3-dbus python3-dev May 07 13:14:03 volumio-bermuda volumio[1077]: info: python3-entrypoints python3-gi python3-keyring python3-keyrings.alt May 07 13:14:03 volumio-bermuda volumio[1077]: info: python3-numpy python3-pip python3-pygame python3-secretstorage May 07 13:14:03 volumio-bermuda volumio[1077]: info: python3-setuptools python3-wheel python3-xdg python3.7-dev May 07 13:14:03 volumio-bermuda volumio[1077]: info: timgm6mb-soundfont May 07 13:14:03 volumio-bermuda volumio[1077]: info: 0 upgraded, 55 newly installed, 0 to remove and 2 not upgraded. May 07 13:14:03 volumio-bermuda volumio[1077]: info: Need to get 86.5 MB of archives. May 07 13:14:03 volumio-bermuda volumio[1077]: info: After this operation, 196 MB of additional disk space will be used. May 07 13:14:03 volumio-bermuda volumio[1077]: info: Get:1 http://archive.raspberrypi.org/debian buster/main armhf python-pip-whl all 18.1-5+rpt1 [1591 kB] May 07 13:14:03 volumio-bermuda volumio[1077]: info: Get:2 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf bzip2 armhf 1.0.6-9.2~deb10u2 [46.3 kB] May 07 13:14:03 volumio-bermuda volumio[1077]: info: Get:3 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf libcc1-0 armhf 8.3.0-6+rpi1 [37.9 kB] May 07 13:14:03 volumio-bermuda volumio[1077]: info: Get:4 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf libasan5 armhf 8.3.0-6+rpi1 [317 kB] May 07 13:14:03 volumio-bermuda volumio[1077]: info: Get:5 http://archive.raspberrypi.org/debian buster/main armhf python3-pip all 18.1-5+rpt1 [171 kB] May 07 13:14:03 volumio-bermuda volumio[1077]: info: Get:6 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf libubsan1 armhf 8.3.0-6+rpi1 [103 kB] May 07 13:14:04 volumio-bermuda volumio[1077]: info: Get:7 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf libgcc-8-dev armhf 8.3.0-6+rpi1 [595 kB] May 07 13:14:04 volumio-bermuda volumio[1077]: info: Get:8 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf gcc-8 armhf 8.3.0-6+rpi1 [7223 kB] May 07 13:14:04 volumio-bermuda volumio[1077]: info: Get:9 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf gcc armhf 4:8.3.0-1+rpi2 [5200 B] May 07 13:14:04 volumio-bermuda volumio[1077]: info: Get:10 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf libstdc++-8-dev armhf 8.3.0-6+rpi1 [1545 kB] May 07 13:14:04 volumio-bermuda volumio[1077]: info: Get:11 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf g++-8 armhf 8.3.0-6+rpi1 [7464 kB] May 07 13:14:04 volumio-bermuda volumio[1077]: info: Get:12 http://raspbian.raspberrypi.org/raspbian buster/main armhf g++ armhf 4:8.3.0-1+rpi2 [1632 B] May 07 13:14:05 volumio-bermuda volumio[1077]: info: Get:13 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf make armhf 4.2.1-1.2 [321 kB] May 07 13:14:05 volumio-bermuda volumio[1077]: info: Get:14 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf patch armhf 2.7.6-3+deb10u1 [115 kB] May 07 13:14:05 volumio-bermuda volumio[1077]: info: Get:15 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf dpkg-dev all 1.19.8 [1776 kB] May 07 13:14:11 volumio-bermuda volumio[1077]: info: Get:18 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf libfakeroot armhf 1.23-1 [43.3 kB] May 07 13:14:11 volumio-bermuda volumio[1077]: info: Get:19 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf fakeroot armhf 1.23-1 [85.2 kB] May 07 13:14:11 volumio-bermuda volumio[1077]: info: Get:16 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf build-essential armhf 12.6 [7568 B] May 07 13:14:11 volumio-bermuda volumio[1077]: info: Get:21 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf libalgorithm-diff-perl all 1.19.03-2 [47.9 kB] May 07 13:14:11 volumio-bermuda volumio[1077]: info: Get:22 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf libalgorithm-diff-xs-perl armhf 0.04-5+b1 [11.4 kB] May 07 13:14:11 volumio-bermuda volumio[1077]: info: Get:23 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf libalgorithm-merge-perl all 0.08-3 [12.7 kB] May 07 13:14:11 volumio-bermuda volumio[1077]: info: Get:25 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf libblas3 armhf 3.8.0-2 [103 kB] May 07 13:14:11 volumio-bermuda volumio[1077]: info: Get:17 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf dh-python all 3.20190308 [99.3 kB] May 07 13:14:11 volumio-bermuda volumio[1077]: info: Get:29 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf liblapack3 armhf 3.8.0-2 [1493 kB] May 07 13:14:11 volumio-bermuda volumio[1077]: info: Get:20 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf fonts-freefont-ttf all 20120503-9 [4319 kB] May 07 13:14:11 volumio-bermuda volumio[1077]: info: Get:30 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf libportmidi0 armhf 1:217-6 [20.2 kB] May 07 13:14:11 volumio-bermuda volumio[1077]: info: Get:36 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf libsdl-mixer1.2 armhf 1.2.12-15 [78.0 kB] May 07 13:14:11 volumio-bermuda volumio[1077]: info: Get:38 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf python3-asn1crypto all 0.24.0-1 [78.2 kB] May 07 13:14:11 volumio-bermuda volumio[1077]: info: Get:24 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf libgfortran5 armhf 8.3.0-6+rpi1 [206 kB] May 07 13:14:11 volumio-bermuda volumio[1077]: info: Get:26 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf libexpat1-dev armhf 2.2.6-2+deb10u7 [130 kB] May 07 13:14:11 volumio-bermuda volumio[1077]: info: Get:27 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf libjpeg62-turbo-dev armhf 1:1.5.2-2+deb10u1 [181 kB] May 07 13:14:18 volumio-bermuda volumio[1077]: info: Get:40 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf python3-crypto armhf 2.6.1-9+b1 [251 kB] May 07 13:14:18 volumio-bermuda volumio[1077]: info: Get:44 http://raspbian.raspberrypi.org/raspbian buster/main armhf python3-dev armhf 3.7.3-1 [1264 B] May 07 13:14:19 volumio-bermuda volumio[1077]: info: Get:28 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf libjpeg-dev all 1:1.5.2-2+deb10u1 [57.7 kB] May 07 13:14:19 volumio-bermuda volumio[1077]: info: Get:31 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf libpython3.7 armhf 3.7.3-2+deb10u7 [1252 kB] May 07 13:14:19 volumio-bermuda volumio[1077]: info: Get:42 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf python3-dbus armhf 1.2.8-3 [95.9 kB] May 07 13:14:19 volumio-bermuda volumio[1077]: info: Get:45 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf python3-entrypoints all 0.3-1 [5508 B] May 07 13:14:19 volumio-bermuda volumio[1077]: info: Get:46 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf python3-gi armhf 3.30.4-1 [161 kB] May 07 13:14:19 volumio-bermuda volumio[1077]: info: Get:47 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf python3-secretstorage all 2.3.1-2 [14.2 kB] May 07 13:14:19 volumio-bermuda volumio[1077]: info: Get:48 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf python3-keyring all 17.1.1-1 [43.1 kB] May 07 13:14:19 volumio-bermuda volumio[1077]: info: Get:49 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf python3-keyrings.alt all 3.1.1-1 [18.2 kB] May 07 13:14:20 volumio-bermuda volumio[1077]: info: Get:32 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf libpython3.7-dev armhf 3.7.3-2+deb10u7 [47.2 MB] May 07 13:14:21 volumio-bermuda volumio[1077]: info: Get:55 http://raspbian.mirror.garr.it/mirrors/raspbian/raspbian buster/main armhf timgm6mb-soundfont all 1.3-2 [5421 kB] May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPlay May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreStateMachine::play index undefined May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 13:14:25 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreStateMachine::startPlaybackTimer May 07 13:14:25 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::clearAddPlayTracks USB/4A3E-B83C/Clean Bandit - Rockabye (feat. Sean Paul & Anne-Marie) [Official Video].mp3 May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand stop May 07 13:14:25 volumio-bermuda volumio[1077]: info: sendMpdCommand stop took 1 milliseconds May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand clear May 07 13:14:25 volumio-bermuda volumio[1077]: info: sendMpdCommand clear took 4 milliseconds May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand add "USB/4A3E-B83C/Clean Bandit - Rockabye (feat. Sean Paul & Anne-Marie) [Official Video].mp3" May 07 13:14:25 volumio-bermuda volumio[1077]: info: May 07 13:14:25 volumio-bermuda volumio[1077]: ---------------------------- MPD announces system playlist update May 07 13:14:25 volumio-bermuda volumio[1077]: info: Ignoring MPD Status Update May 07 13:14:25 volumio-bermuda volumio[1077]: info: May 07 13:14:25 volumio-bermuda volumio[1077]: ---------------------------- MPD announces system playlist update May 07 13:14:25 volumio-bermuda volumio[1077]: info: Ignoring MPD Status Update May 07 13:14:25 volumio-bermuda volumio[1077]: info: sendMpdCommand add "USB/4A3E-B83C/Clean Bandit - Rockabye (feat. Sean Paul & Anne-Marie) [Official Video].mp3" took 2 milliseconds May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand play May 07 13:14:25 volumio-bermuda volumio[1077]: info: May 07 13:14:25 volumio-bermuda volumio[1077]: ---------------------------- MPD announces system playlist update May 07 13:14:25 volumio-bermuda volumio[1077]: info: Ignoring MPD Status Update May 07 13:14:25 volumio-bermuda volumio[1077]: info: May 07 13:14:25 volumio-bermuda volumio[1077]: ---------------------------- MPD announces system playlist update May 07 13:14:25 volumio-bermuda volumio[1077]: info: Ignoring MPD Status Update May 07 13:14:25 volumio-bermuda volumio[1077]: info: ------------------------------ 8ms May 07 13:14:25 volumio-bermuda volumio[1077]: info: ------------------------------ 7ms May 07 13:14:25 volumio-bermuda volumio[1077]: info: sendMpdCommand play took 7 milliseconds May 07 13:14:25 volumio-bermuda volumio[1077]: info: ------------------------------ 6ms May 07 13:14:25 volumio-bermuda volumio[1077]: info: May 07 13:14:25 volumio-bermuda volumio[1077]: ---------------------------- MPD announces system playlist update May 07 13:14:25 volumio-bermuda volumio[1077]: info: Ignoring MPD Status Update May 07 13:14:25 volumio-bermuda volumio[1077]: info: May 07 13:14:25 volumio-bermuda volumio[1077]: ---------------------------- MPD announces system playlist update May 07 13:14:25 volumio-bermuda volumio[1077]: info: Ignoring MPD Status Update May 07 13:14:25 volumio-bermuda volumio[1077]: error: Upnp client error: Error: This socket has been ended by the other party May 07 13:14:25 volumio-bermuda volumio[1077]: info: May 07 13:14:25 volumio-bermuda volumio[1077]: ---------------------------- MPD announces state update: player May 07 13:14:25 volumio-bermuda volumio[1077]: info: May 07 13:14:25 volumio-bermuda volumio[1077]: ---------------------------- MPD announces state update: player May 07 13:14:25 volumio-bermuda volumio[1077]: info: ------------------------------ 8ms May 07 13:14:25 volumio-bermuda volumio[1077]: info: ControllerMpd::getState May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:14:25 volumio-bermuda volumio[1077]: info: ------------------------------ 5ms May 07 13:14:25 volumio-bermuda volumio[1077]: info: ControllerMpd::getState May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:14:25 volumio-bermuda volumio[1077]: info: ------------------------------ 4ms May 07 13:14:25 volumio-bermuda volumio[1077]: info: May 07 13:14:25 volumio-bermuda volumio[1077]: ---------------------------- MPD announces state update: player May 07 13:14:25 volumio-bermuda volumio[1077]: info: ControllerMpd::getState May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:14:25 volumio-bermuda volumio[1077]: info: May 07 13:14:25 volumio-bermuda volumio[1077]: ---------------------------- MPD announces state update: player May 07 13:14:25 volumio-bermuda volumio[1077]: info: ControllerMpd::getState May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand status May 07 13:14:25 volumio-bermuda volumio[1077]: info: sendMpdCommand status took 6 milliseconds May 07 13:14:25 volumio-bermuda volumio[1077]: info: sendMpdCommand status took 4 milliseconds May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseState May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseState May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseState May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 13:14:25 volumio-bermuda volumio[1077]: info: sendMpdCommand status took 6 milliseconds May 07 13:14:25 volumio-bermuda volumio[1077]: info: sendMpdCommand playlistinfo took 4 milliseconds May 07 13:14:25 volumio-bermuda volumio[1077]: info: sendMpdCommand playlistinfo took 5 milliseconds May 07 13:14:25 volumio-bermuda volumio[1077]: info: sendMpdCommand playlistinfo took 2 milliseconds May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseState May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseTrackInfo May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseTrackInfo May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseTrackInfo May 07 13:14:25 volumio-bermuda volumio[1077]: info: ControllerMpd::pushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreCommandRouter::servicePushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Clean Bandit - Rockabye (feat. Sean Paul & Anne-Marie) [Official Video]","artist":"Clean Bandit","album":null,"uri":"USB/4A3E-B83C/Clean Bandit - Rockabye (feat. Sean Paul & Anne-Marie) [Official Video].mp3","trackType":"mp3"} May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: CURRENT POSITION 0 May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState stateService play May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState currentStatus stop May 07 13:14:25 volumio-bermuda volumio[1077]: info: ControllerMpd::pushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreCommandRouter::servicePushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Clean Bandit - Rockabye (feat. Sean Paul & Anne-Marie) [Official Video]","artist":"Clean Bandit","album":null,"uri":"USB/4A3E-B83C/Clean Bandit - Rockabye (feat. Sean Paul & Anne-Marie) [Official Video].mp3","trackType":"mp3"} May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: CURRENT POSITION 0 May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState stateService play May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState currentStatus play May 07 13:14:25 volumio-bermuda volumio[1077]: info: Received an update from plugin. extracting info from payload May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: ControllerMpd::pushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreCommandRouter::servicePushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Clean Bandit - Rockabye (feat. Sean Paul & Anne-Marie) [Official Video]","artist":"Clean Bandit","album":null,"uri":"USB/4A3E-B83C/Clean Bandit - Rockabye (feat. Sean Paul & Anne-Marie) [Official Video].mp3","trackType":"mp3"} May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: CURRENT POSITION 0 May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState stateService play May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState currentStatus play May 07 13:14:25 volumio-bermuda volumio[1077]: info: Received an update from plugin. extracting info from payload May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: ------------------------------ 32ms May 07 13:14:25 volumio-bermuda volumio[1077]: info: ------------------------------ 50ms May 07 13:14:25 volumio-bermuda volumio[1077]: info: ------------------------------ 48ms May 07 13:14:25 volumio-bermuda volumio[1077]: info: sendMpdCommand playlistinfo took 37 milliseconds May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: ControllerMpd::parseTrackInfo May 07 13:14:25 volumio-bermuda volumio[1077]: info: ControllerMpd::pushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreCommandRouter::servicePushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":171,"duration":254,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Clean Bandit - Rockabye (feat. Sean Paul & Anne-Marie) [Official Video]","artist":"Clean Bandit","album":null,"uri":"USB/4A3E-B83C/Clean Bandit - Rockabye (feat. Sean Paul & Anne-Marie) [Official Video].mp3","trackType":"mp3"} May 07 13:14:25 volumio-bermuda volumio[1077]: verbose: CURRENT POSITION 0 May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState stateService play May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreStateMachine::syncState currentStatus play May 07 13:14:25 volumio-bermuda volumio[1077]: info: Received an update from plugin. extracting info from payload May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreStateMachine::pushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: CorePlayQueue::getTrack 0 May 07 13:14:25 volumio-bermuda volumio[1077]: info: CoreCommandRouter::volumioPushState May 07 13:14:25 volumio-bermuda volumio[1077]: info: ------------------------------ 72ms May 07 13:14:50 volumio-bermuda volumio[1077]: info: Get:33 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf libpython3-dev armhf 3.7.3-1 [20.1 kB] May 07 13:14:50 volumio-bermuda volumio[1077]: info: Get:34 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf libsdl1.2debian armhf 1.2.15+dfsg2-6~deb10u1 [162 kB] May 07 13:14:50 volumio-bermuda volumio[1077]: info: Get:35 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf libsdl-image1.2 armhf 1.2.12-10+deb10u1 [32.8 kB] May 07 13:14:50 volumio-bermuda volumio[1077]: info: Get:37 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf libsdl-ttf2.0-0 armhf 2.0.11-6 [17.0 kB] May 07 13:14:50 volumio-bermuda volumio[1077]: info: Get:39 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf python3-cffi-backend armhf 1.12.2-1 [70.1 kB] May 07 13:14:50 volumio-bermuda volumio[1077]: info: Get:41 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf python3-cryptography armhf 2.6.1-3+deb10u4 [184 kB] May 07 13:14:50 volumio-bermuda volumio[1077]: info: Get:43 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf python3.7-dev armhf 3.7.3-2+deb10u7 [527 kB] May 07 13:14:53 volumio-bermuda volumio[1077]: info: Get:50 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf python3-numpy armhf 1:1.16.2-1 [1873 kB] May 07 13:14:53 volumio-bermuda volumio[1077]: info: Get:51 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf python3-pygame armhf 1.9.4.post1+dfsg-3 [534 kB] May 07 13:14:53 volumio-bermuda volumio[1077]: info: Get:52 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf python3-setuptools all 40.8.0-1 [306 kB] May 07 13:14:53 volumio-bermuda volumio[1077]: info: Get:53 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf python3-wheel all 0.32.3-2 [19.4 kB] May 07 13:14:54 volumio-bermuda volumio[1077]: info: Get:54 http://ftp.arnes.si/mirrors/raspbian/raspbian buster/main armhf python3-xdg all 0.25-5 [35.9 kB] May 07 13:14:54 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 13:14:54 volumio-bermuda volumio[1077]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 07 13:14:58 volumio-bermuda volumio[1077]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 13:14:58 volumio-bermuda volumio[1077]: SyntaxError: Unexpected token + in JSON at position 0 May 07 13:14:58 volumio-bermuda volumio[1077]: at JSON.parse () May 07 13:14:58 volumio-bermuda volumio[1077]: at rotaryencoder2.emitDialCommand (/data/plugins/system_hardware/rotaryencoder2/index.js:980:72) May 07 13:14:58 volumio-bermuda volumio[1077]: at Socket. (/data/plugins/system_hardware/rotaryencoder2/index.js:849:11) May 07 13:14:58 volumio-bermuda volumio[1077]: at Socket.emit (events.js:315:20) May 07 13:14:58 volumio-bermuda volumio[1077]: at addChunk (internal/streams/readable.js:309:12) May 07 13:14:58 volumio-bermuda volumio[1077]: at readableAddChunk (internal/streams/readable.js:284:9) May 07 13:14:58 volumio-bermuda volumio[1077]: at Socket.Readable.push (internal/streams/readable.js:223:10) May 07 13:14:58 volumio-bermuda volumio[1077]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) May 07 13:14:58 volumio-bermuda volumio[1077]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 13:14:59 volumio-bermuda sudo[4561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-07 13:13 May 07 13:14:59 volumio-bermuda sudo[4561]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"