-- 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"