Jun 01 13:42:02 innomaker-dac-pro volumio[1234]: info: Enabling plugin ytmusic Jun 01 13:42:02 innomaker-dac-pro volumio[1234]: info: Loading plugin "ytmusic"... Jun 01 13:42:02 innomaker-dac-pro volumio[1234]: info: PLUGIN START: ytmusic Jun 01 13:42:02 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 01 13:42:02 innomaker-dac-pro volumio[1234]: info: [1780314122974] CoreMusicLibrary::Adding element YouTube Music Jun 01 13:42:02 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 13:42:02 innomaker-dac-pro volumio[1234]: Cannot find translation for source Spotify Jun 01 13:42:02 innomaker-dac-pro volumio[1234]: Cannot find translation for source TIDAL Jun 01 13:42:02 innomaker-dac-pro volumio[1234]: Cannot find translation for source YouTube Music Jun 01 13:42:02 innomaker-dac-pro volumio[1234]: info: Done. Jun 01 13:42:07 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 13:42:07 innomaker-dac-pro volumio[1234]: error: [ytmusic] Error getting i18n options: VM operation timed out Error: VM operation timed out Jun 01 13:42:07 innomaker-dac-pro volumio[1234]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) Jun 01 13:42:07 innomaker-dac-pro volumio[1234]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 01 13:42:07 innomaker-dac-pro volumio[1234]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) Jun 01 13:42:07 innomaker-dac-pro volumio[1234]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) Jun 01 13:42:07 innomaker-dac-pro volumio[1234]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) Jun 01 13:42:07 innomaker-dac-pro volumio[1234]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) Jun 01 13:42:07 innomaker-dac-pro volumio[1234]: warn: [ytmusic] Failed to get account config: VM operation timed out Error: VM operation timed out Jun 01 13:42:07 innomaker-dac-pro volumio[1234]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) Jun 01 13:42:07 innomaker-dac-pro volumio[1234]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 01 13:42:07 innomaker-dac-pro volumio[1234]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) Jun 01 13:42:07 innomaker-dac-pro volumio[1234]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) Jun 01 13:42:07 innomaker-dac-pro volumio[1234]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) Jun 01 13:42:07 innomaker-dac-pro volumio[1234]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) Jun 01 13:42:27 innomaker-dac-pro go-librespot[1666]: time="2026-06-01T13:42:27+02:00" level=trace msg="received accesspoint ping" Jun 01 13:42:27 innomaker-dac-pro go-librespot[1666]: time="2026-06-01T13:42:27+02:00" level=trace msg="received accesspoint pong ack" Jun 01 13:42:28 innomaker-dac-pro go-librespot[1666]: time="2026-06-01T13:42:28+02:00" level=trace msg="sent dealer ping" Jun 01 13:42:28 innomaker-dac-pro go-librespot[1666]: time="2026-06-01T13:42:28+02:00" level=trace msg="received dealer pong" Jun 01 13:42:31 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 01 13:42:31 innomaker-dac-pro volumio[1234]: info: Preload queue cleared Jun 01 13:42:33 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 01 13:42:33 innomaker-dac-pro volumio[1234]: info: Preload queue cleared Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Preload queue cleared Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::ClearQueue Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::stop Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::stPlaybackTimer Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::updateTrackBlock Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CorePlayQueue::getTrackBlock Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::pushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CorePlayQueue::getTrack 0 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioPushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: MRS: Pushing multiroomSync output update for this device Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: MRS: Pushing multiroomSync output Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioGetState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CorePlayQueue::getTrack 0 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::serviceStop Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CorePlayQueue::getTrack 0 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::serviceStop Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: [1780314155507] ControllerWebradio::stop Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CorePlayQueue::clearPlayQueue Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CorePlayQueue::saveQueue Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioPushQueue Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::addQueueItems Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CorePlayQueue::addQueueItems Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Preload queue cleared Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Adding Item to queue: http://media-sov.musicradio.com:80/RadioXLondonMP3 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioPushQueue Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CorePlayQueue::saveQueue Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::updateTrackBlock Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CorePlayQueue::getTrackBlock Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioGetState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CorePlayQueue::getTrack 0 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioPlay Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::play index 0 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::stop Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::play index undefined Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CorePlayQueue::getTrack 0 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::startPlaybackTimer Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CorePlayQueue::getTrack 0 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioGetVisibleSources Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: [1780314155541] ControllerWebradio::clearAddPlayTrack Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: ---------------------------- MPD announces state update: player Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: sendMpdCommand stop took 45 milliseconds Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: ControllerMpd::getState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::sendMpdCommand status Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: sendMpdCommand stop took 13 milliseconds Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: ---------------------------- MPD announces system playlist update Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Ignoring MPD Status Update Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: sendMpdCommand status took 2 milliseconds Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: sendMpdCommand clear took 1 milliseconds Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::parseState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::sendMpdCommand load "http://media-sov.musicradio.com:80/RadioXLondonMP3" Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: error: updateQueue error: null Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: ------------------------------ 10ms Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::parseTrackInfo Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: ------------------------------ 13ms Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::sendMpdCommand add "http://media-sov.musicradio.com:80/RadioXLondonMP3" Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: ---------------------------- MPD announces system playlist update Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Ignoring MPD Status Update Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: sendMpdCommand add "http://media-sov.musicradio.com:80/RadioXLondonMP3" took 2 milliseconds Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::sendMpdCommand play Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: ------------------------------ 4ms Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: sendMpdCommand play took 2 milliseconds Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: ---------------------------- MPD announces state update: player Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: ControllerMpd::getState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::sendMpdCommand status Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: ---------------------------- MPD announces state update: player Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: sendMpdCommand status took 4 milliseconds Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: ControllerMpd::getState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::sendMpdCommand status Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::parseState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: sendMpdCommand status took 1 milliseconds Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::parseState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::parseTrackInfo Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: ControllerMpd::pushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::servicePushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CorePlayQueue::getTrack 0 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"RadioXLondonMP3","artist":null,"album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: CURRENT POSITION 0 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::syncState stateService play Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::syncState currentStatus stop Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: ------------------------------ 9ms Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::parseTrackInfo Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: ControllerMpd::pushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::servicePushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CorePlayQueue::getTrack 0 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"RadioXLondonMP3","artist":null,"album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: CURRENT POSITION 0 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::syncState stateService play Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::syncState currentStatus play Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Received an update from plugin. extracting info from payload Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::pushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CorePlayQueue::getTrack 0 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioPushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: MRS: Pushing multiroomSync output update for this device Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: MRS: Pushing multiroomSync output Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioGetState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::pushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioPushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: MRS: Pushing multiroomSync output update for this device Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: MRS: Pushing multiroomSync output Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioGetState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: ------------------------------ 61ms Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: ---------------------------- MPD announces system playlist update Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Ignoring MPD Status Update Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: ---------------------------- MPD announces state update: player Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: ControllerMpd::getState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::sendMpdCommand status Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: ---------------------------- MPD announces system playlist update Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Ignoring MPD Status Update Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: ---------------------------- MPD announces state update: player Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: ControllerMpd::getState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::sendMpdCommand status Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: ------------------------------ 18ms Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: sendMpdCommand status took 17 milliseconds Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::parseState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: ------------------------------ 4ms Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: sendMpdCommand status took 2 milliseconds Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::parseState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::parseTrackInfo Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: ControllerMpd::pushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::servicePushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CorePlayQueue::getTrack 0 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1112,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hozier - Take Me To Church","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: CURRENT POSITION 0 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::syncState stateService play Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::syncState currentStatus play Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Received an update from plugin. extracting info from payload Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::pushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioPushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: MRS: Pushing multiroomSync output update for this device Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: MRS: Pushing multiroomSync output Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioGetState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::pushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioPushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: MRS: Pushing multiroomSync output update for this device Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: MRS: Pushing multiroomSync output Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioGetState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: ------------------------------ 57ms Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: ControllerMpd::parseTrackInfo Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: ControllerMpd::pushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::servicePushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CorePlayQueue::getTrack 0 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1112,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hozier - Take Me To Church","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: verbose: CURRENT POSITION 0 Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::syncState stateService play Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::syncState currentStatus play Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: Received an update from plugin. extracting info from payload Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::pushState Jun 01 13:42:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioPushState Jun 01 13:42:36 innomaker-dac-pro volumio[1234]: info: MRS: Pushing multiroomSync output update for this device Jun 01 13:42:36 innomaker-dac-pro volumio[1234]: info: MRS: Pushing multiroomSync output Jun 01 13:42:36 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioGetState Jun 01 13:42:36 innomaker-dac-pro volumio[1234]: info: CoreStateMachine::pushState Jun 01 13:42:36 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioPushState Jun 01 13:42:36 innomaker-dac-pro volumio[1234]: info: MRS: Pushing multiroomSync output update for this device Jun 01 13:42:36 innomaker-dac-pro volumio[1234]: info: MRS: Pushing multiroomSync output Jun 01 13:42:36 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::volumioGetState Jun 01 13:42:36 innomaker-dac-pro volumio[1234]: info: ------------------------------ 74ms Jun 01 13:42:36 innomaker-dac-pro volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Jun 01 13:42:36 innomaker-dac-pro volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Jun 01 13:42:36 innomaker-dac-pro volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Jun 01 13:42:36 innomaker-dac-pro volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Jun 01 13:42:41 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 01 13:42:41 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 01 13:42:48 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 13:42:48 innomaker-dac-pro volumio[1234]: error: [ytmusic] Error getting i18n options: VM operation timed out Error: VM operation timed out Jun 01 13:42:48 innomaker-dac-pro volumio[1234]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) Jun 01 13:42:48 innomaker-dac-pro volumio[1234]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 01 13:42:48 innomaker-dac-pro volumio[1234]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) Jun 01 13:42:48 innomaker-dac-pro volumio[1234]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) Jun 01 13:42:48 innomaker-dac-pro volumio[1234]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) Jun 01 13:42:48 innomaker-dac-pro volumio[1234]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) Jun 01 13:42:48 innomaker-dac-pro volumio[1234]: warn: [ytmusic] Failed to get account config: VM operation timed out Error: VM operation timed out Jun 01 13:42:48 innomaker-dac-pro volumio[1234]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) Jun 01 13:42:48 innomaker-dac-pro volumio[1234]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 01 13:42:48 innomaker-dac-pro volumio[1234]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) Jun 01 13:42:48 innomaker-dac-pro volumio[1234]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) Jun 01 13:42:48 innomaker-dac-pro volumio[1234]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) Jun 01 13:42:48 innomaker-dac-pro volumio[1234]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) Jun 01 13:42:56 innomaker-dac-pro volumio[1234]: info: CALLMETHOD: music_service ytmusic configSaveDisclaimer [object Object] Jun 01 13:42:56 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: ytmusic , configSaveDisclaimer Jun 01 13:42:56 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 13:42:58 innomaker-dac-pro go-librespot[1666]: time="2026-06-01T13:42:58+02:00" level=trace msg="sent dealer ping" Jun 01 13:42:58 innomaker-dac-pro go-librespot[1666]: time="2026-06-01T13:42:58+02:00" level=trace msg="received dealer pong" Jun 01 13:43:04 innomaker-dac-pro volumio[1234]: info: CALLMETHOD: music_service ytmusic configSaveDisclaimer [object Object] Jun 01 13:43:04 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: ytmusic , configSaveDisclaimer Jun 01 13:43:04 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 13:43:04 innomaker-dac-pro volumio[1234]: error: [ytmusic] Error getting i18n options: VM operation timed out Error: VM operation timed out Jun 01 13:43:04 innomaker-dac-pro volumio[1234]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) Jun 01 13:43:04 innomaker-dac-pro volumio[1234]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 01 13:43:04 innomaker-dac-pro volumio[1234]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) Jun 01 13:43:04 innomaker-dac-pro volumio[1234]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) Jun 01 13:43:04 innomaker-dac-pro volumio[1234]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) Jun 01 13:43:04 innomaker-dac-pro volumio[1234]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) Jun 01 13:43:04 innomaker-dac-pro volumio[1234]: warn: [ytmusic] Failed to get account config: VM operation timed out Error: VM operation timed out Jun 01 13:43:04 innomaker-dac-pro volumio[1234]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) Jun 01 13:43:04 innomaker-dac-pro volumio[1234]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 01 13:43:04 innomaker-dac-pro volumio[1234]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) Jun 01 13:43:04 innomaker-dac-pro volumio[1234]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) Jun 01 13:43:04 innomaker-dac-pro volumio[1234]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) Jun 01 13:43:04 innomaker-dac-pro volumio[1234]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) Jun 01 13:43:09 innomaker-dac-pro volumio[1234]: info: CALLMETHOD: music_service ytmusic showDisclaimer undefined Jun 01 13:43:09 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: ytmusic , showDisclaimer Jun 01 13:43:11 innomaker-dac-pro volumio[1234]: info: CALLMETHOD: music_service ytmusic acceptDisclaimer Jun 01 13:43:11 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: ytmusic , acceptDisclaimer Jun 01 13:43:11 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 13:43:11 innomaker-dac-pro volumio[1234]: error: [ytmusic] Error getting i18n options: VM operation timed out Error: VM operation timed out Jun 01 13:43:11 innomaker-dac-pro volumio[1234]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) Jun 01 13:43:11 innomaker-dac-pro volumio[1234]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 01 13:43:11 innomaker-dac-pro volumio[1234]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) Jun 01 13:43:11 innomaker-dac-pro volumio[1234]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) Jun 01 13:43:11 innomaker-dac-pro volumio[1234]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) Jun 01 13:43:11 innomaker-dac-pro volumio[1234]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) Jun 01 13:43:11 innomaker-dac-pro volumio[1234]: warn: [ytmusic] Failed to get account config: VM operation timed out Error: VM operation timed out Jun 01 13:43:11 innomaker-dac-pro volumio[1234]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) Jun 01 13:43:11 innomaker-dac-pro volumio[1234]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 01 13:43:11 innomaker-dac-pro volumio[1234]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) Jun 01 13:43:11 innomaker-dac-pro volumio[1234]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) Jun 01 13:43:11 innomaker-dac-pro volumio[1234]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) Jun 01 13:43:11 innomaker-dac-pro volumio[1234]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) Jun 01 13:43:19 innomaker-dac-pro volumio[1234]: info: CALLMETHOD: music_service ytmusic showDisclaimer undefined Jun 01 13:43:19 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: ytmusic , showDisclaimer Jun 01 13:43:20 innomaker-dac-pro volumio[1234]: info: CALLMETHOD: music_service ytmusic acceptDisclaimer Jun 01 13:43:20 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: ytmusic , acceptDisclaimer Jun 01 13:43:20 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 13:43:20 innomaker-dac-pro volumio[1234]: error: [ytmusic] Error getting i18n options: VM operation timed out Error: VM operation timed out Jun 01 13:43:20 innomaker-dac-pro volumio[1234]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) Jun 01 13:43:20 innomaker-dac-pro volumio[1234]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 01 13:43:20 innomaker-dac-pro volumio[1234]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) Jun 01 13:43:20 innomaker-dac-pro volumio[1234]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) Jun 01 13:43:20 innomaker-dac-pro volumio[1234]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) Jun 01 13:43:20 innomaker-dac-pro volumio[1234]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) Jun 01 13:43:21 innomaker-dac-pro volumio[1234]: warn: [ytmusic] Failed to get account config: VM operation timed out Error: VM operation timed out Jun 01 13:43:21 innomaker-dac-pro volumio[1234]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) Jun 01 13:43:21 innomaker-dac-pro volumio[1234]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 01 13:43:21 innomaker-dac-pro volumio[1234]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) Jun 01 13:43:21 innomaker-dac-pro volumio[1234]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) Jun 01 13:43:21 innomaker-dac-pro volumio[1234]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) Jun 01 13:43:21 innomaker-dac-pro volumio[1234]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) Jun 01 13:43:28 innomaker-dac-pro go-librespot[1666]: time="2026-06-01T13:43:28+02:00" level=trace msg="sent dealer ping" Jun 01 13:43:28 innomaker-dac-pro go-librespot[1666]: time="2026-06-01T13:43:28+02:00" level=trace msg="received dealer pong" Jun 01 13:43:35 innomaker-dac-pro volumio[1234]: info: CALLMETHOD: music_service ytmusic configSaveI18n [object Object] Jun 01 13:43:35 innomaker-dac-pro volumio[1234]: info: CoreCommandRouter::executeOnPlugin: ytmusic , configSaveI18n Jun 01 13:43:35 innomaker-dac-pro volumio[1234]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 13:43:35 innomaker-dac-pro volumio[1234]: Error: VM operation timed out Jun 01 13:43:35 innomaker-dac-pro volumio[1234]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) Jun 01 13:43:35 innomaker-dac-pro volumio[1234]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 01 13:43:35 innomaker-dac-pro volumio[1234]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) Jun 01 13:43:35 innomaker-dac-pro volumio[1234]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) Jun 01 13:43:35 innomaker-dac-pro volumio[1234]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) Jun 01 13:43:35 innomaker-dac-pro volumio[1234]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) Jun 01 13:43:35 innomaker-dac-pro volumio[1234]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 13:43:36 innomaker-dac-pro sudo[2795]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-01 13:42' Jun 01 13:43:36 innomaker-dac-pro sudo[2795]: 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"