Mar 26 10:09:09 volumio volumio[1210]: info: Preload queue cleared Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::ClearQueue Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::stop Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::stPlaybackTimer Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::updateTrackBlock Mar 26 10:09:09 volumio volumio[1210]: info: CorePlayQueue::getTrackBlock Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:09:09 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:09:09 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:09:09 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:09:09 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::serviceStop Mar 26 10:09:09 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::serviceStop Mar 26 10:09:09 volumio volumio[1210]: info: [1774516149149] ControllerWebradio::stop Mar 26 10:09:09 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 10:09:09 volumio volumio[1210]: info: CorePlayQueue::clearPlayQueue Mar 26 10:09:09 volumio volumio[1210]: info: CorePlayQueue::saveQueue Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::volumioPushQueue Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::addQueueItems Mar 26 10:09:09 volumio volumio[1210]: info: CorePlayQueue::addQueueItems Mar 26 10:09:09 volumio volumio[1210]: info: Preload queue cleared Mar 26 10:09:09 volumio volumio[1210]: info: Adding Item to queue: http://stream.radioparadise.com/flacm Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 26 10:09:09 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:09.154+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_STOPPED positionMs=0 volume=100 Mar 26 10:09:09 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:09.154+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=https://ibizasonica.streaming-pro.com:8011/sonicaclub title="Ibiza Sonica" Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::volumioPushQueue Mar 26 10:09:09 volumio volumio[1210]: info: CorePlayQueue::saveQueue Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::updateTrackBlock Mar 26 10:09:09 volumio volumio[1210]: info: CorePlayQueue::getTrackBlock Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:09:09 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::volumioPlay Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::play index 0 Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::stop Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::play index undefined Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:09:09 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::startPlaybackTimer Mar 26 10:09:09 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 26 10:09:09 volumio volumio[1210]: info: [1774516149190] ControllerWebradio::clearAddPlayTrack Mar 26 10:09:09 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 10:09:09 volumio volumio[1210]: info: Mar 26 10:09:09 volumio volumio[1210]: ---------------------------- MPD announces state update: player Mar 26 10:09:09 volumio volumio[1210]: info: sendMpdCommand stop took 63 milliseconds Mar 26 10:09:09 volumio volumio[1210]: info: ControllerMpd::getState Mar 26 10:09:09 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:09:09 volumio volumio[1210]: info: sendMpdCommand stop took 22 milliseconds Mar 26 10:09:09 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 10:09:09 volumio volumio[1210]: info: Mar 26 10:09:09 volumio volumio[1210]: ---------------------------- MPD announces system playlist update Mar 26 10:09:09 volumio volumio[1210]: info: Ignoring MPD Status Update Mar 26 10:09:09 volumio volumio[1210]: info: sendMpdCommand status took 2 milliseconds Mar 26 10:09:09 volumio volumio[1210]: info: sendMpdCommand clear took 1 milliseconds Mar 26 10:09:09 volumio volumio[1210]: verbose: ControllerMpd::parseState Mar 26 10:09:09 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:09:09 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radioparadise.com/flacm" Mar 26 10:09:09 volumio volumio[1210]: error: updateQueue error: null Mar 26 10:09:09 volumio volumio[1210]: info: ------------------------------ 18ms Mar 26 10:09:09 volumio volumio[1210]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 26 10:09:09 volumio volumio[1210]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:09:09 volumio volumio[1210]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 26 10:09:09 volumio volumio[1210]: info: ------------------------------ 21ms Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 10:09:09 volumio volumio[1210]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Ibiza Sonica","artist":"","uri":"https://ibizasonica.streaming-pro.com:8011/sonicaclub","service":""} Mar 26 10:09:09 volumio volumio[1210]: error: [now-playing] Caught error in callback of MetdataAPI.#getFetchPromise(): Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:09:09 volumio volumio[1210]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Kon info niet ophalen: Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:09:09 volumio volumio[1210]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:180:15) Mar 26 10:09:09 volumio volumio[1210]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 10:09:09 volumio volumio[1210]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Mar 26 10:09:09 volumio volumio[1210]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:126:28) Mar 26 10:09:09 volumio volumio[1210]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 26 10:09:09 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/flacm" Mar 26 10:09:09 volumio volumio[1210]: info: Mar 26 10:09:09 volumio volumio[1210]: ---------------------------- MPD announces system playlist update Mar 26 10:09:09 volumio volumio[1210]: info: Ignoring MPD Status Update Mar 26 10:09:09 volumio volumio[1210]: info: sendMpdCommand add "http://stream.radioparadise.com/flacm" took 3 milliseconds Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 26 10:09:09 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand play Mar 26 10:09:09 volumio volumio[1210]: info: ------------------------------ 2ms Mar 26 10:09:09 volumio volumio[1210]: info: sendMpdCommand play took 3 milliseconds Mar 26 10:09:09 volumio volumio[1210]: info: Mar 26 10:09:09 volumio volumio[1210]: ---------------------------- MPD announces state update: player Mar 26 10:09:09 volumio volumio[1210]: info: ControllerMpd::getState Mar 26 10:09:09 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:09:09 volumio volumio[1210]: info: Mar 26 10:09:09 volumio volumio[1210]: ---------------------------- MPD announces state update: player Mar 26 10:09:09 volumio volumio[1210]: info: sendMpdCommand status took 2 milliseconds Mar 26 10:09:09 volumio volumio[1210]: info: ControllerMpd::getState Mar 26 10:09:09 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:09:09 volumio volumio[1210]: verbose: ControllerMpd::parseState Mar 26 10:09:09 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:09:09 volumio volumio[1210]: info: sendMpdCommand status took 3 milliseconds Mar 26 10:09:09 volumio volumio[1210]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 26 10:09:09 volumio volumio[1210]: verbose: ControllerMpd::parseState Mar 26 10:09:09 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:09:09 volumio volumio[1210]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:09:09 volumio volumio[1210]: info: ControllerMpd::pushState Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::servicePushState Mar 26 10:09:09 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:09 volumio volumio[1210]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Mar 26 10:09:09 volumio volumio[1210]: verbose: CURRENT POSITION 0 Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::syncState stateService play Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::syncState currentStatus stop Mar 26 10:09:09 volumio volumio[1210]: info: ------------------------------ 9ms Mar 26 10:09:09 volumio volumio[1210]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 26 10:09:09 volumio volumio[1210]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:09:09 volumio volumio[1210]: info: ControllerMpd::pushState Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::servicePushState Mar 26 10:09:09 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:09 volumio volumio[1210]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Mar 26 10:09:09 volumio volumio[1210]: verbose: CURRENT POSITION 0 Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::syncState stateService play Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::syncState currentStatus play Mar 26 10:09:09 volumio volumio[1210]: info: Received an update from plugin. extracting info from payload Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:09:09 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:09:09 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:09:09 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:09:09 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:09:09 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:09:09 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:09:09 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:09.495+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_PLAYING positionMs=0 volume=100 Mar 26 10:09:09 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:09.497+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=http://stream.radioparadise.com/flacm title="Radio Paradise (FLAC+)" Mar 26 10:09:09 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:09.498+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_PLAYING positionMs=0 volume=100 Mar 26 10:09:09 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:09.498+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=http://stream.radioparadise.com/flacm title="Radio Paradise (FLAC+)" Mar 26 10:09:09 volumio volumio[1210]: info: ------------------------------ 88ms Mar 26 10:09:09 volumio volumio[1210]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Radio Paradise (FLAC+) Mar 26 10:09:09 volumio volumio[1210]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Radio Paradise (FLAC+) Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 10:09:09 volumio volumio[1210]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Radio Paradise (FLAC+)","artist":"","album":"null","duration":"0","uri":"http://stream.radioparadise.com/flacm","service":""} Mar 26 10:09:09 volumio volumio[1210]: error: [now-playing] Caught error in callback of MetdataAPI.#getFetchPromise(): Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:09:09 volumio volumio[1210]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Kon info niet ophalen: Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:09:09 volumio volumio[1210]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:180:15) Mar 26 10:09:09 volumio volumio[1210]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 10:09:09 volumio volumio[1210]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Mar 26 10:09:09 volumio volumio[1210]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:126:28) Mar 26 10:09:09 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 10:09:09 volumio volumio[1210]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Radio Paradise (FLAC+)","artist":"null","album":"null","duration":"0","uri":"http://stream.radioparadise.com/flacm","service":""} Mar 26 10:09:09 volumio volumio[1210]: error: [now-playing] Caught error in callback of MetdataAPI.#getFetchPromise(): Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:09:09 volumio volumio[1210]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Kon info niet ophalen: Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:09:09 volumio volumio[1210]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:180:15) Mar 26 10:09:09 volumio volumio[1210]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 10:09:09 volumio volumio[1210]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Mar 26 10:09:09 volumio volumio[1210]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:126:28) Mar 26 10:09:09 volumio volumio[1210]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 26 10:09:10 volumio volumio[1210]: info: Mar 26 10:09:10 volumio volumio[1210]: ---------------------------- MPD announces system playlist update Mar 26 10:09:10 volumio volumio[1210]: info: Ignoring MPD Status Update Mar 26 10:09:10 volumio volumio[1210]: info: Mar 26 10:09:10 volumio volumio[1210]: ---------------------------- MPD announces state update: player Mar 26 10:09:10 volumio volumio[1210]: info: ControllerMpd::getState Mar 26 10:09:10 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:09:10 volumio volumio[1210]: info: ------------------------------ 4ms Mar 26 10:09:10 volumio volumio[1210]: info: sendMpdCommand status took 3 milliseconds Mar 26 10:09:10 volumio volumio[1210]: verbose: ControllerMpd::parseState Mar 26 10:09:10 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:09:10 volumio volumio[1210]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 26 10:09:10 volumio volumio[1210]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:09:10 volumio volumio[1210]: info: ControllerMpd::pushState Mar 26 10:09:10 volumio volumio[1210]: info: CoreCommandRouter::servicePushState Mar 26 10:09:10 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:10 volumio volumio[1210]: verbose: STATE SERVICE {"status":"play","position":0,"seek":389,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":"Radio Paradise (flac)","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Mar 26 10:09:10 volumio volumio[1210]: verbose: CURRENT POSITION 0 Mar 26 10:09:10 volumio volumio[1210]: info: CoreStateMachine::syncState stateService play Mar 26 10:09:10 volumio volumio[1210]: info: CoreStateMachine::syncState currentStatus play Mar 26 10:09:10 volumio volumio[1210]: info: Received an update from plugin. extracting info from payload Mar 26 10:09:10 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:09:10 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:10 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:09:10 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:09:10 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:09:10 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:09:10 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:09:10 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:09:10 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:09:10 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:09:10 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:09:10 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:09:10 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:10.212+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_PLAYING positionMs=607 volume=100 Mar 26 10:09:10 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:10.213+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=http://stream.radioparadise.com/flacm title="Radio Paradise (FLAC+)" Mar 26 10:09:10 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:10.215+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_PLAYING positionMs=607 volume=100 Mar 26 10:09:10 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:10.216+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=http://stream.radioparadise.com/flacm title="Radio Paradise (FLAC+)" Mar 26 10:09:10 volumio volumio[1210]: info: ------------------------------ 64ms Mar 26 10:09:10 volumio volumio[1210]: info: [LastFM] Current track has sufficient metadata: title (Radio Paradise (FLAC+)) and artist (Radio Paradise (flac)) passed on explicitly Mar 26 10:09:10 volumio volumio[1210]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Mar 26 10:09:10 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 10:09:10 volumio volumio[1210]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Radio Paradise (FLAC+)","artist":"Radio Paradise (flac)","album":"null","duration":"0","uri":"http://stream.radioparadise.com/flacm","service":""} Mar 26 10:09:10 volumio volumio[1210]: error: [now-playing] Caught error in callback of MetdataAPI.#getFetchPromise(): Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:09:10 volumio volumio[1210]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Kon info niet ophalen: Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:09:10 volumio volumio[1210]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:180:15) Mar 26 10:09:10 volumio volumio[1210]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 10:09:10 volumio volumio[1210]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Mar 26 10:09:10 volumio volumio[1210]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:126:28) Mar 26 10:09:13 volumio volumio[1210]: info: Mar 26 10:09:13 volumio volumio[1210]: ---------------------------- MPD announces system playlist update Mar 26 10:09:13 volumio volumio[1210]: info: Ignoring MPD Status Update Mar 26 10:09:13 volumio volumio[1210]: info: Mar 26 10:09:13 volumio volumio[1210]: ---------------------------- MPD announces state update: player Mar 26 10:09:13 volumio volumio[1210]: info: ControllerMpd::getState Mar 26 10:09:13 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:09:13 volumio volumio[1210]: info: ------------------------------ 2ms Mar 26 10:09:13 volumio volumio[1210]: info: sendMpdCommand status took 1 milliseconds Mar 26 10:09:13 volumio volumio[1210]: verbose: ControllerMpd::parseState Mar 26 10:09:13 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:09:13 volumio volumio[1210]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 26 10:09:13 volumio volumio[1210]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:09:13 volumio volumio[1210]: info: ControllerMpd::pushState Mar 26 10:09:13 volumio volumio[1210]: info: CoreCommandRouter::servicePushState Mar 26 10:09:13 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:13 volumio volumio[1210]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4337,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Am Kloot - Fingerprints","artist":"Radio Paradise (flac)","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Mar 26 10:09:13 volumio volumio[1210]: verbose: CURRENT POSITION 0 Mar 26 10:09:13 volumio volumio[1210]: info: CoreStateMachine::syncState stateService play Mar 26 10:09:13 volumio volumio[1210]: info: CoreStateMachine::syncState currentStatus play Mar 26 10:09:13 volumio volumio[1210]: info: Received an update from plugin. extracting info from payload Mar 26 10:09:13 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:09:13 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:09:13 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:09:13 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:09:13 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:09:13 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:09:13 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:09:13 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:09:13 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:09:13 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:09:13 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:09:13 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:13.494+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_PLAYING positionMs=3861 volume=100 Mar 26 10:09:13 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:13.494+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=http://stream.radioparadise.com/flacm title="I Am Kloot - Fingerprints" Mar 26 10:09:13 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:13.496+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_PLAYING positionMs=3861 volume=100 Mar 26 10:09:13 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:13.497+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=http://stream.radioparadise.com/flacm title="I Am Kloot - Fingerprints" Mar 26 10:09:13 volumio volumio[1210]: info: ------------------------------ 49ms Mar 26 10:09:13 volumio volumio[1210]: info: [LastFM] Split composite title into artist: I Am Kloot and title: Fingerprints Mar 26 10:09:13 volumio volumio[1210]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Mar 26 10:09:13 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 10:09:13 volumio volumio[1210]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"I Am Kloot - Fingerprints","artist":"Radio Paradise (flac)","album":"null","duration":"0","uri":"http://stream.radioparadise.com/flacm","service":""} Mar 26 10:09:13 volumio volumio[1210]: error: [now-playing] Caught error in callback of MetdataAPI.#getFetchPromise(): Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:09:13 volumio volumio[1210]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Kon info niet ophalen: Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:09:13 volumio volumio[1210]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:180:15) Mar 26 10:09:13 volumio volumio[1210]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 10:09:13 volumio volumio[1210]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Mar 26 10:09:13 volumio volumio[1210]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:126:28) Mar 26 10:09:49 volumio volumio[1210]: info: Preload queue cleared Mar 26 10:09:49 volumio volumio[1210]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 10:09:49 volumio volumio[1210]: info: CoreStateMachine::ClearQueue Mar 26 10:09:49 volumio volumio[1210]: info: CoreStateMachine::stop Mar 26 10:09:49 volumio volumio[1210]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:09:49 volumio volumio[1210]: info: CoreStateMachine::stPlaybackTimer Mar 26 10:09:49 volumio volumio[1210]: info: CoreStateMachine::updateTrackBlock Mar 26 10:09:49 volumio volumio[1210]: info: CorePlayQueue::getTrackBlock Mar 26 10:09:49 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:09:49 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:49 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:09:49 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:09:49 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:09:49 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:09:49 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:09:49 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:49 volumio volumio[1210]: info: CoreStateMachine::serviceStop Mar 26 10:09:49 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:49 volumio volumio[1210]: info: CoreCommandRouter::serviceStop Mar 26 10:09:49 volumio volumio[1210]: info: [1774516189927] ControllerWebradio::stop Mar 26 10:09:49 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 10:09:49 volumio volumio[1210]: info: CorePlayQueue::clearPlayQueue Mar 26 10:09:49 volumio volumio[1210]: info: CorePlayQueue::saveQueue Mar 26 10:09:49 volumio volumio[1210]: info: CoreCommandRouter::volumioPushQueue Mar 26 10:09:49 volumio volumio[1210]: info: CoreStateMachine::addQueueItems Mar 26 10:09:49 volumio volumio[1210]: info: CorePlayQueue::addQueueItems Mar 26 10:09:49 volumio volumio[1210]: info: Preload queue cleared Mar 26 10:09:49 volumio volumio[1210]: info: Adding Item to queue: http://live-aacplus-64.kexp.org/kexp64.aac Mar 26 10:09:49 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 26 10:09:49 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:49.933+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_STOPPED positionMs=0 volume=100 Mar 26 10:09:49 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:49.933+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=http://stream.radioparadise.com/flacm title="Radio Paradise (FLAC+)" Mar 26 10:09:49 volumio volumio[1210]: info: CoreCommandRouter::volumioPushQueue Mar 26 10:09:49 volumio volumio[1210]: info: CorePlayQueue::saveQueue Mar 26 10:09:49 volumio volumio[1210]: info: CoreStateMachine::updateTrackBlock Mar 26 10:09:49 volumio volumio[1210]: info: CorePlayQueue::getTrackBlock Mar 26 10:09:49 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:09:49 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:49 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Mar 26 10:09:49 volumio volumio[1210]: info: CoreCommandRouter::volumioPlay Mar 26 10:09:49 volumio volumio[1210]: info: CoreStateMachine::play index 0 Mar 26 10:09:49 volumio volumio[1210]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:09:49 volumio volumio[1210]: info: CoreStateMachine::stop Mar 26 10:09:49 volumio volumio[1210]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:09:49 volumio volumio[1210]: info: CoreStateMachine::play index undefined Mar 26 10:09:49 volumio volumio[1210]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:09:49 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:49 volumio volumio[1210]: info: CoreStateMachine::startPlaybackTimer Mar 26 10:09:49 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:49 volumio volumio[1210]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 10:09:49 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 10:09:49 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 26 10:09:49 volumio volumio[1210]: info: [1774516189969] ControllerWebradio::clearAddPlayTrack Mar 26 10:09:49 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 10:09:49 volumio volumio[1210]: info: Mar 26 10:09:49 volumio volumio[1210]: ---------------------------- MPD announces state update: player Mar 26 10:09:49 volumio volumio[1210]: info: sendMpdCommand stop took 68 milliseconds Mar 26 10:09:49 volumio volumio[1210]: info: ControllerMpd::getState Mar 26 10:09:49 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:09:49 volumio volumio[1210]: info: sendMpdCommand stop took 25 milliseconds Mar 26 10:09:49 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 10:09:49 volumio volumio[1210]: info: Mar 26 10:09:49 volumio volumio[1210]: ---------------------------- MPD announces system playlist update Mar 26 10:09:49 volumio volumio[1210]: info: Ignoring MPD Status Update Mar 26 10:09:49 volumio volumio[1210]: info: sendMpdCommand status took 3 milliseconds Mar 26 10:09:49 volumio volumio[1210]: info: sendMpdCommand clear took 2 milliseconds Mar 26 10:09:49 volumio volumio[1210]: verbose: ControllerMpd::parseState Mar 26 10:09:49 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:09:49 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand load "http://live-aacplus-64.kexp.org/kexp64.aac" Mar 26 10:09:50 volumio volumio[1210]: error: updateQueue error: null Mar 26 10:09:50 volumio volumio[1210]: info: ------------------------------ 20ms Mar 26 10:09:50 volumio volumio[1210]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 26 10:09:50 volumio volumio[1210]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:09:50 volumio volumio[1210]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 26 10:09:50 volumio volumio[1210]: info: ------------------------------ 26ms Mar 26 10:09:50 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 10:09:50 volumio volumio[1210]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Radio Paradise (FLAC+)","artist":"","uri":"http://stream.radioparadise.com/flacm","service":""} Mar 26 10:09:50 volumio volumio[1210]: error: [now-playing] Caught error in callback of MetdataAPI.#getFetchPromise(): Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:09:50 volumio volumio[1210]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Kon info niet ophalen: Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:09:50 volumio volumio[1210]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:180:15) Mar 26 10:09:50 volumio volumio[1210]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 10:09:50 volumio volumio[1210]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Mar 26 10:09:50 volumio volumio[1210]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:126:28) Mar 26 10:09:50 volumio volumio[1210]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 26 10:09:51 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand add "http://live-aacplus-64.kexp.org/kexp64.aac" Mar 26 10:09:51 volumio volumio[1210]: info: Mar 26 10:09:51 volumio volumio[1210]: ---------------------------- MPD announces system playlist update Mar 26 10:09:51 volumio volumio[1210]: info: Ignoring MPD Status Update Mar 26 10:09:51 volumio volumio[1210]: info: sendMpdCommand add "http://live-aacplus-64.kexp.org/kexp64.aac" took 2 milliseconds Mar 26 10:09:51 volumio volumio[1210]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 26 10:09:51 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand play Mar 26 10:09:51 volumio volumio[1210]: info: ------------------------------ 3ms Mar 26 10:09:51 volumio volumio[1210]: info: sendMpdCommand play took 2 milliseconds Mar 26 10:09:52 volumio volumio[1210]: info: Mar 26 10:09:52 volumio volumio[1210]: ---------------------------- MPD announces state update: player Mar 26 10:09:52 volumio volumio[1210]: info: ControllerMpd::getState Mar 26 10:09:52 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:09:52 volumio volumio[1210]: info: Mar 26 10:09:52 volumio volumio[1210]: ---------------------------- MPD announces state update: player Mar 26 10:09:52 volumio volumio[1210]: info: ControllerMpd::getState Mar 26 10:09:52 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:09:52 volumio volumio[1210]: info: sendMpdCommand status took 1 milliseconds Mar 26 10:09:52 volumio volumio[1210]: verbose: ControllerMpd::parseState Mar 26 10:09:52 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:09:52 volumio volumio[1210]: info: sendMpdCommand status took 2 milliseconds Mar 26 10:09:52 volumio volumio[1210]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 26 10:09:52 volumio volumio[1210]: verbose: ControllerMpd::parseState Mar 26 10:09:52 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:09:52 volumio volumio[1210]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:09:52 volumio volumio[1210]: info: ControllerMpd::pushState Mar 26 10:09:52 volumio volumio[1210]: info: CoreCommandRouter::servicePushState Mar 26 10:09:52 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:52 volumio volumio[1210]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kexp64.aac","artist":null,"album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Mar 26 10:09:52 volumio volumio[1210]: verbose: CURRENT POSITION 0 Mar 26 10:09:52 volumio volumio[1210]: info: CoreStateMachine::syncState stateService play Mar 26 10:09:52 volumio volumio[1210]: info: CoreStateMachine::syncState currentStatus stop Mar 26 10:09:52 volumio volumio[1210]: info: ------------------------------ 8ms Mar 26 10:09:52 volumio volumio[1210]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 26 10:09:52 volumio volumio[1210]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:09:52 volumio volumio[1210]: info: ControllerMpd::pushState Mar 26 10:09:52 volumio volumio[1210]: info: CoreCommandRouter::servicePushState Mar 26 10:09:52 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:52 volumio volumio[1210]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kexp64.aac","artist":null,"album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Mar 26 10:09:52 volumio volumio[1210]: verbose: CURRENT POSITION 0 Mar 26 10:09:52 volumio volumio[1210]: info: CoreStateMachine::syncState stateService play Mar 26 10:09:52 volumio volumio[1210]: info: CoreStateMachine::syncState currentStatus play Mar 26 10:09:52 volumio volumio[1210]: info: Received an update from plugin. extracting info from payload Mar 26 10:09:52 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:09:52 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:52 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:09:52 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:09:52 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:09:52 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:09:52 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:09:52 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:09:52 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:09:52 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:09:52 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:09:52 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:09:52 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:52.827+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_PLAYING positionMs=0 volume=100 Mar 26 10:09:52 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:52.827+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=http://live-aacplus-64.kexp.org/kexp64.aac title="KEXP 90.3 FM Seattle" Mar 26 10:09:52 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:52.833+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_PLAYING positionMs=0 volume=100 Mar 26 10:09:52 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:52.833+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=http://live-aacplus-64.kexp.org/kexp64.aac title="KEXP 90.3 FM Seattle" Mar 26 10:09:52 volumio volumio[1210]: info: ------------------------------ 88ms Mar 26 10:09:52 volumio volumio[1210]: info: Mar 26 10:09:52 volumio volumio[1210]: ---------------------------- MPD announces system playlist update Mar 26 10:09:52 volumio volumio[1210]: info: Ignoring MPD Status Update Mar 26 10:09:52 volumio volumio[1210]: info: Mar 26 10:09:52 volumio volumio[1210]: ---------------------------- MPD announces state update: player Mar 26 10:09:52 volumio volumio[1210]: info: ControllerMpd::getState Mar 26 10:09:52 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:09:52 volumio volumio[1210]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! KEXP 90.3 FM Seattle Mar 26 10:09:52 volumio volumio[1210]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! KEXP 90.3 FM Seattle Mar 26 10:09:52 volumio volumio[1210]: info: ------------------------------ 75ms Mar 26 10:09:52 volumio volumio[1210]: info: sendMpdCommand status took 75 milliseconds Mar 26 10:09:52 volumio volumio[1210]: verbose: ControllerMpd::parseState Mar 26 10:09:52 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:09:52 volumio volumio[1210]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 10:09:52 volumio volumio[1210]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:09:52 volumio volumio[1210]: info: ControllerMpd::pushState Mar 26 10:09:52 volumio volumio[1210]: info: CoreCommandRouter::servicePushState Mar 26 10:09:52 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:52 volumio volumio[1210]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"kexp64.aac","artist":"KEXP 90.3 FM","album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Mar 26 10:09:52 volumio volumio[1210]: verbose: CURRENT POSITION 0 Mar 26 10:09:52 volumio volumio[1210]: info: CoreStateMachine::syncState stateService play Mar 26 10:09:52 volumio volumio[1210]: info: CoreStateMachine::syncState currentStatus play Mar 26 10:09:52 volumio volumio[1210]: info: Received an update from plugin. extracting info from payload Mar 26 10:09:52 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:09:52 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:09:52 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:09:52 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:09:52 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:09:52 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:09:52 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:09:52 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:09:52 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:09:52 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:09:52 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:09:52 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:09:52 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:52.989+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_PLAYING positionMs=159 volume=100 Mar 26 10:09:52 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:52.990+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=http://live-aacplus-64.kexp.org/kexp64.aac title="KEXP 90.3 FM Seattle" Mar 26 10:09:52 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:52.997+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_PLAYING positionMs=159 volume=100 Mar 26 10:09:52 volumio volumio5-onboarding[1523]: time=2026-03-26T10:09:52.998+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=http://live-aacplus-64.kexp.org/kexp64.aac title="KEXP 90.3 FM Seattle" Mar 26 10:09:53 volumio volumio[1210]: info: ------------------------------ 163ms Mar 26 10:09:53 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 10:09:53 volumio volumio[1210]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"KEXP 90.3 FM Seattle","artist":"","album":"null","duration":"0","uri":"http://live-aacplus-64.kexp.org/kexp64.aac","service":""} Mar 26 10:09:53 volumio volumio[1210]: error: [now-playing] Caught error in callback of MetdataAPI.#getFetchPromise(): Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:09:53 volumio volumio[1210]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Kon info niet ophalen: Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:09:53 volumio volumio[1210]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:180:15) Mar 26 10:09:53 volumio volumio[1210]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 10:09:53 volumio volumio[1210]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Mar 26 10:09:53 volumio volumio[1210]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:126:28) Mar 26 10:09:53 volumio volumio[1210]: info: [LastFM] Current track has sufficient metadata: title (KEXP 90.3 FM Seattle) and artist (KEXP 90.3 FM) passed on explicitly Mar 26 10:09:53 volumio volumio[1210]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Mar 26 10:09:53 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 10:09:53 volumio volumio[1210]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"KEXP 90.3 FM Seattle","artist":"null","album":"null","duration":"0","uri":"http://live-aacplus-64.kexp.org/kexp64.aac","service":""} Mar 26 10:09:53 volumio volumio[1210]: error: [now-playing] Caught error in callback of MetdataAPI.#getFetchPromise(): Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:09:53 volumio volumio[1210]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Kon info niet ophalen: Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:09:53 volumio volumio[1210]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:180:15) Mar 26 10:09:53 volumio volumio[1210]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 10:09:53 volumio volumio[1210]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Mar 26 10:09:53 volumio volumio[1210]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:126:28) Mar 26 10:09:53 volumio volumio[1210]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 26 10:09:53 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 10:09:53 volumio volumio[1210]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"KEXP 90.3 FM Seattle","artist":"KEXP 90.3 FM","album":"null","duration":"0","uri":"http://live-aacplus-64.kexp.org/kexp64.aac","service":""} Mar 26 10:09:53 volumio volumio[1210]: error: [now-playing] Caught error in callback of MetdataAPI.#getFetchPromise(): Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:09:53 volumio volumio[1210]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Kon info niet ophalen: Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:09:53 volumio volumio[1210]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:180:15) Mar 26 10:09:53 volumio volumio[1210]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 10:09:53 volumio volumio[1210]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Mar 26 10:09:53 volumio volumio[1210]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:126:28) Mar 26 10:10:01 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 26 10:10:01 volumio volumio[1210]: info: Preload queue cleared Mar 26 10:10:04 volumio volumio[1210]: info: Mar 26 10:10:04 volumio volumio[1210]: ---------------------------- MPD announces system playlist update Mar 26 10:10:04 volumio volumio[1210]: info: Ignoring MPD Status Update Mar 26 10:10:04 volumio volumio[1210]: info: Mar 26 10:10:04 volumio volumio[1210]: ---------------------------- MPD announces state update: player Mar 26 10:10:04 volumio volumio[1210]: info: ControllerMpd::getState Mar 26 10:10:04 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:10:04 volumio volumio[1210]: info: ------------------------------ 5ms Mar 26 10:10:04 volumio volumio[1210]: info: sendMpdCommand status took 2 milliseconds Mar 26 10:10:04 volumio volumio[1210]: verbose: ControllerMpd::parseState Mar 26 10:10:04 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:10:04 volumio volumio[1210]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 26 10:10:04 volumio volumio[1210]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:10:04 volumio volumio[1210]: info: ControllerMpd::pushState Mar 26 10:10:04 volumio volumio[1210]: info: CoreCommandRouter::servicePushState Mar 26 10:10:04 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:10:04 volumio volumio[1210]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12859,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Sylvester and the Hot Band - Southern Man","artist":"KEXP 90.3 FM","album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Mar 26 10:10:04 volumio volumio[1210]: verbose: CURRENT POSITION 0 Mar 26 10:10:04 volumio volumio[1210]: info: CoreStateMachine::syncState stateService play Mar 26 10:10:04 volumio volumio[1210]: info: CoreStateMachine::syncState currentStatus play Mar 26 10:10:04 volumio volumio[1210]: info: Received an update from plugin. extracting info from payload Mar 26 10:10:04 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:10:04 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:10:04 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:10:04 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:10:04 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:10:04 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:10:04 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:10:04 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:10:04 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:10:04 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:10:04 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:10:04 volumio volumio5-onboarding[1523]: time=2026-03-26T10:10:04.293+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_PLAYING positionMs=11428 volume=100 Mar 26 10:10:04 volumio volumio5-onboarding[1523]: time=2026-03-26T10:10:04.294+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=http://live-aacplus-64.kexp.org/kexp64.aac title="Sylvester and the Hot Band - Southern Man" Mar 26 10:10:04 volumio volumio5-onboarding[1523]: time=2026-03-26T10:10:04.296+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_PLAYING positionMs=11428 volume=100 Mar 26 10:10:04 volumio volumio5-onboarding[1523]: time=2026-03-26T10:10:04.296+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=http://live-aacplus-64.kexp.org/kexp64.aac title="Sylvester and the Hot Band - Southern Man" Mar 26 10:10:04 volumio volumio[1210]: info: ------------------------------ 47ms Mar 26 10:10:04 volumio volumio[1210]: info: [LastFM] Split composite title into artist: Sylvester and the Hot Band and title: Southern Man Mar 26 10:10:04 volumio volumio[1210]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Mar 26 10:10:04 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 10:10:04 volumio volumio[1210]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Sylvester and the Hot Band - Southern Man","artist":"KEXP 90.3 FM","album":"null","duration":"0","uri":"http://live-aacplus-64.kexp.org/kexp64.aac","service":""} Mar 26 10:10:04 volumio volumio[1210]: error: [now-playing] Caught error in callback of MetdataAPI.#getFetchPromise(): Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:10:04 volumio volumio[1210]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Kon info niet ophalen: Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:10:04 volumio volumio[1210]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:180:15) Mar 26 10:10:04 volumio volumio[1210]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 10:10:04 volumio volumio[1210]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Mar 26 10:10:04 volumio volumio[1210]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:126:28) Mar 26 10:10:11 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 26 10:10:12 volumio volumio[1210]: info: Preload queue cleared Mar 26 10:10:15 volumio volumio[1210]: info: Preload queue cleared Mar 26 10:10:15 volumio volumio[1210]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 10:10:15 volumio volumio[1210]: info: CoreStateMachine::ClearQueue Mar 26 10:10:15 volumio volumio[1210]: info: CoreStateMachine::stop Mar 26 10:10:15 volumio volumio[1210]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:10:15 volumio volumio[1210]: info: CoreStateMachine::stPlaybackTimer Mar 26 10:10:15 volumio volumio[1210]: info: CoreStateMachine::updateTrackBlock Mar 26 10:10:15 volumio volumio[1210]: info: CorePlayQueue::getTrackBlock Mar 26 10:10:15 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:10:15 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:10:15 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:10:15 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:10:15 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:10:15 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:10:15 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:10:15 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:10:15 volumio volumio[1210]: info: CoreStateMachine::serviceStop Mar 26 10:10:15 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:10:15 volumio volumio[1210]: info: CoreCommandRouter::serviceStop Mar 26 10:10:15 volumio volumio[1210]: info: [1774516215863] ControllerWebradio::stop Mar 26 10:10:15 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 10:10:15 volumio volumio[1210]: info: CorePlayQueue::clearPlayQueue Mar 26 10:10:15 volumio volumio[1210]: info: CorePlayQueue::saveQueue Mar 26 10:10:15 volumio volumio[1210]: info: CoreCommandRouter::volumioPushQueue Mar 26 10:10:15 volumio volumio[1210]: info: CoreStateMachine::addQueueItems Mar 26 10:10:15 volumio volumio[1210]: info: CorePlayQueue::addQueueItems Mar 26 10:10:15 volumio volumio[1210]: info: Preload queue cleared Mar 26 10:10:15 volumio volumio[1210]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Mar 26 10:10:15 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 26 10:10:15 volumio volumio5-onboarding[1523]: time=2026-03-26T10:10:15.869+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_STOPPED positionMs=0 volume=100 Mar 26 10:10:15 volumio volumio5-onboarding[1523]: time=2026-03-26T10:10:15.869+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=http://live-aacplus-64.kexp.org/kexp64.aac title="KEXP 90.3 FM Seattle" Mar 26 10:10:15 volumio volumio[1210]: info: CoreCommandRouter::volumioPushQueue Mar 26 10:10:15 volumio volumio[1210]: info: CorePlayQueue::saveQueue Mar 26 10:10:15 volumio volumio[1210]: info: CoreStateMachine::updateTrackBlock Mar 26 10:10:15 volumio volumio[1210]: info: CorePlayQueue::getTrackBlock Mar 26 10:10:15 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:10:15 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:10:15 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Mar 26 10:10:15 volumio volumio[1210]: info: CoreCommandRouter::volumioPlay Mar 26 10:10:15 volumio volumio[1210]: info: CoreStateMachine::play index 0 Mar 26 10:10:15 volumio volumio[1210]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:10:15 volumio volumio[1210]: info: CoreStateMachine::stop Mar 26 10:10:15 volumio volumio[1210]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:10:15 volumio volumio[1210]: info: CoreStateMachine::play index undefined Mar 26 10:10:15 volumio volumio[1210]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:10:15 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:10:15 volumio volumio[1210]: info: CoreStateMachine::startPlaybackTimer Mar 26 10:10:15 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:10:15 volumio volumio[1210]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 10:10:15 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 10:10:15 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 26 10:10:15 volumio volumio[1210]: info: [1774516215911] ControllerWebradio::clearAddPlayTrack Mar 26 10:10:15 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 10:10:15 volumio volumio[1210]: info: Mar 26 10:10:15 volumio volumio[1210]: ---------------------------- MPD announces state update: player Mar 26 10:10:15 volumio volumio[1210]: info: sendMpdCommand stop took 75 milliseconds Mar 26 10:10:15 volumio volumio[1210]: info: ControllerMpd::getState Mar 26 10:10:15 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:10:15 volumio volumio[1210]: info: sendMpdCommand stop took 27 milliseconds Mar 26 10:10:15 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 10:10:15 volumio volumio[1210]: info: Mar 26 10:10:15 volumio volumio[1210]: ---------------------------- MPD announces system playlist update Mar 26 10:10:15 volumio volumio[1210]: info: Ignoring MPD Status Update Mar 26 10:10:15 volumio volumio[1210]: info: sendMpdCommand status took 15 milliseconds Mar 26 10:10:15 volumio volumio[1210]: info: sendMpdCommand clear took 15 milliseconds Mar 26 10:10:15 volumio volumio[1210]: verbose: ControllerMpd::parseState Mar 26 10:10:15 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:10:15 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Mar 26 10:10:15 volumio volumio[1210]: error: updateQueue error: null Mar 26 10:10:15 volumio volumio[1210]: info: ------------------------------ 28ms Mar 26 10:10:15 volumio volumio[1210]: info: sendMpdCommand playlistinfo took 27 milliseconds Mar 26 10:10:15 volumio volumio[1210]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:10:15 volumio volumio[1210]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 26 10:10:15 volumio volumio[1210]: info: ------------------------------ 46ms Mar 26 10:10:15 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 10:10:15 volumio volumio[1210]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"KEXP 90.3 FM Seattle","artist":"","uri":"http://live-aacplus-64.kexp.org/kexp64.aac","service":""} Mar 26 10:10:15 volumio volumio[1210]: error: [now-playing] Caught error in callback of MetdataAPI.#getFetchPromise(): Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:10:15 volumio volumio[1210]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Kon info niet ophalen: Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:10:15 volumio volumio[1210]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:180:15) Mar 26 10:10:15 volumio volumio[1210]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 10:10:15 volumio volumio[1210]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Mar 26 10:10:15 volumio volumio[1210]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:126:28) Mar 26 10:10:16 volumio volumio[1210]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 26 10:10:16 volumio volumio[1210]: info: Mar 26 10:10:16 volumio volumio[1210]: ---------------------------- MPD announces system playlist update Mar 26 10:10:16 volumio volumio[1210]: info: Ignoring MPD Status Update Mar 26 10:10:16 volumio volumio[1210]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 259 milliseconds Mar 26 10:10:16 volumio volumio[1210]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 26 10:10:16 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand play Mar 26 10:10:16 volumio volumio[1210]: info: ------------------------------ 1ms Mar 26 10:10:16 volumio volumio[1210]: info: sendMpdCommand play took 1 milliseconds Mar 26 10:10:16 volumio volumio[1210]: info: Mar 26 10:10:16 volumio volumio[1210]: ---------------------------- MPD announces state update: player Mar 26 10:10:16 volumio volumio[1210]: info: ControllerMpd::getState Mar 26 10:10:16 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:10:16 volumio volumio[1210]: info: Mar 26 10:10:16 volumio volumio[1210]: ---------------------------- MPD announces state update: player Mar 26 10:10:16 volumio volumio[1210]: info: sendMpdCommand status took 2 milliseconds Mar 26 10:10:16 volumio volumio[1210]: info: ControllerMpd::getState Mar 26 10:10:16 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:10:16 volumio volumio[1210]: verbose: ControllerMpd::parseState Mar 26 10:10:16 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:10:16 volumio volumio[1210]: info: sendMpdCommand status took 1 milliseconds Mar 26 10:10:16 volumio volumio[1210]: verbose: ControllerMpd::parseState Mar 26 10:10:16 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:10:16 volumio volumio[1210]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 26 10:10:16 volumio volumio[1210]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 26 10:10:16 volumio volumio[1210]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:10:16 volumio volumio[1210]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:10:16 volumio volumio[1210]: info: ControllerMpd::pushState Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::servicePushState Mar 26 10:10:16 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:10:16 volumio volumio[1210]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 10:10:16 volumio volumio[1210]: verbose: CURRENT POSITION 0 Mar 26 10:10:16 volumio volumio[1210]: info: CoreStateMachine::syncState stateService play Mar 26 10:10:16 volumio volumio[1210]: info: CoreStateMachine::syncState currentStatus stop Mar 26 10:10:16 volumio volumio[1210]: info: ControllerMpd::pushState Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::servicePushState Mar 26 10:10:16 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:10:16 volumio volumio[1210]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 10:10:16 volumio volumio[1210]: verbose: CURRENT POSITION 0 Mar 26 10:10:16 volumio volumio[1210]: info: CoreStateMachine::syncState stateService play Mar 26 10:10:16 volumio volumio[1210]: info: CoreStateMachine::syncState currentStatus play Mar 26 10:10:16 volumio volumio[1210]: info: Received an update from plugin. extracting info from payload Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 10:10:16 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:10:16 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:10:16 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:10:16 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:10:16 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:10:16 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:10:16 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:10:16 volumio volumio[1210]: info: ------------------------------ 20ms Mar 26 10:10:16 volumio volumio5-onboarding[1523]: time=2026-03-26T10:10:16.821+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_PLAYING positionMs=0 volume=100 Mar 26 10:10:16 volumio volumio5-onboarding[1523]: time=2026-03-26T10:10:16.822+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 26 10:10:16 volumio volumio5-onboarding[1523]: time=2026-03-26T10:10:16.826+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_PLAYING positionMs=0 volume=100 Mar 26 10:10:16 volumio volumio5-onboarding[1523]: time=2026-03-26T10:10:16.826+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 26 10:10:16 volumio volumio[1210]: info: ------------------------------ 54ms Mar 26 10:10:16 volumio volumio[1210]: info: Mar 26 10:10:16 volumio volumio[1210]: ---------------------------- MPD announces system playlist update Mar 26 10:10:16 volumio volumio[1210]: info: Ignoring MPD Status Update Mar 26 10:10:16 volumio volumio[1210]: info: Mar 26 10:10:16 volumio volumio[1210]: ---------------------------- MPD announces state update: player Mar 26 10:10:16 volumio volumio[1210]: info: ControllerMpd::getState Mar 26 10:10:16 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:10:16 volumio volumio[1210]: info: [LastFM] Current track has sufficient metadata: title (Dance Wave!) and artist ((#1 - 5791/32000) Dance Wave!) passed on explicitly Mar 26 10:10:16 volumio volumio[1210]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Mar 26 10:10:16 volumio volumio[1210]: info: Mar 26 10:10:16 volumio volumio[1210]: ---------------------------- MPD announces system playlist update Mar 26 10:10:16 volumio volumio[1210]: info: Ignoring MPD Status Update Mar 26 10:10:16 volumio volumio[1210]: info: Mar 26 10:10:16 volumio volumio[1210]: ---------------------------- MPD announces state update: player Mar 26 10:10:16 volumio volumio[1210]: info: ControllerMpd::getState Mar 26 10:10:16 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:10:16 volumio volumio[1210]: info: ------------------------------ 63ms Mar 26 10:10:16 volumio volumio[1210]: info: sendMpdCommand status took 60 milliseconds Mar 26 10:10:16 volumio volumio[1210]: verbose: ControllerMpd::parseState Mar 26 10:10:16 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:10:16 volumio volumio[1210]: info: ------------------------------ 12ms Mar 26 10:10:16 volumio volumio[1210]: info: sendMpdCommand status took 11 milliseconds Mar 26 10:10:16 volumio volumio[1210]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 26 10:10:16 volumio volumio[1210]: verbose: ControllerMpd::parseState Mar 26 10:10:16 volumio volumio[1210]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:10:16 volumio volumio[1210]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:10:16 volumio volumio[1210]: info: ControllerMpd::pushState Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::servicePushState Mar 26 10:10:16 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:10:16 volumio volumio[1210]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1089,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 10:10:16 volumio volumio[1210]: verbose: CURRENT POSITION 0 Mar 26 10:10:16 volumio volumio[1210]: info: CoreStateMachine::syncState stateService play Mar 26 10:10:16 volumio volumio[1210]: info: CoreStateMachine::syncState currentStatus play Mar 26 10:10:16 volumio volumio[1210]: info: Received an update from plugin. extracting info from payload Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 10:10:16 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:10:16 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:10:16 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:10:16 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:10:16 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:10:16 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:10:16 volumio volumio5-onboarding[1523]: time=2026-03-26T10:10:16.946+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_PLAYING positionMs=255 volume=100 Mar 26 10:10:16 volumio volumio5-onboarding[1523]: time=2026-03-26T10:10:16.946+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 26 10:10:16 volumio volumio5-onboarding[1523]: time=2026-03-26T10:10:16.948+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_PLAYING positionMs=255 volume=100 Mar 26 10:10:16 volumio volumio5-onboarding[1523]: time=2026-03-26T10:10:16.949+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 26 10:10:16 volumio volumio[1210]: info: ------------------------------ 111ms Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 10:10:16 volumio volumio[1210]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Dance Wave!","artist":"(#1 - 5791/32000) Dance Wave!","album":"null","duration":"0","uri":"https://dancewave.online:443/dance.mp3","service":""} Mar 26 10:10:16 volumio volumio[1210]: error: [now-playing] Caught error in callback of MetdataAPI.#getFetchPromise(): Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:10:16 volumio volumio[1210]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Kon info niet ophalen: Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:10:16 volumio volumio[1210]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:180:15) Mar 26 10:10:16 volumio volumio[1210]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 10:10:16 volumio volumio[1210]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Mar 26 10:10:16 volumio volumio[1210]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:126:28) Mar 26 10:10:16 volumio volumio[1210]: info: sendMpdCommand playlistinfo took 73 milliseconds Mar 26 10:10:16 volumio volumio[1210]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:10:16 volumio volumio[1210]: info: ControllerMpd::pushState Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::servicePushState Mar 26 10:10:16 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Mar 26 10:10:16 volumio volumio[1210]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1204,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 10:10:16 volumio volumio[1210]: verbose: CURRENT POSITION 0 Mar 26 10:10:16 volumio volumio[1210]: info: CoreStateMachine::syncState stateService play Mar 26 10:10:16 volumio volumio[1210]: info: CoreStateMachine::syncState currentStatus play Mar 26 10:10:16 volumio volumio[1210]: info: Received an update from plugin. extracting info from payload Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 10:10:16 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:10:16 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:10:17 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:10:17 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:10:17 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:10:17 volumio volumio[1210]: info: CoreStateMachine::pushState Mar 26 10:10:17 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Mar 26 10:10:17 volumio volumio[1210]: info: MRS: Pushing multiroomSync output update for this device Mar 26 10:10:17 volumio volumio[1210]: info: MRS: Pushing multiroomSync output Mar 26 10:10:17 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Mar 26 10:10:17 volumio volumio5-onboarding[1523]: time=2026-03-26T10:10:17.011+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_PLAYING positionMs=255 volume=100 Mar 26 10:10:17 volumio volumio5-onboarding[1523]: time=2026-03-26T10:10:17.012+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 26 10:10:17 volumio volumio5-onboarding[1523]: time=2026-03-26T10:10:17.013+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" state=STATUS_PLAYING positionMs=255 volume=100 Mar 26 10:10:17 volumio volumio5-onboarding[1523]: time=2026-03-26T10:10:17.013+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.207:37478 @ 0x20ba720" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 26 10:10:17 volumio volumio[1210]: info: ------------------------------ 108ms Mar 26 10:10:17 volumio volumio[1210]: info: [LastFM] Current track has sufficient metadata: title (Tracklist: https://dancewave.online) and artist (Dance Wave!) passed on explicitly Mar 26 10:10:17 volumio volumio[1210]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Mar 26 10:10:17 volumio volumio[1210]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 26 10:10:17 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 10:10:17 volumio volumio[1210]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":"null","duration":"0","uri":"https://dancewave.online:443/dance.mp3","service":""} Mar 26 10:10:17 volumio volumio[1210]: error: [now-playing] Caught error in callback of MetdataAPI.#getFetchPromise(): Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:10:17 volumio volumio[1210]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Kon info niet ophalen: Token ontbreekt – Vul een Genius-toegangstoken in bij de Now Playing-plugininstellingen. Mar 26 10:10:17 volumio volumio[1210]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:180:15) Mar 26 10:10:17 volumio volumio[1210]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 26 10:10:17 volumio volumio[1210]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Mar 26 10:10:17 volumio volumio[1210]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:126:28) Mar 26 10:10:17 volumio volumio[1210]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 10:10:17 volumio volumio[1210]: TypeError: Cannot read properties of null (reading 'slice') Mar 26 10:10:17 volumio volumio[1210]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 26 10:10:17 volumio volumio[1210]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 26 10:10:17 volumio volumio[1210]: at Object.onceWrapper (node:events:629:26) Mar 26 10:10:17 volumio volumio[1210]: at ClientRequest.emit (node:events:514:28) Mar 26 10:10:17 volumio volumio[1210]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Mar 26 10:10:17 volumio volumio[1210]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Mar 26 10:10:17 volumio volumio[1210]: at TLSSocket.socketOnData (node:_http_client:535:22) Mar 26 10:10:17 volumio volumio[1210]: at TLSSocket.emit (node:events:514:28) Mar 26 10:10:17 volumio volumio[1210]: at addChunk (node:internal/streams/readable:343:12) Mar 26 10:10:17 volumio volumio[1210]: at readableAddChunk (node:internal/streams/readable:316:9) Mar 26 10:10:17 volumio volumio[1210]: at Readable.push (node:internal/streams/readable:253:10) Mar 26 10:10:17 volumio volumio[1210]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Mar 26 10:10:17 volumio volumio[1210]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 10:10:18 volumio sudo[4852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-26 10:09' Mar 26 10:10:18 volumio sudo[4852]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"