-- Logs begin at Fri 2024-10-25 13:16:48 CEST, end at Fri 2024-10-25 13:27:07 CEST. -- Oct 25 13:26:00 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 25 13:26:00 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 25 13:26:09 volumio volumio[955]: info: Starting Uninstall of plugin system_hardware - mpd_oled Oct 25 13:26:09 volumio volumio[955]: info: Uninstalling plugin mpd_oled Oct 25 13:26:09 volumio sudo[4029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd_oled_plugin.service Oct 25 13:26:09 volumio sudo[4029]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:26:10 volumio volumio[955]: info: Disabling plugin mpd_oled Oct 25 13:26:10 volumio volumio[955]: info: Preparing to generate the ALSA configuration file Oct 25 13:26:10 volumio sudo[4029]: pam_unix(sudo:session): session closed for user root Oct 25 13:26:10 volumio volumio[955]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Oct 25 13:26:10 volumio volumio[955]: info: Reading ALSA contributions from plugins. Oct 25 13:26:10 volumio volumio[955]: info: [MPD_OLED Plugin] Stopped mpd_oled_plugin service Oct 25 13:26:10 volumio volumio[955]: info: Asound.conf file written Oct 25 13:26:10 volumio sudo[4034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 25 13:26:10 volumio sudo[4034]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:26:10 volumio sudo[4034]: pam_unix(sudo:session): session closed for user root Oct 25 13:26:10 volumio volumio[955]: info: Output device has changed, restarting MPD Oct 25 13:26:10 volumio sudo[4039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 25 13:26:10 volumio sudo[4039]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:26:10 volumio volumio[955]: info: Output device has changed, restarting Shairport Sync Oct 25 13:26:10 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 13:26:10 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 13:26:10 volumio sudo[4039]: pam_unix(sudo:session): session closed for user root Oct 25 13:26:10 volumio sudo[4042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 13:26:10 volumio sudo[4042]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:26:10 volumio volumio[955]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 25 13:26:10 volumio volumio[955]: info: Checking if uninstall.sh is present Oct 25 13:26:10 volumio systemd[1]: Stopping Music Player Daemon... Oct 25 13:26:10 volumio volumio[955]: info: MPD Permissions set Oct 25 13:26:10 volumio volumio[955]: info: Executing uninstall.sh Oct 25 13:26:10 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 13:26:10 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 13:26:10 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 13:26:10 volumio systemd[1]: mpd.service: Succeeded. Oct 25 13:26:10 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 13:26:10 volumio systemd[1]: Stopped Music Player Daemon. Oct 25 13:26:10 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 13:26:10 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 13:26:10 volumio volumio[955]: info: Starting Shairport Sync Oct 25 13:26:10 volumio systemd[1]: Starting Music Player Daemon... Oct 25 13:26:10 volumio sudo[4049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/mpd_oled/uninstall.sh Oct 25 13:26:10 volumio sudo[4056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 25 13:26:10 volumio sudo[4056]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:26:10 volumio sudo[4049]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:26:10 volumio volumio[955]: error: Upnp client error: Error: This socket has been ended by the other party Oct 25 13:26:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 25 13:26:10 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 25 13:26:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 25 13:26:10 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 25 13:26:10 volumio sudo[4056]: pam_unix(sudo:session): session closed for user root Oct 25 13:26:10 volumio volumio[955]: info: Shairport-Sync Started Oct 25 13:26:10 volumio systemd[1]: Reloading. Oct 25 13:26:10 volumio sudo[4050]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 25 13:26:10 volumio sudo[4050]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:26:10 volumio sudo[4050]: pam_unix(sudo:session): session closed for user root Oct 25 13:26:11 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. Oct 25 13:26:11 volumio systemd[1]: Reloading. Oct 25 13:26:12 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. Oct 25 13:26:12 volumio sudo[4099]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt remove -y mpd-oled Oct 25 13:26:12 volumio sudo[4099]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:26:12 volumio sudo[4099]: pam_unix(sudo:session): session closed for user root Oct 25 13:26:12 volumio sudo[4049]: pam_unix(sudo:session): session closed for user root Oct 25 13:26:12 volumio volumio[955]: info: Uninstall script completed Oct 25 13:26:12 volumio volumio[955]: info: Removing plugin mpd_oled from configuration Oct 25 13:26:13 volumio mpd[4078]: Oct 25 13:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 25 13:26:13 volumio systemd[1]: Started Music Player Daemon. Oct 25 13:26:13 volumio sudo[4042]: pam_unix(sudo:session): session closed for user root Oct 25 13:26:13 volumio volumio[955]: info: Successfully removed mpd_oled configuration files Oct 25 13:26:13 volumio volumio[955]: info: Plugin folders cleanup Oct 25 13:26:13 volumio volumio[955]: info: Scanning into folder /volumio/app/plugins/ Oct 25 13:26:13 volumio volumio[955]: info: Scanning category audio_interface Oct 25 13:26:13 volumio volumio[955]: info: Scanning category miscellanea Oct 25 13:26:13 volumio volumio[955]: info: Scanning category music_service Oct 25 13:26:13 volumio volumio[955]: info: Scanning category plugins.json Oct 25 13:26:13 volumio volumio[955]: info: Scanning category system_controller Oct 25 13:26:13 volumio volumio[955]: info: Scanning category user_interface Oct 25 13:26:13 volumio volumio[955]: info: Scanning into folder /data/plugins/ Oct 25 13:26:13 volumio volumio[955]: info: Scanning category music_service Oct 25 13:26:13 volumio volumio[955]: info: Scanning category system_hardware Oct 25 13:26:13 volumio volumio[955]: info: Cleaning folder for mpd_oled Oct 25 13:26:13 volumio volumio[955]: info: Scanning category user_interface Oct 25 13:26:13 volumio volumio[955]: info: Plugin folders cleanup completed Oct 25 13:26:13 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 25 13:26:13 volumio volumio[955]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.1","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Peppy-spectrum","name":"peppyspectrum","category":"user_interface","version":"0.0.35","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":true,"active":true}] Oct 25 13:26:13 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 25 13:26:13 volumio volumio[955]: error: updateQueue error: null Oct 25 13:26:28 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 25 13:26:28 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 25 13:26:39 volumio volumio[955]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/touch_display/volumio/buster/armhf Oct 25 13:26:41 volumio volumio[955]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/touch_display/volumio/buster/armhf Oct 25 13:26:41 volumio volumio[955]: info: Folder /tmp/plugins removed Oct 25 13:26:41 volumio volumio[955]: info: Check plugin dependencies Oct 25 13:26:41 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 25 13:26:41 volumio volumio[955]: info: Checking if plugin already exists Oct 25 13:26:41 volumio volumio[955]: info: Rename folder Oct 25 13:26:41 volumio volumio[955]: info: Folder /tmp/downloaded_plugin.zip removed Oct 25 13:26:41 volumio volumio[955]: info: Move to category Oct 25 13:26:47 volumio volumio-remote-updater[626]: [2024-10-25 13:26:47] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Oct 25 13:26:52 volumio volumio-remote-updater[626]: [2024-10-25 13:26:52] [connect] Successful connection Oct 25 13:26:52 volumio volumio[955]: info: Checking if install.sh is present Oct 25 13:26:52 volumio volumio[955]: info: Executing install.sh Oct 25 13:26:53 volumio volumio[955]: Upnp client error: Error: This socket has been ended by the other party Oct 25 13:26:53 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.0.17 from 192.168.0.117 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0.1 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 6 Oct 25 13:26:53 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.0.17 from 192.168.0.117 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0.1 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 7 Oct 25 13:26:53 volumio volumio-remote-updater[626]: [2024-10-25 13:26:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729855612 101 Oct 25 13:26:53 volumio volumio[955]: 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: 8 Oct 25 13:26:53 volumio volumio[955]: Upnp client error: Error: This socket has been ended by the other party Oct 25 13:26:53 volumio volumio[955]: Upnp client error: Error: This socket has been ended by the other party Oct 25 13:26:53 volumio volumio[955]: Upnp client error: Error: This socket has been ended by the other party Oct 25 13:26:53 volumio sudo[4220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/touch_display/install.sh Oct 25 13:26:53 volumio sudo[4220]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:26:53 volumio volumio[955]: info: Re-synchronizing package index files from their sources Oct 25 13:26:53 volumio volumio[955]: info: Hit:1 http://raspbian.raspberrypi.org/raspbian buster InRelease Oct 25 13:26:53 volumio volumio[955]: info: Hit:2 http://archive.raspberrypi.org/debian buster InRelease Oct 25 13:26:53 volumio volumio[955]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease Oct 25 13:26:54 volumio volumio[955]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 25 13:26:54 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 25 13:26:55 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.0.17 from 192.168.0.117 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0.1 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 10 Oct 25 13:26:55 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 25 13:26:55 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 25 13:26:55 volumio volumio[955]: info: Listing playlists Oct 25 13:26:55 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Oct 25 13:26:55 volumio volumio[955]: info: CoreStateMachine::getQueue Oct 25 13:26:55 volumio volumio[955]: info: CorePlayQueue::getQueue Oct 25 13:26:59 volumio volumio[955]: info: Reading package lists... Oct 25 13:26:59 volumio volumio[955]: info: Running apt-get -y install failed Oct 25 13:26:59 volumio volumio[955]: info: Plugin failed to install! Oct 25 13:26:59 volumio volumio[955]: info: Cleaning up... Oct 25 13:27:00 volumio systemd[1]: Reloading. Oct 25 13:27:00 volumio volumio[955]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/touch_display/volumio/buster/armhf Oct 25 13:27:00 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. Oct 25 13:27:01 volumio volumio[955]: info: Removing dependencies Oct 25 13:27:01 volumio volumio[955]: info: Deleting /opt/volumiokiosk.sh Oct 25 13:27:01 volumio volumio[955]: info: Deleting /data/volumiokiosk Oct 25 13:27:01 volumio volumio[955]: info: Deleting /data/volumiokioskextensions Oct 25 13:27:01 volumio volumio[955]: info: Deleting /lib/systemd/system/volumio-kiosk.service Oct 25 13:27:01 volumio volumio[955]: info: Enabling login prompt Oct 25 13:27:01 volumio volumio[955]: info: Done Oct 25 13:27:01 volumio volumio[955]: info: Removing plugin directory /data/plugins/user_interface/touch_display Oct 25 13:27:02 volumio volumio[955]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/touch_display/volumio/buster/armhf Oct 25 13:27:02 volumio volumio[955]: info: Folder /tmp/plugins removed Oct 25 13:27:06 volumio volumio[955]: info: Removing temporary directory /tmp/touch_display-ToqKXvaOGi Oct 25 13:27:06 volumio sudo[4220]: pam_unix(sudo:session): session closed for user root Oct 25 13:27:06 volumio volumio[955]: info: Install script return the error Error: Command failed: echo volumio | sudo -S sh /data/plugins/user_interface/touch_display/install.sh > /tmp/installog Oct 25 13:27:06 volumio volumio[955]: [sudo] password for volumio: E: dpkg was interrupted, you must manually run 'sudo dpkg --configure -a' to correct the problem. Oct 25 13:27:06 volumio volumio[955]: E: dpkg was interrupted, you must manually run 'sudo dpkg --configure -a' to correct the problem. Oct 25 13:27:06 volumio volumio[955]: rm: cannot remove '/opt/volumiokiosk.sh': No such file or directory Oct 25 13:27:06 volumio volumio[955]: rm: cannot remove '/lib/systemd/system/volumio-kiosk.service': No such file or directory Oct 25 13:27:06 volumio volumio[955]: info: An error occurred installing the plugin. Rolling back config Oct 25 13:27:06 volumio volumio[955]: info: Plugin folders cleanup Oct 25 13:27:06 volumio volumio[955]: info: Scanning into folder /volumio/app/plugins/ Oct 25 13:27:06 volumio volumio[955]: info: Scanning category audio_interface Oct 25 13:27:06 volumio volumio[955]: info: Scanning category miscellanea Oct 25 13:27:06 volumio volumio[955]: info: Scanning category music_service Oct 25 13:27:06 volumio volumio[955]: info: Scanning category plugins.json Oct 25 13:27:06 volumio volumio[955]: info: Scanning category system_controller Oct 25 13:27:06 volumio volumio[955]: info: Scanning category user_interface Oct 25 13:27:06 volumio volumio[955]: info: Scanning into folder /data/plugins/ Oct 25 13:27:06 volumio volumio[955]: info: Scanning category music_service Oct 25 13:27:06 volumio volumio[955]: info: Scanning category system_hardware Oct 25 13:27:06 volumio volumio[955]: info: Scanning category user_interface Oct 25 13:27:06 volumio volumio[955]: info: Plugin folders cleanup completed Oct 25 13:27:06 volumio volumio[955]: info: Error: Error Oct 25 13:27:06 volumio volumio[955]: info: Folder /tmp/plugins removed Oct 25 13:27:06 volumio volumio[955]: Plugin install end detected on script Oct 25 13:27:06 volumio volumio[955]: info: Folder /tmp/downloaded_plugin.zip removed Oct 25 13:27:06 volumio volumio[955]: info: Folder /data/temp removed Oct 25 13:27:06 volumio volumio[955]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 13:27:06 volumio volumio[955]: Error: Unable to resolve or reject the same promise twice Oct 25 13:27:06 volumio volumio[955]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 25 13:27:06 volumio volumio[955]: at /volumio/app/pluginmanager.js:1087:11 Oct 25 13:27:06 volumio volumio[955]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Oct 25 13:27:06 volumio volumio[955]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Oct 25 13:27:06 volumio volumio[955]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Oct 25 13:27:06 volumio volumio[955]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Oct 25 13:27:06 volumio volumio[955]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Oct 25 13:27:06 volumio volumio[955]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Oct 25 13:27:06 volumio volumio[955]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Oct 25 13:27:06 volumio volumio[955]: at FSReqCallback.oncomplete (fs.js:171:23) Oct 25 13:27:06 volumio volumio[955]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 13:27:07 volumio sudo[4516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-25 13:26 Oct 25 13:27:07 volumio sudo[4516]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"