May 26 23:24:00 volumio volumio[6881]: info: Setting Geolocation for MyVolumio to as1 May 26 23:24:00 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:24:00 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:24:00 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:24:00 volumio volumio[6881]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 26 23:24:01 volumio volumio[6881]: info: Updating MyVolumio device info May 26 23:24:01 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:24:01 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:24:01 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:24:01 volumio volumio[6881]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 26 23:24:02 volumio volumio[6881]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 26 23:24:02 volumio volumio[6881]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 26 23:24:03 volumio volumio[6881]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytmusic/volumio/bookworm/amd64 May 26 23:24:03 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:03.665-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:06 volumio volumio[6881]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytmusic/volumio/bookworm/amd64 May 26 23:24:06 volumio volumio[6881]: info: Folder /tmp/plugins removed May 26 23:24:06 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:06.483-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:06 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:06.964-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:06 volumio volumio[6881]: info: Check plugin dependencies May 26 23:24:06 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 23:24:06 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:06.990-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:06 volumio volumio[6881]: info: Checking if plugin already exists May 26 23:24:06 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:06.991-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:06 volumio volumio[6881]: info: Rename folder May 26 23:24:06 volumio volumio[6881]: info: Folder /tmp/downloaded_plugin.zip removed May 26 23:24:06 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:06.999-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:06 volumio volumio[6881]: info: Move to category May 26 23:24:07 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:07.651-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:07 volumio volumio[6881]: info: Checking if install.sh is present May 26 23:24:07 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 26 23:24:07 volumio volumio[6881]: info: Executing install.sh May 26 23:24:07 volumio volumio[6881]: 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 23:24:07 volumio sudo[7437]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/ytmusic/install.sh May 26 23:24:07 volumio sudo[7437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:24:07 volumio volumio[6881]: info: CoreCommandRouter::volumioGetState May 26 23:24:07 volumio volumio[6881]: info: CorePlayQueue::getTrack 0 May 26 23:24:08 volumio volumio[6881]: info: Installing Python... May 26 23:24:08 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:08.241-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:08 volumio sudo[7463]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt update May 26 23:24:08 volumio sudo[7463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 23:24:08 volumio volumio[6881]: info: Hit:1 http://deb.debian.org/debian bookworm InRelease May 26 23:24:08 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:08.550-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:09 volumio volumio[6881]: info: Reading package lists... May 26 23:24:09 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:09.527-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:09 volumio volumio[6881]: info: Building dependency tree... May 26 23:24:09 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:09.770-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:09 volumio volumio[6881]: info: Reading state information... May 26 23:24:09 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:09.772-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:09 volumio sudo[7463]: pam_unix(sudo:session): session closed for user root May 26 23:24:09 volumio volumio[6881]: info: 107 packages can be upgraded. Run 'apt list --upgradable' to see them. May 26 23:24:09 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:09.805-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:09 volumio sudo[7575]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get install -y python3 May 26 23:24:09 volumio sudo[7575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 23:24:09 volumio volumio[6881]: info: Reading package lists... May 26 23:24:09 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:09.834-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:10 volumio volumio[6881]: info: Building dependency tree... May 26 23:24:10 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:10.068-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:10 volumio volumio[6881]: info: Reading state information... May 26 23:24:10 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:10.071-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:10 volumio volumio[6881]: info: python3 is already the newest version (3.11.2-1+b1). May 26 23:24:10 volumio sudo[7575]: pam_unix(sudo:session): session closed for user root May 26 23:24:10 volumio volumio[6881]: info: 0 upgraded, 0 newly installed, 0 to remove and 107 not upgraded. May 26 23:24:10 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:10.350-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:10 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:10.350-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:10 volumio volumio[6881]: info: Installing VLC media player... May 26 23:24:10 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:10.353-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:10 volumio sudo[7585]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get install -y vlc-bin vlc-plugin-base May 26 23:24:10 volumio sudo[7585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 23:24:10 volumio volumio[6881]: info: Reading package lists... May 26 23:24:10 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:10.376-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:10 volumio volumio[6881]: info: Building dependency tree... May 26 23:24:10 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:10.610-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:10 volumio volumio[6881]: info: Reading state information... May 26 23:24:10 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:10.612-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:10 volumio volumio[6881]: info: vlc-bin is already the newest version (3.0.23-0+deb12u1). May 26 23:24:10 volumio sudo[7585]: pam_unix(sudo:session): session closed for user root May 26 23:24:10 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:10.893-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:10 volumio volumio[6881]: info: vlc-plugin-base is already the newest version (3.0.23-0+deb12u1). May 26 23:24:10 volumio volumio[6881]: info: 0 upgraded, 0 newly installed, 0 to remove and 107 not upgraded. May 26 23:24:10 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:10.895-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:10 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:10.895-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:10 volumio sudo[7596]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get install -y mpv May 26 23:24:10 volumio sudo[7596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 23:24:10 volumio volumio[6881]: info: Installing mpv media player... May 26 23:24:10 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:10.899-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:10 volumio volumio[6881]: info: Reading package lists... May 26 23:24:10 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:10.921-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:11 volumio volumio[6881]: info: Building dependency tree... May 26 23:24:11 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:11.155-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:11 volumio volumio[6881]: info: Reading state information... May 26 23:24:11 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:11.157-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:11 volumio volumio[6881]: info: mpv is already the newest version (0.35.1-4). May 26 23:24:11 volumio sudo[7596]: pam_unix(sudo:session): session closed for user root May 26 23:24:11 volumio sudo[7437]: pam_unix(sudo:session): session closed for user root May 26 23:24:11 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:11.435-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:11 volumio volumio[6881]: info: 0 upgraded, 0 newly installed, 0 to remove and 107 not upgraded. May 26 23:24:11 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:11.436-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:11 volumio volumio[6881]: info: Install script completed May 26 23:24:11 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:11.438-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:11 volumio volumio[6881]: info: Adding reference to registry May 26 23:24:11 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:11.439-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:11 volumio volumio[6881]: info: Done installing plugin. May 26 23:24:11 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 23:24:11 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 26 23:24:11 volumio volumio[6881]: info: Folder /tmp/plugins removed May 26 23:24:11 volumio volumio[6881]: info: Folder /tmp/downloaded_plugin.zip removed May 26 23:24:11 volumio volumio[6881]: info: YouTube Music plugin installed May 26 23:24:11 volumio volumio5-onboarding[1616]: time=2026-05-26T07:24:11.446-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 26 23:24:11 volumio volumio[6881]: Plugin install end detected on script May 26 23:24:11 volumio volumio[6881]: info: Folder /data/temp removed May 26 23:24:12 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:24:12 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:24:12 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 26 23:24:12 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 26 23:24:12 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 26 23:24:12 volumio volumio[6881]: info: CoreCommandRouter::volumioGetBrowseSources May 26 23:24:12 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 23:24:12 volumio volumio[6881]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 26 23:24:12 volumio volumio[6881]: info: Completed starting MyVolumio Plugin May 26 23:24:12 volumio volumio[6881]: [Metrics] CommandRouter: 30s 429.97ms May 26 23:24:12 volumio volumio[6881]: info: CoreCommandRouter::volumiosetStartupVolume May 26 23:24:12 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:24:12 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:24:12 volumio volumio[6881]: info: CoreCommandRouter::Close All Modals sent May 26 23:24:12 volumio volumio[6881]: info: CoreCommandRouter::Close All Modals sent May 26 23:24:13 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 23:24:13 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:24:13 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 26 23:24:13 volumio volumio-remote-updater[992]: Test mode enabled May 26 23:24:13 volumio volumio-remote-updater[992]: Alpha mode disabled May 26 23:24:13 volumio volumio-remote-updater[992]: Alpha legacy test mode disabled May 26 23:24:13 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 26 23:24:13 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 26 23:24:13 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 23:24:13 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 26 23:24:13 volumio volumio[6881]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 26 23:24:13 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 26 23:24:16 volumio volumio[6881]: info: Enabling plugin ytmusic May 26 23:24:16 volumio volumio[6881]: info: Loading plugin "ytmusic"... May 26 23:24:16 volumio volumio[6881]: info: PLUGIN START: ytmusic May 26 23:24:16 volumio volumio[6881]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 23:24:16 volumio volumio[6881]: info: [1779805456366] CoreMusicLibrary::Adding element YouTube Music May 26 23:24:16 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 23:24:16 volumio volumio[6881]: Cannot find translation for source YouTube Music May 26 23:24:16 volumio volumio[6881]: info: Done. May 26 23:24:17 volumio volumio[6881]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 23:24:19 volumio volumio[6881]: info: BOOT COMPLETED May 26 23:24:21 volumio volumio[6881]: info: CALLMETHOD: music_service ytmusic configSaveDisclaimer [object Object] May 26 23:24:21 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: ytmusic , configSaveDisclaimer May 26 23:24:21 volumio volumio[6881]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 23:24:51 volumio volumio[6881]: error: [ytmusic] Error getting i18n options: fetch failed TypeError: fetch failed May 26 23:24:51 volumio volumio[6881]: at Object.fetch (node:internal/deps/undici/undici:11576:11) May 26 23:24:51 volumio volumio[6881]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 26 23:24:51 volumio volumio[6881]: at async Player.create (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:34366:19) May 26 23:24:51 volumio volumio[6881]: at async Session.create (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:34612:129) May 26 23:24:51 volumio volumio[6881]: at async Innertube.create (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:40158:27) May 26 23:24:51 volumio volumio[6881]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:65:45) May 26 23:24:51 volumio volumio[6881]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) May 26 23:24:51 volumio volumio[6881]: at async ConfigModel.getInnertube (/data/plugins/music_service/ytmusic/dist/lib/model/BaseModel.js:13:25) May 26 23:24:51 volumio volumio[6881]: at async ConfigModel._ConfigModel_fetchSettingsPage (/data/plugins/music_service/ytmusic/dist/lib/model/ConfigModel.js:117:27) May 26 23:24:51 volumio volumio[6881]: at async ConfigModel.getI18nOptions (/data/plugins/music_service/ytmusic/dist/lib/model/ConfigModel.js:49:26) May 26 23:24:51 volumio volumio[6881]: at async ControllerYTMusic._ControllerYTMusic_getConfigI18nOptions (/data/plugins/music_service/ytmusic/dist/index.js:488:25) May 26 23:24:51 volumio volumio[6881]: at async ControllerYTMusic._ControllerYTMusic_doGetUIConfig (/data/plugins/music_service/ytmusic/dist/index.js:382:49) May 26 23:24:51 volumio volumio[6881]: warn: [ytmusic] Failed to get account config: fetch failed TypeError: fetch failed May 26 23:24:51 volumio volumio[6881]: at Object.fetch (node:internal/deps/undici/undici:11576:11) May 26 23:24:51 volumio volumio[6881]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 26 23:24:51 volumio volumio[6881]: at async Player.create (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:34366:19) May 26 23:24:51 volumio volumio[6881]: at async Session.create (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:34612:129) May 26 23:24:51 volumio volumio[6881]: at async Innertube.create (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:40158:27) May 26 23:24:51 volumio volumio[6881]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:65:45) May 26 23:24:51 volumio volumio[6881]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) May 26 23:24:51 volumio volumio[6881]: at async ConfigModel.getInnertube (/data/plugins/music_service/ytmusic/dist/lib/model/BaseModel.js:13:25) May 26 23:24:51 volumio volumio[6881]: at async ConfigModel._ConfigModel_fetchSettingsPage (/data/plugins/music_service/ytmusic/dist/lib/model/ConfigModel.js:117:27) May 26 23:24:51 volumio volumio[6881]: at async ConfigModel.getI18nOptions (/data/plugins/music_service/ytmusic/dist/lib/model/ConfigModel.js:49:26) May 26 23:24:51 volumio volumio[6881]: at async ControllerYTMusic._ControllerYTMusic_getConfigI18nOptions (/data/plugins/music_service/ytmusic/dist/index.js:488:25) May 26 23:24:51 volumio volumio[6881]: at async ControllerYTMusic._ControllerYTMusic_doGetUIConfig (/data/plugins/music_service/ytmusic/dist/index.js:382:49) May 26 23:24:59 volumio volumio[6881]: info: CoreCommandRouter::volumioGetState May 26 23:24:59 volumio volumio[6881]: info: CorePlayQueue::getTrack 0 May 26 23:25:03 volumio volumio[6881]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 23:25:03 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 26 23:25:03 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 26 23:25:03 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 26 23:25:03 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 26 23:25:03 volumio volumio[6881]: info: Received Get System Version May 26 23:25:03 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 23:25:18 volumio volumio[6881]: info: CALLMETHOD: system_controller system setLanguageTimezone [object Object] May 26 23:25:18 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: system , setLanguageTimezone May 26 23:25:18 volumio volumio[6881]: info: Setting timezone to Asia/Seoul May 26 23:25:18 volumio sudo[8246]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 26 23:25:18 volumio sudo[8246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:25:18 volumio sudo[8246]: pam_unix(sudo:session): session closed for user root May 26 23:25:18 volumio sudo[8250]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Seoul /etc/localtime May 26 23:25:18 volumio sudo[8250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:25:18 volumio sudo[8250]: pam_unix(sudo:session): session closed for user root May 26 23:25:18 volumio sudo[8254]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 26 23:25:18 volumio sudo[8254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:25:18 volumio sudo[8254]: pam_unix(sudo:session): session closed for user root May 26 23:25:18 volumio sudo[8258]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Seoul May 26 23:25:18 volumio sudo[8258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:25:18 volumio dbus-daemon[985]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.31' (uid=0 pid=8259 comm="/usr/bin/timedatectl set-timezone Asia/Seoul") May 26 23:25:18 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 26 23:25:18 volumio dbus-daemon[985]: [system] Successfully activated service 'org.freedesktop.timedate1' May 26 23:25:18 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 26 23:25:18 volumio sudo[8258]: pam_unix(sudo:session): session closed for user root May 26 23:25:18 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 26 23:25:18 volumio volumio[6881]: info: Loading i18n strings for locale en May 26 23:25:18 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 26 23:25:18 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 26 23:25:18 volumio volumio[6881]: Updating browse sources language May 26 23:25:18 volumio volumio[6881]: Cannot find translation for source YouTube Music May 26 23:25:18 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 23:25:18 volumio volumio[6881]: Cannot find translation for source YouTube Music May 26 23:25:19 volumio volumio[6881]: info: Fetching Streaming Services browse cache May 26 23:25:20 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 26 23:25:20 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 26 23:25:28 volumio volumio-remote-updater[992]: Test mode enabled May 26 23:25:28 volumio volumio-remote-updater[992]: Alpha mode disabled May 26 23:25:28 volumio volumio-remote-updater[992]: Alpha legacy test mode disabled May 26 23:25:28 volumio volumio[6881]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 26 23:25:28 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 26 23:25:30 volumio volumio[6881]: info: CoreCommandRouter::Close All Modals sent May 26 23:25:36 volumio volumio[6881]: info: CoreCommandRouter::volumioGetState May 26 23:25:36 volumio volumio[6881]: info: CorePlayQueue::getTrack 0 May 26 23:25:38 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 23:25:38 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 26 23:25:41 volumio volumio[6881]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 23:25:41 volumio volumio[6881]: error: [ytmusic] Error getting i18n options: fetch failed TypeError: fetch failed May 26 23:25:41 volumio volumio[6881]: at Object.fetch (node:internal/deps/undici/undici:11576:11) May 26 23:25:41 volumio volumio[6881]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 26 23:25:41 volumio volumio[6881]: at async Player.create (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:34366:19) May 26 23:25:41 volumio volumio[6881]: at async Session.create (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:34612:129) May 26 23:25:41 volumio volumio[6881]: at async Innertube.create (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:40158:27) May 26 23:25:41 volumio volumio[6881]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:65:45) May 26 23:25:41 volumio volumio[6881]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) May 26 23:25:41 volumio volumio[6881]: at async ConfigModel.getInnertube (/data/plugins/music_service/ytmusic/dist/lib/model/BaseModel.js:13:25) May 26 23:25:41 volumio volumio[6881]: at async ConfigModel._ConfigModel_fetchSettingsPage (/data/plugins/music_service/ytmusic/dist/lib/model/ConfigModel.js:117:27) May 26 23:25:41 volumio volumio[6881]: at async ConfigModel.getI18nOptions (/data/plugins/music_service/ytmusic/dist/lib/model/ConfigModel.js:49:26) May 26 23:25:41 volumio volumio[6881]: at async ControllerYTMusic._ControllerYTMusic_getConfigI18nOptions (/data/plugins/music_service/ytmusic/dist/index.js:488:25) May 26 23:25:41 volumio volumio[6881]: at async ControllerYTMusic._ControllerYTMusic_doGetUIConfig (/data/plugins/music_service/ytmusic/dist/index.js:382:49) May 26 23:25:41 volumio volumio[6881]: warn: [ytmusic] Failed to get account config: fetch failed TypeError: fetch failed May 26 23:25:41 volumio volumio[6881]: at Object.fetch (node:internal/deps/undici/undici:11576:11) May 26 23:25:41 volumio volumio[6881]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 26 23:25:41 volumio volumio[6881]: at async Player.create (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:34366:19) May 26 23:25:41 volumio volumio[6881]: at async Session.create (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:34612:129) May 26 23:25:41 volumio volumio[6881]: at async Innertube.create (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:40158:27) May 26 23:25:41 volumio volumio[6881]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:65:45) May 26 23:25:41 volumio volumio[6881]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) May 26 23:25:41 volumio volumio[6881]: at async ConfigModel.getInnertube (/data/plugins/music_service/ytmusic/dist/lib/model/BaseModel.js:13:25) May 26 23:25:41 volumio volumio[6881]: at async ConfigModel._ConfigModel_fetchSettingsPage (/data/plugins/music_service/ytmusic/dist/lib/model/ConfigModel.js:117:27) May 26 23:25:41 volumio volumio[6881]: at async ConfigModel.getI18nOptions (/data/plugins/music_service/ytmusic/dist/lib/model/ConfigModel.js:49:26) May 26 23:25:41 volumio volumio[6881]: at async ControllerYTMusic._ControllerYTMusic_getConfigI18nOptions (/data/plugins/music_service/ytmusic/dist/index.js:488:25) May 26 23:25:41 volumio volumio[6881]: at async ControllerYTMusic._ControllerYTMusic_doGetUIConfig (/data/plugins/music_service/ytmusic/dist/index.js:382:49) May 26 23:25:48 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 26 23:25:48 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 26 23:25:48 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 26 23:25:48 volumio volumio-remote-updater[992]: Test mode enabled May 26 23:25:48 volumio volumio-remote-updater[992]: Alpha mode disabled May 26 23:25:48 volumio volumio-remote-updater[992]: Alpha legacy test mode disabled May 26 23:25:48 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 26 23:25:49 volumio volumio[6881]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 26 23:25:49 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 26 23:25:50 volumio volumio[6881]: info: CALLMETHOD: music_service ytmusic configSaveI18n [object Object] May 26 23:25:50 volumio volumio[6881]: info: CoreCommandRouter::executeOnPlugin: ytmusic , configSaveI18n May 26 23:25:50 volumio volumio[6881]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 23:25:50 volumio volumio[6881]: TypeError: fetch failed May 26 23:25:50 volumio volumio[6881]: at Object.fetch (node:internal/deps/undici/undici:11576:11) May 26 23:25:50 volumio volumio[6881]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 26 23:25:50 volumio volumio[6881]: at async Player.create (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:34366:19) May 26 23:25:50 volumio volumio[6881]: at async Session.create (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:34612:129) May 26 23:25:50 volumio volumio[6881]: at async Innertube.create (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:40158:27) May 26 23:25:50 volumio volumio[6881]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:65:45) May 26 23:25:50 volumio volumio[6881]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) May 26 23:25:50 volumio volumio[6881]: at async ConfigModel.getInnertube (/data/plugins/music_service/ytmusic/dist/lib/model/BaseModel.js:13:25) May 26 23:25:50 volumio volumio[6881]: at async ConfigModel._ConfigModel_fetchSettingsPage (/data/plugins/music_service/ytmusic/dist/lib/model/ConfigModel.js:117:27) May 26 23:25:50 volumio volumio[6881]: at async ConfigModel.getI18nOptions (/data/plugins/music_service/ytmusic/dist/lib/model/ConfigModel.js:49:26) May 26 23:25:50 volumio volumio[6881]: at async ControllerYTMusic._ControllerYTMusic_getConfigI18nOptions (/data/plugins/music_service/ytmusic/dist/index.js:488:25) May 26 23:25:50 volumio volumio[6881]: at async ControllerYTMusic._ControllerYTMusic_doGetUIConfig (/data/plugins/music_service/ytmusic/dist/index.js:382:49) { May 26 23:25:50 volumio volumio[6881]: cause: ConnectTimeoutError: Connect Timeout Error May 26 23:25:50 volumio volumio[6881]: at onConnectTimeout (node:internal/deps/undici/undici:8522:28) May 26 23:25:50 volumio volumio[6881]: at node:internal/deps/undici/undici:8480:50 May 26 23:25:50 volumio volumio[6881]: at Immediate._onImmediate (node:internal/deps/undici/undici:8511:13) May 26 23:25:50 volumio volumio[6881]: at process.processImmediate (node:internal/timers:478:21) { May 26 23:25:50 volumio volumio[6881]: code: 'UND_ERR_CONNECT_TIMEOUT' May 26 23:25:50 volumio volumio[6881]: } May 26 23:25:50 volumio volumio[6881]: } May 26 23:25:50 volumio volumio[6881]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 23:25:50 volumio sudo[8571]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-26 23:24' May 26 23:25:50 volumio sudo[8571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"