-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2025-05-10 22:20:39 CEST. -- May 10 22:19:14 volumio volumio-remote-updater[637]: [2025-05-10 20:19:14] [connect] Successful connection May 10 22:19:16 volumio volumio[938]: info: Checking if install.sh is present May 10 22:19:16 volumio volumio[938]: info: Executing install.sh May 10 22:19:16 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.1.88 from 192.168.1.96 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 10 22:19:16 volumio sudo[10964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/touch_display/install.sh May 10 22:19:16 volumio sudo[10964]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 22:19:16 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.1.88 from 192.168.1.96 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 10 22:19:16 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.1.88 from 192.168.1.96 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 10 22:19:16 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.1.88 from 192.168.1.96 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 10 22:19:16 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.1.88 from 192.168.1.96 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 10 22:19:16 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.1.88 from 192.168.1.96 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 10 22:19:16 volumio volumio-remote-updater[637]: [2025-05-10 20:19:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746908354 101 May 10 22:19:16 volumio volumio[938]: 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 10 22:19:17 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.1.88 from 192.168.1.96 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 10 22:19:17 volumio volumio[938]: 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 10 22:19:17 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 10 22:19:18 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.1.88 from 192.168.1.96 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 10 22:19:18 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 10 22:19:18 volumio volumio[938]: info: CoreCommandRouter::volumioGetVisibleSources May 10 22:19:18 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 22:19:18 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 10 22:19:18 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 10 22:19:18 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 10 22:19:18 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 10 22:19:18 volumio volumio[938]: info: Received Get System Info May 10 22:19:18 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 22:19:18 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 22:19:18 volumio volumio[938]: info: Discovery: Getting this device information May 10 22:19:18 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 10 22:19:18 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 22:19:18 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 10 22:19:18 volumio volumio[938]: info: Listing playlists May 10 22:19:18 volumio volumio[938]: info: CoreCommandRouter::volumioGetQueue May 10 22:19:18 volumio volumio[938]: info: CoreStateMachine::getQueue May 10 22:19:18 volumio volumio[938]: info: CorePlayQueue::getQueue May 10 22:19:18 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 May 10 22:19:18 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 10 22:19:18 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 May 10 22:19:18 volumio volumio[938]: info: Re-synchronizing package index files from their sources May 10 22:19:18 volumio volumio[938]: info: Re-synchronizing package index files from their sources May 10 22:19:18 volumio volumio[938]: info: Re-synchronizing package index files from their sources May 10 22:19:18 volumio volumio[938]: info: Re-synchronizing package index files from their sources May 10 22:19:18 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 May 10 22:19:18 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 10 22:19:18 volumio volumio[938]: info: Hit:1 http://raspbian.raspberrypi.org/raspbian buster InRelease May 10 22:19:18 volumio volumio[938]: info: Hit:2 http://archive.raspberrypi.org/debian buster InRelease May 10 22:19:18 volumio volumio[938]: info: Hit:1 http://raspbian.raspberrypi.org/raspbian buster InRelease May 10 22:19:18 volumio volumio[938]: info: Hit:2 http://archive.raspberrypi.org/debian buster InRelease May 10 22:19:18 volumio volumio[938]: info: Hit:1 http://raspbian.raspberrypi.org/raspbian buster InRelease May 10 22:19:18 volumio volumio[938]: info: Hit:2 http://archive.raspberrypi.org/debian buster InRelease May 10 22:19:18 volumio volumio[938]: info: Hit:1 http://raspbian.raspberrypi.org/raspbian buster InRelease May 10 22:19:18 volumio volumio[938]: info: Hit:2 http://archive.raspberrypi.org/debian buster InRelease May 10 22:19:18 volumio volumio[938]: info: Hit:1 http://raspbian.raspberrypi.org/raspbian buster InRelease May 10 22:19:18 volumio volumio[938]: info: Hit:2 http://archive.raspberrypi.org/debian buster InRelease May 10 22:19:19 volumio volumio[938]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease May 10 22:19:19 volumio volumio[938]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease May 10 22:19:19 volumio volumio[938]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease May 10 22:19:19 volumio volumio[938]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease May 10 22:19:19 volumio volumio[938]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease May 10 22:19:22 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 10 22:19:22 volumio volumio[938]: info: Reading package lists... May 10 22:19:23 volumio volumio[938]: Selecting previously unselected package libatspi2.0-0:armhf. May 10 22:19:23 volumio volumio[938]: info: Reading package lists... May 10 22:19:23 volumio volumio[938]: Selecting previously unselected package libatspi2.0-0:armhf. May 10 22:19:23 volumio volumio[938]: info: Reading package lists... May 10 22:19:23 volumio volumio[938]: Selecting previously unselected package libatspi2.0-0:armhf. May 10 22:19:23 volumio volumio[938]: info: Reading package lists... May 10 22:19:23 volumio volumio[938]: Selecting previously unselected package libatspi2.0-0:armhf. May 10 22:19:23 volumio volumio[938]: info: Reading package lists... May 10 22:19:23 volumio volumio[938]: Selecting previously unselected package libatspi2.0-0:armhf. May 10 22:19:23 volumio volumio[938]: info: Preparing to unpack .../04-libatspi2.0-0_2.30.0-7_armhf.deb ... May 10 22:19:23 volumio volumio[938]: info: Preparing to unpack .../04-libatspi2.0-0_2.30.0-7_armhf.deb ... May 10 22:19:23 volumio volumio[938]: info: Preparing to unpack .../04-libatspi2.0-0_2.30.0-7_armhf.deb ... May 10 22:19:23 volumio volumio[938]: info: Preparing to unpack .../04-libatspi2.0-0_2.30.0-7_armhf.deb ... May 10 22:19:23 volumio volumio[938]: info: Preparing to unpack .../04-libatspi2.0-0_2.30.0-7_armhf.deb ... May 10 22:19:23 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 10 22:19:24 volumio volumio[938]: info: Unpacking libatspi2.0-0:armhf (2.30.0-7) ... May 10 22:19:24 volumio volumio[938]: info: Unpacking libatspi2.0-0:armhf (2.30.0-7) ... May 10 22:19:24 volumio volumio[938]: info: Unpacking libatspi2.0-0:armhf (2.30.0-7) ... May 10 22:19:24 volumio volumio[938]: info: Unpacking libatspi2.0-0:armhf (2.30.0-7) ... May 10 22:19:24 volumio volumio[938]: info: Unpacking libatspi2.0-0:armhf (2.30.0-7) ... May 10 22:19:25 volumio systemd[1]: Reloading. May 10 22:19:25 volumio 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 10 22:19:26 volumio sudo[10964]: pam_unix(sudo:session): session closed for user root May 10 22:19:26 volumio volumio[938]: info: Install script return the error Error: Command failed: echo volumio | sudo -S sh /data/plugins/user_interface/touch_display/install.sh > /tmp/installog May 10 22:19:26 volumio volumio[938]: [sudo] password for volumio: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:19:26 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:19:26 volumio volumio[938]: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:19:26 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:19:26 volumio volumio[938]: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:19:26 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:19:26 volumio volumio[938]: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:19:26 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:19:26 volumio volumio[938]: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:19:26 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:19:26 volumio volumio[938]: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:19:26 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:19:26 volumio volumio[938]: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:19:26 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:19:26 volumio volumio[938]: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:19:26 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:19:26 volumio volumio[938]: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:19:26 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:19:26 volumio volumio[938]: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:19:26 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:19:26 volumio volumio[938]: rm: cannot remove '/opt/volumiokiosk.sh': No such file or directory May 10 22:19:26 volumio volumio[938]: rm: cannot remove '/lib/systemd/system/volumio-kiosk.service': No such file or directory May 10 22:19:26 volumio volumio[938]: info: An error occurred installing the plugin. Rolling back config May 10 22:19:26 volumio volumio[938]: info: Plugin folders cleanup May 10 22:19:26 volumio volumio[938]: info: Scanning into folder /volumio/app/plugins/ May 10 22:19:26 volumio volumio[938]: info: Scanning category audio_interface May 10 22:19:26 volumio volumio[938]: info: Scanning category miscellanea May 10 22:19:26 volumio volumio[938]: info: Scanning category music_service May 10 22:19:26 volumio volumio[938]: info: Scanning category plugins.json May 10 22:19:26 volumio volumio[938]: info: Scanning category system_controller May 10 22:19:26 volumio volumio[938]: info: Scanning category user_interface May 10 22:19:26 volumio volumio[938]: info: Scanning into folder /data/plugins/ May 10 22:19:26 volumio volumio[938]: info: Scanning category user_interface May 10 22:19:26 volumio volumio[938]: info: Plugin folders cleanup completed May 10 22:19:26 volumio volumio[938]: info: Error: Error May 10 22:19:26 volumio volumio[938]: info: Folder /tmp/plugins removed May 10 22:19:26 volumio volumio[938]: info: Folder /tmp/downloaded_plugin.zip removed May 10 22:19:26 volumio volumio[938]: info: Folder /data/temp removed May 10 22:19:30 volumio volumio[938]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/touch_display/volumio/buster/armhf May 10 22:19:31 volumio volumio[938]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/touch_display/volumio/buster/armhf May 10 22:19:31 volumio volumio[938]: info: Folder /tmp/plugins removed May 10 22:19:41 volumio volumio[938]: info: Check plugin dependencies May 10 22:19:41 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 22:19:41 volumio volumio[938]: info: Checking if plugin already exists May 10 22:19:41 volumio volumio[938]: info: Rename folder May 10 22:19:41 volumio volumio[938]: info: Folder /tmp/downloaded_plugin.zip removed May 10 22:19:41 volumio volumio[938]: info: Move to category May 10 22:19:47 volumio volumio-remote-updater[637]: [2025-05-10 20:19:46] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] May 10 22:19:52 volumio volumio-remote-updater[637]: [2025-05-10 20:19:52] [connect] Successful connection May 10 22:19:55 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 5 ep 1 May 10 22:19:55 volumio kernel: xhci_hcd 0000:01:00.0: @000000042951abd0 00000000 00000000 0e000000 05028000 May 10 22:20:04 volumio volumio-remote-updater[637]: [2025-05-10 20:20:04] [connect] Successful connection May 10 22:20:19 volumio volumio-remote-updater[637]: [2025-05-10 20:20:19] [connect] Successful connection May 10 22:20:19 volumio volumio[938]: info: Checking if install.sh is present May 10 22:20:19 volumio volumio[938]: info: Executing install.sh May 10 22:20:19 volumio volumio[938]: info: May 10 22:20:19 volumio volumio[938]: ---------------------------- MPD announces state update: player May 10 22:20:19 volumio volumio[938]: info: ControllerMpd::getState May 10 22:20:19 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand status May 10 22:20:19 volumio volumio[938]: info: sendMpdCommand status took 21 milliseconds May 10 22:20:19 volumio volumio[938]: verbose: ControllerMpd::parseState May 10 22:20:19 volumio volumio[938]: info: ControllerMpd::pushState May 10 22:20:19 volumio volumio[938]: info: CoreCommandRouter::servicePushState May 10 22:20:19 volumio volumio[938]: info: CoreStateMachine::pushState May 10 22:20:19 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 22:20:19 volumio volumio[938]: info: CoreCommandRouter::volumioPushState May 10 22:20:19 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 10 22:20:19 volumio volumio[938]: 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 10 22:20:19 volumio volumio[938]: verbose: CURRENT POSITION 0 May 10 22:20:19 volumio volumio[938]: info: CoreStateMachine::syncState stateService stop May 10 22:20:19 volumio volumio[938]: info: CoreStateMachine::syncState currentStatus play May 10 22:20:19 volumio volumio[938]: info: CoreStateMachine::play index undefined May 10 22:20:19 volumio volumio[938]: info: CoreStateMachine::setConsumeUpdateService undefined May 10 22:20:19 volumio volumio[938]: info: CoreStateMachine::pushState May 10 22:20:19 volumio volumio[938]: info: CorePlayQueue::getTrack 1 May 10 22:20:19 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 22:20:19 volumio volumio[938]: info: CoreCommandRouter::volumioPushState May 10 22:20:19 volumio sudo[11403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/touch_display/install.sh May 10 22:20:19 volumio sudo[11403]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 22:20:19 volumio volumio[938]: info: CorePlayQueue::getTrack 1 May 10 22:20:19 volumio volumio[938]: info: CoreStateMachine::startPlaybackTimer May 10 22:20:19 volumio volumio[938]: info: CorePlayQueue::getTrack 1 May 10 22:20:19 volumio volumio[938]: info: [1746908419621] ControllerTidal::clearAddPlayTrack May 10 22:20:19 volumio volumio[938]: info: Getting stream with soundQuality HI_RES May 10 22:20:19 volumio volumio[938]: info: ------------------------------ 45ms May 10 22:20:19 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand stop May 10 22:20:19 volumio volumio[938]: info: CoreStateMachine::pushState May 10 22:20:19 volumio volumio[938]: info: CorePlayQueue::getTrack 1 May 10 22:20:19 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 22:20:19 volumio volumio[938]: info: CoreCommandRouter::volumioPushState May 10 22:20:19 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.1.88 from 192.168.1.96 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 10 22:20:19 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.1.88 from 192.168.1.96 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 10 22:20:19 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.1.88 from 192.168.1.96 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 10 22:20:19 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.1.88 from 192.168.1.96 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 10 22:20:19 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.1.88 from 192.168.1.96 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 10 22:20:19 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.1.88 from 192.168.1.96 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 10 22:20:19 volumio volumio-remote-updater[637]: [2025-05-10 20:20:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746908419 101 May 10 22:20:19 volumio volumio[938]: 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 10 22:20:19 volumio volumio[938]: info: sendMpdCommand stop took 66 milliseconds May 10 22:20:19 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand clear May 10 22:20:19 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.1.88 from 192.168.1.96 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 10 22:20:19 volumio volumio[938]: info: May 10 22:20:19 volumio volumio[938]: ---------------------------- MPD announces system playlist update May 10 22:20:19 volumio volumio[938]: info: Ignoring MPD Status Update May 10 22:20:19 volumio volumio[938]: info: sendMpdCommand clear took 17 milliseconds May 10 22:20:19 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/79279093&soundQuality=HI_RES" May 10 22:20:19 volumio volumio[938]: error: updateQueue error: null May 10 22:20:19 volumio volumio[938]: info: May 10 22:20:19 volumio volumio[938]: ---------------------------- MPD announces system playlist update May 10 22:20:19 volumio volumio[938]: info: Ignoring MPD Status Update May 10 22:20:19 volumio volumio[938]: info: ------------------------------ 4ms May 10 22:20:19 volumio volumio[938]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/79279093&soundQuality=HI_RES" took 2 milliseconds May 10 22:20:19 volumio volumio[938]: info: CoreStateMachine::setConsumeUpdateService mpd May 10 22:20:19 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand play May 10 22:20:19 volumio volumio[938]: info: ------------------------------ 3ms May 10 22:20:19 volumio volumio[938]: info: sendMpdCommand play took 2 milliseconds May 10 22:20:19 volumio volumio[938]: STREAMING PROXY: Handling url /?data=tidal://song/79279093&soundQuality=HI_RES May 10 22:20:19 volumio volumio[938]: info: Executing endpoint getStreamUrltidal May 10 22:20:19 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI May 10 22:20:20 volumio volumio[938]: info: getStreamUrl took 362 milliseconds May 10 22:20:20 volumio volumio[938]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidhYzBkNWFjNDM0ZjZhNWU2Yjc0YmI5ODg1YTVjNTI1Y182Mi5tcDQ/0.flac?token=1746912020~Yjc0NzhjMjg2MzVlMTY5MmEyM2EzOTEzZWQ1Y2E2MTljODIyMTZjOA== May 10 22:20:20 volumio volumio[938]: STREAMING PROXY: Response: 200, length: 61008974 May 10 22:20:20 volumio volumio[938]: info: May 10 22:20:20 volumio volumio[938]: ---------------------------- MPD announces state update: player May 10 22:20:20 volumio volumio[938]: info: ControllerMpd::getState May 10 22:20:20 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand status May 10 22:20:20 volumio volumio[938]: info: May 10 22:20:20 volumio volumio[938]: ---------------------------- MPD announces state update: player May 10 22:20:20 volumio volumio[938]: info: sendMpdCommand status took 12 milliseconds May 10 22:20:20 volumio volumio[938]: info: ControllerMpd::getState May 10 22:20:20 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand status May 10 22:20:20 volumio volumio[938]: verbose: ControllerMpd::parseState May 10 22:20:20 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 22:20:20 volumio volumio[938]: info: sendMpdCommand status took 2 milliseconds May 10 22:20:20 volumio volumio[938]: info: sendMpdCommand playlistinfo took 1 milliseconds May 10 22:20:20 volumio volumio[938]: verbose: ControllerMpd::parseState May 10 22:20:20 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 22:20:20 volumio volumio[938]: verbose: ControllerMpd::parseTrackInfo May 10 22:20:20 volumio volumio[938]: info: ControllerMpd::pushState May 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::servicePushState May 10 22:20:20 volumio volumio[938]: info: CorePlayQueue::getTrack 1 May 10 22:20:20 volumio volumio[938]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":160,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"79279093&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/79279093&soundQuality=HI_RES","trackType":"tidal"} May 10 22:20:20 volumio volumio[938]: verbose: CURRENT POSITION 1 May 10 22:20:20 volumio volumio[938]: info: CoreStateMachine::syncState stateService play May 10 22:20:20 volumio volumio[938]: info: CoreStateMachine::syncState currentStatus stop May 10 22:20:20 volumio volumio[938]: info: ------------------------------ 19ms May 10 22:20:20 volumio volumio[938]: info: sendMpdCommand playlistinfo took 4 milliseconds May 10 22:20:20 volumio volumio[938]: verbose: ControllerMpd::parseTrackInfo May 10 22:20:20 volumio volumio[938]: info: ControllerMpd::pushState May 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::servicePushState May 10 22:20:20 volumio volumio[938]: info: CorePlayQueue::getTrack 1 May 10 22:20:20 volumio volumio[938]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":160,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"79279093&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/79279093&soundQuality=HI_RES","trackType":"tidal"} May 10 22:20:20 volumio volumio[938]: verbose: CURRENT POSITION 1 May 10 22:20:20 volumio volumio[938]: info: CoreStateMachine::syncState stateService play May 10 22:20:20 volumio volumio[938]: info: CoreStateMachine::syncState currentStatus play May 10 22:20:20 volumio volumio[938]: info: Received an update from plugin. extracting info from payload May 10 22:20:20 volumio volumio[938]: info: CoreStateMachine::pushState May 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::volumioPushState May 10 22:20:20 volumio volumio[938]: info: CoreStateMachine::pushState May 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::volumioPushState May 10 22:20:20 volumio volumio[938]: info: ------------------------------ 14ms May 10 22:20:20 volumio volumio[938]: 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 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 10 22:20:20 volumio volumio[938]: info: Re-synchronizing package index files from their sources May 10 22:20:20 volumio volumio[938]: info: Re-synchronizing package index files from their sources May 10 22:20:20 volumio volumio[938]: info: Re-synchronizing package index files from their sources May 10 22:20:20 volumio volumio[938]: info: Re-synchronizing package index files from their sources May 10 22:20:20 volumio volumio[938]: info: Re-synchronizing package index files from their sources May 10 22:20:20 volumio volumio[938]: info: Re-synchronizing package index files from their sources May 10 22:20:20 volumio volumio[938]: 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 10 22:20:20 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.1.88 from 192.168.1.96 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 May 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::volumioGetVisibleSources May 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 10 22:20:20 volumio volumio[938]: info: Received Get System Info May 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 22:20:20 volumio volumio[938]: info: Discovery: Getting this device information May 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 10 22:20:20 volumio volumio[938]: info: Listing playlists May 10 22:20:20 volumio volumio[938]: info: CoreCommandRouter::volumioGetQueue May 10 22:20:20 volumio volumio[938]: info: CoreStateMachine::getQueue May 10 22:20:20 volumio volumio[938]: info: CorePlayQueue::getQueue May 10 22:20:21 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 May 10 22:20:21 volumio volumio[938]: info: Hit:1 http://raspbian.raspberrypi.org/raspbian buster InRelease May 10 22:20:21 volumio volumio[938]: info: Hit:2 http://archive.raspberrypi.org/debian buster InRelease May 10 22:20:21 volumio volumio[938]: info: Hit:1 http://raspbian.raspberrypi.org/raspbian buster InRelease May 10 22:20:21 volumio volumio[938]: info: Hit:2 http://archive.raspberrypi.org/debian buster InRelease May 10 22:20:21 volumio volumio[938]: info: Hit:1 http://raspbian.raspberrypi.org/raspbian buster InRelease May 10 22:20:21 volumio volumio[938]: info: Hit:2 http://archive.raspberrypi.org/debian buster InRelease May 10 22:20:21 volumio volumio[938]: info: Hit:1 http://raspbian.raspberrypi.org/raspbian buster InRelease May 10 22:20:21 volumio volumio[938]: info: Hit:2 http://archive.raspberrypi.org/debian buster InRelease May 10 22:20:21 volumio volumio[938]: info: Hit:1 http://raspbian.raspberrypi.org/raspbian buster InRelease May 10 22:20:21 volumio volumio[938]: info: Hit:2 http://archive.raspberrypi.org/debian buster InRelease May 10 22:20:21 volumio volumio[938]: info: Hit:1 http://raspbian.raspberrypi.org/raspbian buster InRelease May 10 22:20:21 volumio volumio[938]: info: Hit:2 http://archive.raspberrypi.org/debian buster InRelease May 10 22:20:21 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 10 22:20:22 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 May 10 22:20:22 volumio volumio[938]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease May 10 22:20:22 volumio volumio[938]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease May 10 22:20:22 volumio volumio[938]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease May 10 22:20:22 volumio volumio[938]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease May 10 22:20:22 volumio volumio[938]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease May 10 22:20:22 volumio volumio[938]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease May 10 22:20:22 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 10 22:20:24 volumio volumio[938]: info: Reading package lists... May 10 22:20:24 volumio volumio[938]: Selecting previously unselected package at-spi2-core. May 10 22:20:24 volumio volumio[938]: info: Reading package lists... May 10 22:20:24 volumio volumio[938]: Selecting previously unselected package at-spi2-core. May 10 22:20:24 volumio volumio[938]: info: Reading package lists... May 10 22:20:24 volumio volumio[938]: Selecting previously unselected package at-spi2-core. May 10 22:20:24 volumio volumio[938]: info: Reading package lists... May 10 22:20:24 volumio volumio[938]: Selecting previously unselected package at-spi2-core. May 10 22:20:24 volumio volumio[938]: info: Reading package lists... May 10 22:20:24 volumio volumio[938]: Selecting previously unselected package at-spi2-core. May 10 22:20:25 volumio volumio[938]: info: Reading package lists... May 10 22:20:25 volumio volumio[938]: Selecting previously unselected package at-spi2-core. May 10 22:20:25 volumio volumio[938]: info: Preparing to unpack .../05-at-spi2-core_2.30.0-7_armhf.deb ... May 10 22:20:25 volumio volumio[938]: info: Preparing to unpack .../05-at-spi2-core_2.30.0-7_armhf.deb ... May 10 22:20:25 volumio volumio[938]: info: Preparing to unpack .../05-at-spi2-core_2.30.0-7_armhf.deb ... May 10 22:20:25 volumio volumio[938]: info: Preparing to unpack .../05-at-spi2-core_2.30.0-7_armhf.deb ... May 10 22:20:25 volumio volumio[938]: info: Preparing to unpack .../05-at-spi2-core_2.30.0-7_armhf.deb ... May 10 22:20:25 volumio volumio[938]: info: Preparing to unpack .../05-at-spi2-core_2.30.0-7_armhf.deb ... May 10 22:20:25 volumio volumio[938]: info: Unpacking at-spi2-core (2.30.0-7) ... May 10 22:20:25 volumio volumio[938]: info: Unpacking at-spi2-core (2.30.0-7) ... May 10 22:20:25 volumio volumio[938]: info: Unpacking at-spi2-core (2.30.0-7) ... May 10 22:20:26 volumio volumio[938]: info: Unpacking at-spi2-core (2.30.0-7) ... May 10 22:20:26 volumio volumio[938]: info: Unpacking at-spi2-core (2.30.0-7) ... May 10 22:20:26 volumio volumio[938]: info: Unpacking at-spi2-core (2.30.0-7) ... May 10 22:20:27 volumio volumio[938]: info: Selecting previously unselected package libdconf1:armhf. May 10 22:20:27 volumio volumio[938]: info: Preparing to unpack .../06-libdconf1_0.30.1-2_armhf.deb ... May 10 22:20:27 volumio volumio[938]: info: Unpacking libdconf1:armhf (0.30.1-2) ... May 10 22:20:27 volumio volumio[938]: info: Selecting previously unselected package dconf-service. May 10 22:20:27 volumio volumio[938]: info: Preparing to unpack .../07-dconf-service_0.30.1-2_armhf.deb ... May 10 22:20:27 volumio volumio[938]: info: Unpacking dconf-service (0.30.1-2) ... May 10 22:20:27 volumio volumio[938]: info: Selecting previously unselected package libdconf1:armhf. May 10 22:20:27 volumio volumio[938]: info: Preparing to unpack .../06-libdconf1_0.30.1-2_armhf.deb ... May 10 22:20:27 volumio volumio[938]: info: Unpacking libdconf1:armhf (0.30.1-2) ... May 10 22:20:27 volumio volumio[938]: info: Selecting previously unselected package dconf-service. May 10 22:20:27 volumio volumio[938]: info: Preparing to unpack .../07-dconf-service_0.30.1-2_armhf.deb ... May 10 22:20:27 volumio volumio[938]: info: Unpacking dconf-service (0.30.1-2) ... May 10 22:20:28 volumio volumio[938]: info: Selecting previously unselected package libdconf1:armhf. May 10 22:20:28 volumio volumio[938]: info: Preparing to unpack .../06-libdconf1_0.30.1-2_armhf.deb ... May 10 22:20:28 volumio volumio[938]: info: Unpacking libdconf1:armhf (0.30.1-2) ... May 10 22:20:28 volumio volumio[938]: info: Selecting previously unselected package dconf-service. May 10 22:20:28 volumio volumio[938]: info: Preparing to unpack .../07-dconf-service_0.30.1-2_armhf.deb ... May 10 22:20:28 volumio volumio[938]: info: Unpacking dconf-service (0.30.1-2) ... May 10 22:20:28 volumio volumio[938]: info: Selecting previously unselected package libdconf1:armhf. May 10 22:20:28 volumio volumio[938]: info: Preparing to unpack .../06-libdconf1_0.30.1-2_armhf.deb ... May 10 22:20:28 volumio volumio[938]: info: Unpacking libdconf1:armhf (0.30.1-2) ... May 10 22:20:28 volumio volumio[938]: info: Selecting previously unselected package dconf-service. May 10 22:20:28 volumio volumio[938]: info: Preparing to unpack .../07-dconf-service_0.30.1-2_armhf.deb ... May 10 22:20:28 volumio volumio[938]: info: Unpacking dconf-service (0.30.1-2) ... May 10 22:20:28 volumio volumio[938]: info: Selecting previously unselected package libdconf1:armhf. May 10 22:20:28 volumio volumio[938]: info: Preparing to unpack .../06-libdconf1_0.30.1-2_armhf.deb ... May 10 22:20:28 volumio volumio[938]: info: Unpacking libdconf1:armhf (0.30.1-2) ... May 10 22:20:28 volumio volumio[938]: info: Selecting previously unselected package dconf-service. May 10 22:20:28 volumio volumio[938]: info: Preparing to unpack .../07-dconf-service_0.30.1-2_armhf.deb ... May 10 22:20:28 volumio volumio[938]: info: Unpacking dconf-service (0.30.1-2) ... May 10 22:20:28 volumio volumio[938]: info: Selecting previously unselected package libdconf1:armhf. May 10 22:20:28 volumio volumio[938]: info: Preparing to unpack .../06-libdconf1_0.30.1-2_armhf.deb ... May 10 22:20:28 volumio volumio[938]: info: Unpacking libdconf1:armhf (0.30.1-2) ... May 10 22:20:28 volumio volumio[938]: info: Selecting previously unselected package dconf-service. May 10 22:20:28 volumio volumio[938]: info: Preparing to unpack .../07-dconf-service_0.30.1-2_armhf.deb ... May 10 22:20:28 volumio volumio[938]: info: Unpacking dconf-service (0.30.1-2) ... May 10 22:20:31 volumio systemd[1]: Reloading. May 10 22:20:31 volumio 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 10 22:20:33 volumio volumio[938]: STREAMING PROXY: Client dropped request, destroying May 10 22:20:34 volumio volumio[938]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/peppymeterbasic/volumio/buster/armhf May 10 22:20:36 volumio volumio[938]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/peppymeterbasic/volumio/buster/armhf May 10 22:20:36 volumio volumio[938]: info: Stopping peppymeterbasic service May 10 22:20:36 volumio volumio[938]: info: CoreStateMachine::stop May 10 22:20:36 volumio volumio[938]: info: CoreStateMachine::setConsumeUpdateService undefined May 10 22:20:36 volumio volumio[938]: info: CoreStateMachine::stPlaybackTimer May 10 22:20:36 volumio volumio[938]: info: CoreStateMachine::updateTrackBlock May 10 22:20:36 volumio volumio[938]: info: CorePlayQueue::getTrackBlock May 10 22:20:36 volumio volumio[938]: info: CoreStateMachine::pushState May 10 22:20:36 volumio volumio[938]: info: CorePlayQueue::getTrack 1 May 10 22:20:36 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 22:20:36 volumio volumio[938]: info: CoreCommandRouter::volumioPushState May 10 22:20:36 volumio volumio[938]: info: CoreStateMachine::serviceStop May 10 22:20:36 volumio volumio[938]: info: CorePlayQueue::getTrack 1 May 10 22:20:36 volumio volumio[938]: info: CoreCommandRouter::serviceStop May 10 22:20:36 volumio volumio[938]: info: [1746908436227] ControllerTidal::stop May 10 22:20:36 volumio volumio[938]: info: CoreStateMachine::setConsumeUpdateService mpd May 10 22:20:36 volumio volumio[938]: info: ControllerMpd::stop May 10 22:20:36 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand stop May 10 22:20:37 volumio volumio[938]: info: PeppyMeterBasic ---peppymeterbasic status stop May 10 22:20:37 volumio volumio[938]: info: May 10 22:20:37 volumio volumio[938]: ---------------------------- MPD announces state update: player May 10 22:20:37 volumio volumio[938]: info: sendMpdCommand stop took 933 milliseconds May 10 22:20:37 volumio volumio[938]: info: ControllerMpd::getState May 10 22:20:37 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand status May 10 22:20:37 volumio sudo[11731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 10 22:20:37 volumio sudo[11731]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 22:20:37 volumio sudo[11731]: pam_unix(sudo:session): session closed for user root May 10 22:20:37 volumio volumio[938]: info: sendMpdCommand status took 39 milliseconds May 10 22:20:37 volumio volumio[938]: verbose: ControllerMpd::parseState May 10 22:20:37 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 22:20:37 volumio sudo[11734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 10 22:20:37 volumio sudo[11734]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 22:20:37 volumio volumio[938]: info: peppymeterbasic Daemon Stop May 10 22:20:37 volumio volumio[938]: info: sendMpdCommand playlistinfo took 11 milliseconds May 10 22:20:37 volumio volumio[938]: verbose: ControllerMpd::parseTrackInfo May 10 22:20:37 volumio volumio[938]: info: ControllerMpd::pushState May 10 22:20:37 volumio volumio[938]: info: CoreCommandRouter::servicePushState May 10 22:20:37 volumio volumio[938]: info: CoreStateMachine::pushState May 10 22:20:37 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 22:20:37 volumio volumio[938]: info: CoreCommandRouter::volumioPushState May 10 22:20:37 volumio volumio[938]: info: CorePlayQueue::getTrack 1 May 10 22:20:37 volumio volumio[938]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"79279093&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/79279093&soundQuality=HI_RES","trackType":"tidal"} May 10 22:20:37 volumio volumio[938]: verbose: CURRENT POSITION 1 May 10 22:20:37 volumio volumio[938]: info: CoreStateMachine::syncState stateService stop May 10 22:20:37 volumio volumio[938]: info: CoreStateMachine::syncState currentStatus stop May 10 22:20:37 volumio volumio[938]: info: CoreStateMachine::pushState May 10 22:20:37 volumio volumio[938]: info: CoreCommandRouter::volumioPushState May 10 22:20:37 volumio volumio[938]: info: No code May 10 22:20:37 volumio volumio[938]: info: CoreStateMachine::pushState May 10 22:20:37 volumio volumio[938]: info: CoreCommandRouter::volumioPushState May 10 22:20:37 volumio sudo[11734]: pam_unix(sudo:session): session closed for user root May 10 22:20:37 volumio volumio[938]: info: ------------------------------ 73ms May 10 22:20:39 volumio sudo[11403]: pam_unix(sudo:session): session closed for user root May 10 22:20:39 volumio volumio[938]: info: Install script return the error Error: Command failed: echo volumio | sudo -S sh /data/plugins/user_interface/touch_display/install.sh > /tmp/installog May 10 22:20:39 volumio volumio[938]: [sudo] password for volumio: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:20:39 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:20:39 volumio volumio[938]: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:20:39 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:20:39 volumio volumio[938]: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:20:39 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:20:39 volumio volumio[938]: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:20:39 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:20:39 volumio volumio[938]: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:20:39 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:20:39 volumio volumio[938]: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:20:39 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:20:39 volumio volumio[938]: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:20:39 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:20:39 volumio volumio[938]: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:20:39 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:20:39 volumio volumio[938]: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:20:39 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:20:39 volumio volumio[938]: E: Could not get lock /var/lib/dpkg/lock-frontend - open (11: Resource temporarily unavailable) May 10 22:20:39 volumio volumio[938]: E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it? May 10 22:20:39 volumio volumio[938]: rm: cannot remove '/opt/volumiokiosk.sh': No such file or directory May 10 22:20:39 volumio volumio[938]: rm: cannot remove '/lib/systemd/system/volumio-kiosk.service': No such file or directory May 10 22:20:39 volumio volumio[938]: info: An error occurred installing the plugin. Rolling back config May 10 22:20:39 volumio volumio[938]: info: Plugin folders cleanup May 10 22:20:39 volumio volumio[938]: info: Scanning into folder /volumio/app/plugins/ May 10 22:20:39 volumio volumio[938]: info: Scanning category audio_interface May 10 22:20:39 volumio volumio[938]: info: Scanning category miscellanea May 10 22:20:39 volumio volumio[938]: info: Scanning category music_service May 10 22:20:39 volumio volumio[938]: info: Scanning category plugins.json May 10 22:20:39 volumio volumio[938]: info: Scanning category system_controller May 10 22:20:39 volumio volumio[938]: info: Scanning category user_interface May 10 22:20:39 volumio volumio[938]: info: Scanning into folder /data/plugins/ May 10 22:20:39 volumio volumio[938]: info: Scanning category user_interface May 10 22:20:39 volumio volumio[938]: info: Plugin folders cleanup completed May 10 22:20:39 volumio volumio[938]: info: Error: Error May 10 22:20:39 volumio volumio[938]: info: Folder /tmp/plugins removed May 10 22:20:39 volumio volumio[938]: info: Folder /tmp/downloaded_plugin.zip removed May 10 22:20:39 volumio volumio[938]: info: Folder /data/temp removed May 10 22:20:39 volumio volumio[938]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 10 22:20:39 volumio volumio[938]: Error: Unable to resolve or reject the same promise twice May 10 22:20:39 volumio volumio[938]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 10 22:20:39 volumio volumio[938]: at /volumio/app/pluginmanager.js:1087:11 May 10 22:20:39 volumio volumio[938]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) May 10 22:20:39 volumio volumio[938]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 May 10 22:20:39 volumio volumio[938]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) May 10 22:20:39 volumio volumio[938]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 May 10 22:20:39 volumio volumio[938]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) May 10 22:20:39 volumio volumio[938]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 May 10 22:20:39 volumio volumio[938]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) May 10 22:20:39 volumio volumio[938]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 May 10 22:20:39 volumio volumio[938]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) May 10 22:20:39 volumio volumio[938]: at FSReqCallback.oncomplete (fs.js:171:23) May 10 22:20:39 volumio volumio[938]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 10 22:20:39 volumio sudo[11747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-10 22:19 May 10 22:20:39 volumio sudo[11747]: 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="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"