-- Logs begin at Mon 2025-05-26 15:38:03 CEST, end at Mon 2025-05-26 15:49:54 CEST. -- May 26 15:48:01 volumio volumio[882]: error: Cannot download Available plugins list: Error: Client network socket disconnected before secure TLS connection was established May 26 15:48:06 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 26 15:48:20 volumio nmbd[723]: [2025/05/26 15:48:20.673884, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 26 15:48:20 volumio nmbd[723]: query_name_response: Multiple (2) responses received for a query on subnet 172.26.2.232 for name WORKGROUP<1d>. May 26 15:48:20 volumio nmbd[723]: This response was from IP 172.26.2.84, reporting an IP address of 172.26.2.84. May 26 15:48:36 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 26 15:48:36 volumio volumio[882]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 26 15:48:36 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 26 15:48:36 volumio volumio[882]: info: Received Get System Version May 26 15:48:36 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 15:48:36 volumio volumio[882]: info: Received Get System Info May 26 15:48:36 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:48:36 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:48:36 volumio volumio[882]: info: Discovery: Getting this device information May 26 15:48:36 volumio volumio[882]: info: CoreCommandRouter::volumioGetState May 26 15:48:36 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:48:36 volumio volumio[882]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/volumio/buster/armhf May 26 15:48:38 volumio volumio[882]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/volumio/buster/armhf May 26 15:48:38 volumio volumio[882]: info: Folder /tmp/plugins removed May 26 15:48:38 volumio volumio[882]: info: Check plugin dependencies May 26 15:48:38 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 15:48:38 volumio volumio[882]: info: Checking if plugin already exists May 26 15:48:38 volumio volumio[882]: info: Rename folder May 26 15:48:38 volumio volumio[882]: info: Folder /tmp/downloaded_plugin.zip removed May 26 15:48:38 volumio volumio[882]: info: Move to category May 26 15:48:42 volumio volumio[882]: info: Checking if install.sh is present May 26 15:48:42 volumio volumio[882]: info: Executing install.sh May 26 15:48:42 volumio volumio[882]: Upnp client error: Error: This socket has been ended by the other party May 26 15:48:42 volumio sudo[3331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/soundcloud/install.sh May 26 15:48:42 volumio sudo[3331]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:48:42 volumio sudo[3331]: pam_unix(sudo:session): session closed for user root May 26 15:48:42 volumio volumio[882]: info: SoundCloud plugin installed May 26 15:48:42 volumio volumio[882]: Plugin install end detected on script May 26 15:48:42 volumio volumio[882]: info: Install script completed May 26 15:48:42 volumio volumio[882]: info: Adding reference to registry May 26 15:48:42 volumio volumio[882]: info: Done installing plugin. May 26 15:48:42 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 15:48:42 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 26 15:48:42 volumio volumio[882]: info: Folder /tmp/plugins removed May 26 15:48:42 volumio volumio[882]: info: Folder /tmp/downloaded_plugin.zip removed May 26 15:48:42 volumio volumio[882]: info: Folder /data/temp removed May 26 15:48:44 volumio volumio[882]: info: Enabling plugin soundcloud May 26 15:48:44 volumio volumio[882]: info: Loading plugin "soundcloud"... May 26 15:48:44 volumio volumio[882]: info: PLUGIN START: soundcloud May 26 15:48:44 volumio volumio[882]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 15:48:44 volumio volumio[882]: info: [1748267324587] CoreMusicLibrary::Adding element SoundCloud May 26 15:48:44 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 15:48:44 volumio volumio[882]: Cannot find translation for source YouTube Music May 26 15:48:44 volumio volumio[882]: Cannot find translation for source SoundCloud May 26 15:48:44 volumio volumio[882]: info: Done. May 26 15:48:57 volumio volumio[882]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/youtube2/volumio/buster/armhf May 26 15:48:59 volumio volumio[882]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/youtube2/volumio/buster/armhf May 26 15:48:59 volumio volumio[882]: info: Folder /tmp/plugins removed May 26 15:49:00 volumio volumio[882]: info: Check plugin dependencies May 26 15:49:00 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 15:49:00 volumio volumio[882]: info: Checking if plugin already exists May 26 15:49:00 volumio volumio[882]: info: Rename folder May 26 15:49:00 volumio volumio[882]: info: Folder /tmp/downloaded_plugin.zip removed May 26 15:49:00 volumio volumio[882]: info: Move to category May 26 15:49:15 volumio volumio-remote-updater[585]: [2025-05-26 15:49:15] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] May 26 15:49:20 volumio volumio-remote-updater[585]: [2025-05-26 15:49:20] [connect] Successful connection May 26 15:49:20 volumio volumio[882]: info: Checking if install.sh is present May 26 15:49:20 volumio volumio[882]: info: Executing install.sh May 26 15:49:20 volumio volumio[882]: Upnp client error: Error: This socket has been ended by the other party May 26 15:49:20 volumio volumio[882]: verbose: New Socket.io Connection to 172.26.2.232 from 172.26.2.180 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 26 15:49:20 volumio volumio[882]: verbose: New Socket.io Connection to 172.26.2.232 from 172.26.2.180 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 26 15:49:20 volumio volumio[882]: verbose: New Socket.io Connection to 172.26.2.232 from 172.26.2.180 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 26 15:49:20 volumio volumio-remote-updater[585]: [2025-05-26 15:49:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748267360 101 May 26 15:49:20 volumio volumio[882]: 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: 5 May 26 15:49:20 volumio volumio[882]: Upnp client error: Error: This socket has been ended by the other party May 26 15:49:20 volumio volumio[882]: Upnp client error: Error: This socket has been ended by the other party May 26 15:49:20 volumio volumio[882]: Upnp client error: Error: This socket has been ended by the other party May 26 15:49:20 volumio volumio[882]: Upnp client error: Error: This socket has been ended by the other party May 26 15:49:20 volumio volumio[882]: Upnp client error: Error: This socket has been ended by the other party May 26 15:49:20 volumio volumio[882]: Upnp client error: Error: This socket has been ended by the other party May 26 15:49:20 volumio sudo[3482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/youtube2/install.sh May 26 15:49:20 volumio sudo[3482]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:49:20 volumio sudo[3482]: pam_unix(sudo:session): session closed for user root May 26 15:49:20 volumio volumio[882]: info: YouTube2 plugin installed May 26 15:49:20 volumio volumio[882]: Plugin install end detected on script May 26 15:49:20 volumio volumio[882]: info: Install script completed May 26 15:49:20 volumio volumio[882]: info: Adding reference to registry May 26 15:49:20 volumio volumio[882]: info: Done installing plugin. May 26 15:49:20 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 15:49:20 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 26 15:49:20 volumio volumio[882]: info: Folder /tmp/plugins removed May 26 15:49:20 volumio volumio[882]: info: Folder /tmp/downloaded_plugin.zip removed May 26 15:49:20 volumio volumio[882]: info: Folder /data/temp removed May 26 15:49:21 volumio volumio[882]: verbose: New Socket.io Connection to 172.26.2.232 from 172.26.2.180 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 26 15:49:21 volumio volumio[882]: info: Received Get System Info May 26 15:49:21 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:49:21 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:49:21 volumio volumio[882]: info: Discovery: Getting this device information May 26 15:49:21 volumio volumio[882]: info: CoreCommandRouter::volumioGetState May 26 15:49:21 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:49:21 volumio volumio[882]: info: CoreCommandRouter::volumioGetState May 26 15:49:21 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 26 15:49:21 volumio volumio[882]: info: CoreCommandRouter::volumioGetVisibleSources May 26 15:49:21 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 15:49:21 volumio volumio[882]: info: CoreCommandRouter::volumioGetState May 26 15:49:21 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 26 15:49:21 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 26 15:49:21 volumio volumio[882]: info: Received Get System Info May 26 15:49:21 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:49:21 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:49:21 volumio volumio[882]: info: Discovery: Getting this device information May 26 15:49:21 volumio volumio[882]: info: CoreCommandRouter::volumioGetState May 26 15:49:21 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:49:21 volumio volumio[882]: info: CoreCommandRouter::volumioGetState May 26 15:49:21 volumio volumio[882]: info: Listing playlists May 26 15:49:21 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 26 15:49:21 volumio volumio[882]: 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 26 15:49:21 volumio volumio[882]: info: CoreCommandRouter::volumioGetState May 26 15:49:21 volumio volumio[882]: 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 26 15:49:28 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 26 15:49:29 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 26 15:49:29 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 26 15:49:30 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 26 15:49:30 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 26 15:49:31 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 26 15:49:32 volumio volumio[882]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/youtube2/volumio/buster/armhf May 26 15:49:34 volumio volumio[882]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/youtube2/volumio/buster/armhf May 26 15:49:34 volumio volumio[882]: info: Folder /tmp/plugins removed May 26 15:49:35 volumio volumio[882]: info: Check plugin dependencies May 26 15:49:35 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 15:49:35 volumio volumio[882]: info: Checking if plugin already exists May 26 15:49:35 volumio volumio[882]: info: An error occurred installing the plugin. Rolling back config May 26 15:49:35 volumio volumio[882]: info: Plugin folders cleanup May 26 15:49:35 volumio volumio[882]: info: Scanning into folder /volumio/app/plugins/ May 26 15:49:35 volumio volumio[882]: info: Scanning category audio_interface May 26 15:49:35 volumio volumio[882]: info: Scanning category miscellanea May 26 15:49:35 volumio volumio[882]: info: Scanning category music_service May 26 15:49:35 volumio volumio[882]: info: Scanning category plugins.json May 26 15:49:35 volumio volumio[882]: info: Scanning category system_controller May 26 15:49:35 volumio volumio[882]: info: Scanning category user_interface May 26 15:49:35 volumio volumio[882]: info: Scanning into folder /data/plugins/ May 26 15:49:35 volumio volumio[882]: info: Scanning category music_service May 26 15:49:35 volumio volumio[882]: info: Plugin folders cleanup completed May 26 15:49:35 volumio volumio[882]: info: Error: Error May 26 15:49:35 volumio volumio[882]: info: Folder /tmp/plugins removed May 26 15:49:35 volumio volumio[882]: info: Folder /tmp/downloaded_plugin.zip removed May 26 15:49:35 volumio volumio[882]: info: Folder /tmp/downloaded_plugin.zip removed May 26 15:49:38 volumio volumio[882]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf May 26 15:49:40 volumio volumio[882]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf May 26 15:49:41 volumio volumio[882]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf May 26 15:49:42 volumio volumio[882]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf May 26 15:49:44 volumio volumio[882]: info: CoreCommandRouter::volumioGetState May 26 15:49:47 volumio volumio[882]: info: Folder /tmp/plugins removed May 26 15:49:47 volumio volumio[882]: info: Folder /tmp/plugins removed May 26 15:49:53 volumio volumio[882]: info: CoreCommandRouter::volumioGetQueue May 26 15:49:53 volumio volumio[882]: info: CoreStateMachine::getQueue May 26 15:49:53 volumio volumio[882]: info: CorePlayQueue::getQueue May 26 15:49:54 volumio volumio[882]: info: Folder /data/temp removed May 26 15:49:54 volumio volumio[882]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 15:49:54 volumio volumio[882]: Error: Unable to resolve or reject the same promise twice May 26 15:49:54 volumio volumio[882]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 26 15:49:54 volumio volumio[882]: at /volumio/app/pluginmanager.js:1087:11 May 26 15:49:54 volumio volumio[882]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) May 26 15:49:54 volumio volumio[882]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 May 26 15:49:54 volumio volumio[882]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) May 26 15:49:54 volumio volumio[882]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 May 26 15:49:54 volumio volumio[882]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) May 26 15:49:54 volumio volumio[882]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 May 26 15:49:54 volumio volumio[882]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) May 26 15:49:54 volumio volumio[882]: at FSReqCallback.oncomplete (fs.js:171:23) May 26 15:49:54 volumio volumio[882]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 15:49:54 volumio sudo[3642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-26 15:48 May 26 15:49:54 volumio sudo[3642]: 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"