-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2026-04-14 18:34:16 CEST. -- Apr 14 18:33:27 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:33:27 volumio volumio[1015]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 18:33:27 volumio volumio[1015]: info: CoreStateMachine::ClearQueue Apr 14 18:33:27 volumio volumio[1015]: info: CoreStateMachine::stop Apr 14 18:33:27 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:33:27 volumio volumio[1015]: info: CoreStateMachine::stPlaybackTimer Apr 14 18:33:27 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:33:27 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:33:27 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:33:27 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:27 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:33:27 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:33:27 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:33:27 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:27 volumio volumio[1015]: info: CoreStateMachine::serviceStop Apr 14 18:33:27 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:27 volumio volumio[1015]: info: CoreCommandRouter::serviceStop Apr 14 18:33:27 volumio volumio[1015]: info: [1776184407769] ControllerWebradio::stop Apr 14 18:33:27 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 18:33:27 volumio volumio[1015]: info: CorePlayQueue::clearPlayQueue Apr 14 18:33:27 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:33:27 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:33:27 volumio volumio[1015]: info: CoreStateMachine::addQueueItems Apr 14 18:33:27 volumio volumio[1015]: info: CorePlayQueue::addQueueItems Apr 14 18:33:27 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:33:27 volumio volumio[1015]: info: Adding Item to queue: http://stream.radioparadise.com/flacm Apr 14 18:33:27 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 18:33:27 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:27.779+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_STOPPED positionMs=0 volume=100 Apr 14 18:33:27 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:27.780+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Apr 14 18:33:27 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:33:27 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:33:27 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:33:27 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:33:27 volumio volumio[1015]: info: CoreCommandRouter::volumioPlay Apr 14 18:33:27 volumio volumio[1015]: info: CoreStateMachine::play index 0 Apr 14 18:33:27 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:33:27 volumio volumio[1015]: info: CoreStateMachine::stop Apr 14 18:33:27 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:33:27 volumio volumio[1015]: info: CoreStateMachine::play index undefined Apr 14 18:33:27 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:33:27 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:27 volumio volumio[1015]: info: CoreStateMachine::startPlaybackTimer Apr 14 18:33:27 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:27 volumio volumio[1015]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 18:33:27 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 18:33:27 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 14 18:33:27 volumio volumio[1015]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Apr 14 18:33:27 volumio volumio[1015]: info: [1776184407801] ControllerWebradio::clearAddPlayTrack Apr 14 18:33:27 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 18:33:27 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:33:27 volumio volumio[1015]: info: Apr 14 18:33:27 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:33:27 volumio volumio[1015]: info: sendMpdCommand stop took 43 milliseconds Apr 14 18:33:27 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:33:27 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:33:27 volumio volumio[1015]: info: sendMpdCommand stop took 12 milliseconds Apr 14 18:33:27 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 18:33:27 volumio volumio[1015]: info: Apr 14 18:33:27 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Apr 14 18:33:27 volumio volumio[1015]: info: Ignoring MPD Status Update Apr 14 18:33:27 volumio volumio[1015]: info: sendMpdCommand status took 3 milliseconds Apr 14 18:33:27 volumio volumio[1015]: info: sendMpdCommand clear took 3 milliseconds Apr 14 18:33:27 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:33:27 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:33:27 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radioparadise.com/flacm" Apr 14 18:33:27 volumio volumio[1015]: error: updateQueue error: null Apr 14 18:33:27 volumio volumio[1015]: info: ------------------------------ 5ms Apr 14 18:33:27 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 14 18:33:27 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:33:27 volumio volumio[1015]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 14 18:33:27 volumio volumio[1015]: info: ------------------------------ 9ms Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/flacm" Apr 14 18:33:28 volumio volumio[1015]: info: Apr 14 18:33:28 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Apr 14 18:33:28 volumio volumio[1015]: info: Ignoring MPD Status Update Apr 14 18:33:28 volumio volumio[1015]: info: sendMpdCommand add "http://stream.radioparadise.com/flacm" took 1 milliseconds Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand play Apr 14 18:33:28 volumio volumio[1015]: info: ------------------------------ 4ms Apr 14 18:33:28 volumio volumio[1015]: info: sendMpdCommand play took 2 milliseconds Apr 14 18:33:28 volumio volumio[1015]: info: Apr 14 18:33:28 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:33:28 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:33:28 volumio volumio[1015]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 14 18:33:28 volumio volumio[1015]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 14 18:33:28 volumio volumio[1015]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 14 18:33:28 volumio volumio[1015]: info: Apr 14 18:33:28 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:33:28 volumio volumio[1015]: info: sendMpdCommand status took 19 milliseconds Apr 14 18:33:28 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:33:28 volumio volumio[1015]: info: sendMpdCommand status took 6 milliseconds Apr 14 18:33:28 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:33:28 volumio volumio[1015]: info: ControllerMpd::pushState Apr 14 18:33:28 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Apr 14 18:33:28 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:28 volumio volumio[1015]: 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"} Apr 14 18:33:28 volumio volumio[1015]: verbose: CURRENT POSITION 0 Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus stop Apr 14 18:33:28 volumio volumio[1015]: info: ------------------------------ 30ms Apr 14 18:33:28 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:33:28 volumio volumio[1015]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::ClearQueue Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::stop Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::stPlaybackTimer Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:33:28 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:33:28 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:33:28 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:33:28 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:33:28 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::serviceStop Apr 14 18:33:28 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:28 volumio volumio[1015]: info: CoreCommandRouter::serviceStop Apr 14 18:33:28 volumio volumio[1015]: info: [1776184408848] ControllerWebradio::stop Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 18:33:28 volumio volumio[1015]: info: CorePlayQueue::clearPlayQueue Apr 14 18:33:28 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:33:28 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::addQueueItems Apr 14 18:33:28 volumio volumio[1015]: info: CorePlayQueue::addQueueItems Apr 14 18:33:28 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:33:28 volumio volumio[1015]: info: Adding Item to queue: http://stream.radioparadise.com/flacm Apr 14 18:33:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 18:33:28 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:28.859+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_STOPPED positionMs=0 volume=100 Apr 14 18:33:28 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:28.860+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://stream.radioparadise.com/flacm title="Radio Paradise (FLAC+)" Apr 14 18:33:28 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:33:28 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:33:28 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:33:28 volumio volumio[1015]: info: CoreCommandRouter::volumioPlay Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::play index 0 Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::stop Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::play index undefined Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:33:28 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:28 volumio volumio[1015]: info: CoreStateMachine::startPlaybackTimer Apr 14 18:33:28 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:28 volumio volumio[1015]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 18:33:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 18:33:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 14 18:33:28 volumio volumio[1015]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Apr 14 18:33:28 volumio volumio[1015]: info: [1776184408898] ControllerWebradio::clearAddPlayTrack Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 18:33:28 volumio volumio[1015]: info: Apr 14 18:33:28 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Apr 14 18:33:28 volumio volumio[1015]: info: Ignoring MPD Status Update Apr 14 18:33:28 volumio volumio[1015]: info: Apr 14 18:33:28 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:33:28 volumio volumio[1015]: info: Apr 14 18:33:28 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:33:28 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 72 milliseconds Apr 14 18:33:28 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:33:28 volumio volumio[1015]: info: sendMpdCommand stop took 56 milliseconds Apr 14 18:33:28 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:33:28 volumio volumio[1015]: info: sendMpdCommand stop took 6 milliseconds Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 18:33:28 volumio volumio[1015]: info: ControllerMpd::pushState Apr 14 18:33:28 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Apr 14 18:33:28 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:28 volumio volumio[1015]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 14 18:33:28 volumio volumio[1015]: info: ------------------------------ 85ms Apr 14 18:33:28 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:33:28 volumio volumio[1015]: info: Apr 14 18:33:28 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Apr 14 18:33:28 volumio volumio[1015]: info: Ignoring MPD Status Update Apr 14 18:33:28 volumio volumio[1015]: info: ------------------------------ 22ms Apr 14 18:33:28 volumio volumio[1015]: info: sendMpdCommand status took 20 milliseconds Apr 14 18:33:28 volumio volumio[1015]: info: sendMpdCommand status took 20 milliseconds Apr 14 18:33:28 volumio volumio[1015]: info: sendMpdCommand clear took 19 milliseconds Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radioparadise.com/flacm" Apr 14 18:33:28 volumio volumio[1015]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 14 18:33:28 volumio volumio[1015]: error: updateQueue error: null Apr 14 18:33:28 volumio volumio[1015]: info: ------------------------------ 6ms Apr 14 18:33:28 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 14 18:33:28 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:33:28 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:33:28 volumio volumio[1015]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 14 18:33:28 volumio volumio[1015]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 14 18:33:28 volumio volumio[1015]: info: ------------------------------ 30ms Apr 14 18:33:28 volumio volumio[1015]: info: ------------------------------ 30ms Apr 14 18:33:29 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/flacm" Apr 14 18:33:29 volumio volumio[1015]: info: Apr 14 18:33:29 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Apr 14 18:33:29 volumio volumio[1015]: info: Ignoring MPD Status Update Apr 14 18:33:29 volumio volumio[1015]: info: sendMpdCommand add "http://stream.radioparadise.com/flacm" took 2 milliseconds Apr 14 18:33:29 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 18:33:29 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand play Apr 14 18:33:29 volumio volumio[1015]: info: ------------------------------ 3ms Apr 14 18:33:29 volumio volumio[1015]: info: sendMpdCommand play took 3 milliseconds Apr 14 18:33:29 volumio volumio[1015]: info: Apr 14 18:33:29 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:33:29 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:33:29 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:33:29 volumio volumio[1015]: info: FusionDsp - ---- read samplerate, raw: Apr 14 18:33:29 volumio volumio[1015]: error: FusionDsp - invalid sample rate Apr 14 18:33:29 volumio volumio[1015]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 14 18:33:29 volumio volumio[1015]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 14 18:33:29 volumio volumio[1015]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 14 18:33:29 volumio volumio[1015]: info: Apr 14 18:33:29 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:33:29 volumio volumio[1015]: info: sendMpdCommand status took 19 milliseconds Apr 14 18:33:29 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:33:29 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:33:29 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:33:29 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:33:29 volumio volumio[1015]: info: sendMpdCommand status took 2 milliseconds Apr 14 18:33:29 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 14 18:33:29 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:33:29 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:33:29 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:33:29 volumio volumio[1015]: info: ControllerMpd::pushState Apr 14 18:33:29 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Apr 14 18:33:29 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:29 volumio volumio[1015]: 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"} Apr 14 18:33:29 volumio volumio[1015]: verbose: CURRENT POSITION 0 Apr 14 18:33:29 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Apr 14 18:33:29 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus stop Apr 14 18:33:29 volumio volumio[1015]: info: ------------------------------ 26ms Apr 14 18:33:29 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 14 18:33:29 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:33:29 volumio volumio[1015]: info: ControllerMpd::pushState Apr 14 18:33:29 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Apr 14 18:33:29 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:29 volumio volumio[1015]: 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"} Apr 14 18:33:29 volumio volumio[1015]: verbose: CURRENT POSITION 0 Apr 14 18:33:29 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Apr 14 18:33:29 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Apr 14 18:33:29 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Apr 14 18:33:29 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:33:29 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:29 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:33:29 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:33:29 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:33:29 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:33:29 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:33:29 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:33:29 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:29.278+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=0 volume=100 Apr 14 18:33:29 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:29.279+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://stream.radioparadise.com/flacm title="Radio Paradise (FLAC+)" Apr 14 18:33:29 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:29.280+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=0 volume=100 Apr 14 18:33:29 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:29.281+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://stream.radioparadise.com/flacm title="Radio Paradise (FLAC+)" Apr 14 18:33:29 volumio volumio[1015]: info: ------------------------------ 43ms Apr 14 18:33:29 volumio volumio[1015]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 14 18:33:29 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:33:29 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:33:29 volumio volumio[1015]: info: Apr 14 18:33:29 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Apr 14 18:33:29 volumio volumio[1015]: info: Ignoring MPD Status Update Apr 14 18:33:29 volumio volumio[1015]: info: Apr 14 18:33:29 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:33:29 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:33:29 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:33:29 volumio volumio[1015]: info: ------------------------------ 4ms Apr 14 18:33:29 volumio volumio[1015]: info: sendMpdCommand status took 3 milliseconds Apr 14 18:33:29 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:33:29 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:33:29 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 14 18:33:29 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:33:29 volumio volumio[1015]: info: ControllerMpd::pushState Apr 14 18:33:29 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Apr 14 18:33:29 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:29 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"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"} Apr 14 18:33:29 volumio volumio[1015]: verbose: CURRENT POSITION 0 Apr 14 18:33:29 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Apr 14 18:33:29 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Apr 14 18:33:29 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Apr 14 18:33:29 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:33:29 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:29 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:33:29 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:33:29 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:33:29 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:33:29 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:33:29 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:33:29 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:29.768+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=483 volume=100 Apr 14 18:33:29 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:29.769+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://stream.radioparadise.com/flacm title="Radio Paradise (FLAC+)" Apr 14 18:33:29 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:29.770+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=483 volume=100 Apr 14 18:33:29 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:29.771+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://stream.radioparadise.com/flacm title="Radio Paradise (FLAC+)" Apr 14 18:33:29 volumio volumio[1015]: info: ------------------------------ 44ms Apr 14 18:33:29 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:33:29 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:33:33 volumio volumio[1015]: info: Apr 14 18:33:33 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Apr 14 18:33:33 volumio volumio[1015]: info: Ignoring MPD Status Update Apr 14 18:33:33 volumio volumio[1015]: info: Apr 14 18:33:33 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:33:33 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:33:33 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:33:33 volumio volumio[1015]: info: ------------------------------ 4ms Apr 14 18:33:33 volumio volumio[1015]: info: sendMpdCommand status took 2 milliseconds Apr 14 18:33:33 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:33:33 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:33:33 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 14 18:33:33 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:33:33 volumio volumio[1015]: info: ControllerMpd::pushState Apr 14 18:33:33 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Apr 14 18:33:33 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:33 volumio volumio[1015]: 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":"The Third Mind - The Creator Has A Master Plan","artist":"Radio Paradise (flac)","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Apr 14 18:33:33 volumio volumio[1015]: verbose: CURRENT POSITION 0 Apr 14 18:33:33 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Apr 14 18:33:33 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Apr 14 18:33:33 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Apr 14 18:33:33 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:33:33 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:33:33 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:33:33 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:33:33 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:33:33 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:33:33 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:33:33 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:33.065+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=3785 volume=100 Apr 14 18:33:33 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:33.066+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://stream.radioparadise.com/flacm title="The Third Mind - The Creator Has A Master Plan" Apr 14 18:33:33 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:33.067+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=3785 volume=100 Apr 14 18:33:33 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:33.067+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://stream.radioparadise.com/flacm title="The Third Mind - The Creator Has A Master Plan" Apr 14 18:33:33 volumio volumio[1015]: info: ------------------------------ 40ms Apr 14 18:33:33 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:33:33 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:33:41 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::ClearQueue Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::stop Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::stPlaybackTimer Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:33:41 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:33:41 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:33:41 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::serviceStop Apr 14 18:33:41 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::serviceStop Apr 14 18:33:41 volumio volumio[1015]: info: [1776184421120] ControllerWebradio::stop Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 18:33:41 volumio volumio[1015]: info: CorePlayQueue::clearPlayQueue Apr 14 18:33:41 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::addQueueItems Apr 14 18:33:41 volumio volumio[1015]: info: CorePlayQueue::addQueueItems Apr 14 18:33:41 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:33:41 volumio volumio[1015]: info: Adding Item to queue: http://onair15.xdevel.com:7012/1 Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 18:33:41 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:41.130+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_STOPPED positionMs=0 volume=100 Apr 14 18:33:41 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:41.130+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://stream.radioparadise.com/flacm title="Radio Paradise (FLAC+)" Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:33:41 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:33:41 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::volumioPlay Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::play index 0 Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::stop Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::play index undefined Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:33:41 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::startPlaybackTimer Apr 14 18:33:41 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 14 18:33:41 volumio volumio[1015]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Apr 14 18:33:41 volumio volumio[1015]: info: [1776184421147] ControllerWebradio::clearAddPlayTrack Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 18:33:41 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:33:41 volumio volumio[1015]: info: Apr 14 18:33:41 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:33:41 volumio volumio[1015]: info: sendMpdCommand stop took 38 milliseconds Apr 14 18:33:41 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:33:41 volumio volumio[1015]: info: sendMpdCommand stop took 10 milliseconds Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 18:33:41 volumio volumio[1015]: info: Apr 14 18:33:41 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Apr 14 18:33:41 volumio volumio[1015]: info: Ignoring MPD Status Update Apr 14 18:33:41 volumio volumio[1015]: info: sendMpdCommand status took 3 milliseconds Apr 14 18:33:41 volumio volumio[1015]: info: sendMpdCommand clear took 2 milliseconds Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand load "http://onair15.xdevel.com:7012/1" Apr 14 18:33:41 volumio volumio[1015]: error: updateQueue error: null Apr 14 18:33:41 volumio volumio[1015]: info: ------------------------------ 6ms Apr 14 18:33:41 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:33:41 volumio volumio[1015]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 14 18:33:41 volumio volumio[1015]: info: ------------------------------ 11ms Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand add "http://onair15.xdevel.com:7012/1" Apr 14 18:33:41 volumio volumio[1015]: info: Apr 14 18:33:41 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Apr 14 18:33:41 volumio volumio[1015]: info: Ignoring MPD Status Update Apr 14 18:33:41 volumio volumio[1015]: info: sendMpdCommand add "http://onair15.xdevel.com:7012/1" took 1 milliseconds Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand play Apr 14 18:33:41 volumio volumio[1015]: info: ------------------------------ 2ms Apr 14 18:33:41 volumio volumio[1015]: info: sendMpdCommand play took 2 milliseconds Apr 14 18:33:41 volumio volumio[1015]: info: Apr 14 18:33:41 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:33:41 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:33:41 volumio volumio[1015]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 14 18:33:41 volumio volumio[1015]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 14 18:33:41 volumio volumio[1015]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 14 18:33:41 volumio volumio[1015]: info: Apr 14 18:33:41 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:33:41 volumio volumio[1015]: info: sendMpdCommand status took 18 milliseconds Apr 14 18:33:41 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:33:41 volumio volumio[1015]: info: sendMpdCommand status took 3 milliseconds Apr 14 18:33:41 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:33:41 volumio volumio[1015]: info: ControllerMpd::pushState Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Apr 14 18:33:41 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:41 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"http://onair15.xdevel.com:7012/1","trackType":"com:7012/1"} Apr 14 18:33:41 volumio volumio[1015]: verbose: CURRENT POSITION 0 Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus stop Apr 14 18:33:41 volumio volumio[1015]: info: ------------------------------ 29ms Apr 14 18:33:41 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:33:41 volumio volumio[1015]: info: ControllerMpd::pushState Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Apr 14 18:33:41 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:41 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"http://onair15.xdevel.com:7012/1","trackType":"com:7012/1"} Apr 14 18:33:41 volumio volumio[1015]: verbose: CURRENT POSITION 0 Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Apr 14 18:33:41 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:33:41 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:33:41 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:41.866+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=56 volume=100 Apr 14 18:33:41 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:41.866+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://onair15.xdevel.com:7012/1 title="Radio Reload" Apr 14 18:33:41 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:41.868+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=56 volume=100 Apr 14 18:33:41 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:41.868+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://onair15.xdevel.com:7012/1 title="Radio Reload" Apr 14 18:33:41 volumio volumio[1015]: info: ------------------------------ 54ms Apr 14 18:33:41 volumio volumio[1015]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 14 18:33:41 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:33:41 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:33:41 volumio volumio[1015]: info: Apr 14 18:33:41 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Apr 14 18:33:41 volumio volumio[1015]: info: Ignoring MPD Status Update Apr 14 18:33:41 volumio volumio[1015]: info: Apr 14 18:33:41 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:33:41 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:33:41 volumio volumio[1015]: info: ------------------------------ 6ms Apr 14 18:33:41 volumio volumio[1015]: info: sendMpdCommand status took 3 milliseconds Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:33:41 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 14 18:33:41 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:33:41 volumio volumio[1015]: info: ControllerMpd::pushState Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Apr 14 18:33:41 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:41 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RELOAD NEWS in english","artist":"RadioRload.it ?","album":null,"uri":"http://onair15.xdevel.com:7012/1","trackType":"com:7012/1"} Apr 14 18:33:41 volumio volumio[1015]: verbose: CURRENT POSITION 0 Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Apr 14 18:33:41 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Apr 14 18:33:41 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:33:41 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:33:42 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:33:42 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:33:42 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:33:42 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:33:42 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:33:42 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:42.034+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=202 volume=100 Apr 14 18:33:42 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:42.035+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://onair15.xdevel.com:7012/1 title="RELOAD NEWS in english" Apr 14 18:33:42 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:42.036+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=202 volume=100 Apr 14 18:33:42 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:42.037+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://onair15.xdevel.com:7012/1 title="RELOAD NEWS in english" Apr 14 18:33:42 volumio volumio[1015]: info: ------------------------------ 53ms Apr 14 18:33:42 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:33:42 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:33:48 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:33:48 volumio volumio[1015]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 18:33:48 volumio volumio[1015]: info: CoreStateMachine::ClearQueue Apr 14 18:33:48 volumio volumio[1015]: info: CoreStateMachine::stop Apr 14 18:33:48 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:33:48 volumio volumio[1015]: info: CoreStateMachine::stPlaybackTimer Apr 14 18:33:48 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:33:48 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:33:48 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:33:48 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:33:48 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:33:48 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:33:48 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:48 volumio volumio[1015]: info: CoreStateMachine::serviceStop Apr 14 18:33:48 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:48 volumio volumio[1015]: info: CoreCommandRouter::serviceStop Apr 14 18:33:48 volumio volumio[1015]: info: [1776184428024] ControllerWebradio::stop Apr 14 18:33:48 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 18:33:48 volumio volumio[1015]: info: CorePlayQueue::clearPlayQueue Apr 14 18:33:48 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:33:48 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:33:48 volumio volumio[1015]: info: CoreStateMachine::addQueueItems Apr 14 18:33:48 volumio volumio[1015]: info: CorePlayQueue::addQueueItems Apr 14 18:33:48 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:33:48 volumio volumio[1015]: info: Adding Item to queue: http://stream-relay-geo.ntslive.net/stream2 Apr 14 18:33:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 18:33:48 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:48.035+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_STOPPED positionMs=0 volume=100 Apr 14 18:33:48 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:48.035+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://onair15.xdevel.com:7012/1 title="Radio Reload" Apr 14 18:33:48 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:33:48 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:33:48 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:33:48 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:33:48 volumio volumio[1015]: info: CoreCommandRouter::volumioPlay Apr 14 18:33:48 volumio volumio[1015]: info: CoreStateMachine::play index 0 Apr 14 18:33:48 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:33:48 volumio volumio[1015]: info: CoreStateMachine::stop Apr 14 18:33:48 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:33:48 volumio volumio[1015]: info: CoreStateMachine::play index undefined Apr 14 18:33:48 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:33:48 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:48 volumio volumio[1015]: info: CoreStateMachine::startPlaybackTimer Apr 14 18:33:48 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:48 volumio volumio[1015]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 18:33:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 18:33:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 14 18:33:48 volumio volumio[1015]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Apr 14 18:33:48 volumio volumio[1015]: info: [1776184428052] ControllerWebradio::clearAddPlayTrack Apr 14 18:33:48 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 18:33:48 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:33:48 volumio volumio[1015]: info: Apr 14 18:33:48 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:33:48 volumio volumio[1015]: info: sendMpdCommand stop took 39 milliseconds Apr 14 18:33:48 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:33:48 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:33:48 volumio volumio[1015]: info: sendMpdCommand stop took 10 milliseconds Apr 14 18:33:48 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 18:33:48 volumio volumio[1015]: info: Apr 14 18:33:48 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Apr 14 18:33:48 volumio volumio[1015]: info: Ignoring MPD Status Update Apr 14 18:33:48 volumio volumio[1015]: info: sendMpdCommand status took 5 milliseconds Apr 14 18:33:48 volumio volumio[1015]: info: sendMpdCommand clear took 4 milliseconds Apr 14 18:33:48 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:33:48 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:33:48 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand load "http://stream-relay-geo.ntslive.net/stream2" Apr 14 18:33:48 volumio volumio[1015]: error: updateQueue error: null Apr 14 18:33:48 volumio volumio[1015]: info: ------------------------------ 4ms Apr 14 18:33:48 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 14 18:33:48 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:33:48 volumio volumio[1015]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 14 18:33:48 volumio volumio[1015]: info: ------------------------------ 11ms Apr 14 18:33:51 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand add "http://stream-relay-geo.ntslive.net/stream2" Apr 14 18:33:51 volumio volumio[1015]: info: Apr 14 18:33:51 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Apr 14 18:33:51 volumio volumio[1015]: info: Ignoring MPD Status Update Apr 14 18:33:51 volumio volumio[1015]: info: sendMpdCommand add "http://stream-relay-geo.ntslive.net/stream2" took 8 milliseconds Apr 14 18:33:51 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 18:33:51 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand play Apr 14 18:33:51 volumio volumio[1015]: info: ------------------------------ 9ms Apr 14 18:33:51 volumio volumio[1015]: info: sendMpdCommand play took 2 milliseconds Apr 14 18:33:52 volumio volumio[1015]: info: Apr 14 18:33:52 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:33:52 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:33:52 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:33:52 volumio volumio[1015]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 14 18:33:52 volumio volumio[1015]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 14 18:33:52 volumio volumio[1015]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 14 18:33:52 volumio volumio[1015]: info: Apr 14 18:33:52 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:33:52 volumio volumio[1015]: info: sendMpdCommand status took 21 milliseconds Apr 14 18:33:52 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:33:52 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:33:52 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:33:52 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:33:52 volumio volumio[1015]: info: sendMpdCommand status took 2 milliseconds Apr 14 18:33:52 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 14 18:33:52 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:33:52 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:33:52 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:33:52 volumio volumio[1015]: info: ControllerMpd::pushState Apr 14 18:33:52 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Apr 14 18:33:52 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:52 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2","artist":null,"album":null,"uri":"http://stream-relay-geo.ntslive.net/stream2","trackType":""} Apr 14 18:33:52 volumio volumio[1015]: verbose: CURRENT POSITION 0 Apr 14 18:33:52 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Apr 14 18:33:52 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus stop Apr 14 18:33:52 volumio volumio[1015]: info: ------------------------------ 29ms Apr 14 18:33:52 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 14 18:33:52 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:33:52 volumio volumio[1015]: info: ControllerMpd::pushState Apr 14 18:33:52 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Apr 14 18:33:52 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:52 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2","artist":null,"album":null,"uri":"http://stream-relay-geo.ntslive.net/stream2","trackType":""} Apr 14 18:33:52 volumio volumio[1015]: verbose: CURRENT POSITION 0 Apr 14 18:33:52 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Apr 14 18:33:52 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Apr 14 18:33:52 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Apr 14 18:33:52 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:33:52 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:52 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:33:52 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:33:52 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:33:52 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:33:52 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:33:52 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:33:52 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:52.321+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=0 volume=100 Apr 14 18:33:52 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:52.322+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://stream-relay-geo.ntslive.net/stream2 title="NTS Live International" Apr 14 18:33:52 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:52.325+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=0 volume=100 Apr 14 18:33:52 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:52.326+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://stream-relay-geo.ntslive.net/stream2 title="NTS Live International" Apr 14 18:33:52 volumio volumio[1015]: info: ------------------------------ 56ms Apr 14 18:33:52 volumio volumio[1015]: info: Apr 14 18:33:52 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Apr 14 18:33:52 volumio volumio[1015]: info: Ignoring MPD Status Update Apr 14 18:33:52 volumio volumio[1015]: info: Apr 14 18:33:52 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:33:52 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:33:52 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:33:52 volumio volumio[1015]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 14 18:33:52 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:33:52 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:33:52 volumio volumio[1015]: info: ------------------------------ 18ms Apr 14 18:33:52 volumio volumio[1015]: info: sendMpdCommand status took 15 milliseconds Apr 14 18:33:52 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:33:52 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:33:52 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 14 18:33:52 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:33:52 volumio volumio[1015]: info: ControllerMpd::pushState Apr 14 18:33:52 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Apr 14 18:33:52 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:33:52 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1727,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":" - ","artist":"NTS 2","album":null,"uri":"http://stream-relay-geo.ntslive.net/stream2","trackType":""} Apr 14 18:33:52 volumio volumio[1015]: verbose: CURRENT POSITION 0 Apr 14 18:33:52 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Apr 14 18:33:52 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Apr 14 18:33:52 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Apr 14 18:33:52 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:33:52 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:33:52 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:33:52 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:33:52 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:33:52 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:33:52 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:33:52 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:52.381+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=116 volume=100 Apr 14 18:33:52 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:52.381+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://stream-relay-geo.ntslive.net/stream2 title=" - " Apr 14 18:33:52 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:52.383+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=116 volume=100 Apr 14 18:33:52 volumio volumio5-onboarding[1645]: time=2026-04-14T18:33:52.384+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://stream-relay-geo.ntslive.net/stream2 title=" - " Apr 14 18:33:52 volumio volumio[1015]: info: ------------------------------ 54ms Apr 14 18:33:52 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:33:52 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:34:06 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 14 18:34:06 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:34:09 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 14 18:34:10 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:34:11 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:34:11 volumio volumio[1015]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 18:34:11 volumio volumio[1015]: info: CoreStateMachine::ClearQueue Apr 14 18:34:11 volumio volumio[1015]: info: CoreStateMachine::stop Apr 14 18:34:11 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:34:11 volumio volumio[1015]: info: CoreStateMachine::stPlaybackTimer Apr 14 18:34:11 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:34:11 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:34:11 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:34:11 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:34:11 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:34:11 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:34:11 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:34:11 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:34:11 volumio volumio[1015]: info: CoreStateMachine::serviceStop Apr 14 18:34:11 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:34:11 volumio volumio[1015]: info: CoreCommandRouter::serviceStop Apr 14 18:34:11 volumio volumio[1015]: info: [1776184451448] ControllerWebradio::stop Apr 14 18:34:11 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 18:34:11 volumio volumio[1015]: info: CorePlayQueue::clearPlayQueue Apr 14 18:34:11 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:34:11 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:34:11 volumio volumio[1015]: info: CoreStateMachine::addQueueItems Apr 14 18:34:11 volumio volumio[1015]: info: CorePlayQueue::addQueueItems Apr 14 18:34:11 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:34:11 volumio volumio[1015]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Apr 14 18:34:11 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 18:34:11 volumio volumio5-onboarding[1645]: time=2026-04-14T18:34:11.457+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_STOPPED positionMs=0 volume=100 Apr 14 18:34:11 volumio volumio5-onboarding[1645]: time=2026-04-14T18:34:11.458+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=http://stream-relay-geo.ntslive.net/stream2 title="NTS Live International" Apr 14 18:34:11 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:34:11 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:34:11 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:34:11 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:34:11 volumio volumio[1015]: info: CoreCommandRouter::volumioPlay Apr 14 18:34:11 volumio volumio[1015]: info: CoreStateMachine::play index 0 Apr 14 18:34:11 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:34:11 volumio volumio[1015]: info: CoreStateMachine::stop Apr 14 18:34:11 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:34:11 volumio volumio[1015]: info: CoreStateMachine::play index undefined Apr 14 18:34:11 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:34:11 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:34:11 volumio volumio[1015]: info: CoreStateMachine::startPlaybackTimer Apr 14 18:34:11 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:34:11 volumio volumio[1015]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 18:34:11 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 18:34:11 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 14 18:34:11 volumio volumio[1015]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Apr 14 18:34:11 volumio volumio[1015]: info: [1776184451481] ControllerWebradio::clearAddPlayTrack Apr 14 18:34:11 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 18:34:11 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:34:11 volumio volumio[1015]: info: Apr 14 18:34:11 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:34:11 volumio volumio[1015]: info: sendMpdCommand stop took 53 milliseconds Apr 14 18:34:11 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:34:11 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:34:11 volumio volumio[1015]: info: sendMpdCommand stop took 21 milliseconds Apr 14 18:34:11 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 18:34:11 volumio volumio[1015]: info: Apr 14 18:34:11 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Apr 14 18:34:11 volumio volumio[1015]: info: Ignoring MPD Status Update Apr 14 18:34:11 volumio volumio[1015]: info: sendMpdCommand status took 3 milliseconds Apr 14 18:34:11 volumio volumio[1015]: info: sendMpdCommand clear took 3 milliseconds Apr 14 18:34:11 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:34:11 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:34:11 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Apr 14 18:34:11 volumio volumio[1015]: error: updateQueue error: null Apr 14 18:34:11 volumio volumio[1015]: info: ------------------------------ 5ms Apr 14 18:34:11 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 14 18:34:11 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:34:11 volumio volumio[1015]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 14 18:34:11 volumio volumio[1015]: info: ------------------------------ 11ms Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: my_music , getSuperSearchEnabledSourcesList Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::volumioGetBrowseSources Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::volumioGetBrowseSources Apr 14 18:34:12 volumio volumio[1015]: info: All cached search sources collected Apr 14 18:34:12 volumio volumio[1015]: info: All cached search sources collected Apr 14 18:34:12 volumio volumio[1015]: info: All cached search sources collected Apr 14 18:34:12 volumio volumio[1015]: info: All cached search sources collected Apr 14 18:34:12 volumio volumio[1015]: info: All cached search sources collected Apr 14 18:34:12 volumio volumio[1015]: info: All cached search sources collected Apr 14 18:34:12 volumio volumio[1015]: info: All cached search sources collected Apr 14 18:34:12 volumio volumio[1015]: info: All cached search sources collected Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 18:34:12 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 18:34:12 volumio volumio[1015]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 1219 milliseconds Apr 14 18:34:12 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 18:34:12 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand play Apr 14 18:34:12 volumio volumio[1015]: info: Apr 14 18:34:12 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Apr 14 18:34:12 volumio volumio[1015]: info: Ignoring MPD Status Update Apr 14 18:34:12 volumio volumio[1015]: info: sendMpdCommand play took 22 milliseconds Apr 14 18:34:12 volumio volumio[1015]: info: ------------------------------ 18ms Apr 14 18:34:13 volumio volumio[1015]: info: searchQOBUZUri took 1595 milliseconds Apr 14 18:34:13 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:13 volumio volumio[1015]: info: CoreCommandRouter::volumioGetQueue Apr 14 18:34:13 volumio volumio[1015]: info: CoreStateMachine::getQueue Apr 14 18:34:13 volumio volumio[1015]: info: CorePlayQueue::getQueue Apr 14 18:34:13 volumio volumio[1015]: info: CoreCommandRouter::volumioAddQueueItems Apr 14 18:34:13 volumio volumio[1015]: info: CoreStateMachine::addQueueItems Apr 14 18:34:13 volumio volumio[1015]: info: CorePlayQueue::addQueueItems Apr 14 18:34:13 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:34:13 volumio volumio[1015]: info: Adding Item to queue: qobuz://song/5246687 Apr 14 18:34:13 volumio volumio[1015]: info: Exploding uri qobuz://song/5246687 in service qobuz Apr 14 18:34:13 volumio volumio[1015]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 14 18:34:13 volumio volumio[1015]: info: searchQOBUZUri took 1721 milliseconds Apr 14 18:34:13 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:13 volumio volumio[1015]: info: CoreCommandRouter::volumioGetQueue Apr 14 18:34:13 volumio volumio[1015]: info: CoreStateMachine::getQueue Apr 14 18:34:13 volumio volumio[1015]: info: CorePlayQueue::getQueue Apr 14 18:34:13 volumio volumio[1015]: info: CoreCommandRouter::volumioAddQueueItems Apr 14 18:34:13 volumio volumio[1015]: info: CoreStateMachine::addQueueItems Apr 14 18:34:13 volumio volumio[1015]: info: CorePlayQueue::addQueueItems Apr 14 18:34:13 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:34:13 volumio volumio[1015]: info: Adding Item to queue: qobuz://song/4864501 Apr 14 18:34:13 volumio volumio[1015]: info: Exploding uri qobuz://song/4864501 in service qobuz Apr 14 18:34:13 volumio volumio[1015]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 14 18:34:13 volumio volumio[1015]: info: searchQOBUZUri took 1711 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:14 volumio volumio[1015]: info: searchQOBUZUri took 1750 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:14 volumio volumio[1015]: info: searchQOBUZUri took 1758 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioGetQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::getQueue Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::getQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioAddQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::addQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::addQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:34:14 volumio volumio[1015]: info: Adding Item to queue: qobuz://song/20827809 Apr 14 18:34:14 volumio volumio[1015]: info: Exploding uri qobuz://song/20827809 in service qobuz Apr 14 18:34:14 volumio volumio[1015]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 14 18:34:14 volumio volumio[1015]: info: searchQOBUZUri took 1791 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:14 volumio volumio[1015]: info: searchQOBUZUri took 1770 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:14 volumio volumio[1015]: info: searchQOBUZUri took 1807 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioGetQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::getQueue Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::getQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioAddQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::addQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::addQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:34:14 volumio volumio[1015]: info: Adding Item to queue: qobuz://song/22119415 Apr 14 18:34:14 volumio volumio[1015]: info: Exploding uri qobuz://song/22119415 in service qobuz Apr 14 18:34:14 volumio volumio[1015]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 14 18:34:14 volumio volumio[1015]: info: searchQOBUZUri took 1831 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:14 volumio volumio[1015]: info: searchQOBUZUri took 1897 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:14 volumio volumio[1015]: info: searchQOBUZUri took 1946 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioGetQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::getQueue Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::getQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioAddQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::addQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::addQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:34:14 volumio volumio[1015]: info: Adding Item to queue: qobuz://song/15644299 Apr 14 18:34:14 volumio volumio[1015]: info: Exploding uri qobuz://song/15644299 in service qobuz Apr 14 18:34:14 volumio volumio[1015]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 14 18:34:14 volumio volumio[1015]: info: searchQOBUZUri took 1988 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioGetQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::getQueue Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::getQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioAddQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::addQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::addQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:34:14 volumio volumio[1015]: info: Adding Item to queue: qobuz://song/31831835 Apr 14 18:34:14 volumio volumio[1015]: info: Exploding uri qobuz://song/31831835 in service qobuz Apr 14 18:34:14 volumio volumio[1015]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 14 18:34:14 volumio volumio[1015]: info: searchQOBUZUri took 1978 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:14 volumio volumio[1015]: info: searchQOBUZUri took 1998 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioGetQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::getQueue Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::getQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioAddQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::addQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::addQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:34:14 volumio volumio[1015]: info: Adding Item to queue: qobuz://song/163497669 Apr 14 18:34:14 volumio volumio[1015]: info: Exploding uri qobuz://song/163497669 in service qobuz Apr 14 18:34:14 volumio volumio[1015]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 14 18:34:14 volumio volumio[1015]: info: searchQOBUZUri took 2009 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioGetQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::getQueue Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::getQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioAddQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::addQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::addQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:34:14 volumio volumio[1015]: info: Adding Item to queue: qobuz://song/3893501 Apr 14 18:34:14 volumio volumio[1015]: info: Exploding uri qobuz://song/3893501 in service qobuz Apr 14 18:34:14 volumio volumio[1015]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 14 18:34:14 volumio volumio[1015]: info: searchQOBUZUri took 2031 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioGetQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::getQueue Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::getQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioAddQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::addQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::addQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:34:14 volumio volumio[1015]: info: Adding Item to queue: qobuz://song/378385 Apr 14 18:34:14 volumio volumio[1015]: info: Exploding uri qobuz://song/378385 in service qobuz Apr 14 18:34:14 volumio volumio[1015]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 14 18:34:14 volumio volumio[1015]: info: searchQOBUZUri took 2043 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioGetQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::getQueue Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::getQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioAddQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::addQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::addQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:34:14 volumio volumio[1015]: info: Adding Item to queue: qobuz://song/742808 Apr 14 18:34:14 volumio volumio[1015]: info: Exploding uri qobuz://song/742808 in service qobuz Apr 14 18:34:14 volumio volumio[1015]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 14 18:34:14 volumio volumio[1015]: info: searchQOBUZUri took 2114 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:14 volumio volumio[1015]: info: searchQOBUZUri took 2168 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioGetQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::getQueue Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::getQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioAddQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::addQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::addQueueItems Apr 14 18:34:14 volumio volumio[1015]: info: Preload queue cleared Apr 14 18:34:14 volumio volumio[1015]: info: Adding Item to queue: qobuz://song/66392794 Apr 14 18:34:14 volumio volumio[1015]: info: Exploding uri qobuz://song/66392794 in service qobuz Apr 14 18:34:14 volumio volumio[1015]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 14 18:34:14 volumio volumio[1015]: info: explodeUri took 591 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:34:14 volumio volumio[1015]: info: Apr 14 18:34:14 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:34:14 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:34:14 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:34:14 volumio volumio[1015]: info: searchQOBUZUri took 2275 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: All search sources collected, pushing search results Apr 14 18:34:14 volumio volumio[1015]: info: METAVOLUMIO: Infinity Playback added 11 Tracks to queue Apr 14 18:34:14 volumio volumio[1015]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 14 18:34:14 volumio volumio[1015]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 14 18:34:14 volumio volumio[1015]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 14 18:34:14 volumio volumio[1015]: info: Apr 14 18:34:14 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:34:14 volumio volumio[1015]: info: sendMpdCommand status took 17 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:34:14 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:34:14 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:34:14 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:34:14 volumio volumio[1015]: info: sendMpdCommand status took 3 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 14 18:34:14 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:34:14 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:34:14 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:34:14 volumio volumio[1015]: info: ControllerMpd::pushState Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:34:14 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8314/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 18:34:14 volumio volumio[1015]: verbose: CURRENT POSITION 0 Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus stop Apr 14 18:34:14 volumio volumio[1015]: info: ------------------------------ 30ms Apr 14 18:34:14 volumio volumio[1015]: info: Apr 14 18:34:14 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Apr 14 18:34:14 volumio volumio[1015]: info: Ignoring MPD Status Update Apr 14 18:34:14 volumio volumio[1015]: info: Apr 14 18:34:14 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:34:14 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:34:14 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:34:14 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:34:14 volumio volumio[1015]: info: ControllerMpd::pushState Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:34:14 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8314/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 18:34:14 volumio volumio[1015]: verbose: CURRENT POSITION 0 Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Apr 14 18:34:14 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:34:14 volumio volumio5-onboarding[1645]: time=2026-04-14T18:34:14.664+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=0 volume=100 Apr 14 18:34:14 volumio volumio5-onboarding[1645]: time=2026-04-14T18:34:14.664+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 18:34:14 volumio volumio5-onboarding[1645]: time=2026-04-14T18:34:14.666+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=0 volume=100 Apr 14 18:34:14 volumio volumio5-onboarding[1645]: time=2026-04-14T18:34:14.667+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 18:34:14 volumio volumio[1015]: info: ------------------------------ 70ms Apr 14 18:34:14 volumio volumio[1015]: info: Apr 14 18:34:14 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Apr 14 18:34:14 volumio volumio[1015]: info: Ignoring MPD Status Update Apr 14 18:34:14 volumio volumio[1015]: info: Apr 14 18:34:14 volumio volumio[1015]: ---------------------------- MPD announces state update: player Apr 14 18:34:14 volumio volumio[1015]: info: ControllerMpd::getState Apr 14 18:34:14 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:34:14 volumio volumio[1015]: info: ------------------------------ 63ms Apr 14 18:34:14 volumio volumio[1015]: info: sendMpdCommand status took 59 milliseconds Apr 14 18:34:14 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:34:14 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:34:14 volumio volumio[1015]: info: explodeUri took 722 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:34:14 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:34:14 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:34:14 volumio volumio[1015]: info: explodeUri took 653 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:34:14 volumio volumio[1015]: info: explodeUri took 717 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:34:14 volumio volumio[1015]: info: ------------------------------ 89ms Apr 14 18:34:14 volumio volumio[1015]: info: sendMpdCommand status took 89 milliseconds Apr 14 18:34:14 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 87 milliseconds Apr 14 18:34:14 volumio volumio[1015]: verbose: ControllerMpd::parseState Apr 14 18:34:14 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 18:34:14 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:34:14 volumio volumio[1015]: info: ControllerMpd::pushState Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:34:14 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1188,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 18:34:14 volumio volumio[1015]: verbose: CURRENT POSITION 0 Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Apr 14 18:34:14 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:34:14 volumio volumio5-onboarding[1645]: time=2026-04-14T18:34:14.793+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=283 volume=100 Apr 14 18:34:14 volumio volumio5-onboarding[1645]: time=2026-04-14T18:34:14.794+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 18:34:14 volumio volumio5-onboarding[1645]: time=2026-04-14T18:34:14.794+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=283 volume=100 Apr 14 18:34:14 volumio volumio5-onboarding[1645]: time=2026-04-14T18:34:14.795+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 18:34:14 volumio volumio[1015]: info: ------------------------------ 182ms Apr 14 18:34:14 volumio volumio[1015]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 14 18:34:14 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:34:14 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:34:14 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 91 milliseconds Apr 14 18:34:14 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Apr 14 18:34:14 volumio volumio[1015]: info: ControllerMpd::pushState Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Apr 14 18:34:14 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 14 18:34:14 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1737,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 18:34:14 volumio volumio[1015]: verbose: CURRENT POSITION 0 Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Apr 14 18:34:14 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:34:14 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 14 18:34:14 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 14 18:34:14 volumio volumio5-onboarding[1645]: time=2026-04-14T18:34:14.889+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=283 volume=100 Apr 14 18:34:14 volumio volumio5-onboarding[1645]: time=2026-04-14T18:34:14.889+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 18:34:14 volumio volumio5-onboarding[1645]: time=2026-04-14T18:34:14.891+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" state=STATUS_PLAYING positionMs=283 volume=100 Apr 14 18:34:14 volumio volumio5-onboarding[1645]: time=2026-04-14T18:34:14.891+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.3:39694 @ 0x2000ff0" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 18:34:14 volumio volumio[1015]: info: ------------------------------ 219ms Apr 14 18:34:15 volumio volumio[1015]: info: explodeUri took 735 milliseconds Apr 14 18:34:15 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:34:15 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:34:15 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:34:15 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:34:15 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:34:15 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 14 18:34:15 volumio volumio[1015]: info: explodeUri took 744 milliseconds Apr 14 18:34:15 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:34:15 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:34:15 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:34:15 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:34:15 volumio volumio[1015]: info: explodeUri took 810 milliseconds Apr 14 18:34:15 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:34:15 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:34:15 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:34:15 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:34:15 volumio volumio[1015]: info: explodeUri took 789 milliseconds Apr 14 18:34:15 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:34:15 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:34:15 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:34:15 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:34:15 volumio volumio[1015]: info: explodeUri took 783 milliseconds Apr 14 18:34:15 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:34:15 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:34:15 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:34:15 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:34:15 volumio volumio[1015]: info: explodeUri took 788 milliseconds Apr 14 18:34:15 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:34:15 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:34:15 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:34:15 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:34:15 volumio volumio[1015]: info: explodeUri took 967 milliseconds Apr 14 18:34:15 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:34:15 volumio volumio[1015]: info: CorePlayQueue::saveQueue Apr 14 18:34:15 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Apr 14 18:34:15 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Apr 14 18:34:16 volumio volumio[1015]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 18:34:16 volumio volumio[1015]: TypeError: Cannot read property 'slice' of null Apr 14 18:34:16 volumio volumio[1015]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Apr 14 18:34:16 volumio volumio[1015]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Apr 14 18:34:16 volumio volumio[1015]: at Object.onceWrapper (events.js:422:26) Apr 14 18:34:16 volumio volumio[1015]: at ClientRequest.emit (events.js:315:20) Apr 14 18:34:16 volumio volumio[1015]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Apr 14 18:34:16 volumio volumio[1015]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Apr 14 18:34:16 volumio volumio[1015]: at TLSSocket.socketOnData (_http_client.js:509:22) Apr 14 18:34:16 volumio volumio[1015]: at TLSSocket.emit (events.js:315:20) Apr 14 18:34:16 volumio volumio[1015]: at addChunk (internal/streams/readable.js:309:12) Apr 14 18:34:16 volumio volumio[1015]: at readableAddChunk (internal/streams/readable.js:284:9) Apr 14 18:34:16 volumio volumio[1015]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Apr 14 18:34:16 volumio volumio[1015]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Apr 14 18:34:16 volumio volumio[1015]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 18:34:16 volumio sudo[2361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-14 18:33 Apr 14 18:34:16 volumio sudo[2361]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"