Apr 14 10:18:02 volboxddp volumio[1183]: info: CoreCommandRouter::volumioPlay Apr 14 10:18:02 volboxddp volumio[1183]: info: CoreStateMachine::play index undefined Apr 14 10:18:02 volboxddp volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 10:18:02 volboxddp volumio[1183]: info: CorePlayQueue::getTrack 0 Apr 14 10:18:02 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.setVolume(): Apr 14 10:18:02 volboxddp volumio[1183]: { level: 89, muted: false } Apr 14 10:18:02 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.setVolume(): Apr 14 10:18:02 volboxddp volumio[1183]: { level: 83, muted: false } Apr 14 10:18:02 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.resume() Apr 14 10:18:02 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.play(): 8XWayY7JuD4 @ 0s Apr 14 10:18:02 volboxddp volumio[1183]: info: CoreCommandRouter::volumioGetState Apr 14 10:18:02 volboxddp volumio[1183]: info: CorePlayQueue::getTrack 0 Apr 14 10:18:03 volboxddp volumio[1183]: info: CoreCommandRouter::volumioGetState Apr 14 10:18:03 volboxddp volumio[1183]: info: CorePlayQueue::getTrack 0 Apr 14 10:18:03 volboxddp volumio[1183]: info: CoreCommandRouter::volumioStop Apr 14 10:18:03 volboxddp volumio[1183]: info: CoreStateMachine::stop Apr 14 10:18:03 volboxddp volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 10:18:03 volboxddp volumio[1183]: info: CoreCommandRouter::volumioGetState Apr 14 10:18:03 volboxddp volumio[1183]: info: CorePlayQueue::getTrack 0 Apr 14 10:18:03 volboxddp volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 10:18:03 volboxddp volumio[1183]: info: CoreCommandRouter::servicePushState Apr 14 10:18:03 volboxddp volumio[1183]: info: CoreStateMachine::pushState Apr 14 10:18:03 volboxddp volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 10:18:03 volboxddp volumio[1183]: info: CoreCommandRouter::volumioPushState Apr 14 10:18:03 volboxddp volumio[1183]: info: CoreCommandRouter::servicePushState Apr 14 10:18:03 volboxddp volumio[1183]: info: CoreStateMachine::pushState Apr 14 10:18:03 volboxddp volumio[1183]: info: CoreCommandRouter::volumioPushState Apr 14 10:18:03 volboxddp volumio[1183]: info: CoreCommandRouter::volumioGetState Apr 14 10:18:03 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.resume() Apr 14 10:18:03 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.play(): 8XWayY7JuD4 @ 0s Apr 14 10:18:03 volboxddp volumio[1183]: info: CoreCommandRouter::volumioGetState Apr 14 10:18:03 volboxddp volumio[1183]: info: CoreCommandRouter::volumioGetState Apr 14 10:18:05 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.stop() Apr 14 10:18:05 volboxddp volumio[1183]: info: CoreCommandRouter::volumioGetState Apr 14 10:18:05 volboxddp volumio[1183]: info: CoreCommandRouter::servicePushState Apr 14 10:18:05 volboxddp volumio[1183]: info: CoreStateMachine::pushState Apr 14 10:18:05 volboxddp volumio[1183]: info: CoreCommandRouter::volumioPushState Apr 14 10:18:05 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.play(): 8XWayY7JuD4 @ 0s Apr 14 10:18:05 volboxddp volumio[1183]: info: CoreCommandRouter::volumioGetState Apr 14 10:18:05 volboxddp volumio[1183]: info: CoreCommandRouter::volumioGetState Apr 14 10:18:26 volboxddp volumio[1183]: info: CoreCommandRouter::volumioVolatilePlay Apr 14 10:18:26 volboxddp volumio[1183]: info: CoreStateMachine::volatilePlay Apr 14 10:18:26 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.resume() Apr 14 10:18:26 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.play(): 8XWayY7JuD4 @ 0s Apr 14 10:18:26 volboxddp volumio[1183]: info: CoreCommandRouter::volumioGetState Apr 14 10:18:26 volboxddp volumio[1183]: info: CoreCommandRouter::volumioGetState Apr 14 10:18:26 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.setVolume(): Apr 14 10:18:26 volboxddp volumio[1183]: { level: 89, muted: false } Apr 14 10:18:26 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.setVolume(): Apr 14 10:18:26 volboxddp volumio[1183]: { level: 89, muted: false } Apr 14 10:18:26 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.setVolume(): Apr 14 10:18:26 volboxddp volumio[1183]: { level: 83, muted: false } Apr 14 10:18:26 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.setVolume(): Apr 14 10:18:26 volboxddp volumio[1183]: { level: 83, muted: false } Apr 14 10:18:26 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.resume() Apr 14 10:18:26 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.play(): 8XWayY7JuD4 @ 0s Apr 14 10:18:26 volboxddp volumio[1183]: info: CoreCommandRouter::volumioGetState Apr 14 10:18:26 volboxddp volumio[1183]: info: CoreCommandRouter::volumioGetState Apr 14 10:18:26 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.resume() Apr 14 10:18:26 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.play(): 8XWayY7JuD4 @ 0s Apr 14 10:18:26 volboxddp volumio[1183]: info: CoreCommandRouter::volumioGetState Apr 14 10:18:26 volboxddp volumio[1183]: info: CoreCommandRouter::volumioGetState Apr 14 10:19:04 volboxddp volumio[1183]: info: [ytcr] Innertube support service: Deno not installed or otherwise failed to start: Command failed: npx --no-install --yes deno --version Apr 14 10:19:04 volboxddp volumio[1183]: info: [ytcr] Innertube support service: Start service with Node Apr 14 10:19:04 volboxddp volumio[1183]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 10:19:04 volboxddp volumio[1183]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 10:19:06 volboxddp volumio[1183]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 10:19:13 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.pause() Apr 14 10:19:23 volboxddp volumio[1183]: info: CALLMETHOD: music_service ytcr configClearDataStore undefined Apr 14 10:19:23 volboxddp volumio[1183]: info: CoreCommandRouter::executeOnPlugin: ytcr , configClearDataStore Apr 14 10:19:24 volboxddp volumio[1183]: info: [ytcr] Innertube support service: result: {"status":"started","server":{"address":"127.0.0.1","port":34067}} Apr 14 10:19:24 volboxddp volumio[1183]: info: [ytcr] Innertube support service running at http://127.0.0.1:34067 Apr 14 10:19:24 volboxddp volumio[1183]: info: CALLMETHOD: music_service ytcr configConfirmClearDataStore undefined Apr 14 10:19:24 volboxddp volumio[1183]: info: CoreCommandRouter::executeOnPlugin: ytcr , configConfirmClearDataStore Apr 14 10:19:24 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.reset() Apr 14 10:19:24 volboxddp volumio[1183]: info: [yt-cast-receiver] Player.stop() Apr 14 10:19:24 volboxddp volumio[1183]: info: CoreCommandRouter::volumioGetState Apr 14 10:19:24 volboxddp volumio[1183]: info: CoreCommandRouter::servicePushState Apr 14 10:19:24 volboxddp volumio[1183]: info: CoreStateMachine::pushState Apr 14 10:19:24 volboxddp volumio[1183]: info: CoreCommandRouter::volumioPushState Apr 14 10:19:24 volboxddp volumio[1183]: info: CoreCommandRouter::servicePushState Apr 14 10:19:24 volboxddp volumio[1183]: info: CoreStateMachine::pushState Apr 14 10:19:24 volboxddp volumio[1183]: info: CoreCommandRouter::volumioPushState Apr 14 10:19:24 volboxddp volumio[1183]: info: CoreCommandRouter::volumioGetState Apr 14 10:19:24 volboxddp volumio[1183]: info: CoreCommandRouter::servicePushState Apr 14 10:19:24 volboxddp volumio[1183]: info: CoreStateMachine::pushState Apr 14 10:19:24 volboxddp volumio[1183]: info: CoreCommandRouter::volumioPushState Apr 14 10:19:24 volboxddp volumio[1183]: info: CoreCommandRouter::servicePushState Apr 14 10:19:24 volboxddp volumio[1183]: info: CoreStateMachine::pushState Apr 14 10:19:24 volboxddp volumio[1183]: info: CoreCommandRouter::volumioPushState Apr 14 10:19:25 volboxddp volumio[1183]: verbose: UNSET VOLATILE: Service: ytcr Apr 14 10:19:25 volboxddp volumio[1183]: info: CoreCommandRouter::servicePushState Apr 14 10:19:25 volboxddp volumio[1183]: info: CoreStateMachine::pushState Apr 14 10:19:25 volboxddp volumio[1183]: info: CoreCommandRouter::volumioPushState Apr 14 10:19:25 volboxddp volumio[1183]: info: CoreCommandRouter::servicePushState Apr 14 10:19:25 volboxddp volumio[1183]: info: CoreStateMachine::pushState Apr 14 10:19:25 volboxddp volumio[1183]: info: CoreCommandRouter::volumioPushState Apr 14 10:19:43 volboxddp volumio[1183]: info: CALLMETHOD: music_service ytcr configSaveI18n [object Object] Apr 14 10:19:43 volboxddp volumio[1183]: info: CoreCommandRouter::executeOnPlugin: ytcr , configSaveI18n Apr 14 10:19:45 volboxddp volumio[1183]: info: CALLMETHOD: music_service ytcr configSaveI18n [object Object] Apr 14 10:19:45 volboxddp volumio[1183]: info: CoreCommandRouter::executeOnPlugin: ytcr , configSaveI18n Apr 14 10:19:46 volboxddp volumio[1183]: error: [ytcr] Failed to stop receiver: Apr 14 10:19:46 volboxddp volumio[1183]: (Error) VM operation timed out Apr 14 10:19:46 volboxddp volumio[1183]: Error: VM operation timed out Apr 14 10:19:46 volboxddp volumio[1183]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:93:15) Apr 14 10:19:46 volboxddp volumio[1183]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_generateSessionPoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:209:31) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:172:21) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_init (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:164:5) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper.create (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:72:9) Apr 14 10:19:46 volboxddp volumio[1183]: error: [yt-cast-receiver] Caught error handling incoming message: Apr 14 10:19:46 volboxddp volumio[1183]: (Error) VM operation timed out Apr 14 10:19:46 volboxddp volumio[1183]: Error: VM operation timed out Apr 14 10:19:46 volboxddp volumio[1183]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:93:15) Apr 14 10:19:46 volboxddp volumio[1183]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_generateSessionPoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:209:31) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:172:21) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_init (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:164:5) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper.create (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:72:9) Apr 14 10:19:46 volboxddp volumio[1183]: error: [yt-cast-receiver] Caught error handling incoming message: Apr 14 10:19:46 volboxddp volumio[1183]: (Error) VM operation timed out Apr 14 10:19:46 volboxddp volumio[1183]: Error: VM operation timed out Apr 14 10:19:46 volboxddp volumio[1183]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:93:15) Apr 14 10:19:46 volboxddp volumio[1183]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_generateSessionPoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:209:31) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:172:21) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_init (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:164:5) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper.create (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:72:9) Apr 14 10:19:46 volboxddp volumio[1183]: error: [yt-cast-receiver] Caught error handling incoming message: Apr 14 10:19:46 volboxddp volumio[1183]: (Error) VM operation timed out Apr 14 10:19:46 volboxddp volumio[1183]: Error: VM operation timed out Apr 14 10:19:46 volboxddp volumio[1183]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:93:15) Apr 14 10:19:46 volboxddp volumio[1183]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_generateSessionPoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:209:31) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:172:21) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_init (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:164:5) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper.create (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:72:9) Apr 14 10:19:46 volboxddp volumio[1183]: error: [yt-cast-receiver] Caught error handling incoming message: Apr 14 10:19:46 volboxddp volumio[1183]: (Error) VM operation timed out Apr 14 10:19:46 volboxddp volumio[1183]: Error: VM operation timed out Apr 14 10:19:46 volboxddp volumio[1183]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:93:15) Apr 14 10:19:46 volboxddp volumio[1183]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_generateSessionPoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:209:31) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:172:21) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_init (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:164:5) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper.create (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:72:9) Apr 14 10:19:46 volboxddp volumio[1183]: error: [yt-cast-receiver] Caught error handling incoming message: Apr 14 10:19:46 volboxddp volumio[1183]: (Error) VM operation timed out Apr 14 10:19:46 volboxddp volumio[1183]: Error: VM operation timed out Apr 14 10:19:46 volboxddp volumio[1183]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:93:15) Apr 14 10:19:46 volboxddp volumio[1183]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_generateSessionPoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:209:31) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:172:21) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_init (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:164:5) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper.create (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:72:9) Apr 14 10:19:46 volboxddp volumio[1183]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 10:19:46 volboxddp volumio[1183]: Error: VM operation timed out Apr 14 10:19:46 volboxddp volumio[1183]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:93:15) Apr 14 10:19:46 volboxddp volumio[1183]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_generateSessionPoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:209:31) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:172:21) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper._InnertubeWrapper_init (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:164:5) Apr 14 10:19:46 volboxddp volumio[1183]: at async InnertubeWrapper.create (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:72:9) Apr 14 10:19:46 volboxddp volumio[1183]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 10:19:51 volboxddp sudo[2184]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-14 10:18' Apr 14 10:19:51 volboxddp sudo[2184]: 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"