Oct 15 22:33:02 volumio3kit upmpdcli[1894]: writing RSA key Oct 15 22:33:20 volumio3kit volumio[1158]: info: Plugin folders cleanup completed Oct 15 22:33:20 volumio3kit volumio5-onboarding[1704]: time=2026-10-15T22:33:20.173+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 22:33:20 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 22:33:20 volumio3kit volumio[1158]: info: [] Oct 15 22:33:20 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 15 22:33:20 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 22:33:20 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 22:33:20 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 22:33:20 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 22:33:20 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 22:33:20 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 22:33:20 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 22:33:20 volumio3kit volumio[1158]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 15 22:33:21 volumio3kit volumio[1158]: info: Discovery: Disconnected from remote: 10.255.1.43 Oct 15 22:33:23 volumio3kit volumio[1158]: info: Discovery: Connected to remote: 10.255.1.43 Oct 15 22:33:40 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 22:33:49 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 15 22:33:57 volumio3kit volumio[1158]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/bandcamp/2.1.0/volumio/bookworm/armhf Oct 15 22:33:57 volumio3kit volumio5-onboarding[1704]: time=2026-10-15T22:33:57.891+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 22:34:05 volumio3kit volumio[1158]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/bandcamp/2.1.0/volumio/bookworm/armhf Oct 15 22:34:05 volumio3kit volumio[1158]: info: Folder /tmp/plugins removed Oct 15 22:34:05 volumio3kit volumio5-onboarding[1704]: time=2026-10-15T22:34:05.244+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 22:34:13 volumio3kit volumio5-onboarding[1704]: time=2026-10-15T22:34:13.183+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 22:34:13 volumio3kit volumio[1158]: info: Check plugin dependencies Oct 15 22:34:13 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 22:34:14 volumio3kit volumio5-onboarding[1704]: time=2026-10-15T22:34:14.015+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 22:34:14 volumio3kit volumio[1158]: info: Checking if plugin already exists Oct 15 22:34:14 volumio3kit volumio5-onboarding[1704]: time=2026-10-15T22:34:14.119+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 22:34:14 volumio3kit volumio[1158]: info: Rename folder Oct 15 22:34:14 volumio3kit volumio[1158]: info: Folder /tmp/downloaded_plugin.zip removed Oct 15 22:34:14 volumio3kit volumio5-onboarding[1704]: time=2026-10-15T22:34:14.389+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 22:34:14 volumio3kit volumio[1158]: info: Move to category Oct 15 22:34:19 volumio3kit volumio5-onboarding[1704]: time=2026-10-15T22:34:19.867+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 22:34:19 volumio3kit volumio[1158]: info: Checking if install.sh is present Oct 15 22:34:19 volumio3kit volumio[1158]: info: Executing install.sh Oct 15 22:34:20 volumio3kit sudo[2035]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/bandcamp/install.sh Oct 15 22:34:20 volumio3kit sudo[2035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 22:34:20 volumio3kit sudo[2035]: pam_unix(sudo:session): session closed for user root Oct 15 22:34:20 volumio3kit volumio[1158]: info: Install script completed Oct 15 22:34:20 volumio3kit volumio5-onboarding[1704]: time=2026-10-15T22:34:20.431+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 22:34:20 volumio3kit volumio[1158]: info: Adding reference to registry Oct 15 22:34:20 volumio3kit volumio5-onboarding[1704]: time=2026-10-15T22:34:20.506+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 22:34:20 volumio3kit volumio[1158]: info: Done installing plugin. Oct 15 22:34:20 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 22:34:20 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 15 22:34:20 volumio3kit volumio[1158]: info: Bandcamp Discover plugin installed Oct 15 22:34:20 volumio3kit volumio5-onboarding[1704]: time=2026-10-15T22:34:20.902+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 22:34:20 volumio3kit volumio[1158]: Plugin install end detected on script Oct 15 22:34:20 volumio3kit volumio[1158]: info: Folder /tmp/plugins removed Oct 15 22:34:20 volumio3kit volumio[1158]: info: Folder /tmp/downloaded_plugin.zip removed Oct 15 22:34:21 volumio3kit volumio[1158]: info: Folder /data/temp removed Oct 15 22:34:24 volumio3kit volumio[1158]: info: Enabling plugin bandcamp Oct 15 22:34:24 volumio3kit volumio[1158]: info: Loading plugin "bandcamp"... Oct 15 22:34:24 volumio3kit volumio[1158]: info: PLUGIN START: bandcamp Oct 15 22:34:24 volumio3kit volumio[1158]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 22:34:24 volumio3kit volumio[1158]: info: [1792071264226] CoreMusicLibrary::Adding element Bandcamp Discover Oct 15 22:34:24 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 22:34:24 volumio3kit volumio[1158]: Cannot find translation for source Bandcamp Discover Oct 15 22:34:24 volumio3kit volumio[1158]: info: Done. Oct 15 22:34:29 volumio3kit volumio[1158]: info: CALLMETHOD: miscellanea novaui getNovaStatus undefined Oct 15 22:34:29 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: novaui , getNovaStatus Oct 15 22:34:29 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 22:34:30 volumio3kit volumio[1158]: info: CoreCommandRouter::volumioGetState Oct 15 22:34:30 volumio3kit volumio[1158]: info: CorePlayQueue::getTrack 0 Oct 15 22:34:31 volumio3kit volumio[1158]: info: Nova UI: novaEnabled for volumio: true Oct 15 22:34:31 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: appearance , registerThirdPartyUI Oct 15 22:34:31 volumio3kit volumio[1158]: info: Registering Third Party UI: {"uiName":"nova","uiPath":"/data/nova/current","uiPrettyName":"Nova"} Oct 15 22:34:31 volumio3kit volumio[1158]: info: UI already registered: {"uiName":"nova","uiPath":"/data/nova/current","uiPrettyName":"Nova"} Oct 15 22:34:34 volumio3kit volumio[1158]: info: CoreCommandRouter::volumioGetQueue Oct 15 22:34:34 volumio3kit volumio[1158]: info: CoreStateMachine::getQueue Oct 15 22:34:34 volumio3kit volumio[1158]: info: CorePlayQueue::getQueue Oct 15 22:34:35 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: bandcamp , handleBrowseUri Oct 15 22:34:35 volumio3kit volumio[1158]: info: [bandcamp-browse] browseUri: bandcamp Oct 15 22:34:41 volumio3kit volumio[1158]: info: Preload queue cleared Oct 15 22:34:41 volumio3kit volumio[1158]: info: Preload queue cleared Oct 15 22:34:41 volumio3kit volumio[1158]: info: Preload queue cleared Oct 15 22:34:41 volumio3kit volumio[1158]: info: Preload queue cleared Oct 15 22:34:41 volumio3kit volumio[1158]: info: Preload queue cleared Oct 15 22:34:53 volumio3kit volumio[1158]: info: CoreCommandRouter::executeOnPlugin: bandcamp , handleBrowseUri Oct 15 22:34:53 volumio3kit volumio[1158]: info: [bandcamp-browse] browseUri: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D1009@explode:o=%7B%22title%22%3A%22Bandcamp%20Weekly%22%2C%22artist%22%3A%2210%2F12%2F2026%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2F47838244_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Fshow%40showUrl%3Dhttps%253A%252F%252Fbandcamp.com%252F%253Fshow%253D1009%22%7D Oct 15 22:34:55 volumio3kit volumio[1158]: error: Failed to execute browseSource: Error: Failed to parse show: JSON error in data-blob. Oct 15 22:34:55 volumio3kit volumio[1158]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 22:34:55 volumio3kit volumio[1158]: [UnhandledPromiseRejection: This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). The promise rejected with the reason "Error: Failed to parse show: JSON error in data-blob.".] { Oct 15 22:34:55 volumio3kit volumio[1158]: code: 'ERR_UNHANDLED_REJECTION' Oct 15 22:34:55 volumio3kit volumio[1158]: } Oct 15 22:34:55 volumio3kit volumio[1158]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 22:34:57 volumio3kit sudo[2120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-15 22:33' Oct 15 22:34:57 volumio3kit sudo[2120]: 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="49c3b8c3d90722cb303dcca504ebce2ff3c7ebdf" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4bdfbfc68c4d22128e9bac172462cbd14ece0c88" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Oct 12 13:56:12 UTC 2026" VOLUMIO_VERSION="4.231" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="43f53a1f9ad8f3b898137f70f5cd23ce"