Oct 16 22:19:25 volumio volumio5-onboarding[1506]: time=2026-10-16T22:19:25.479+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.50.2.152:37816 @ 0xc00053a810" latency=-366.117153ms timeout=10s Oct 16 22:19:25 volumio volumio5-onboarding[1506]: time=2026-10-16T22:19:25.553+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.50.2.152:37816 @ 0xc00053a810" latency=-366.117153ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=73.864283ms Oct 16 22:19:25 volumio volumio5-onboarding[1506]: time=2026-10-16T22:19:25.564+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.50.2.152:37816 @ 0xc00053a810" latency=-366.117153ms timeout=10s endpoint=http://pushupdates.volumio.org duration=83.083244ms Oct 16 22:19:25 volumio volumio5-onboarding[1506]: time=2026-10-16T22:19:25.606+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.50.2.152:37816 @ 0xc00053a810" latency=-366.117153ms timeout=10s endpoint=http://cddb.volumio.org duration=126.365268ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 16 22:19:25 volumio volumio5-onboarding[1506]: time=2026-10-16T22:19:25.666+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.50.2.152:37816 @ 0xc00053a810" latency=-366.117153ms timeout=10s endpoint=https://www.googleapis.com duration=186.489623ms Oct 16 22:19:25 volumio volumio5-onboarding[1506]: time=2026-10-16T22:19:25.691+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.50.2.152:37816 @ 0xc00053a810" latency=-366.117153ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=210.26001ms Oct 16 22:19:25 volumio volumio5-onboarding[1506]: time=2026-10-16T22:19:25.694+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.50.2.152:37816 @ 0xc00053a810" latency=-366.117153ms timeout=10s endpoint=https://functions.volumio.cloud duration=213.53816ms Oct 16 22:19:25 volumio volumio5-onboarding[1506]: time=2026-10-16T22:19:25.694+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.50.2.152:37816 @ 0xc00053a810" latency=-366.117153ms timeout=10s endpoint=https://functions.volumio.cloud duration=214.196126ms Oct 16 22:19:25 volumio volumio5-onboarding[1506]: time=2026-10-16T22:19:25.710+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.50.2.152:37816 @ 0xc00053a810" latency=-366.117153ms timeout=10s endpoint=https://securetoken.googleapis.com duration=229.46949ms Oct 16 22:19:25 volumio volumio5-onboarding[1506]: time=2026-10-16T22:19:25.729+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.50.2.152:37816 @ 0xc00053a810" latency=-366.117153ms timeout=10s endpoint=http://plugins.volumio.org duration=248.167195ms Oct 16 22:19:25 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 22:19:25 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 22:19:25 volumio volumio[1123]: info: Discovery: Getting this device information Oct 16 22:19:25 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Oct 16 22:19:25 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 22:19:25 volumio volumio[1123]: verbose: New Socket.io Connection to 10.50.2.159:3000 from 10.50.2.152 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Oct 16 22:19:25 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 22:19:25 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 22:19:25 volumio volumio5-onboarding[1506]: time=2026-10-16T22:19:25.857+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.50.2.152:37816 @ 0xc00053a810" latency=-366.117153ms timeout=10s endpoint=https://database.volumio.cloud duration=377.048821ms Oct 16 22:19:25 volumio volumio5-onboarding[1506]: time=2026-10-16T22:19:25.984+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.50.2.152:37816 @ 0xc00053a810" latency=-366.117153ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=503.215491ms Oct 16 22:19:26 volumio volumio5-onboarding[1506]: time=2026-10-16T22:19:26.303+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.50.2.152:37816 @ 0xc00053a810" latency=-366.117153ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=822.355736ms Oct 16 22:19:26 volumio volumio5-onboarding[1506]: time=2026-10-16T22:19:26.336+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.50.2.152:37816 @ 0xc00053a810" latency=-366.117153ms timeout=10s endpoint=https://google.com duration=856.225019ms Oct 16 22:19:46 volumio volumio[1123]: Searching all installed plugins Oct 16 22:19:46 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 22:19:46 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: , search Oct 16 22:19:46 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 16 22:19:46 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Oct 16 22:19:46 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Oct 16 22:19:46 volumio volumio[1123]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Oct 16 22:19:46 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 16 22:19:46 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Oct 16 22:19:47 volumio volumio[1123]: info: All search sources collected, pushing search results Oct 16 22:19:58 volumio volumio[1123]: Searching all installed plugins Oct 16 22:19:58 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 22:19:58 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: , search Oct 16 22:19:58 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 16 22:19:58 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Oct 16 22:19:58 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Oct 16 22:19:58 volumio volumio[1123]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Oct 16 22:19:58 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 16 22:19:58 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Oct 16 22:19:58 volumio volumio[1123]: info: All search sources collected, pushing search results Oct 16 22:20:00 volumio volumio[1123]: Searching all installed plugins Oct 16 22:20:00 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 22:20:00 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: , search Oct 16 22:20:00 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 16 22:20:00 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Oct 16 22:20:00 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Oct 16 22:20:00 volumio volumio[1123]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Oct 16 22:20:00 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 16 22:20:00 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Oct 16 22:20:01 volumio volumio[1123]: info: All search sources collected, pushing search results Oct 16 22:20:01 volumio volumio[1123]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 16 22:20:44 volumio volumio[1123]: info: Preload queue cleared Oct 16 22:20:44 volumio volumio[1123]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::ClearQueue Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::stop Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::stPlaybackTimer Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::updateTrackBlock Oct 16 22:20:44 volumio volumio[1123]: info: CorePlayQueue::getTrackBlock Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::pushState Oct 16 22:20:44 volumio volumio[1123]: info: CorePlayQueue::getTrack 0 Oct 16 22:20:44 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:20:44 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Oct 16 22:20:44 volumio volumio[1123]: info: MRS: Pushing multiroomSync output update for this device Oct 16 22:20:44 volumio volumio[1123]: info: MRS: Pushing multiroomSync output Oct 16 22:20:44 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Oct 16 22:20:44 volumio volumio[1123]: info: CorePlayQueue::getTrack 0 Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::serviceStop Oct 16 22:20:44 volumio volumio[1123]: info: CorePlayQueue::getTrack 0 Oct 16 22:20:44 volumio volumio[1123]: info: CoreCommandRouter::serviceStop Oct 16 22:20:44 volumio volumio[1123]: info: [ytmusic] (AutoplayManager) Removed mpdStateListener Oct 16 22:20:44 volumio volumio[1123]: info: [ytmusic] (AutoplayManager) Removed volumioStateListener Oct 16 22:20:44 volumio volumio[1123]: info: [ytmusic] (AutoplayManager) Disabled Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 22:20:44 volumio volumio[1123]: info: ControllerMpd::stop Oct 16 22:20:44 volumio volumio[1123]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 22:20:44 volumio volumio[1123]: info: CorePlayQueue::clearPlayQueue Oct 16 22:20:44 volumio volumio[1123]: info: CorePlayQueue::saveQueue Oct 16 22:20:44 volumio volumio[1123]: info: CoreCommandRouter::volumioPushQueue Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::addQueueItems Oct 16 22:20:44 volumio volumio[1123]: info: CorePlayQueue::addQueueItems Oct 16 22:20:44 volumio volumio[1123]: info: Preload queue cleared Oct 16 22:20:44 volumio volumio[1123]: info: Adding Item to queue: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Madness%22%2C%22artist%22%3A%22Muse%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2Fp0Acx9zzSc4Hd5tIBzwHceLToIapGSaWpbcCGXEwN161kQmrqDJ0c-yTyptapdTUAJK_BVciBhNibsw-%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22__oF3wM5s-k%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%22videoId%22%3A%22__oF3wM5s-k%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Oct 16 22:20:44 volumio volumio[1123]: info: Exploding uri ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Madness%22%2C%22artist%22%3A%22Muse%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2Fp0Acx9zzSc4Hd5tIBzwHceLToIapGSaWpbcCGXEwN161kQmrqDJ0c-yTyptapdTUAJK_BVciBhNibsw-%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22__oF3wM5s-k%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%22videoId%22%3A%22__oF3wM5s-k%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D in service ytmusic Oct 16 22:20:44 volumio volumio[1123]: info: [ytmusic-browse] explodeUri: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Madness%22%2C%22artist%22%3A%22Muse%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2Fp0Acx9zzSc4Hd5tIBzwHceLToIapGSaWpbcCGXEwN161kQmrqDJ0c-yTyptapdTUAJK_BVciBhNibsw-%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22__oF3wM5s-k%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%22videoId%22%3A%22__oF3wM5s-k%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Oct 16 22:20:44 volumio volumio5-onboarding[1506]: time=2026-10-16T22:20:44.471+02:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.2.152:37816 @ 0xc00053a810" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 22:20:44 volumio volumio5-onboarding[1506]: time=2026-10-16T22:20:44.471+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.2.152:37816 @ 0xc00053a810" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22%C5%9Awiat%C5%82o%22%2C%22artist%22%3A%22Natalia%20%C5%9Alusarczyk%22%2C%22album%22%3A%22%C5%9Awiat%C5%82o%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2Fn3LBNNvaIUSwiKCfp_kwqfWz40HAm8I4wfu3DmViP071uUCbyQattK04U99hx6fCni27fSpZkQWFMz5H%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22mGtYdOSj3Sc%22%2C%22playlistId%22%3A%22OLAK5uy_ltjs7gK20uphXmzdjAu2cL3sbU1fIbgVE%22%2C%22index%22%3A0%2C%22playlistSetVideoId%22%3A%223D0C8FC9C40694A3%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_ltjs7gK20uphXmzdjAu2cL3sbU1fIbgVE%22%2C%22videoId%22%3A%22mGtYdOSj3Sc%22%7D%7D%7D%7D" title=Światło Oct 16 22:20:44 volumio volumio[1123]: info: CoreCommandRouter::volumioPushQueue Oct 16 22:20:44 volumio volumio[1123]: info: CorePlayQueue::saveQueue Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::updateTrackBlock Oct 16 22:20:44 volumio volumio[1123]: info: CorePlayQueue::getTrackBlock Oct 16 22:20:44 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Oct 16 22:20:44 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 16 22:20:44 volumio volumio[1123]: info: CoreCommandRouter::volumioPlay Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::play index 0 Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::stop Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::play index undefined Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 22:20:44 volumio volumio[1123]: info: CorePlayQueue::getTrack 0 Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::startPlaybackTimer Oct 16 22:20:44 volumio volumio[1123]: info: CorePlayQueue::getTrack 0 Oct 16 22:20:44 volumio volumio[1123]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 22:20:44 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 22:20:44 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 16 22:20:44 volumio volumio[1123]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Madness%22%2C%22artist%22%3A%22Muse%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2Fp0Acx9zzSc4Hd5tIBzwHceLToIapGSaWpbcCGXEwN161kQmrqDJ0c-yTyptapdTUAJK_BVciBhNibsw-%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22__oF3wM5s-k%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%22videoId%22%3A%22__oF3wM5s-k%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Oct 16 22:20:44 volumio volumio[1123]: info: Oct 16 22:20:44 volumio volumio[1123]: ---------------------------- MPD announces state update: player Oct 16 22:20:44 volumio volumio[1123]: info: sendMpdCommand stop took 35 milliseconds Oct 16 22:20:44 volumio volumio[1123]: info: ControllerMpd::getState Oct 16 22:20:44 volumio volumio[1123]: verbose: ControllerMpd::sendMpdCommand status Oct 16 22:20:44 volumio volumio[1123]: info: sendMpdCommand status took 3 milliseconds Oct 16 22:20:44 volumio volumio[1123]: verbose: ControllerMpd::parseState Oct 16 22:20:44 volumio volumio[1123]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 22:20:44 volumio volumio[1123]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 16 22:20:44 volumio volumio[1123]: verbose: ControllerMpd::parseTrackInfo Oct 16 22:20:44 volumio volumio[1123]: info: ControllerMpd::pushState Oct 16 22:20:44 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::pushState Oct 16 22:20:44 volumio volumio[1123]: info: CorePlayQueue::getTrack 0 Oct 16 22:20:44 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:20:44 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Oct 16 22:20:44 volumio volumio[1123]: info: MRS: Pushing multiroomSync output update for this device Oct 16 22:20:44 volumio volumio[1123]: info: MRS: Pushing multiroomSync output Oct 16 22:20:44 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Oct 16 22:20:44 volumio volumio[1123]: info: CorePlayQueue::getTrack 0 Oct 16 22:20:44 volumio volumio[1123]: info: CorePlayQueue::getTrack 0 Oct 16 22:20:44 volumio volumio[1123]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Oct 16 22:20:44 volumio volumio5-onboarding[1506]: time=2026-10-16T22:20:44.519+02:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.2.152:37816 @ 0xc00053a810" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 22:20:44 volumio volumio5-onboarding[1506]: time=2026-10-16T22:20:44.519+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.2.152:37816 @ 0xc00053a810" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Madness%22%2C%22artist%22%3A%22Muse%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2Fp0Acx9zzSc4Hd5tIBzwHceLToIapGSaWpbcCGXEwN161kQmrqDJ0c-yTyptapdTUAJK_BVciBhNibsw-%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22__oF3wM5s-k%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%22videoId%22%3A%22__oF3wM5s-k%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D" title=Madness Oct 16 22:20:44 volumio volumio[1123]: info: ------------------------------ 17ms Oct 16 22:20:44 volumio volumio[1123]: info: [ytmusic] Obtained PO token for video #__oF3wM5s-k: Ml1N0j5wzCCz3U2UMWtl6bidB6G8JlJvN5cQXzURBJhRsbQBSVZSYiC13J7Cu-S2x04AuqLfOTFkOTAOR-CbhdMcYww66sqRbLKiHY_hXKDfB5HoCnIsmzCwG-Ab7ew= Oct 16 22:20:44 volumio volumio[1123]: info: [ytmusic] (Madness) validating stream URL "https://rr2---sn-f5f7lnly.googlevideo.com/videoplayback?expire=1792203644&ei=HIfSap3aI7mB6LUP8e7PqAs&ip=46.148.149.98&id=o-AJBFw_pwhQJSCWXIspdpCykK1-oNoxWl4116dZ-Semob&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=547&met=1792182044%2C&mh=YJ&mm=18%2C26&mn=sn-f5f7lnly%2Csn-i5heen7s&ms=aub%2Conr&mv=m&mvi=2&pl=22&rms=aub%2Caub&gcr=pl&initcwndbps=2226000&bui=AWzQHwr0OLcV2T4ASanTzapm4RpWf-Br9QHAfkxs_E8uSAMuBx3lAtl_AYfI4fFwbjtroi534ZQm82H0&spc=mpRQ_xP2WewoKKam4dUPKQckWefpCXQo3Lr4jd02kMt59pjI7Y8z&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IWOGdYqkzmjy6HgocMzk0fUZ&rqh=1&gir=yes&clen=4570738&dur=281.061&lmt=1714701609695172&mt=1792181622&fvip=3&epbp=1&keepalive=yes&fexp=51565116%2C52184231&c=WEB_REMIX&sefc=1&txp=5532434&n=fATt95ERo0buyw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgBPCoJSXOu6P886clJ7b-YUXL19cMuGLjW7iAwQCW3RICIQD_5MH8en0KEfwVC_P2LRpHAu0PwfAvhDhJAiAolX8KUg%3D%3D&sig=AE0s2JYwRAIgDoUjlSl3l1-Y4b-1uht1qcPUEVZBj-MSZlHS2mnOvV0CIAYEAeU6Mb_BYL7kJFH0iO_zZ0DbceuifRNxvZI9jP3-&cver=1.20250219.01.00&pot=Ml1N0j5wzCCz3U2UMWtl6bidB6G8JlJvN5cQXzURBJhRsbQBSVZSYiC13J7Cu-S2x04AuqLfOTFkOTAOR-CbhdMcYww66sqRbLKiHY_hXKDfB5HoCnIsmzCwG-Ab7ew%3D"... Oct 16 22:20:44 volumio volumio[1123]: info: [ytmusic] (Madness) stream validated in 0.052s. Oct 16 22:20:44 volumio volumio[1123]: info: [ytmusic] (AutoplayManager) Added volumioStateListener Oct 16 22:20:44 volumio volumio[1123]: info: [ytmusic] (AutoplayManager) Enabled Oct 16 22:20:44 volumio volumio[1123]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 22:20:44 volumio volumio[1123]: info: sendMpdCommand stop took 1 milliseconds Oct 16 22:20:44 volumio volumio[1123]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 22:20:44 volumio volumio[1123]: info: Oct 16 22:20:44 volumio volumio[1123]: ---------------------------- MPD announces system playlist update Oct 16 22:20:44 volumio volumio[1123]: info: Ignoring MPD Status Update Oct 16 22:20:44 volumio volumio[1123]: info: sendMpdCommand clear took 0 milliseconds Oct 16 22:20:44 volumio volumio[1123]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-f5f7lnly.googlevideo.com/videoplayback?expire=1792203644&ei=HIfSap3aI7mB6LUP8e7PqAs&ip=46.148.149.98&id=o-AJBFw_pwhQJSCWXIspdpCykK1-oNoxWl4116dZ-Semob&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=547&met=1792182044%2C&mh=YJ&mm=18%2C26&mn=sn-f5f7lnly%2Csn-i5heen7s&ms=aub%2Conr&mv=m&mvi=2&pl=22&rms=aub%2Caub&gcr=pl&initcwndbps=2226000&bui=AWzQHwr0OLcV2T4ASanTzapm4RpWf-Br9QHAfkxs_E8uSAMuBx3lAtl_AYfI4fFwbjtroi534ZQm82H0&spc=mpRQ_xP2WewoKKam4dUPKQckWefpCXQo3Lr4jd02kMt59pjI7Y8z&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IWOGdYqkzmjy6HgocMzk0fUZ&rqh=1&gir=yes&clen=4570738&dur=281.061&lmt=1714701609695172&mt=1792181622&fvip=3&epbp=1&keepalive=yes&fexp=51565116%2C52184231&c=WEB_REMIX&sefc=1&txp=5532434&n=fATt95ERo0buyw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgBPCoJSXOu6P886clJ7b-YUXL19cMuGLjW7iAwQCW3RICIQD_5MH8en0KEfwVC_P2LRpHAu0PwfAvhDhJAiAolX8KUg%3D%3D&sig=AE0s2JYwRAIgDoUjlSl3l1-Y4b-1uht1qcPUEVZBj-MSZlHS2mnOvV0CIAYEAeU6Mb_BYL7kJFH0iO_zZ0DbceuifRNxvZI9jP3-&cver=1.20250219.01.00&pot=Ml1N0j5wzCCz3U2UMWtl6bidB6G8JlJvN5cQXzURBJhRsbQBSVZSYiC13J7Cu-S2x04AuqLfOTFkOTAOR-CbhdMcYww66sqRbLKiHY_hXKDfB5HoCnIsmzCwG-Ab7ew%3D&t.YouTube" Oct 16 22:20:44 volumio volumio[1123]: error: updateQueue error: null Oct 16 22:20:44 volumio volumio[1123]: info: ------------------------------ 1ms Oct 16 22:20:44 volumio volumio[1123]: info: Oct 16 22:20:44 volumio volumio[1123]: ---------------------------- MPD announces system playlist update Oct 16 22:20:44 volumio volumio[1123]: info: Ignoring MPD Status Update Oct 16 22:20:44 volumio volumio[1123]: info: sendMpdCommand addid "https://rr2---sn-f5f7lnly.googlevideo.com/videoplayback?expire=1792203644&ei=HIfSap3aI7mB6LUP8e7PqAs&ip=46.148.149.98&id=o-AJBFw_pwhQJSCWXIspdpCykK1-oNoxWl4116dZ-Semob&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=547&met=1792182044%2C&mh=YJ&mm=18%2C26&mn=sn-f5f7lnly%2Csn-i5heen7s&ms=aub%2Conr&mv=m&mvi=2&pl=22&rms=aub%2Caub&gcr=pl&initcwndbps=2226000&bui=AWzQHwr0OLcV2T4ASanTzapm4RpWf-Br9QHAfkxs_E8uSAMuBx3lAtl_AYfI4fFwbjtroi534ZQm82H0&spc=mpRQ_xP2WewoKKam4dUPKQckWefpCXQo3Lr4jd02kMt59pjI7Y8z&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IWOGdYqkzmjy6HgocMzk0fUZ&rqh=1&gir=yes&clen=4570738&dur=281.061&lmt=1714701609695172&mt=1792181622&fvip=3&epbp=1&keepalive=yes&fexp=51565116%2C52184231&c=WEB_REMIX&sefc=1&txp=5532434&n=fATt95ERo0buyw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgBPCoJSXOu6P886clJ7b-YUXL19cMuGLjW7iAwQCW3RICIQD_5MH8en0KEfwVC_P2LRpHAu0PwfAvhDhJAiAolX8KUg%3D%3D&sig=AE0s2JYwRAIgDoUjlSl3l1-Y4b-1uht1qcPUEVZBj-MSZlHS2mnOvV0CIAYEAeU6Mb_BYL7kJFH0iO_zZ0DbceuifRNxvZI9jP3-&cver=1.20250219.01.00&pot=Ml1N0j5wzCCz3U2UMWtl6bidB6G8JlJvN5cQXzURBJhRsbQBSVZSYiC13J7Cu-S2x04AuqLfOTFkOTAOR-CbhdMcYww66sqRbLKiHY_hXKDfB5HoCnIsmzCwG-Ab7ew%3D&t.YouTube" took 0 milliseconds Oct 16 22:20:44 volumio volumio[1123]: verbose: MPD COMMAND [object Object] Oct 16 22:20:44 volumio volumio[1123]: verbose: MPD COMMAND [object Object] Oct 16 22:20:44 volumio volumio[1123]: verbose: MPD COMMAND [object Object] Oct 16 22:20:44 volumio volumio[1123]: info: Oct 16 22:20:44 volumio volumio[1123]: ---------------------------- MPD announces system playlist update Oct 16 22:20:44 volumio volumio[1123]: info: Ignoring MPD Status Update Oct 16 22:20:44 volumio volumio[1123]: info: ------------------------------ 1ms Oct 16 22:20:44 volumio volumio[1123]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 22:20:44 volumio volumio[1123]: verbose: ControllerMpd::sendMpdCommand play Oct 16 22:20:44 volumio volumio[1123]: info: ------------------------------ 1ms Oct 16 22:20:44 volumio volumio[1123]: info: sendMpdCommand play took 0 milliseconds Oct 16 22:20:45 volumio volumio[1123]: info: Oct 16 22:20:45 volumio volumio[1123]: ---------------------------- MPD announces state update: player Oct 16 22:20:45 volumio volumio[1123]: info: ControllerMpd::getState Oct 16 22:20:45 volumio volumio[1123]: verbose: ControllerMpd::sendMpdCommand status Oct 16 22:20:45 volumio volumio[1123]: info: Oct 16 22:20:45 volumio volumio[1123]: ---------------------------- MPD announces state update: player Oct 16 22:20:45 volumio volumio[1123]: info: ControllerMpd::getState Oct 16 22:20:45 volumio volumio[1123]: verbose: ControllerMpd::sendMpdCommand status Oct 16 22:20:45 volumio volumio[1123]: info: sendMpdCommand status took 1 milliseconds Oct 16 22:20:45 volumio volumio[1123]: verbose: ControllerMpd::parseState Oct 16 22:20:45 volumio volumio[1123]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 22:20:45 volumio volumio[1123]: info: sendMpdCommand status took 1 milliseconds Oct 16 22:20:45 volumio volumio[1123]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 22:20:45 volumio volumio[1123]: verbose: ControllerMpd::parseState Oct 16 22:20:45 volumio volumio[1123]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 22:20:45 volumio volumio[1123]: verbose: ControllerMpd::parseTrackInfo Oct 16 22:20:45 volumio volumio[1123]: info: ControllerMpd::pushState Oct 16 22:20:45 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Oct 16 22:20:45 volumio volumio[1123]: info: CorePlayQueue::getTrack 0 Oct 16 22:20:45 volumio volumio[1123]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Madness","artist":"Muse","album":"The 2nd Law","uri":"https://rr2---sn-f5f7lnly.googlevideo.com/videoplayback?expire=1792203644&ei=HIfSap3aI7mB6LUP8e7PqAs&ip=46.148.149.98&id=o-AJBFw_pwhQJSCWXIspdpCykK1-oNoxWl4116dZ-Semob&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=547&met=1792182044%2C&mh=YJ&mm=18%2C26&mn=sn-f5f7lnly%2Csn-i5heen7s&ms=aub%2Conr&mv=m&mvi=2&pl=22&rms=aub%2Caub&gcr=pl&initcwndbps=2226000&bui=AWzQHwr0OLcV2T4ASanTzapm4RpWf-Br9QHAfkxs_E8uSAMuBx3lAtl_AYfI4fFwbjtroi534ZQm82H0&spc=mpRQ_xP2WewoKKam4dUPKQckWefpCXQo3Lr4jd02kMt59pjI7Y8z&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IWOGdYqkzmjy6HgocMzk0fUZ&rqh=1&gir=yes&clen=4570738&dur=281.061&lmt=1714701609695172&mt=1792181622&fvip=3&epbp=1&keepalive=yes&fexp=51565116%2C52184231&c=WEB_REMIX&sefc=1&txp=5532434&n=fATt95ERo0buyw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgBPCoJSXOu6P886clJ7b-YUXL19cMuGLjW7iAwQCW3RICIQD_5MH8en0KEfwVC_P2LRpHAu0PwfAvhDhJAiAolX8KUg%3D%3D&sig=AE0s2JYwRAIgDoUjlSl3l1-Y4b-1uht1qcPUEVZBj-MSZlHS2mnOvV0CIAYEAeU6Mb_BYL7kJFH0iO_zZ0DbceuifRNxvZI9jP3-&cver=1.20250219.01.00&pot=Ml1N0j5wzCCz3U2UMWtl6bidB6G8JlJvN5cQXzURBJhRsbQBSVZSYiC13J7Cu-S2x04AuqLfOTFkOTAOR-CbhdMcYww66sqRbLKiHY_hXKDfB5HoCnIsmzCwG-Ab7ew%3D&t.YouTube","trackType":"YouTube"} Oct 16 22:20:45 volumio volumio[1123]: verbose: CURRENT POSITION 0 Oct 16 22:20:45 volumio volumio[1123]: info: CoreStateMachine::syncState stateService play Oct 16 22:20:45 volumio volumio[1123]: info: CoreStateMachine::syncState currentStatus stop Oct 16 22:20:45 volumio volumio[1123]: info: ------------------------------ 3ms Oct 16 22:20:45 volumio volumio[1123]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 22:20:45 volumio volumio[1123]: verbose: ControllerMpd::parseTrackInfo Oct 16 22:20:45 volumio volumio[1123]: info: ControllerMpd::pushState Oct 16 22:20:45 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Oct 16 22:20:45 volumio volumio[1123]: info: CorePlayQueue::getTrack 0 Oct 16 22:20:45 volumio volumio[1123]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Madness","artist":"Muse","album":"The 2nd Law","uri":"https://rr2---sn-f5f7lnly.googlevideo.com/videoplayback?expire=1792203644&ei=HIfSap3aI7mB6LUP8e7PqAs&ip=46.148.149.98&id=o-AJBFw_pwhQJSCWXIspdpCykK1-oNoxWl4116dZ-Semob&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=547&met=1792182044%2C&mh=YJ&mm=18%2C26&mn=sn-f5f7lnly%2Csn-i5heen7s&ms=aub%2Conr&mv=m&mvi=2&pl=22&rms=aub%2Caub&gcr=pl&initcwndbps=2226000&bui=AWzQHwr0OLcV2T4ASanTzapm4RpWf-Br9QHAfkxs_E8uSAMuBx3lAtl_AYfI4fFwbjtroi534ZQm82H0&spc=mpRQ_xP2WewoKKam4dUPKQckWefpCXQo3Lr4jd02kMt59pjI7Y8z&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IWOGdYqkzmjy6HgocMzk0fUZ&rqh=1&gir=yes&clen=4570738&dur=281.061&lmt=1714701609695172&mt=1792181622&fvip=3&epbp=1&keepalive=yes&fexp=51565116%2C52184231&c=WEB_REMIX&sefc=1&txp=5532434&n=fATt95ERo0buyw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgBPCoJSXOu6P886clJ7b-YUXL19cMuGLjW7iAwQCW3RICIQD_5MH8en0KEfwVC_P2LRpHAu0PwfAvhDhJAiAolX8KUg%3D%3D&sig=AE0s2JYwRAIgDoUjlSl3l1-Y4b-1uht1qcPUEVZBj-MSZlHS2mnOvV0CIAYEAeU6Mb_BYL7kJFH0iO_zZ0DbceuifRNxvZI9jP3-&cver=1.20250219.01.00&pot=Ml1N0j5wzCCz3U2UMWtl6bidB6G8JlJvN5cQXzURBJhRsbQBSVZSYiC13J7Cu-S2x04AuqLfOTFkOTAOR-CbhdMcYww66sqRbLKiHY_hXKDfB5HoCnIsmzCwG-Ab7ew%3D&t.YouTube","trackType":"YouTube"} Oct 16 22:20:45 volumio volumio[1123]: verbose: CURRENT POSITION 0 Oct 16 22:20:45 volumio volumio[1123]: info: CoreStateMachine::syncState stateService play Oct 16 22:20:45 volumio volumio[1123]: info: CoreStateMachine::syncState currentStatus play Oct 16 22:20:45 volumio volumio[1123]: info: Received an update from plugin. extracting info from payload Oct 16 22:20:45 volumio volumio[1123]: info: CoreStateMachine::pushState Oct 16 22:20:45 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:20:45 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Oct 16 22:20:45 volumio volumio[1123]: info: MRS: Pushing multiroomSync output update for this device Oct 16 22:20:45 volumio volumio[1123]: info: MRS: Pushing multiroomSync output Oct 16 22:20:45 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Oct 16 22:20:45 volumio volumio[1123]: info: [ytmusic] (AutoplayManager) Added mpdStateListener Oct 16 22:20:45 volumio volumio[1123]: info: CoreStateMachine::pushState Oct 16 22:20:45 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Oct 16 22:20:45 volumio volumio[1123]: info: MRS: Pushing multiroomSync output update for this device Oct 16 22:20:45 volumio volumio[1123]: info: MRS: Pushing multiroomSync output Oct 16 22:20:45 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Oct 16 22:20:45 volumio volumio5-onboarding[1506]: time=2026-10-16T22:20:45.221+02:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.2.152:37816 @ 0xc00053a810" state=STATUS_PLAYING positionMs=0 volume=100 Oct 16 22:20:45 volumio volumio5-onboarding[1506]: time=2026-10-16T22:20:45.221+02:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.2.152:37816 @ 0xc00053a810" state=STATUS_PLAYING positionMs=0 volume=100 Oct 16 22:20:45 volumio volumio5-onboarding[1506]: time=2026-10-16T22:20:45.221+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.2.152:37816 @ 0xc00053a810" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Madness%22%2C%22artist%22%3A%22Muse%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2Fp0Acx9zzSc4Hd5tIBzwHceLToIapGSaWpbcCGXEwN161kQmrqDJ0c-yTyptapdTUAJK_BVciBhNibsw-%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22__oF3wM5s-k%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%22videoId%22%3A%22__oF3wM5s-k%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D" title=Madness Oct 16 22:20:45 volumio volumio5-onboarding[1506]: time=2026-10-16T22:20:45.221+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.2.152:37816 @ 0xc00053a810" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Madness%22%2C%22artist%22%3A%22Muse%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2Fp0Acx9zzSc4Hd5tIBzwHceLToIapGSaWpbcCGXEwN161kQmrqDJ0c-yTyptapdTUAJK_BVciBhNibsw-%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22__oF3wM5s-k%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%22videoId%22%3A%22__oF3wM5s-k%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D" title=Madness Oct 16 22:20:45 volumio volumio[1123]: info: ------------------------------ 10ms Oct 16 22:20:53 volumio volumio[1123]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 22:20:53 volumio volumio[1123]: TypeError: Cannot read properties of null (reading 'slice') Oct 16 22:20:53 volumio volumio[1123]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Oct 16 22:20:53 volumio volumio[1123]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Oct 16 22:20:53 volumio volumio[1123]: at Object.onceWrapper (node:events:629:26) Oct 16 22:20:53 volumio volumio[1123]: at ClientRequest.emit (node:events:514:28) Oct 16 22:20:53 volumio volumio[1123]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Oct 16 22:20:53 volumio volumio[1123]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Oct 16 22:20:53 volumio volumio[1123]: at TLSSocket.socketOnData (node:_http_client:535:22) Oct 16 22:20:53 volumio volumio[1123]: at TLSSocket.emit (node:events:514:28) Oct 16 22:20:53 volumio volumio[1123]: at addChunk (node:internal/streams/readable:343:12) Oct 16 22:20:53 volumio volumio[1123]: at readableAddChunk (node:internal/streams/readable:316:9) Oct 16 22:20:53 volumio volumio[1123]: at Readable.push (node:internal/streams/readable:253:10) Oct 16 22:20:53 volumio volumio[1123]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Oct 16 22:20:53 volumio volumio[1123]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 22:20:53 volumio sudo[45392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-16 22:19' Oct 16 22:20:53 volumio sudo[45392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"