May 25 16:49:01 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 25 16:49:01 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 16:49:01 volumio volumio[1385]: info: Received Get System Info May 25 16:49:01 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 16:49:01 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 16:49:01 volumio volumio[1385]: info: Discovery: Getting this device information May 25 16:49:01 volumio volumio[1385]: info: CoreCommandRouter::volumioGetState May 25 16:49:01 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 May 25 16:49:01 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 16:49:02 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 16:49:02 volumio volumio[1385]: info: Received Get System Info May 25 16:49:02 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 16:49:02 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 16:49:02 volumio volumio[1385]: info: Discovery: Getting this device information May 25 16:49:02 volumio volumio[1385]: info: CoreCommandRouter::volumioGetState May 25 16:49:02 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 May 25 16:49:02 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 16:49:07 volumio ntpd[1163]: PROTO: 193.219.61.120 unlink local addr 192.168.1.4 -> May 25 16:49:08 volumio volumio[1385]: error: [now-playing] Error fetching https://api.openweathermap.org/data/3.0/onecall?appid=6cd12afcc686b595464432ea230ebe14&lat=55.8985&lon=21.1443&units=metric: Error: Response error: 401 - Unauthorized May 25 16:49:08 volumio volumio[1385]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:30:15) May 25 16:49:08 volumio volumio[1385]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 25 16:49:08 volumio volumio[1385]: at async Promise.all (index 0) May 25 16:49:08 volumio volumio[1385]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:91:24) May 25 16:49:08 volumio volumio[1385]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:104:45) May 25 16:49:08 volumio volumio[1385]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:301:25 May 25 16:49:09 volumio volumio[1385]: error: [now-playing] Error fetching https://api.openweathermap.org/data/3.0/onecall?appid=6cd12afcc686b595464432ea230ebe14&lat=55.8985&lon=21.1443&units=metric: Error: Response error: 401 - Unauthorized May 25 16:49:09 volumio volumio[1385]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:30:15) May 25 16:49:09 volumio volumio[1385]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 25 16:49:09 volumio volumio[1385]: at async Promise.all (index 0) May 25 16:49:09 volumio volumio[1385]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:91:24) May 25 16:49:09 volumio volumio[1385]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:104:45) May 25 16:49:09 volumio volumio[1385]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:301:25 May 25 16:49:09 volumio volumio[1385]: error: [now-playing] Caught error in callback of WeatherAPI.#getFetchPromise(): Response error: 401 - Unauthorized May 25 16:49:09 volumio volumio[1385]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not fetch weather: Response error: 401 - Unauthorized May 25 16:49:09 volumio volumio[1385]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:108:25) May 25 16:49:09 volumio volumio[1385]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 25 16:49:09 volumio volumio[1385]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:126:28) May 25 16:49:09 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 25 16:49:09 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri May 25 16:49:09 volumio volumio[1385]: info: [ytmusic-browse] browseUri: ytmusic May 25 16:49:09 volumio volumio[1385]: info: Preload queue cleared May 25 16:49:12 volumio ntpd[1163]: PROTO: 195.72.61.39 unlink local addr 192.168.1.4 -> May 25 16:49:15 volumio volumio[1385]: Searching plugin music_service/ytmusic May 25 16:49:15 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search May 25 16:49:16 volumio volumio[1385]: Searching plugin music_service/ytmusic May 25 16:49:16 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search May 25 16:49:16 volumio volumio[1385]: Searching plugin music_service/ytmusic May 25 16:49:16 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search May 25 16:49:17 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 16:49:17 volumio volumio[1385]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 25 16:49:17 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 25 16:49:17 volumio volumio[1385]: info: Received Get System Version May 25 16:49:17 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 16:49:17 volumio volumio[1385]: info: Received Get System Info May 25 16:49:17 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 16:49:17 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 16:49:17 volumio volumio[1385]: info: Discovery: Getting this device information May 25 16:49:17 volumio volumio[1385]: info: CoreCommandRouter::volumioGetState May 25 16:49:17 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 May 25 16:49:17 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 16:49:38 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 16:49:38 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 25 16:49:38 volumio volumio[1385]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 16:49:42 volumio volumio[1385]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 16:49:42 volumio volumio[1385]: error: [ytmusic] Error getting i18n options: VM operation timed out Error: VM operation timed out May 25 16:49:42 volumio volumio[1385]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) May 25 16:49:42 volumio volumio[1385]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 25 16:49:42 volumio volumio[1385]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) May 25 16:49:42 volumio volumio[1385]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) May 25 16:49:42 volumio volumio[1385]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) May 25 16:49:42 volumio volumio[1385]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) May 25 16:49:42 volumio volumio[1385]: warn: [ytmusic] Failed to get account config: VM operation timed out Error: VM operation timed out May 25 16:49:42 volumio volumio[1385]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) May 25 16:49:42 volumio volumio[1385]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 25 16:49:42 volumio volumio[1385]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) May 25 16:49:42 volumio volumio[1385]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) May 25 16:49:42 volumio volumio[1385]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) May 25 16:49:42 volumio volumio[1385]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) May 25 16:49:50 volumio volumio[1385]: info: CALLMETHOD: music_service ytmusic configSaveAccount [object Object] May 25 16:49:50 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: ytmusic , configSaveAccount May 25 16:50:07 volumio volumio[1385]: verbose: New Socket.io Connection to 192.168.1.4 from 192.168.1.6 UA: Mozilla/5.0 (Linux; Android 15; ALI-NX1 Build/HONORALI-N21; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 25 16:50:07 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 16:50:07 volumio volumio[1385]: info: CoreCommandRouter::volumioGetVisibleSources May 25 16:50:07 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 16:50:07 volumio volumio[1385]: info: CoreCommandRouter::volumioGetState May 25 16:50:07 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 May 25 16:50:07 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 25 16:50:07 volumio volumio[1385]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 16:50:07 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 25 16:50:07 volumio volumio[1385]: info: Received Get System Info May 25 16:50:07 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 16:50:07 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 16:50:07 volumio volumio[1385]: info: Discovery: Getting this device information May 25 16:50:07 volumio volumio[1385]: info: CoreCommandRouter::volumioGetState May 25 16:50:07 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 May 25 16:50:07 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 16:50:07 volumio volumio[1385]: info: CoreCommandRouter::volumioGetState May 25 16:50:07 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 May 25 16:50:07 volumio volumio[1385]: info: Listing playlists May 25 16:50:07 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 16:50:07 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 16:50:07 volumio volumio[1385]: error: [ytmusic] Error getting i18n options: VM operation timed out Error: VM operation timed out May 25 16:50:07 volumio volumio[1385]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) May 25 16:50:07 volumio volumio[1385]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 25 16:50:07 volumio volumio[1385]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) May 25 16:50:07 volumio volumio[1385]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) May 25 16:50:07 volumio volumio[1385]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) May 25 16:50:07 volumio volumio[1385]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) May 25 16:50:07 volumio volumio[1385]: warn: [ytmusic] Failed to get account config: VM operation timed out Error: VM operation timed out May 25 16:50:07 volumio volumio[1385]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) May 25 16:50:07 volumio volumio[1385]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 25 16:50:07 volumio volumio[1385]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) May 25 16:50:07 volumio volumio[1385]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) May 25 16:50:07 volumio volumio[1385]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) May 25 16:50:07 volumio volumio[1385]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) May 25 16:50:07 volumio volumio[1385]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 16:50:07 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 25 16:50:08 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 25 16:50:09 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 16:50:09 volumio volumio[1385]: info: Received Get System Info May 25 16:50:09 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 16:50:09 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 16:50:09 volumio volumio[1385]: info: Discovery: Getting this device information May 25 16:50:09 volumio volumio[1385]: info: CoreCommandRouter::volumioGetState May 25 16:50:09 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 May 25 16:50:09 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 16:50:09 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 16:50:09 volumio volumio[1385]: info: Received Get System Info May 25 16:50:09 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 16:50:09 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 16:50:09 volumio volumio[1385]: info: Discovery: Getting this device information May 25 16:50:09 volumio volumio[1385]: info: CoreCommandRouter::volumioGetState May 25 16:50:09 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 May 25 16:50:09 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 16:50:17 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 25 16:50:19 volumio volumio[1385]: info: CALLMETHOD: music_service ytmusic configSaveAccount [object Object] May 25 16:50:19 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: ytmusic , configSaveAccount May 25 16:50:19 volumio volumio[1385]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 16:50:19 volumio volumio[1385]: Error: VM operation timed out May 25 16:50:19 volumio volumio[1385]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) May 25 16:50:19 volumio volumio[1385]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 25 16:50:19 volumio volumio[1385]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) May 25 16:50:19 volumio volumio[1385]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) May 25 16:50:19 volumio volumio[1385]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) May 25 16:50:19 volumio volumio[1385]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) May 25 16:50:19 volumio volumio[1385]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 16:50:20 volumio sudo[4569]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-25 16:49' May 25 16:50:20 volumio sudo[4569]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"