Feb 11 02:21:05 volumio volumio[1218]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 11 02:21:05 volumio volumio[1218]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 11 02:21:05 volumio volumio[1218]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 11 02:21:05 volumio volumio[1218]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 11 02:21:05 volumio volumio[1218]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Feb 11 02:21:10 volumio volumio[1218]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Feb 11 02:21:10 volumio volumio[1218]: info: Received Get System Info Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 02:21:10 volumio volumio[1218]: info: Discovery: Getting this device information Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:21:10 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:21:10 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:21:10 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 11 02:21:10 volumio volumio[1218]: info: Received Get System Info Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 02:21:10 volumio volumio[1218]: info: Discovery: Getting this device information Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:21:10 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:21:10 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:21:10 volumio volumio[1218]: info: Listing playlists Feb 11 02:21:10 volumio volumio[1218]: info: Received Get System Info Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 02:21:10 volumio volumio[1218]: info: Discovery: Getting this device information Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:21:10 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:21:10 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:21:10 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 11 02:21:10 volumio volumio[1218]: info: Received Get System Info Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 02:21:10 volumio volumio[1218]: info: Discovery: Getting this device information Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:21:10 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:21:10 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:21:10 volumio volumio[1218]: info: Listing playlists Feb 11 02:21:10 volumio volumio[1218]: info: Received Get System Info Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 02:21:10 volumio volumio[1218]: info: Discovery: Getting this device information Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:21:10 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:21:10 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:21:10 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 11 02:21:10 volumio volumio[1218]: info: Received Get System Info Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 02:21:10 volumio volumio[1218]: info: Discovery: Getting this device information Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:21:10 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:21:10 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:21:10 volumio volumio[1218]: info: Listing playlists Feb 11 02:21:10 volumio volumio[1218]: info: Received Get System Info Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 02:21:10 volumio volumio[1218]: info: Discovery: Getting this device information Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:21:10 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:21:10 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 11 02:21:10 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 11 02:21:35 volumio volumio[1218]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Feb 11 02:21:35 volumio volumio5-onboarding[1484]: time=2026-02-11T02:21:35.603-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:21:38 volumio volumio[1218]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Feb 11 02:21:38 volumio volumio[1218]: info: Folder /tmp/plugins removed Feb 11 02:21:38 volumio volumio5-onboarding[1484]: time=2026-02-11T02:21:38.466-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:21:40 volumio volumio5-onboarding[1484]: time=2026-02-11T02:21:40.301-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:21:40 volumio volumio[1218]: info: Check plugin dependencies Feb 11 02:21:40 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 02:21:40 volumio volumio5-onboarding[1484]: time=2026-02-11T02:21:40.467-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:21:40 volumio volumio[1218]: info: Checking if plugin already exists Feb 11 02:21:40 volumio volumio5-onboarding[1484]: time=2026-02-11T02:21:40.482-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:21:40 volumio volumio[1218]: info: Rename folder Feb 11 02:21:40 volumio volumio[1218]: info: Folder /tmp/downloaded_plugin.zip removed Feb 11 02:21:40 volumio volumio5-onboarding[1484]: time=2026-02-11T02:21:40.544-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:21:40 volumio volumio[1218]: info: Move to category Feb 11 02:22:30 volumio volumio5-onboarding[1484]: time=2026-02-11T02:22:30.763-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:22:30 volumio volumio[1218]: info: Checking if install.sh is present Feb 11 02:22:30 volumio volumio[1218]: info: Executing install.sh Feb 11 02:22:30 volumio volumio[1218]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 11 02:22:30 volumio volumio[1218]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 11 02:22:30 volumio volumio[1218]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 11 02:22:30 volumio volumio[1218]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 11 02:22:30 volumio volumio[1218]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Feb 11 02:22:30 volumio volumio[1218]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Feb 11 02:22:30 volumio volumio[1218]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Feb 11 02:22:31 volumio sudo[24166]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/spop/install.sh Feb 11 02:22:31 volumio sudo[24166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 02:22:31 volumio volumio[1218]: info: Installing Go-librespot Feb 11 02:22:31 volumio volumio5-onboarding[1484]: time=2026-02-11T02:22:31.059-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:22:31 volumio volumio[1218]: info: Checking old vollibrespot installs Feb 11 02:22:31 volumio volumio5-onboarding[1484]: time=2026-02-11T02:22:31.090-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:22:31 volumio systemd[1]: Reloading. Feb 11 02:22:33 volumio volumio[1218]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Feb 11 02:22:33 volumio volumio[1218]: info: Received Get System Info Feb 11 02:22:33 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 02:22:33 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 02:22:33 volumio volumio[1218]: info: Discovery: Getting this device information Feb 11 02:22:33 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:22:33 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:22:33 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 02:22:33 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:22:33 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:22:33 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 02:22:33 volumio volumio[1218]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 02:22:33 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 02:22:33 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:22:33 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:22:33 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 11 02:22:33 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 11 02:22:33 volumio volumio[1218]: info: Received Get System Info Feb 11 02:22:33 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 02:22:33 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 02:22:33 volumio volumio[1218]: info: Discovery: Getting this device information Feb 11 02:22:33 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:22:33 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:22:33 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 02:22:33 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:22:33 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:22:33 volumio volumio[1218]: info: Listing playlists Feb 11 02:22:34 volumio volumio[1218]: info: Received Get System Info Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 02:22:34 volumio volumio[1218]: info: Discovery: Getting this device information Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:22:34 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:22:34 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:22:34 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 11 02:22:34 volumio volumio[1218]: info: Received Get System Info Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 02:22:34 volumio volumio[1218]: info: Discovery: Getting this device information Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:22:34 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:22:34 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:22:34 volumio volumio[1218]: info: Listing playlists Feb 11 02:22:34 volumio volumio[1218]: info: Received Get System Info Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 02:22:34 volumio volumio[1218]: info: Discovery: Getting this device information Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:22:34 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:22:34 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:22:34 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 11 02:22:34 volumio volumio[1218]: info: Received Get System Info Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 02:22:34 volumio volumio[1218]: info: Discovery: Getting this device information Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:22:34 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:22:34 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:22:34 volumio volumio[1218]: info: Listing playlists Feb 11 02:22:34 volumio volumio[1218]: info: Received Get System Info Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 02:22:34 volumio volumio[1218]: info: Discovery: Getting this device information Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:22:34 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 02:22:34 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 11 02:22:34 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 11 02:22:34 volumio volumio[1218]: info: Downloading daemon Feb 11 02:22:34 volumio volumio5-onboarding[1484]: time=2026-02-11T02:22:34.585-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:22:37 volumio volumio[1218]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Feb 11 02:22:37 volumio volumio5-onboarding[1484]: time=2026-02-11T02:22:37.052-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:22:37 volumio volumio[1218]: info: Creating directories Feb 11 02:22:37 volumio volumio5-onboarding[1484]: time=2026-02-11T02:22:37.592-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:22:37 volumio volumio[1218]: info: Creating data directory Feb 11 02:22:37 volumio volumio5-onboarding[1484]: time=2026-02-11T02:22:37.619-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:22:37 volumio volumio[1218]: info: Creating Start Script Feb 11 02:22:37 volumio volumio5-onboarding[1484]: time=2026-02-11T02:22:37.644-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:22:37 volumio systemd[1]: Reloading. Feb 11 02:22:39 volumio volumio[1218]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Feb 11 02:22:39 volumio volumio[1218]: info: Folder /tmp/plugins removed Feb 11 02:22:39 volumio volumio5-onboarding[1484]: time=2026-02-11T02:22:39.852-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:22:40 volumio sudo[24166]: pam_unix(sudo:session): session closed for user root Feb 11 02:22:40 volumio volumio[1218]: Plugin install end detected on script Feb 11 02:22:40 volumio volumio[1218]: info: Install script completed Feb 11 02:22:40 volumio volumio5-onboarding[1484]: time=2026-02-11T02:22:40.791-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:22:40 volumio volumio[1218]: info: Adding reference to registry Feb 11 02:22:40 volumio volumio5-onboarding[1484]: time=2026-02-11T02:22:40.825-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:22:40 volumio volumio[1218]: info: Done installing plugin. Feb 11 02:22:40 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 02:22:40 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 11 02:22:40 volumio volumio[1218]: info: Folder /tmp/plugins removed Feb 11 02:22:40 volumio volumio[1218]: info: Folder /tmp/downloaded_plugin.zip removed Feb 11 02:22:45 volumio volumio[1218]: info: Enabling plugin spop Feb 11 02:22:45 volumio volumio[1218]: info: Loading plugin "spop"... Feb 11 02:22:47 volumio volumio[1218]: info: PLUGIN START: spop Feb 11 02:22:47 volumio volumio[1218]: info: Creating Spotify config file Feb 11 02:22:47 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 02:22:47 volumio volumio[1218]: info: Done. Feb 11 02:22:47 volumio volumio5-onboarding[1484]: time=2026-02-11T02:22:47.518-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:22:47 volumio volumio[1218]: info: Check plugin dependencies Feb 11 02:22:47 volumio volumio5-onboarding[1484]: time=2026-02-11T02:22:47.636-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:22:47 volumio volumio[1218]: info: An error occurred installing the plugin. Rolling back config Feb 11 02:22:47 volumio volumio[1218]: info: Plugin folders cleanup Feb 11 02:22:47 volumio volumio[1218]: info: Scanning into folder /volumio/app/plugins/ Feb 11 02:22:47 volumio volumio[1218]: info: Scanning category audio_interface Feb 11 02:22:47 volumio volumio[1218]: info: Scanning category miscellanea Feb 11 02:22:47 volumio volumio[1218]: info: Scanning category music_service Feb 11 02:22:47 volumio volumio[1218]: info: Scanning category plugins.json Feb 11 02:22:47 volumio volumio[1218]: info: Scanning category system_controller Feb 11 02:22:47 volumio volumio[1218]: info: Scanning category user_interface Feb 11 02:22:47 volumio volumio[1218]: info: Scanning into folder /data/plugins/ Feb 11 02:22:47 volumio volumio[1218]: info: Scanning category music_service Feb 11 02:22:47 volumio volumio[1218]: info: Plugin folders cleanup completed Feb 11 02:22:47 volumio volumio[1218]: info: Error: Error Feb 11 02:22:47 volumio volumio[1218]: info: Spotify config file written Feb 11 02:22:47 volumio volumio[1218]: info: Folder /tmp/downloaded_plugin.zip removed Feb 11 02:22:47 volumio volumio[1218]: info: Folder /tmp/plugins removed Feb 11 02:22:47 volumio volumio[1218]: info: Folder /tmp/downloaded_plugin.zip removed Feb 11 02:22:47 volumio volumio[1218]: info: No need to fix Spotify hosts Feb 11 02:22:47 volumio sudo[24284]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 11 02:22:47 volumio sudo[24284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 02:22:47 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 02:22:47 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 02:22:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 02:22:48 volumio go-librespot[24286]: go-librespot daemon starting... Feb 11 02:22:48 volumio sudo[24284]: pam_unix(sudo:session): session closed for user root Feb 11 02:22:48 volumio go-librespot[24287]: time="2026-02-11T02:22:48-03:00" level=info msg="running go-librespot 0.6.2" Feb 11 02:22:48 volumio go-librespot[24287]: time="2026-02-11T02:22:48-03:00" level=debug msg="no app state found" Feb 11 02:22:48 volumio go-librespot[24287]: time="2026-02-11T02:22:48-03:00" level=debug msg="stored credentials not found" Feb 11 02:22:48 volumio go-librespot[24287]: time="2026-02-11T02:22:48-03:00" level=info msg="generated new device id: 8413070323fb317c2c98443756f42d863d2f7e5b" Feb 11 02:22:48 volumio volumio[1218]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Feb 11 02:22:48 volumio volumio5-onboarding[1484]: time=2026-02-11T02:22:48.232-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 02:22:48 volumio go-librespot[24287]: time="2026-02-11T02:22:48-03:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 02:22:48 volumio ntpd[828]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 11 02:22:48 volumio ntpd[828]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Feb 11 02:22:48 volumio ntpd[828]: DNS: Pool skipping: 200.40.115.74 Feb 11 02:22:48 volumio ntpd[828]: DNS: Pool skipping: 164.73.225.4 Feb 11 02:22:48 volumio ntpd[828]: DNS: Pool skipping: 190.64.134.53 Feb 11 02:22:48 volumio ntpd[828]: DNS: Pool skipping: 164.73.232.34 Feb 11 02:22:48 volumio ntpd[828]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Feb 11 02:22:48 volumio volumio[1218]: info: Folder /data/temp removed Feb 11 02:22:48 volumio volumio[1218]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 02:22:48 volumio volumio[1218]: Error: Unable to resolve or reject the same promise twice Feb 11 02:22:48 volumio volumio[1218]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Feb 11 02:22:48 volumio volumio[1218]: at /volumio/app/pluginmanager.js:1087:11 Feb 11 02:22:48 volumio volumio[1218]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Feb 11 02:22:48 volumio volumio[1218]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Feb 11 02:22:48 volumio volumio[1218]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Feb 11 02:22:48 volumio volumio[1218]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Feb 11 02:22:48 volumio volumio[1218]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Feb 11 02:22:48 volumio volumio[1218]: at FSReqCallback.oncomplete (node:fs:190:23) Feb 11 02:22:48 volumio volumio[1218]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 02:22:51 volumio ntpd[828]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 11 02:22:51 volumio ntpd[828]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Feb 11 02:22:51 volumio ntpd[828]: DNS: Pool skipping: 200.40.115.74 Feb 11 02:22:51 volumio ntpd[828]: DNS: Pool skipping: 190.64.134.53 Feb 11 02:22:51 volumio ntpd[828]: DNS: Pool skipping: 164.73.225.4 Feb 11 02:22:51 volumio ntpd[828]: DNS: Pool skipping: 164.73.232.34 Feb 11 02:22:51 volumio ntpd[828]: DNS: Pool skipping: 2001:1328:56:fff::4 Feb 11 02:22:51 volumio ntpd[828]: DNS: Pool skipping: 2001:1328:54::34 Feb 11 02:22:51 volumio ntpd[828]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Feb 11 02:22:52 volumio go-librespot[24287]: time="2026-02-11T02:22:52-03:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 11 02:22:52 volumio go-librespot[24287]: time="2026-02-11T02:22:52-03:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 11 02:22:52 volumio go-librespot[24287]: time="2026-02-11T02:22:52-03:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 11 02:22:52 volumio go-librespot[24287]: time="2026-02-11T02:22:52-03:00" level=info msg="zeroconf server listening on port 34353" Feb 11 02:22:53 volumio ntpd[828]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 11 02:22:53 volumio ntpd[828]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Feb 11 02:22:53 volumio ntpd[828]: DNS: Pool skipping: 200.40.115.74 Feb 11 02:22:53 volumio ntpd[828]: DNS: Pool skipping: 164.73.232.34 Feb 11 02:22:53 volumio ntpd[828]: DNS: Pool skipping: 164.73.225.4 Feb 11 02:22:53 volumio ntpd[828]: DNS: Pool skipping: 190.64.134.53 Feb 11 02:22:53 volumio ntpd[828]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Feb 11 02:22:54 volumio ntpd[828]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 11 02:22:54 volumio ntpd[828]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Feb 11 02:22:54 volumio ntpd[828]: DNS: Pool skipping: 200.40.115.74 Feb 11 02:22:54 volumio ntpd[828]: DNS: Pool skipping: 164.73.232.34 Feb 11 02:22:54 volumio ntpd[828]: DNS: Pool skipping: 164.73.225.4 Feb 11 02:22:54 volumio ntpd[828]: DNS: Pool skipping: 190.64.134.53 Feb 11 02:22:54 volumio ntpd[828]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Feb 11 02:22:59 volumio sudo[24336]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-11 02:21' Feb 11 02:22:59 volumio sudo[24336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"