Feb 24 13:25:02 volumio go-librespot[2654]: time="2026-02-24T13:25:02+03:00" level=trace msg="sent dealer ping" Feb 24 13:25:02 volumio go-librespot[2654]: time="2026-02-24T13:25:02+03:00" level=trace msg="received dealer pong" Feb 24 13:25:06 volumio volumio[1119]: info: CALLMETHOD: system_hardware pi_screen_setup checkForDatabaseUpdate undefined Feb 24 13:25:06 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: pi_screen_setup , checkForDatabaseUpdate Feb 24 13:25:06 volumio volumio[1119]: info: pi_screen_setup: Checking for database update from https://raw.githubusercontent.com/foonerd/pi_screen_setup/refs/heads/main/display_presets.json Feb 24 13:25:12 volumio volumio[1119]: info: CALLMETHOD: system_hardware pi_screen_setup applyDatabaseUpdate undefined Feb 24 13:25:12 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: pi_screen_setup , applyDatabaseUpdate Feb 24 13:25:12 volumio volumio[1119]: info: pi_screen_setup: Downloading database update from https://raw.githubusercontent.com/foonerd/pi_screen_setup/refs/heads/main/display_presets.json Feb 24 13:25:13 volumio volumio[1119]: info: pi_screen_setup: Database updated to v1.5.6 with 227 presets Feb 24 13:25:13 volumio volumio[1119]: info: pi_screen_setup: refreshUIConfig called, will trigger after 100ms Feb 24 13:25:13 volumio volumio[1119]: info: pi_screen_setup: refreshUIConfig timeout - calling getUIConfig Feb 24 13:25:13 volumio volumio[1119]: info: pi_screen_setup: getUIConfig - wizardStep=0, wizardComplete=false, migrationState=review (cache=review, stepCache=0) Feb 24 13:25:13 volumio volumio[1119]: info: pi_screen_setup: getUIConfig - checkMigration=false Feb 24 13:25:13 volumio volumio[1119]: info: pi_screen_setup: OTA drift section hidden=true (driftDetected=false, wizardComplete=false) Feb 24 13:25:13 volumio volumio[1119]: info: pi_screen_setup: Section 1 - default hidden, checkMigration=false, migrationState=review Feb 24 13:25:13 volumio volumio[1119]: info: pi_screen_setup: In review state - showing review section Feb 24 13:25:13 volumio volumio[1119]: info: pi_screen_setup: Review - rawLines count: 6, parseResult keys: parsed,recognized,unknown Feb 24 13:25:13 volumio volumio[1119]: info: pi_screen_setup: Review - interpreted: KMS Driver: vc4-kms-v3d | HDMI Force Hotplug: Enabled Feb 24 13:25:13 volumio volumio[1119]: info: pi_screen_setup: Resolving uiconf with review section visible Feb 24 13:25:13 volumio volumio[1119]: info: pi_screen_setup: refreshUIConfig - broadcasting pushUiConfig Feb 24 13:25:16 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 13:25:16 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 24 13:25:22 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Feb 24 13:25:22 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:25:26 volumio volumio[1119]: info: CoreCommandRouter::volumioGetQueue Feb 24 13:25:26 volumio volumio[1119]: info: CoreStateMachine::getQueue Feb 24 13:25:26 volumio volumio[1119]: info: CorePlayQueue::getQueue Feb 24 13:25:27 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 13:25:27 volumio volumio[1119]: info: CURURI: playlists Feb 24 13:25:27 volumio volumio[1119]: info: Listing playlists Feb 24 13:25:28 volumio volumio[1119]: info: Preload queue cleared Feb 24 13:25:31 volumio go-librespot[2654]: time="2026-02-24T13:25:31+03:00" level=trace msg="received accesspoint ping" Feb 24 13:25:31 volumio go-librespot[2654]: time="2026-02-24T13:25:31+03:00" level=trace msg="received accesspoint pong ack" Feb 24 13:25:32 volumio go-librespot[2654]: time="2026-02-24T13:25:32+03:00" level=trace msg="sent dealer ping" Feb 24 13:25:32 volumio go-librespot[2654]: time="2026-02-24T13:25:32+03:00" level=trace msg="received dealer pong" Feb 24 13:25:33 volumio volumio[1119]: info: CoreCommandRouter::volumioPlay Feb 24 13:25:33 volumio volumio[1119]: info: CoreStateMachine::play index undefined Feb 24 13:25:33 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 13:25:33 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:25:40 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 13:25:40 volumio volumio[1119]: info: CURURI: music-library Feb 24 13:25:40 volumio volumio[1119]: error: Failed LSINFO: null Feb 24 13:25:40 volumio volumio[1119]: info: Preload queue cleared Feb 24 13:25:45 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 24 13:25:45 volumio volumio[1119]: info: Preload queue cleared Feb 24 13:25:46 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 24 13:25:51 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 24 13:25:51 volumio volumio[1119]: info: Getting webradio selection Feb 24 13:25:51 volumio volumio[1119]: info: Preload queue cleared Feb 24 13:25:58 volumio volumio[1119]: info: Preload queue cleared Feb 24 13:25:58 volumio volumio[1119]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 13:25:58 volumio volumio[1119]: info: CoreStateMachine::ClearQueue Feb 24 13:25:58 volumio volumio[1119]: info: CoreStateMachine::stop Feb 24 13:25:58 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 13:25:58 volumio volumio[1119]: info: CorePlayQueue::clearPlayQueue Feb 24 13:25:58 volumio volumio[1119]: info: CorePlayQueue::saveQueue Feb 24 13:25:58 volumio volumio[1119]: info: CoreCommandRouter::volumioPushQueue Feb 24 13:25:58 volumio volumio[1119]: info: CoreStateMachine::addQueueItems Feb 24 13:25:58 volumio volumio[1119]: info: CorePlayQueue::addQueueItems Feb 24 13:25:58 volumio volumio[1119]: info: Preload queue cleared Feb 24 13:25:58 volumio volumio[1119]: info: Adding Item to queue: http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3 Feb 24 13:25:58 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 24 13:25:58 volumio volumio[1119]: info: CoreCommandRouter::volumioPushQueue Feb 24 13:25:58 volumio volumio[1119]: info: CorePlayQueue::saveQueue Feb 24 13:25:58 volumio volumio[1119]: info: CoreStateMachine::updateTrackBlock Feb 24 13:25:58 volumio volumio[1119]: info: CorePlayQueue::getTrackBlock Feb 24 13:25:58 volumio volumio[1119]: info: CoreCommandRouter::volumioPlay Feb 24 13:25:58 volumio volumio[1119]: info: CoreStateMachine::play index 0 Feb 24 13:25:58 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 13:25:58 volumio volumio[1119]: info: CoreStateMachine::stop Feb 24 13:25:58 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 13:25:58 volumio volumio[1119]: info: CoreStateMachine::play index undefined Feb 24 13:25:58 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 13:25:58 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:25:58 volumio volumio[1119]: info: CoreStateMachine::startPlaybackTimer Feb 24 13:25:58 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:25:58 volumio volumio[1119]: info: [1771928758907] ControllerWebradio::clearAddPlayTrack Feb 24 13:25:58 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 13:25:58 volumio volumio[1119]: info: sendMpdCommand stop took 9 milliseconds Feb 24 13:25:58 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 13:25:58 volumio volumio[1119]: info: Feb 24 13:25:58 volumio volumio[1119]: ---------------------------- MPD announces system playlist update Feb 24 13:25:58 volumio volumio[1119]: info: Ignoring MPD Status Update Feb 24 13:25:58 volumio volumio[1119]: info: sendMpdCommand clear took 4 milliseconds Feb 24 13:25:58 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand load "http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3" Feb 24 13:25:58 volumio volumio[1119]: info: Feb 24 13:25:58 volumio volumio[1119]: ---------------------------- MPD announces system playlist update Feb 24 13:25:58 volumio volumio[1119]: info: Ignoring MPD Status Update Feb 24 13:25:58 volumio volumio[1119]: info: Feb 24 13:25:58 volumio volumio[1119]: ---------------------------- MPD announces system playlist update Feb 24 13:25:58 volumio volumio[1119]: info: Ignoring MPD Status Update Feb 24 13:25:58 volumio volumio[1119]: error: updateQueue error: null Feb 24 13:25:58 volumio volumio[1119]: info: ------------------------------ 14ms Feb 24 13:25:59 volumio volumio[1119]: error: updateQueue error: null Feb 24 13:25:59 volumio volumio[1119]: error: updateQueue error: null Feb 24 13:25:59 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand add "http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3" Feb 24 13:25:59 volumio volumio[1119]: info: ------------------------------ 707ms Feb 24 13:25:59 volumio volumio[1119]: info: ------------------------------ 705ms Feb 24 13:25:59 volumio volumio[1119]: info: Feb 24 13:25:59 volumio volumio[1119]: ---------------------------- MPD announces system playlist update Feb 24 13:25:59 volumio volumio[1119]: info: Ignoring MPD Status Update Feb 24 13:25:59 volumio volumio[1119]: info: sendMpdCommand add "http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3" took 3 milliseconds Feb 24 13:25:59 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 13:25:59 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand play Feb 24 13:25:59 volumio volumio[1119]: info: Feb 24 13:25:59 volumio volumio[1119]: ---------------------------- MPD announces system playlist update Feb 24 13:25:59 volumio volumio[1119]: info: Ignoring MPD Status Update Feb 24 13:25:59 volumio volumio[1119]: info: Feb 24 13:25:59 volumio volumio[1119]: ---------------------------- MPD announces system playlist update Feb 24 13:25:59 volumio volumio[1119]: info: Ignoring MPD Status Update Feb 24 13:25:59 volumio volumio[1119]: info: ------------------------------ 10ms Feb 24 13:25:59 volumio volumio[1119]: info: sendMpdCommand play took 8 milliseconds Feb 24 13:25:59 volumio volumio[1119]: info: ------------------------------ 8ms Feb 24 13:25:59 volumio volumio[1119]: info: ------------------------------ 6ms Feb 24 13:26:00 volumio volumio[1119]: info: Feb 24 13:26:00 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:00 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:00 volumio volumio[1119]: info: Feb 24 13:26:00 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:00 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:00 volumio volumio[1119]: info: Feb 24 13:26:00 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:00 volumio volumio[1119]: info: Feb 24 13:26:00 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:00 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:00 volumio volumio[1119]: info: sendMpdCommand status took 12 milliseconds Feb 24 13:26:00 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:00 volumio volumio[1119]: info: Feb 24 13:26:00 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:00 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:00 volumio volumio[1119]: info: Feb 24 13:26:00 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:00 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:00 volumio volumio[1119]: info: sendMpdCommand status took 19 milliseconds Feb 24 13:26:00 volumio volumio[1119]: info: sendMpdCommand status took 13 milliseconds Feb 24 13:26:00 volumio volumio[1119]: info: sendMpdCommand status took 10 milliseconds Feb 24 13:26:00 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 24 13:26:00 volumio volumio[1119]: info: sendMpdCommand status took 6 milliseconds Feb 24 13:26:00 volumio volumio[1119]: info: sendMpdCommand status took 4 milliseconds Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:00 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:00 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:00 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusiqueclassiqueplus-midfi.mp3","artist":null,"album":null,"uri":"http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3","trackType":"mp3"} Feb 24 13:26:00 volumio volumio[1119]: verbose: CURRENT POSITION 0 Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus stop Feb 24 13:26:00 volumio volumio[1119]: info: ------------------------------ 41ms Feb 24 13:26:00 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 24 13:26:00 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 24 13:26:00 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 24 13:26:00 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 24 13:26:00 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:00 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:00 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:00 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusiqueclassiqueplus-midfi.mp3","artist":null,"album":null,"uri":"http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3","trackType":"mp3"} Feb 24 13:26:00 volumio volumio[1119]: verbose: CURRENT POSITION 0 Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Feb 24 13:26:00 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:00 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:00 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:00 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusiqueclassiqueplus-midfi.mp3","artist":null,"album":null,"uri":"http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3","trackType":"mp3"} Feb 24 13:26:00 volumio volumio[1119]: verbose: CURRENT POSITION 0 Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Feb 24 13:26:00 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:00 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:00 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:00 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusiqueclassiqueplus-midfi.mp3","artist":null,"album":null,"uri":"http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3","trackType":"mp3"} Feb 24 13:26:00 volumio volumio[1119]: verbose: CURRENT POSITION 0 Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Feb 24 13:26:00 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:00 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:00 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:00 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusiqueclassiqueplus-midfi.mp3","artist":null,"album":null,"uri":"http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3","trackType":"mp3"} Feb 24 13:26:00 volumio volumio[1119]: verbose: CURRENT POSITION 0 Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Feb 24 13:26:00 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:00 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:00 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:00 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusiqueclassiqueplus-midfi.mp3","artist":null,"album":null,"uri":"http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3","trackType":"mp3"} Feb 24 13:26:00 volumio volumio[1119]: verbose: CURRENT POSITION 0 Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Feb 24 13:26:00 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:00 volumio volumio[1119]: info: ------------------------------ 205ms Feb 24 13:26:00 volumio volumio[1119]: info: ------------------------------ 203ms Feb 24 13:26:00 volumio volumio[1119]: info: ------------------------------ 203ms Feb 24 13:26:00 volumio volumio[1119]: info: ------------------------------ 191ms Feb 24 13:26:00 volumio volumio[1119]: info: ------------------------------ 190ms Feb 24 13:26:00 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:00 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:00 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:00 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:00 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:00 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:00 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:00 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:00 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:00 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:00 volumio volumio[1119]: info: Feb 24 13:26:00 volumio volumio[1119]: ---------------------------- MPD announces system playlist update Feb 24 13:26:00 volumio volumio[1119]: info: Ignoring MPD Status Update Feb 24 13:26:00 volumio volumio[1119]: info: Feb 24 13:26:00 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:00 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:00 volumio volumio[1119]: info: Feb 24 13:26:00 volumio volumio[1119]: ---------------------------- MPD announces system playlist update Feb 24 13:26:00 volumio volumio[1119]: info: Ignoring MPD Status Update Feb 24 13:26:00 volumio volumio[1119]: info: Feb 24 13:26:00 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:00 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:00 volumio volumio[1119]: info: Feb 24 13:26:00 volumio volumio[1119]: ---------------------------- MPD announces system playlist update Feb 24 13:26:00 volumio volumio[1119]: info: Ignoring MPD Status Update Feb 24 13:26:00 volumio volumio[1119]: info: Feb 24 13:26:00 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:00 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:00 volumio volumio[1119]: info: ------------------------------ 10ms Feb 24 13:26:00 volumio volumio[1119]: info: sendMpdCommand status took 7 milliseconds Feb 24 13:26:00 volumio volumio[1119]: info: ------------------------------ 7ms Feb 24 13:26:00 volumio volumio[1119]: info: sendMpdCommand status took 6 milliseconds Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:00 volumio volumio[1119]: info: ------------------------------ 9ms Feb 24 13:26:00 volumio volumio[1119]: info: sendMpdCommand status took 7 milliseconds Feb 24 13:26:00 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 13:26:00 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:00 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:00 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:00 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"francemusiqueclassiqueplus-midfi.mp3","artist":"francemusiqueclassiqueplus-midfi.mp3","album":null,"uri":"http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3","trackType":"mp3"} Feb 24 13:26:00 volumio volumio[1119]: verbose: CURRENT POSITION 0 Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Feb 24 13:26:00 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:00 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:00 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:00 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"francemusiqueclassiqueplus-midfi.mp3","artist":"francemusiqueclassiqueplus-midfi.mp3","album":null,"uri":"http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3","trackType":"mp3"} Feb 24 13:26:00 volumio volumio[1119]: verbose: CURRENT POSITION 0 Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Feb 24 13:26:00 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:00 volumio volumio[1119]: info: ------------------------------ 85ms Feb 24 13:26:00 volumio volumio[1119]: info: ------------------------------ 85ms Feb 24 13:26:00 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 72 milliseconds Feb 24 13:26:00 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:00 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:00 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:00 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"francemusiqueclassiqueplus-midfi.mp3","artist":"francemusiqueclassiqueplus-midfi.mp3","album":null,"uri":"http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3","trackType":"mp3"} Feb 24 13:26:00 volumio volumio[1119]: verbose: CURRENT POSITION 0 Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Feb 24 13:26:00 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:00 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:00 volumio volumio[1119]: info: ------------------------------ 136ms Feb 24 13:26:00 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:00 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:00 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:00 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:00 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:00 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:02 volumio go-librespot[2654]: time="2026-02-24T13:26:02+03:00" level=trace msg="sent dealer ping" Feb 24 13:26:02 volumio go-librespot[2654]: time="2026-02-24T13:26:02+03:00" level=trace msg="received dealer pong" Feb 24 13:26:02 volumio volumio[1119]: info: Preload queue cleared Feb 24 13:26:02 volumio volumio[1119]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 13:26:02 volumio volumio[1119]: info: CoreStateMachine::ClearQueue Feb 24 13:26:02 volumio volumio[1119]: info: CoreStateMachine::stop Feb 24 13:26:02 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 13:26:02 volumio volumio[1119]: info: CoreStateMachine::stPlaybackTimer Feb 24 13:26:02 volumio volumio[1119]: info: CoreStateMachine::updateTrackBlock Feb 24 13:26:02 volumio volumio[1119]: info: CorePlayQueue::getTrackBlock Feb 24 13:26:02 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:02 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:02 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 13:26:02 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:02 volumio volumio[1119]: info: CoreStateMachine::serviceStop Feb 24 13:26:02 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:02 volumio volumio[1119]: info: CoreCommandRouter::serviceStop Feb 24 13:26:02 volumio volumio[1119]: info: [1771928762901] ControllerWebradio::stop Feb 24 13:26:02 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 13:26:02 volumio volumio[1119]: info: CorePlayQueue::clearPlayQueue Feb 24 13:26:02 volumio volumio[1119]: info: CorePlayQueue::saveQueue Feb 24 13:26:02 volumio volumio[1119]: info: CoreCommandRouter::volumioPushQueue Feb 24 13:26:02 volumio volumio[1119]: info: CoreStateMachine::addQueueItems Feb 24 13:26:02 volumio volumio[1119]: info: CorePlayQueue::addQueueItems Feb 24 13:26:02 volumio volumio[1119]: info: Preload queue cleared Feb 24 13:26:02 volumio volumio[1119]: info: Adding Item to queue: http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3 Feb 24 13:26:02 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 24 13:26:02 volumio volumio[1119]: info: CoreCommandRouter::volumioPushQueue Feb 24 13:26:02 volumio volumio[1119]: info: CorePlayQueue::saveQueue Feb 24 13:26:02 volumio volumio[1119]: info: CoreStateMachine::updateTrackBlock Feb 24 13:26:02 volumio volumio[1119]: info: CorePlayQueue::getTrackBlock Feb 24 13:26:02 volumio volumio[1119]: info: CoreCommandRouter::volumioPlay Feb 24 13:26:02 volumio volumio[1119]: info: CoreStateMachine::play index 0 Feb 24 13:26:02 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 13:26:02 volumio volumio[1119]: info: CoreStateMachine::stop Feb 24 13:26:02 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 13:26:02 volumio volumio[1119]: info: CoreStateMachine::play index undefined Feb 24 13:26:02 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 13:26:02 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:02 volumio volumio[1119]: info: CoreStateMachine::startPlaybackTimer Feb 24 13:26:02 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:02 volumio volumio[1119]: info: [1771928762944] ControllerWebradio::clearAddPlayTrack Feb 24 13:26:02 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 13:26:02 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:02 volumio volumio[1119]: info: Feb 24 13:26:02 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:02 volumio volumio[1119]: info: sendMpdCommand stop took 55 milliseconds Feb 24 13:26:02 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:02 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:02 volumio volumio[1119]: info: sendMpdCommand stop took 15 milliseconds Feb 24 13:26:02 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 13:26:02 volumio volumio[1119]: info: Feb 24 13:26:02 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:02 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:02 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:02 volumio volumio[1119]: info: Feb 24 13:26:02 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:02 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:02 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:02 volumio volumio[1119]: info: Feb 24 13:26:02 volumio volumio[1119]: ---------------------------- MPD announces system playlist update Feb 24 13:26:02 volumio volumio[1119]: info: Ignoring MPD Status Update Feb 24 13:26:02 volumio volumio[1119]: info: sendMpdCommand status took 8 milliseconds Feb 24 13:26:02 volumio volumio[1119]: info: sendMpdCommand clear took 7 milliseconds Feb 24 13:26:02 volumio volumio[1119]: info: sendMpdCommand status took 6 milliseconds Feb 24 13:26:02 volumio volumio[1119]: info: sendMpdCommand status took 4 milliseconds Feb 24 13:26:02 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:02 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:02 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand load "http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3" Feb 24 13:26:02 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:02 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:02 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:02 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:02 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:02 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:02 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:02 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:02 volumio volumio[1119]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 13:26:02 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:02 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:02 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:02 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:02 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:02 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:02 volumio volumio[1119]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 13:26:03 volumio volumio[1119]: info: ------------------------------ 53ms Feb 24 13:26:03 volumio volumio[1119]: info: ------------------------------ 52ms Feb 24 13:26:03 volumio volumio[1119]: info: Feb 24 13:26:03 volumio volumio[1119]: ---------------------------- MPD announces system playlist update Feb 24 13:26:03 volumio volumio[1119]: info: Ignoring MPD Status Update Feb 24 13:26:03 volumio volumio[1119]: info: Feb 24 13:26:03 volumio volumio[1119]: ---------------------------- MPD announces system playlist update Feb 24 13:26:03 volumio volumio[1119]: info: Ignoring MPD Status Update Feb 24 13:26:03 volumio volumio[1119]: error: updateQueue error: null Feb 24 13:26:03 volumio volumio[1119]: info: ------------------------------ 60ms Feb 24 13:26:03 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 57 milliseconds Feb 24 13:26:03 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:03 volumio volumio[1119]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 24 13:26:03 volumio volumio[1119]: info: ------------------------------ 72ms Feb 24 13:26:03 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:03 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:03 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 24 13:26:03 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 24 13:26:03 volumio systemd[1]: setdatetime-helper.service: Consumed 1.034s CPU time. Feb 24 13:26:03 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand add "http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3" Feb 24 13:26:03 volumio volumio[1119]: error: updateQueue error: null Feb 24 13:26:03 volumio volumio[1119]: error: updateQueue error: null Feb 24 13:26:03 volumio volumio[1119]: info: Feb 24 13:26:03 volumio volumio[1119]: ---------------------------- MPD announces system playlist update Feb 24 13:26:03 volumio volumio[1119]: info: Ignoring MPD Status Update Feb 24 13:26:03 volumio volumio[1119]: info: ------------------------------ 528ms Feb 24 13:26:03 volumio volumio[1119]: info: ------------------------------ 526ms Feb 24 13:26:03 volumio volumio[1119]: info: sendMpdCommand add "http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3" took 2 milliseconds Feb 24 13:26:03 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 13:26:03 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand play Feb 24 13:26:03 volumio volumio[1119]: info: Feb 24 13:26:03 volumio volumio[1119]: ---------------------------- MPD announces system playlist update Feb 24 13:26:03 volumio volumio[1119]: info: Ignoring MPD Status Update Feb 24 13:26:03 volumio volumio[1119]: info: Feb 24 13:26:03 volumio volumio[1119]: ---------------------------- MPD announces system playlist update Feb 24 13:26:03 volumio volumio[1119]: info: Ignoring MPD Status Update Feb 24 13:26:03 volumio volumio[1119]: info: ------------------------------ 9ms Feb 24 13:26:03 volumio volumio[1119]: info: sendMpdCommand play took 8 milliseconds Feb 24 13:26:03 volumio volumio[1119]: info: ------------------------------ 6ms Feb 24 13:26:03 volumio volumio[1119]: info: ------------------------------ 4ms Feb 24 13:26:04 volumio volumio[1119]: info: Feb 24 13:26:04 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:04 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:04 volumio volumio[1119]: info: Feb 24 13:26:04 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:04 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:04 volumio volumio[1119]: info: Feb 24 13:26:04 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:04 volumio volumio[1119]: info: Feb 24 13:26:04 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:04 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:04 volumio volumio[1119]: info: sendMpdCommand status took 5 milliseconds Feb 24 13:26:04 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:04 volumio volumio[1119]: info: Feb 24 13:26:04 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:04 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:04 volumio volumio[1119]: info: Feb 24 13:26:04 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:04 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:04 volumio volumio[1119]: info: sendMpdCommand status took 11 milliseconds Feb 24 13:26:04 volumio volumio[1119]: info: sendMpdCommand status took 9 milliseconds Feb 24 13:26:04 volumio volumio[1119]: info: sendMpdCommand status took 9 milliseconds Feb 24 13:26:04 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 24 13:26:04 volumio volumio[1119]: info: sendMpdCommand status took 5 milliseconds Feb 24 13:26:04 volumio volumio[1119]: info: sendMpdCommand status took 4 milliseconds Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:04 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:04 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:04 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusiqueclassiqueplus-midfi.mp3","artist":null,"album":null,"uri":"http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3","trackType":"mp3"} Feb 24 13:26:04 volumio volumio[1119]: verbose: CURRENT POSITION 0 Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus stop Feb 24 13:26:04 volumio volumio[1119]: info: ------------------------------ 23ms Feb 24 13:26:04 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 24 13:26:04 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 24 13:26:04 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 24 13:26:04 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 24 13:26:04 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:04 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:04 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:04 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusiqueclassiqueplus-midfi.mp3","artist":null,"album":null,"uri":"http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3","trackType":"mp3"} Feb 24 13:26:04 volumio volumio[1119]: verbose: CURRENT POSITION 0 Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Feb 24 13:26:04 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:04 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:04 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:04 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusiqueclassiqueplus-midfi.mp3","artist":null,"album":null,"uri":"http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3","trackType":"mp3"} Feb 24 13:26:04 volumio volumio[1119]: verbose: CURRENT POSITION 0 Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Feb 24 13:26:04 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:04 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:04 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:04 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusiqueclassiqueplus-midfi.mp3","artist":null,"album":null,"uri":"http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3","trackType":"mp3"} Feb 24 13:26:04 volumio volumio[1119]: verbose: CURRENT POSITION 0 Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Feb 24 13:26:04 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:04 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:04 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:04 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusiqueclassiqueplus-midfi.mp3","artist":null,"album":null,"uri":"http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3","trackType":"mp3"} Feb 24 13:26:04 volumio volumio[1119]: verbose: CURRENT POSITION 0 Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Feb 24 13:26:04 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:04 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:04 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:04 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusiqueclassiqueplus-midfi.mp3","artist":null,"album":null,"uri":"http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3","trackType":"mp3"} Feb 24 13:26:04 volumio volumio[1119]: verbose: CURRENT POSITION 0 Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Feb 24 13:26:04 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:04 volumio volumio[1119]: info: ------------------------------ 179ms Feb 24 13:26:04 volumio volumio[1119]: info: ------------------------------ 177ms Feb 24 13:26:04 volumio volumio[1119]: info: ------------------------------ 177ms Feb 24 13:26:04 volumio volumio[1119]: info: ------------------------------ 173ms Feb 24 13:26:04 volumio volumio[1119]: info: ------------------------------ 172ms Feb 24 13:26:04 volumio volumio[1119]: info: Feb 24 13:26:04 volumio volumio[1119]: ---------------------------- MPD announces system playlist update Feb 24 13:26:04 volumio volumio[1119]: info: Ignoring MPD Status Update Feb 24 13:26:04 volumio volumio[1119]: info: Feb 24 13:26:04 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:04 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:04 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:04 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:04 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:04 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:04 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:04 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:04 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:04 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:04 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:04 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:04 volumio volumio[1119]: info: Feb 24 13:26:04 volumio volumio[1119]: ---------------------------- MPD announces system playlist update Feb 24 13:26:04 volumio volumio[1119]: info: Ignoring MPD Status Update Feb 24 13:26:04 volumio volumio[1119]: info: Feb 24 13:26:04 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:04 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:04 volumio volumio[1119]: info: Feb 24 13:26:04 volumio volumio[1119]: ---------------------------- MPD announces system playlist update Feb 24 13:26:04 volumio volumio[1119]: info: Ignoring MPD Status Update Feb 24 13:26:04 volumio volumio[1119]: info: Feb 24 13:26:04 volumio volumio[1119]: ---------------------------- MPD announces state update: player Feb 24 13:26:04 volumio volumio[1119]: info: ControllerMpd::getState Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Feb 24 13:26:04 volumio volumio[1119]: info: ------------------------------ 63ms Feb 24 13:26:04 volumio volumio[1119]: info: sendMpdCommand status took 63 milliseconds Feb 24 13:26:04 volumio volumio[1119]: info: ------------------------------ 7ms Feb 24 13:26:04 volumio volumio[1119]: info: sendMpdCommand status took 7 milliseconds Feb 24 13:26:04 volumio volumio[1119]: info: ------------------------------ 6ms Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:04 volumio volumio[1119]: info: sendMpdCommand status took 8 milliseconds Feb 24 13:26:04 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 13:26:04 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::parseState Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:04 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:04 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:04 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"francemusiqueclassiqueplus-midfi.mp3","artist":"francemusiqueclassiqueplus-midfi.mp3","album":null,"uri":"http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3","trackType":"mp3"} Feb 24 13:26:04 volumio volumio[1119]: verbose: CURRENT POSITION 0 Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Feb 24 13:26:04 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:04 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:04 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:04 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1104,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"francemusiqueclassiqueplus-midfi.mp3","artist":"francemusiqueclassiqueplus-midfi.mp3","album":null,"uri":"http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3","trackType":"mp3"} Feb 24 13:26:04 volumio volumio[1119]: verbose: CURRENT POSITION 0 Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Feb 24 13:26:04 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:04 volumio volumio[1119]: info: ------------------------------ 135ms Feb 24 13:26:04 volumio volumio[1119]: info: ------------------------------ 78ms Feb 24 13:26:04 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 67 milliseconds Feb 24 13:26:04 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo Feb 24 13:26:04 volumio volumio[1119]: info: ControllerMpd::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Feb 24 13:26:04 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:04 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1104,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"francemusiqueclassiqueplus-midfi.mp3","artist":"francemusiqueclassiqueplus-midfi.mp3","album":null,"uri":"http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3","trackType":"mp3"} Feb 24 13:26:04 volumio volumio[1119]: verbose: CURRENT POSITION 0 Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Feb 24 13:26:04 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreStateMachine::pushState Feb 24 13:26:04 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Feb 24 13:26:04 volumio volumio[1119]: info: ------------------------------ 116ms Feb 24 13:26:04 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:04 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:04 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:04 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:04 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:04 volumio volumio[1119]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 13:26:13 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 24 13:26:13 volumio volumio[1119]: info: Preload queue cleared Feb 24 13:26:18 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 24 13:26:21 volumio volumio[1119]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 13:26:21 volumio volumio[1119]: XMLStructuredError: Premature end of data in tag br line 189 (Line: 199, Column: 1) Feb 24 13:26:21 volumio volumio[1119]: at new XMLStructuredError (/volumio/node_modules/libxmljs/dist/lib/types.js:23:28) Feb 24 13:26:21 volumio volumio[1119]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:190:23 Feb 24 13:26:21 volumio volumio[1119]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Feb 24 13:26:21 volumio volumio[1119]: at /volumio/app/plugins/music_service/webradio/index.js:434:31 Feb 24 13:26:21 volumio volumio[1119]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) { Feb 24 13:26:21 volumio volumio[1119]: domain: 1, Feb 24 13:26:21 volumio volumio[1119]: code: 77, Feb 24 13:26:21 volumio volumio[1119]: level: 3, Feb 24 13:26:21 volumio volumio[1119]: column: 1, Feb 24 13:26:21 volumio volumio[1119]: file: '', Feb 24 13:26:21 volumio volumio[1119]: line: 199, Feb 24 13:26:21 volumio volumio[1119]: str1: 'br', Feb 24 13:26:21 volumio volumio[1119]: str2: undefined, Feb 24 13:26:21 volumio volumio[1119]: str3: undefined, Feb 24 13:26:21 volumio volumio[1119]: int1: 189 Feb 24 13:26:21 volumio volumio[1119]: } Feb 24 13:26:21 volumio volumio[1119]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 13:26:21 volumio sudo[3018]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-24 13:25' Feb 24 13:26:21 volumio sudo[3018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"