Mar 26 18:06:15 pi4test03252026 volumio[1226]: verbose: New Socket.io Connection to 192.168.50.114 from 192.168.50.160 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 29 Mar 26 18:06:15 pi4test03252026 volumio[1226]: verbose: New Socket.io Connection to 192.168.50.114 from 192.168.50.160 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 30 Mar 26 18:06:15 pi4test03252026 volumio[1226]: verbose: New Socket.io Connection to 192.168.50.114 from 192.168.50.160 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 31 Mar 26 18:06:15 pi4test03252026 volumio[1226]: verbose: New Socket.io Connection to 192.168.50.114 from 192.168.50.160 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 32 Mar 26 18:06:29 pi4test03252026 go-librespot[1766]: time="2026-03-26T18:06:29-05:00" level=trace msg="received accesspoint ping" Mar 26 18:06:29 pi4test03252026 go-librespot[1766]: time="2026-03-26T18:06:29-05:00" level=trace msg="received accesspoint pong ack" Mar 26 18:06:29 pi4test03252026 go-librespot[1766]: time="2026-03-26T18:06:29-05:00" level=trace msg="sent dealer ping" Mar 26 18:06:29 pi4test03252026 go-librespot[1766]: time="2026-03-26T18:06:29-05:00" level=trace msg="received dealer pong" Mar 26 18:06:45 pi4test03252026 volumio[1226]: info: [ytmusic] Innertube support service: Deno not installed or otherwise failed to start: Command failed: npx --no-install --yes deno --version Mar 26 18:06:45 pi4test03252026 volumio[1226]: info: [ytmusic] Innertube support service: Start service with Node Mar 26 18:06:45 pi4test03252026 volumio[1226]: verbose: New Socket.io Connection to 192.168.50.114 from 192.168.50.160 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 31 Mar 26 18:06:45 pi4test03252026 volumio[1226]: verbose: New Socket.io Connection to 192.168.50.114 from 192.168.50.160 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 32 Mar 26 18:06:45 pi4test03252026 volumio[1226]: verbose: New Socket.io Connection to 192.168.50.114 from 192.168.50.160 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 33 Mar 26 18:06:45 pi4test03252026 volumio[1226]: verbose: New Socket.io Connection to 192.168.50.114 from 192.168.50.160 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 34 Mar 26 18:06:45 pi4test03252026 volumio[1226]: verbose: New Socket.io Connection to 192.168.50.114 from 192.168.50.160 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 35 Mar 26 18:06:45 pi4test03252026 volumio[1226]: verbose: New Socket.io Connection to 192.168.50.114 from 192.168.50.160 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 36 Mar 26 18:06:50 pi4test03252026 volumio[1226]: verbose: New Socket.io Connection to 192.168.50.114 from 192.168.50.160 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 37 Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::volumioGetState Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: Received Get System Info Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: Discovery: Getting this device information Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::volumioGetState Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::volumioGetState Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: Listing playlists Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::volumioGetState Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: Received Get System Info Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: Discovery: Getting this device information Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::volumioGetState Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: Received Get System Info Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: Discovery: Getting this device information Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::volumioGetState Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: Received Get System Version Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: Received Get System Info Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: Discovery: Getting this device information Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::volumioGetState Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::volumioGetState Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::volumioGetState Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: Received Get System Info Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: Discovery: Getting this device information Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::volumioGetState Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: CoreCommandRouter::volumioGetState Mar 26 18:06:50 pi4test03252026 volumio[1226]: info: Listing playlists Mar 26 18:06:51 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 26 18:06:51 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 26 18:06:52 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 26 18:06:53 pi4test03252026 volumio[1226]: info: Executing endpoint metavolumio Mar 26 18:06:53 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 26 18:06:58 pi4test03252026 volumio[1226]: info: CoreCommandRouter::volumioGetQueue Mar 26 18:06:58 pi4test03252026 volumio[1226]: info: CoreStateMachine::getQueue Mar 26 18:06:58 pi4test03252026 volumio[1226]: info: CorePlayQueue::getQueue Mar 26 18:06:59 pi4test03252026 go-librespot[1766]: time="2026-03-26T18:06:59-05:00" level=trace msg="sent dealer ping" Mar 26 18:06:59 pi4test03252026 go-librespot[1766]: time="2026-03-26T18:06:59-05:00" level=trace msg="received dealer pong" Mar 26 18:07:01 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Mar 26 18:07:01 pi4test03252026 volumio[1226]: info: [ytmusic-browse] browseUri: ytmusic Mar 26 18:07:01 pi4test03252026 volumio[1226]: info: Preload queue cleared Mar 26 18:07:02 pi4test03252026 volumio[1226]: info: [ytmusic] Innertube support service: result: {"status":"started","server":{"address":"127.0.0.1","port":40815}} Mar 26 18:07:02 pi4test03252026 volumio[1226]: info: [ytmusic] Innertube support service running at http://127.0.0.1:40815 Mar 26 18:07:02 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Mar 26 18:07:02 pi4test03252026 volumio[1226]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_library_landing%22%7D%7D Mar 26 18:07:14 pi4test03252026 volumio[1226]: info: Tunnel connection is inactive, restarting it Mar 26 18:07:14 pi4test03252026 volumio[1226]: info: Starting Tunnel 1 Mar 26 18:07:14 pi4test03252026 volumio[1226]: info: Starting Tunnel Connection Checker Mar 26 18:07:15 pi4test03252026 sudo[2723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 26 18:07:15 pi4test03252026 sudo[2723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 18:07:15 pi4test03252026 autossh[2363]: received signal to exit (15) Mar 26 18:07:15 pi4test03252026 systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Mar 26 18:07:15 pi4test03252026 systemd[1]: sshtunnel.service: Deactivated successfully. Mar 26 18:07:15 pi4test03252026 systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Mar 26 18:07:15 pi4test03252026 systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Mar 26 18:07:15 pi4test03252026 sudo[2723]: pam_unix(sudo:session): session closed for user root Mar 26 18:07:15 pi4test03252026 volumio[1226]: info: Remote SSH Started Mar 26 18:07:15 pi4test03252026 autossh[2727]: port set to 0, monitoring disabled Mar 26 18:07:15 pi4test03252026 autossh[2727]: starting ssh (count 1) Mar 26 18:07:15 pi4test03252026 autossh[2727]: ssh child pid is 2730 Mar 26 18:07:15 pi4test03252026 volumio[1226]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 36 Mar 26 18:07:15 pi4test03252026 volumiossh-tunnel[2730]: Warning: Permanently added '[us2.myvolumio.org]:2222' (RSA) to the list of known hosts. Mar 26 18:07:15 pi4test03252026 volumio[1226]: info: CoreCommandRouter::volumioGetState Mar 26 18:07:15 pi4test03252026 volumio[1226]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dxIOxfq7zzNRdXi4DWI91B7lY3j2.b3a704c855124ea52915c7227f62608b.state.status' Mar 26 18:07:20 pi4test03252026 volumio[1226]: error: VM operation timed out Error: VM operation timed out Mar 26 18:07:20 pi4test03252026 volumio[1226]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:93:15) Mar 26 18:07:20 pi4test03252026 volumio[1226]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 18:07:20 pi4test03252026 volumio[1226]: at async InnertubeWrapper._InnertubeWrapper_generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:209:31) Mar 26 18:07:20 pi4test03252026 volumio[1226]: at async InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:172:21) Mar 26 18:07:20 pi4test03252026 volumio[1226]: at async InnertubeWrapper._InnertubeWrapper_init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:164:5) Mar 26 18:07:20 pi4test03252026 volumio[1226]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:72:9) Mar 26 18:07:20 pi4test03252026 volumio[1226]: error: VM operation timed out Error: VM operation timed out Mar 26 18:07:20 pi4test03252026 volumio[1226]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:93:15) Mar 26 18:07:20 pi4test03252026 volumio[1226]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 18:07:20 pi4test03252026 volumio[1226]: at async InnertubeWrapper._InnertubeWrapper_generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:209:31) Mar 26 18:07:20 pi4test03252026 volumio[1226]: at async InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:172:21) Mar 26 18:07:20 pi4test03252026 volumio[1226]: at async InnertubeWrapper._InnertubeWrapper_init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:164:5) Mar 26 18:07:20 pi4test03252026 volumio[1226]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:72:9) Mar 26 18:07:20 pi4test03252026 volumio[1226]: error: VM operation timed out Error: VM operation timed out Mar 26 18:07:20 pi4test03252026 volumio[1226]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:93:15) Mar 26 18:07:20 pi4test03252026 volumio[1226]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 18:07:20 pi4test03252026 volumio[1226]: at async InnertubeWrapper._InnertubeWrapper_generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:209:31) Mar 26 18:07:20 pi4test03252026 volumio[1226]: at async InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:172:21) Mar 26 18:07:20 pi4test03252026 volumio[1226]: at async InnertubeWrapper._InnertubeWrapper_init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:164:5) Mar 26 18:07:20 pi4test03252026 volumio[1226]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:72:9) Mar 26 18:07:20 pi4test03252026 volumio[1226]: error: [now-playing] Caught error in callback of MetdataAPI.#getFetchPromise(): VM operation timed out Mar 26 18:07:20 pi4test03252026 volumio[1226]: error: [now_playing] Error fetching metdata using ytmusic plugin: VM operation timed out Mar 26 18:07:20 pi4test03252026 volumio[1226]: error: [now_playing] Falling back to DefaultMetadataProvider Mar 26 18:07:20 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 18:07:20 pi4test03252026 volumio[1226]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Laid to Rest","artist":"Lamb of God","album":"Ashes of the Wake (20th Anniversary Edition)","uri":"ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Laid%20to%20Rest%22%2C%22artist%22%3A%22Lamb%20of%20God%22%2C%22album%22%3A%22Ashes%20of%20the%20Wake%20(20th%20Anniversary%20Edition)%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FsHfo2Y_0oaDVMglvCxr6IFr1HL8ljy4DxQPVvRGBc3yObrxBwinmiVabpnOHgfawuPKDy31_eJPZmG2-%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%227ukEcQ_H774%22%2C%22playlistId%22%3A%22OLAK5uy_l5c62IEy-kHTwiJKUQEOW2Loof_lAoxsM%22%2C%22index%22%3A0%2C%22playlistSetVideoId%22%3A%2220BB2D1CA162D962%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_l5c62IEy-kHTwiJKUQEOW2Loof_lAoxsM%22%2C%22videoId%22%3A%22T4Th34J48bY%22%7D%7D%7D%7D","service":""} Mar 26 18:07:20 pi4test03252026 volumio[1226]: error: Failed to execute browseSource: Error: VM operation timed out Mar 26 18:07:20 pi4test03252026 volumio[1226]: error: Failed to execute browseSource: Error: VM operation timed out Mar 26 18:07:20 pi4test03252026 volumio[1226]: error: Failed to execute browseSource: Error: VM operation timed out Mar 26 18:07:29 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Mar 26 18:07:29 pi4test03252026 volumio[1226]: info: [ytmusic-browse] browseUri: ytmusic Mar 26 18:07:29 pi4test03252026 volumio[1226]: info: Preload queue cleared Mar 26 18:07:29 pi4test03252026 go-librespot[1766]: time="2026-03-26T18:07:29-05:00" level=trace msg="sent dealer ping" Mar 26 18:07:29 pi4test03252026 go-librespot[1766]: time="2026-03-26T18:07:29-05:00" level=trace msg="received dealer pong" Mar 26 18:07:33 pi4test03252026 volumio[1226]: info: CoreCommandRouter::volumioGetState Mar 26 18:07:35 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 18:07:35 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 26 18:07:35 pi4test03252026 volumio[1226]: info: Executing endpoint metavolumio Mar 26 18:07:35 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 26 18:07:38 pi4test03252026 volumio[1226]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 18:07:38 pi4test03252026 volumio[1226]: error: [ytmusic] Error getting i18n options: VM operation timed out Error: VM operation timed out Mar 26 18:07:38 pi4test03252026 volumio[1226]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:93:15) Mar 26 18:07:38 pi4test03252026 volumio[1226]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 18:07:38 pi4test03252026 volumio[1226]: at async InnertubeWrapper._InnertubeWrapper_generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:209:31) Mar 26 18:07:38 pi4test03252026 volumio[1226]: at async InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:172:21) Mar 26 18:07:38 pi4test03252026 volumio[1226]: at async InnertubeWrapper._InnertubeWrapper_init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:164:5) Mar 26 18:07:38 pi4test03252026 volumio[1226]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:72:9) Mar 26 18:07:38 pi4test03252026 volumio[1226]: warn: [ytmusic] Failed to get account config: VM operation timed out Error: VM operation timed out Mar 26 18:07:38 pi4test03252026 volumio[1226]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:93:15) Mar 26 18:07:38 pi4test03252026 volumio[1226]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 18:07:38 pi4test03252026 volumio[1226]: at async InnertubeWrapper._InnertubeWrapper_generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:209:31) Mar 26 18:07:38 pi4test03252026 volumio[1226]: at async InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:172:21) Mar 26 18:07:38 pi4test03252026 volumio[1226]: at async InnertubeWrapper._InnertubeWrapper_init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:164:5) Mar 26 18:07:38 pi4test03252026 volumio[1226]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:72:9) Mar 26 18:07:49 pi4test03252026 volumio[1226]: info: CALLMETHOD: music_service ytmusic configSaveAccount [object Object] Mar 26 18:07:49 pi4test03252026 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: ytmusic , configSaveAccount Mar 26 18:07:49 pi4test03252026 volumio[1226]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 18:07:49 pi4test03252026 volumio[1226]: Error: VM operation timed out Mar 26 18:07:49 pi4test03252026 volumio[1226]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:93:15) Mar 26 18:07:49 pi4test03252026 volumio[1226]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 18:07:49 pi4test03252026 volumio[1226]: at async InnertubeWrapper._InnertubeWrapper_generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:209:31) Mar 26 18:07:49 pi4test03252026 volumio[1226]: at async InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:172:21) Mar 26 18:07:49 pi4test03252026 volumio[1226]: at async InnertubeWrapper._InnertubeWrapper_init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:164:5) Mar 26 18:07:49 pi4test03252026 volumio[1226]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:72:9) Mar 26 18:07:49 pi4test03252026 volumio[1226]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 18:07:53 pi4test03252026 sudo[2809]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-26 18:06' Mar 26 18:07:53 pi4test03252026 sudo[2809]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"