Apr 11 12:13:00 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 11 12:13:00 volumio volumio[1347]: info: Preload queue cleared Apr 11 12:13:04 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 11 12:13:05 volumio volumio[1347]: info: Preload queue cleared Apr 11 12:13:08 volumio volumio[1347]: info: Preload queue cleared Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::ClearQueue Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::stop Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::stPlaybackTimer Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::updateTrackBlock Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::getTrackBlock Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::serviceStop Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::serviceStop Apr 11 12:13:08 volumio volumio[1347]: info: [1775905988342] ControllerWebradio::stop Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::clearPlayQueue Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::saveQueue Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::volumioPushQueue Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::addQueueItems Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::addQueueItems Apr 11 12:13:08 volumio volumio[1347]: info: Preload queue cleared Apr 11 12:13:08 volumio volumio[1347]: info: Adding Item to queue: https://ice-sov.musicradio.com/ClassicFMHD?hdauth=:2000000000:a290d4b39a16153061d4c743008b9fe8d424a7e5ec6469d40acf51fdcd336e80 Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::volumioPushQueue Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::saveQueue Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::updateTrackBlock Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::getTrackBlock Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::volumioPlay Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::play index 0 Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::stop Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::play index undefined Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::startPlaybackTimer Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:08 volumio volumio[1347]: info: [1775905988353] ControllerWebradio::clearAddPlayTrack Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 12:13:08 volumio volumio[1347]: info: Apr 11 12:13:08 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand stop took 14 milliseconds Apr 11 12:13:08 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand stop took 3 milliseconds Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand clear Apr 11 12:13:08 volumio volumio[1347]: info: Apr 11 12:13:08 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:08 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:08 volumio volumio[1347]: info: Apr 11 12:13:08 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:08 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:08 volumio volumio[1347]: info: Apr 11 12:13:08 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:08 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand status took 2 milliseconds Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand clear took 2 milliseconds Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand status took 1 milliseconds Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand load "https://ice-sov.musicradio.com/ClassicFMHD?hdauth=:2000000000:a290d4b39a16153061d4c743008b9fe8d424a7e5ec6469d40acf51fdcd336e80" Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:08 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:08 volumio volumio[1347]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 11 12:13:08 volumio volumio[1347]: info: ------------------------------ 7ms Apr 11 12:13:08 volumio volumio[1347]: info: Apr 11 12:13:08 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:08 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:08 volumio volumio[1347]: info: Apr 11 12:13:08 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:08 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:08 volumio volumio[1347]: error: updateQueue error: null Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand status took 132 milliseconds Apr 11 12:13:08 volumio volumio[1347]: info: ------------------------------ 131ms Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 131 milliseconds Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand add "https://ice-sov.musicradio.com/ClassicFMHD?hdauth=:2000000000:a290d4b39a16153061d4c743008b9fe8d424a7e5ec6469d40acf51fdcd336e80" Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:08 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:08 volumio volumio[1347]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 11 12:13:08 volumio volumio[1347]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Apr 11 12:13:08 volumio volumio[1347]: info: ------------------------------ 138ms Apr 11 12:13:08 volumio volumio[1347]: info: ------------------------------ 140ms Apr 11 12:13:08 volumio volumio[1347]: error: updateQueue error: null Apr 11 12:13:08 volumio volumio[1347]: error: updateQueue error: null Apr 11 12:13:08 volumio volumio[1347]: info: Apr 11 12:13:08 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:08 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:08 volumio volumio[1347]: info: ------------------------------ 131ms Apr 11 12:13:08 volumio volumio[1347]: info: ------------------------------ 131ms Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand add "https://ice-sov.musicradio.com/ClassicFMHD?hdauth=:2000000000:a290d4b39a16153061d4c743008b9fe8d424a7e5ec6469d40acf51fdcd336e80" took 7 milliseconds Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand play Apr 11 12:13:08 volumio volumio[1347]: info: Apr 11 12:13:08 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:08 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:08 volumio volumio[1347]: info: Apr 11 12:13:08 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:08 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:08 volumio volumio[1347]: info: ------------------------------ 2ms Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand play took 2 milliseconds Apr 11 12:13:08 volumio volumio[1347]: info: ------------------------------ 2ms Apr 11 12:13:08 volumio volumio[1347]: info: ------------------------------ 1ms Apr 11 12:13:08 volumio volumio[1347]: info: Apr 11 12:13:08 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:08 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:08 volumio volumio[1347]: info: Apr 11 12:13:08 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:08 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:08 volumio volumio[1347]: info: Apr 11 12:13:08 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:08 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:08 volumio volumio[1347]: info: Apr 11 12:13:08 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand status took 3 milliseconds Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand status took 3 milliseconds Apr 11 12:13:08 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:08 volumio volumio[1347]: info: Apr 11 12:13:08 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:08 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand status took 3 milliseconds Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand status took 0 milliseconds Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:08 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:08 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"191 Kbps","isStreaming":false,"title":"Francois Dompierre , Angele Dubeau - Les Beautes du Diable","artist":"Classic FM","album":null,"uri":"https://ice-sov.musicradio.com/ClassicFMHD?hdauth=:2000000000:a290d4b39a16153061d4c743008b9fe8d424a7e5ec6469d40acf51fdcd336e80","trackType":""} Apr 11 12:13:08 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus stop Apr 11 12:13:08 volumio volumio[1347]: info: ------------------------------ 5ms Apr 11 12:13:08 volumio volumio[1347]: info: Apr 11 12:13:08 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:08 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:08 volumio volumio[1347]: info: Apr 11 12:13:08 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:08 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 12:13:08 volumio volumio[1347]: info: ------------------------------ 2ms Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand status took 0 milliseconds Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:08 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:08 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"191 Kbps","isStreaming":false,"title":"Francois Dompierre , Angele Dubeau - Les Beautes du Diable","artist":"Classic FM","album":null,"uri":"https://ice-sov.musicradio.com/ClassicFMHD?hdauth=:2000000000:a290d4b39a16153061d4c743008b9fe8d424a7e5ec6469d40acf51fdcd336e80","trackType":""} Apr 11 12:13:08 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:08 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:08 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:08 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"191 Kbps","isStreaming":false,"title":"Francois Dompierre , Angele Dubeau - Les Beautes du Diable","artist":"Classic FM","album":null,"uri":"https://ice-sov.musicradio.com/ClassicFMHD?hdauth=:2000000000:a290d4b39a16153061d4c743008b9fe8d424a7e5ec6469d40acf51fdcd336e80","trackType":""} Apr 11 12:13:08 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:08 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:08 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:08 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":343,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"191 Kbps","isStreaming":false,"title":"Francois Dompierre , Angele Dubeau - Les Beautes du Diable","artist":"Classic FM","album":null,"uri":"https://ice-sov.musicradio.com/ClassicFMHD?hdauth=:2000000000:a290d4b39a16153061d4c743008b9fe8d424a7e5ec6469d40acf51fdcd336e80","trackType":""} Apr 11 12:13:08 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:08 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:08 volumio volumio[1347]: info: ------------------------------ 27ms Apr 11 12:13:08 volumio volumio[1347]: info: ------------------------------ 27ms Apr 11 12:13:08 volumio volumio[1347]: info: ------------------------------ 24ms Apr 11 12:13:08 volumio volumio[1347]: info: Apr 11 12:13:08 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:08 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand status took 22 milliseconds Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 21 milliseconds Apr 11 12:13:08 volumio volumio[1347]: info: ------------------------------ 1ms Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:08 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:08 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"191 Kbps","isStreaming":false,"title":"Francois Dompierre , Angele Dubeau - Les Beautes du Diable","artist":"Classic FM","album":null,"uri":"https://ice-sov.musicradio.com/ClassicFMHD?hdauth=:2000000000:a290d4b39a16153061d4c743008b9fe8d424a7e5ec6469d40acf51fdcd336e80","trackType":""} Apr 11 12:13:08 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:08 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:08 volumio volumio[1347]: info: ------------------------------ 30ms Apr 11 12:13:08 volumio volumio[1347]: info: Apr 11 12:13:08 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:08 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:08 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 11 12:13:08 volumio volumio[1347]: info: ------------------------------ 7ms Apr 11 12:13:08 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:08 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:08 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:08 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"191 Kbps","isStreaming":false,"title":"Francois Dompierre , Angele Dubeau - Les Beautes du Diable","artist":"Classic FM","album":null,"uri":"https://ice-sov.musicradio.com/ClassicFMHD?hdauth=:2000000000:a290d4b39a16153061d4c743008b9fe8d424a7e5ec6469d40acf51fdcd336e80","trackType":""} Apr 11 12:13:08 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:08 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:08 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:08 volumio volumio[1347]: info: ------------------------------ 44ms Apr 11 12:13:09 volumio volumio[1347]: info: Apr 11 12:13:09 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:09 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:09 volumio volumio[1347]: info: Apr 11 12:13:09 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:09 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:09 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:09 volumio volumio[1347]: info: Apr 11 12:13:09 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:09 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:09 volumio volumio[1347]: info: Apr 11 12:13:09 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:09 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:09 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:09 volumio volumio[1347]: info: Apr 11 12:13:09 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:09 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:09 volumio volumio[1347]: info: Apr 11 12:13:09 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:09 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:09 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:09 volumio volumio[1347]: info: ------------------------------ 2ms Apr 11 12:13:09 volumio volumio[1347]: info: sendMpdCommand status took 1 milliseconds Apr 11 12:13:09 volumio volumio[1347]: info: ------------------------------ 1ms Apr 11 12:13:09 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:09 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:09 volumio volumio[1347]: info: sendMpdCommand status took 1 milliseconds Apr 11 12:13:09 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:09 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:09 volumio volumio[1347]: info: ------------------------------ 5ms Apr 11 12:13:09 volumio volumio[1347]: info: sendMpdCommand status took 4 milliseconds Apr 11 12:13:09 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 11 12:13:09 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 11 12:13:09 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:09 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:09 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:09 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:09 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:09 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:09 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:09 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1981,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"191 Kbps","isStreaming":false,"title":"Ralph Vaughan Williams - Fantasia on a theme by Thomas Tallis","artist":"Classic FM","album":null,"uri":"https://ice-sov.musicradio.com/ClassicFMHD?hdauth=:2000000000:a290d4b39a16153061d4c743008b9fe8d424a7e5ec6469d40acf51fdcd336e80","trackType":""} Apr 11 12:13:09 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:09 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:09 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:09 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:09 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 12:13:09 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:09 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 12:13:09 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:09 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:09 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:09 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:09 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:09 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:09 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1981,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"191 Kbps","isStreaming":false,"title":"Ralph Vaughan Williams - Fantasia on a theme by Thomas Tallis","artist":"Classic FM","album":null,"uri":"https://ice-sov.musicradio.com/ClassicFMHD?hdauth=:2000000000:a290d4b39a16153061d4c743008b9fe8d424a7e5ec6469d40acf51fdcd336e80","trackType":""} Apr 11 12:13:09 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:09 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:09 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:09 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:09 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 12:13:09 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:09 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:09 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:09 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:09 volumio volumio[1347]: info: ------------------------------ 20ms Apr 11 12:13:09 volumio volumio[1347]: info: ------------------------------ 20ms Apr 11 12:13:09 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 11 12:13:09 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:09 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:09 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:09 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:09 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1981,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"191 Kbps","isStreaming":false,"title":"Ralph Vaughan Williams - Fantasia on a theme by Thomas Tallis","artist":"Classic FM","album":null,"uri":"https://ice-sov.musicradio.com/ClassicFMHD?hdauth=:2000000000:a290d4b39a16153061d4c743008b9fe8d424a7e5ec6469d40acf51fdcd336e80","trackType":""} Apr 11 12:13:09 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:09 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:09 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:09 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:09 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 12:13:09 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:09 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:09 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:09 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:09 volumio volumio[1347]: info: ------------------------------ 28ms Apr 11 12:13:13 volumio volumio[1347]: info: VolumeController::SetAlsaVolume0 Apr 11 12:13:13 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:13 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 12:13:13 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:13 volumio volumio[1347]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Apr 11 12:13:28 volumio volumio[1347]: info: Preload queue cleared Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::ClearQueue Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::stop Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::stPlaybackTimer Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::updateTrackBlock Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrackBlock Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::serviceStop Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::serviceStop Apr 11 12:13:28 volumio volumio[1347]: info: [1775906008301] ControllerWebradio::stop Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::clearPlayQueue Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::saveQueue Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::volumioPushQueue Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::addQueueItems Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::addQueueItems Apr 11 12:13:28 volumio volumio[1347]: info: Preload queue cleared Apr 11 12:13:28 volumio volumio[1347]: info: Adding Item to queue: http://as-hls-ww-live.akamaized.net/pool_23461179/live/ww/bbc_radio_three/bbc_radio_three.isml/bbc_radio_three-audio=320000.norewind.m3u8 Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::volumioPushQueue Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::saveQueue Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::updateTrackBlock Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrackBlock Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::volumioPlay Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::play index 0 Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::stop Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::play index undefined Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::startPlaybackTimer Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: info: [1775906008309] ControllerWebradio::clearAddPlayTrack Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 12:13:28 volumio volumio[1347]: info: Apr 11 12:13:28 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand stop took 9 milliseconds Apr 11 12:13:28 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand stop took 3 milliseconds Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand clear Apr 11 12:13:28 volumio volumio[1347]: info: Apr 11 12:13:28 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:28 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:28 volumio volumio[1347]: info: Apr 11 12:13:28 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:28 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:28 volumio volumio[1347]: info: Apr 11 12:13:28 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:28 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand status took 1 milliseconds Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand clear took 2 milliseconds Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand status took 1 milliseconds Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand status took 1 milliseconds Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_23461179/live/ww/bbc_radio_three/bbc_radio_three.isml/bbc_radio_three-audio=320000.norewind.m3u8" Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:28 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 11 12:13:28 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 11 12:13:28 volumio volumio[1347]: info: ------------------------------ 10ms Apr 11 12:13:28 volumio volumio[1347]: info: ------------------------------ 10ms Apr 11 12:13:28 volumio volumio[1347]: info: Apr 11 12:13:28 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:28 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:28 volumio volumio[1347]: info: Apr 11 12:13:28 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:28 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:28 volumio volumio[1347]: error: updateQueue error: null Apr 11 12:13:28 volumio volumio[1347]: info: Apr 11 12:13:28 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:28 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:28 volumio volumio[1347]: info: ------------------------------ 11ms Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_23461179/live/ww/bbc_radio_three/bbc_radio_three.isml/bbc_radio_three-audio=320000.norewind.m3u8" took 10 milliseconds Apr 11 12:13:28 volumio volumio[1347]: info: ------------------------------ 1ms Apr 11 12:13:28 volumio volumio[1347]: info: ------------------------------ 1ms Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand play Apr 11 12:13:28 volumio volumio[1347]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Apr 11 12:13:28 volumio volumio[1347]: info: ------------------------------ 13ms Apr 11 12:13:28 volumio volumio[1347]: info: Apr 11 12:13:28 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:28 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:28 volumio volumio[1347]: info: Apr 11 12:13:28 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:28 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:28 volumio volumio[1347]: info: ------------------------------ 6ms Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand play took 5 milliseconds Apr 11 12:13:28 volumio volumio[1347]: info: ------------------------------ 5ms Apr 11 12:13:28 volumio volumio[1347]: info: ------------------------------ 4ms Apr 11 12:13:28 volumio volumio[1347]: info: Apr 11 12:13:28 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:28 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:28 volumio volumio[1347]: info: Apr 11 12:13:28 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:28 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:28 volumio volumio[1347]: info: Apr 11 12:13:28 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:28 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:28 volumio volumio[1347]: info: Apr 11 12:13:28 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand status took 26 milliseconds Apr 11 12:13:28 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand status took 27 milliseconds Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand status took 27 milliseconds Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand status took 0 milliseconds Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:28 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_three-audio=320000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_23461179/live/ww/bbc_radio_three/bbc_radio_three.isml/bbc_radio_three-audio=320000.norewind.m3u8","trackType":"m3u8"} Apr 11 12:13:28 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus stop Apr 11 12:13:28 volumio volumio[1347]: info: ------------------------------ 29ms Apr 11 12:13:28 volumio volumio[1347]: info: Apr 11 12:13:28 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:28 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:28 volumio volumio[1347]: info: Apr 11 12:13:28 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:28 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand status took 0 milliseconds Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand status took 0 milliseconds Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:28 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_three-audio=320000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_23461179/live/ww/bbc_radio_three/bbc_radio_three.isml/bbc_radio_three-audio=320000.norewind.m3u8","trackType":"m3u8"} Apr 11 12:13:28 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:28 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:28 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":41,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_three-audio=320000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_23461179/live/ww/bbc_radio_three/bbc_radio_three.isml/bbc_radio_three-audio=320000.norewind.m3u8","trackType":"m3u8"} Apr 11 12:13:28 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:28 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:28 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":431,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_three-audio=320000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_23461179/live/ww/bbc_radio_three/bbc_radio_three.isml/bbc_radio_three-audio=320000.norewind.m3u8","trackType":"m3u8"} Apr 11 12:13:28 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:28 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:28 volumio volumio[1347]: info: ------------------------------ 50ms Apr 11 12:13:28 volumio volumio[1347]: info: ------------------------------ 50ms Apr 11 12:13:28 volumio volumio[1347]: info: ------------------------------ 24ms Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 20 milliseconds Apr 11 12:13:28 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 21 milliseconds Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:28 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:28 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_three-audio=320000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_23461179/live/ww/bbc_radio_three/bbc_radio_three.isml/bbc_radio_three-audio=320000.norewind.m3u8","trackType":"m3u8"} Apr 11 12:13:28 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:28 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:28 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_three-audio=320000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_23461179/live/ww/bbc_radio_three/bbc_radio_three.isml/bbc_radio_three-audio=320000.norewind.m3u8","trackType":"m3u8"} Apr 11 12:13:28 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:28 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:28 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:28 volumio volumio[1347]: info: ------------------------------ 36ms Apr 11 12:13:28 volumio volumio[1347]: info: ------------------------------ 35ms Apr 11 12:13:41 volumio volumio[1347]: info: Preload queue cleared Apr 11 12:13:41 volumio volumio[1347]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 11 12:13:41 volumio volumio[1347]: info: CoreStateMachine::ClearQueue Apr 11 12:13:41 volumio volumio[1347]: info: CoreStateMachine::stop Apr 11 12:13:41 volumio volumio[1347]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 12:13:41 volumio volumio[1347]: info: CoreStateMachine::stPlaybackTimer Apr 11 12:13:41 volumio volumio[1347]: info: CoreStateMachine::updateTrackBlock Apr 11 12:13:41 volumio volumio[1347]: info: CorePlayQueue::getTrackBlock Apr 11 12:13:41 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:41 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:41 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 12:13:41 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:41 volumio volumio[1347]: info: CoreStateMachine::serviceStop Apr 11 12:13:41 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:41 volumio volumio[1347]: info: CoreCommandRouter::serviceStop Apr 11 12:13:41 volumio volumio[1347]: info: [1775906021618] ControllerWebradio::stop Apr 11 12:13:41 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 12:13:41 volumio volumio[1347]: info: CorePlayQueue::clearPlayQueue Apr 11 12:13:41 volumio volumio[1347]: info: CorePlayQueue::saveQueue Apr 11 12:13:41 volumio volumio[1347]: info: CoreCommandRouter::volumioPushQueue Apr 11 12:13:41 volumio volumio[1347]: info: CoreStateMachine::addQueueItems Apr 11 12:13:41 volumio volumio[1347]: info: CorePlayQueue::addQueueItems Apr 11 12:13:41 volumio volumio[1347]: info: Preload queue cleared Apr 11 12:13:41 volumio volumio[1347]: info: Adding Item to queue: https://stream.klassikradio.de/filmklassiker/mp3-192/ Apr 11 12:13:41 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 11 12:13:41 volumio volumio[1347]: info: CoreCommandRouter::volumioPushQueue Apr 11 12:13:41 volumio volumio[1347]: info: CorePlayQueue::saveQueue Apr 11 12:13:41 volumio volumio[1347]: info: CoreStateMachine::updateTrackBlock Apr 11 12:13:41 volumio volumio[1347]: info: CorePlayQueue::getTrackBlock Apr 11 12:13:41 volumio volumio[1347]: info: CoreCommandRouter::volumioPlay Apr 11 12:13:41 volumio volumio[1347]: info: CoreStateMachine::play index 0 Apr 11 12:13:41 volumio volumio[1347]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 12:13:41 volumio volumio[1347]: info: CoreStateMachine::stop Apr 11 12:13:41 volumio volumio[1347]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 12:13:41 volumio volumio[1347]: info: CoreStateMachine::play index undefined Apr 11 12:13:41 volumio volumio[1347]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 12:13:41 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:41 volumio volumio[1347]: info: CoreStateMachine::startPlaybackTimer Apr 11 12:13:41 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:41 volumio volumio[1347]: info: [1775906021625] ControllerWebradio::clearAddPlayTrack Apr 11 12:13:41 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 12:13:44 volumio volumio[1347]: info: Apr 11 12:13:44 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:44 volumio volumio[1347]: info: sendMpdCommand stop took 3029 milliseconds Apr 11 12:13:44 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:44 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:44 volumio volumio[1347]: info: sendMpdCommand stop took 3022 milliseconds Apr 11 12:13:44 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand clear Apr 11 12:13:44 volumio volumio[1347]: info: Apr 11 12:13:44 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:44 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:44 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:44 volumio volumio[1347]: info: Apr 11 12:13:44 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:44 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:44 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:44 volumio volumio[1347]: info: Apr 11 12:13:44 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:44 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:44 volumio volumio[1347]: info: sendMpdCommand status took 2 milliseconds Apr 11 12:13:44 volumio volumio[1347]: info: sendMpdCommand clear took 2 milliseconds Apr 11 12:13:44 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:44 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:44 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand load "https://stream.klassikradio.de/filmklassiker/mp3-192/" Apr 11 12:13:44 volumio volumio[1347]: info: Apr 11 12:13:44 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:44 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:44 volumio volumio[1347]: error: updateQueue error: null Apr 11 12:13:44 volumio volumio[1347]: info: sendMpdCommand status took 3 milliseconds Apr 11 12:13:44 volumio volumio[1347]: info: sendMpdCommand status took 2 milliseconds Apr 11 12:13:44 volumio volumio[1347]: info: ------------------------------ 2ms Apr 11 12:13:44 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 12:13:44 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:44 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:44 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:44 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:44 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:44 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:44 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:44 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 12:13:44 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:44 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:44 volumio volumio[1347]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 11 12:13:44 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:44 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:44 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:44 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:44 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:44 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:44 volumio volumio[1347]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 11 12:13:44 volumio volumio[1347]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Apr 11 12:13:44 volumio volumio[1347]: info: ------------------------------ 13ms Apr 11 12:13:44 volumio volumio[1347]: info: ------------------------------ 12ms Apr 11 12:13:44 volumio volumio[1347]: info: ------------------------------ 14ms Apr 11 12:13:44 volumio volumio[1347]: info: Apr 11 12:13:44 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:44 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:45 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand add "https://stream.klassikradio.de/filmklassiker/mp3-192/" Apr 11 12:13:45 volumio volumio[1347]: error: updateQueue error: null Apr 11 12:13:45 volumio volumio[1347]: error: updateQueue error: null Apr 11 12:13:45 volumio volumio[1347]: info: ------------------------------ 617ms Apr 11 12:13:45 volumio volumio[1347]: info: ------------------------------ 606ms Apr 11 12:13:45 volumio volumio[1347]: info: sendMpdCommand add "https://stream.klassikradio.de/filmklassiker/mp3-192/" took 1 milliseconds Apr 11 12:13:45 volumio volumio[1347]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 12:13:45 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand play Apr 11 12:13:45 volumio volumio[1347]: info: Apr 11 12:13:45 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:45 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:45 volumio volumio[1347]: info: sendMpdCommand play took 1 milliseconds Apr 11 12:13:45 volumio volumio[1347]: info: Apr 11 12:13:45 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:45 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:45 volumio volumio[1347]: info: Apr 11 12:13:45 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:45 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:45 volumio volumio[1347]: info: ------------------------------ 1ms Apr 11 12:13:45 volumio volumio[1347]: info: ------------------------------ 1ms Apr 11 12:13:45 volumio volumio[1347]: info: ------------------------------ 1ms Apr 11 12:13:46 volumio volumio[1347]: info: Apr 11 12:13:46 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:46 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:46 volumio volumio[1347]: info: Apr 11 12:13:46 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:46 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:46 volumio volumio[1347]: info: Apr 11 12:13:46 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:46 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:46 volumio volumio[1347]: info: sendMpdCommand status took 26 milliseconds Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:46 volumio volumio[1347]: info: Apr 11 12:13:46 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:46 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:46 volumio volumio[1347]: info: Apr 11 12:13:46 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:46 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:46 volumio volumio[1347]: info: sendMpdCommand status took 27 milliseconds Apr 11 12:13:46 volumio volumio[1347]: info: sendMpdCommand status took 26 milliseconds Apr 11 12:13:46 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:46 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:46 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:46 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:46 volumio volumio[1347]: 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":"","artist":null,"album":null,"uri":"https://stream.klassikradio.de/filmklassiker/mp3-192/","trackType":""} Apr 11 12:13:46 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:46 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:46 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus stop Apr 11 12:13:46 volumio volumio[1347]: info: ------------------------------ 28ms Apr 11 12:13:46 volumio volumio[1347]: info: Apr 11 12:13:46 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:46 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:46 volumio volumio[1347]: info: sendMpdCommand status took 2 milliseconds Apr 11 12:13:46 volumio volumio[1347]: info: sendMpdCommand status took 2 milliseconds Apr 11 12:13:46 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 12:13:46 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 12:13:46 volumio volumio[1347]: info: sendMpdCommand status took 1 milliseconds Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:46 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:46 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:46 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:46 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:46 volumio volumio[1347]: 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":"","artist":null,"album":null,"uri":"https://stream.klassikradio.de/filmklassiker/mp3-192/","trackType":""} Apr 11 12:13:46 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:46 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:46 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:46 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:46 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:46 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 12:13:46 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:46 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:46 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:46 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:46 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:46 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:46 volumio volumio[1347]: 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":"","artist":null,"album":null,"uri":"https://stream.klassikradio.de/filmklassiker/mp3-192/","trackType":""} Apr 11 12:13:46 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:46 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:46 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:46 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:46 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:46 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:46 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:46 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:46 volumio volumio[1347]: info: ------------------------------ 46ms Apr 11 12:13:46 volumio volumio[1347]: info: ------------------------------ 46ms Apr 11 12:13:47 volumio volumio[1347]: info: Apr 11 12:13:47 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:47 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:47 volumio volumio[1347]: info: Apr 11 12:13:47 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:47 volumio volumio[1347]: info: Apr 11 12:13:47 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:47 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:47 volumio volumio[1347]: info: Apr 11 12:13:47 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:47 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 11 12:13:47 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 11 12:13:47 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:47 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 11 12:13:47 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:47 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:47 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:47 volumio volumio[1347]: 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":"","artist":null,"album":null,"uri":"https://stream.klassikradio.de/filmklassiker/mp3-192/","trackType":""} Apr 11 12:13:47 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:47 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:47 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:47 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:47 volumio volumio[1347]: 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":"","artist":null,"album":null,"uri":"https://stream.klassikradio.de/filmklassiker/mp3-192/","trackType":""} Apr 11 12:13:47 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:47 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:47 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:47 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:47 volumio volumio[1347]: 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":"Filmklassiker","artist":"Filmklassiker","album":null,"uri":"https://stream.klassikradio.de/filmklassiker/mp3-192/","trackType":""} Apr 11 12:13:47 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:47 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:47 volumio volumio[1347]: info: ------------------------------ 44ms Apr 11 12:13:47 volumio volumio[1347]: info: ------------------------------ 43ms Apr 11 12:13:47 volumio volumio[1347]: info: ------------------------------ 42ms Apr 11 12:13:47 volumio volumio[1347]: info: Apr 11 12:13:47 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:47 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:47 volumio volumio[1347]: info: Apr 11 12:13:47 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:47 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:47 volumio volumio[1347]: info: Apr 11 12:13:47 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:47 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:47 volumio volumio[1347]: info: Apr 11 12:13:47 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:47 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:47 volumio volumio[1347]: info: ------------------------------ 32ms Apr 11 12:13:47 volumio volumio[1347]: info: sendMpdCommand status took 33 milliseconds Apr 11 12:13:47 volumio volumio[1347]: info: ------------------------------ 33ms Apr 11 12:13:47 volumio volumio[1347]: info: sendMpdCommand status took 33 milliseconds Apr 11 12:13:47 volumio volumio[1347]: info: ------------------------------ 4ms Apr 11 12:13:47 volumio volumio[1347]: info: sendMpdCommand status took 4 milliseconds Apr 11 12:13:47 volumio volumio[1347]: info: ------------------------------ 4ms Apr 11 12:13:47 volumio volumio[1347]: info: sendMpdCommand status took 4 milliseconds Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:47 volumio volumio[1347]: info: Apr 11 12:13:47 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:47 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:47 volumio volumio[1347]: info: Apr 11 12:13:47 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:47 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:47 volumio volumio[1347]: info: Apr 11 12:13:47 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:13:47 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:13:47 volumio volumio[1347]: info: Apr 11 12:13:47 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:13:47 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:13:47 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 12:13:47 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 12:13:47 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 12:13:47 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 12:13:47 volumio volumio[1347]: info: ------------------------------ 2ms Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:47 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:47 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:47 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Filmklassiker","artist":"Filmklassiker","album":null,"uri":"https://stream.klassikradio.de/filmklassiker/mp3-192/","trackType":""} Apr 11 12:13:47 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:47 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:47 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:47 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:47 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Filmklassiker","artist":"Filmklassiker","album":null,"uri":"https://stream.klassikradio.de/filmklassiker/mp3-192/","trackType":""} Apr 11 12:13:47 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:47 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:47 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:47 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:47 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Filmklassiker","artist":"Filmklassiker","album":null,"uri":"https://stream.klassikradio.de/filmklassiker/mp3-192/","trackType":""} Apr 11 12:13:47 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:47 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:47 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:47 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:47 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Filmklassiker","artist":"Filmklassiker","album":null,"uri":"https://stream.klassikradio.de/filmklassiker/mp3-192/","trackType":""} Apr 11 12:13:47 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:47 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:47 volumio volumio[1347]: info: ------------------------------ 57ms Apr 11 12:13:47 volumio volumio[1347]: info: ------------------------------ 57ms Apr 11 12:13:47 volumio volumio[1347]: info: ------------------------------ 29ms Apr 11 12:13:47 volumio volumio[1347]: info: ------------------------------ 28ms Apr 11 12:13:47 volumio volumio[1347]: info: sendMpdCommand status took 23 milliseconds Apr 11 12:13:47 volumio volumio[1347]: info: ------------------------------ 23ms Apr 11 12:13:47 volumio volumio[1347]: info: sendMpdCommand status took 23 milliseconds Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:13:47 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 11 12:13:47 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:47 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:13:47 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:47 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:47 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Filmklassiker","artist":"Filmklassiker","album":null,"uri":"https://stream.klassikradio.de/filmklassiker/mp3-192/","trackType":""} Apr 11 12:13:47 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:47 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:47 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:13:47 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:13:47 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Filmklassiker","artist":"Filmklassiker","album":null,"uri":"https://stream.klassikradio.de/filmklassiker/mp3-192/","trackType":""} Apr 11 12:13:47 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:13:47 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:13:47 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:13:47 volumio volumio[1347]: info: ------------------------------ 45ms Apr 11 12:13:47 volumio volumio[1347]: info: ------------------------------ 44ms Apr 11 12:14:04 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 12:14:04 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 11 12:14:05 volumio volumio[1347]: info: Apr 11 12:14:05 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:14:05 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:14:05 volumio volumio[1347]: info: Apr 11 12:14:05 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:14:05 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:14:05 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:14:05 volumio volumio[1347]: info: Apr 11 12:14:05 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:14:05 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:14:05 volumio volumio[1347]: info: Apr 11 12:14:05 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:14:05 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:14:05 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:14:05 volumio volumio[1347]: info: Apr 11 12:14:05 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Apr 11 12:14:05 volumio volumio[1347]: info: Ignoring MPD Status Update Apr 11 12:14:05 volumio volumio[1347]: info: Apr 11 12:14:05 volumio volumio[1347]: ---------------------------- MPD announces state update: player Apr 11 12:14:05 volumio volumio[1347]: info: ControllerMpd::getState Apr 11 12:14:05 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Apr 11 12:14:05 volumio volumio[1347]: info: ------------------------------ 1ms Apr 11 12:14:05 volumio volumio[1347]: info: sendMpdCommand status took 2 milliseconds Apr 11 12:14:05 volumio volumio[1347]: info: ------------------------------ 1ms Apr 11 12:14:05 volumio volumio[1347]: info: sendMpdCommand status took 1 milliseconds Apr 11 12:14:05 volumio volumio[1347]: info: ------------------------------ 1ms Apr 11 12:14:05 volumio volumio[1347]: info: sendMpdCommand status took 1 milliseconds Apr 11 12:14:05 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:14:05 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:14:05 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:14:05 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:14:05 volumio volumio[1347]: verbose: ControllerMpd::parseState Apr 11 12:14:05 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 12:14:05 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 12:14:05 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 12:14:05 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 12:14:05 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:14:05 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:14:05 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Apr 11 12:14:05 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:14:05 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:14:05 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:14:05 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"John Williams - The Imperial March (Star Wars V / Film)","artist":"Filmklassiker","album":null,"uri":"https://stream.klassikradio.de/filmklassiker/mp3-192/","trackType":""} Apr 11 12:14:05 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:14:05 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:14:05 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:14:05 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:14:05 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 12:14:05 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:14:05 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 12:14:05 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:14:05 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:14:05 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:14:05 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:14:05 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:14:05 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:14:05 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"John Williams - The Imperial March (Star Wars V / Film)","artist":"Filmklassiker","album":null,"uri":"https://stream.klassikradio.de/filmklassiker/mp3-192/","trackType":""} Apr 11 12:14:05 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:14:05 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:14:05 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:14:05 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:14:05 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 12:14:05 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:14:05 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:14:05 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:14:05 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:14:05 volumio volumio[1347]: info: ControllerMpd::pushState Apr 11 12:14:05 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Apr 11 12:14:05 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Apr 11 12:14:05 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"John Williams - The Imperial March (Star Wars V / Film)","artist":"Filmklassiker","album":null,"uri":"https://stream.klassikradio.de/filmklassiker/mp3-192/","trackType":""} Apr 11 12:14:05 volumio volumio[1347]: verbose: CURRENT POSITION 0 Apr 11 12:14:05 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Apr 11 12:14:05 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Apr 11 12:14:05 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Apr 11 12:14:05 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 12:14:05 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:14:05 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:14:05 volumio volumio[1347]: info: CoreStateMachine::pushState Apr 11 12:14:05 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Apr 11 12:14:05 volumio volumio[1347]: info: ------------------------------ 25ms Apr 11 12:14:05 volumio volumio[1347]: info: ------------------------------ 25ms Apr 11 12:14:05 volumio volumio[1347]: info: ------------------------------ 25ms Apr 11 12:14:22 volumio volumio[1347]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Apr 11 12:14:22 volumio volumio5-onboarding[1562]: time=2026-04-11T12:14:22.589+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 12:14:23 volumio volumio[1347]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Apr 11 12:14:23 volumio volumio[1347]: info: Folder /tmp/plugins removed Apr 11 12:14:23 volumio volumio5-onboarding[1562]: time=2026-04-11T12:14:23.925+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 12:14:24 volumio volumio5-onboarding[1562]: time=2026-04-11T12:14:24.105+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 12:14:24 volumio volumio[1347]: info: Check plugin dependencies Apr 11 12:14:24 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 12:14:24 volumio volumio5-onboarding[1562]: time=2026-04-11T12:14:24.125+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 12:14:24 volumio volumio[1347]: info: Checking if plugin already exists Apr 11 12:14:24 volumio volumio5-onboarding[1562]: time=2026-04-11T12:14:24.127+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 12:14:24 volumio volumio[1347]: info: Rename folder Apr 11 12:14:24 volumio volumio[1347]: info: Folder /tmp/downloaded_plugin.zip removed Apr 11 12:14:24 volumio volumio5-onboarding[1562]: time=2026-04-11T12:14:24.137+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 12:14:24 volumio volumio[1347]: info: Move to category Apr 11 12:14:24 volumio volumio5-onboarding[1562]: time=2026-04-11T12:14:24.204+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 12:14:24 volumio volumio[1347]: info: Checking if install.sh is present Apr 11 12:14:24 volumio volumio[1347]: info: Executing install.sh Apr 11 12:14:24 volumio sudo[2791]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/spop/install.sh Apr 11 12:14:24 volumio sudo[2791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 12:14:24 volumio volumio[1347]: info: Installing Go-librespot Apr 11 12:14:24 volumio volumio5-onboarding[1562]: time=2026-04-11T12:14:24.233+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 12:14:24 volumio volumio[1347]: info: Checking old vollibrespot installs Apr 11 12:14:24 volumio volumio5-onboarding[1562]: time=2026-04-11T12:14:24.236+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 12:14:24 volumio systemd[1]: Reloading. Apr 11 12:14:24 volumio volumio[1347]: info: Downloading daemon Apr 11 12:14:24 volumio volumio5-onboarding[1562]: time=2026-04-11T12:14:24.628+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 12:14:25 volumio volumio[1347]: info: Creating directories Apr 11 12:14:25 volumio volumio5-onboarding[1562]: time=2026-04-11T12:14:25.779+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 12:14:25 volumio volumio[1347]: info: Creating data directory Apr 11 12:14:25 volumio volumio5-onboarding[1562]: time=2026-04-11T12:14:25.783+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 12:14:25 volumio volumio[1347]: info: Creating Start Script Apr 11 12:14:25 volumio volumio5-onboarding[1562]: time=2026-04-11T12:14:25.786+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 12:14:25 volumio systemd[1]: Reloading. Apr 11 12:14:26 volumio sudo[2791]: pam_unix(sudo:session): session closed for user root Apr 11 12:14:26 volumio volumio[1347]: Plugin install end detected on script Apr 11 12:14:26 volumio volumio[1347]: info: Install script completed Apr 11 12:14:26 volumio volumio5-onboarding[1562]: time=2026-04-11T12:14:26.147+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 12:14:26 volumio volumio[1347]: info: Adding reference to registry Apr 11 12:14:26 volumio volumio[1347]: info: Done installing plugin. Apr 11 12:14:26 volumio volumio5-onboarding[1562]: time=2026-04-11T12:14:26.151+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 12:14:26 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 12:14:26 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 11 12:14:26 volumio volumio[1347]: info: Folder /tmp/plugins removed Apr 11 12:14:26 volumio volumio[1347]: info: Folder /tmp/downloaded_plugin.zip removed Apr 11 12:14:26 volumio volumio[1347]: info: Folder /data/temp removed Apr 11 12:14:27 volumio volumio[1347]: info: Enabling plugin spop Apr 11 12:14:27 volumio volumio[1347]: info: Loading plugin "spop"... Apr 11 12:14:27 volumio volumio[1347]: info: PLUGIN START: spop Apr 11 12:14:27 volumio volumio[1347]: info: Creating Spotify config file Apr 11 12:14:27 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 12:14:27 volumio volumio[1347]: info: Done. Apr 11 12:14:27 volumio volumio[1347]: info: Spotify config file written Apr 11 12:14:27 volumio volumio[1347]: info: No need to fix Spotify hosts Apr 11 12:14:27 volumio sudo[2869]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 11 12:14:27 volumio sudo[2869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 12:14:27 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 11 12:14:27 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 11 12:14:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:14:27 volumio go-librespot[2871]: go-librespot daemon starting... Apr 11 12:14:27 volumio sudo[2869]: pam_unix(sudo:session): session closed for user root Apr 11 12:14:27 volumio go-librespot[2872]: time="2026-04-11T12:14:27+01:00" level=info msg="running go-librespot 0.7.1" Apr 11 12:14:27 volumio go-librespot[2872]: time="2026-04-11T12:14:27+01:00" level=debug msg="no app state found" Apr 11 12:14:27 volumio go-librespot[2872]: time="2026-04-11T12:14:27+01:00" level=debug msg="stored credentials not found" Apr 11 12:14:27 volumio go-librespot[2872]: time="2026-04-11T12:14:27+01:00" level=info msg="generated new device id: 6c950b9f9ee85e0f459aeadaebab9b4dd29318f2" Apr 11 12:14:27 volumio go-librespot[2872]: time="2026-04-11T12:14:27+01:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 11 12:14:28 volumio volumio[1347]: info: New Spotify access tokenBQDBCRtDow... Apr 11 12:14:28 volumio volumio[1347]: info: Spotify credentials grant success - running version from March 24, 2019 Apr 11 12:14:28 volumio go-librespot[2872]: time="2026-04-11T12:14:28+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Apr 11 12:14:28 volumio go-librespot[2872]: time="2026-04-11T12:14:28+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 11 12:14:28 volumio go-librespot[2872]: time="2026-04-11T12:14:28+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 11 12:14:28 volumio go-librespot[2872]: time="2026-04-11T12:14:28+01:00" level=info msg="zeroconf server listening on port 40407" Apr 11 12:14:28 volumio go-librespot[2872]: time="2026-04-11T12:14:28+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Apr 11 12:14:28 volumio go-librespot[2872]: time="2026-04-11T12:14:28+01:00" level=debug msg="obtained new client token: AADjRGHVbUpz56sfmIsTimiPdHVng5qMRvkRco1+iq/qCcdqDKO1Muzw9GKodKprogPirWo3ytK2Wt9OztcSQxHCmGbmsynUr6ktqqr9ORH0gQWKNWYjXdxA+nyl++VHye+wIzXrYfF1g8hov8/rCQ1J8X9HWFBZz8qXwrT9TepR7UR4iSKsvhTHxUpwzMmfJg7suBhigA9pLTQXKNCAGOZ+g5F43c3LRrP+f3GTsyxIi2qns+UP" Apr 11 12:14:28 volumio go-librespot[2872]: time="2026-04-11T12:14:28+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Apr 11 12:14:28 volumio go-librespot[2872]: time="2026-04-11T12:14:28+01:00" level=debug msg="completed keyexchange" Apr 11 12:14:28 volumio go-librespot[2872]: time="2026-04-11T12:14:28+01:00" level=debug msg="completed challenge" Apr 11 12:14:28 volumio go-librespot[2872]: time="2026-04-11T12:14:28+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 11 12:14:28 volumio volumio[1347]: SPOTIFY: User informations: {"country":"GB","display_name":"oram01","email":"stephen.oram@gmx.co.uk","explicit_content":{"filter_enabled":true,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/oram01"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/oram01","id":"oram01","images":[],"product":"premium","type":"user","uri":"spotify:user:oram01"} Apr 11 12:14:28 volumio volumio[1347]: info: Spotify Successfully logged in Apr 11 12:14:28 volumio volumio[1347]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 12:14:28 volumio volumio[1347]: info: [1775906068153] CoreMusicLibrary::Adding element Spotify Apr 11 12:14:28 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 12:14:28 volumio volumio[1347]: Cannot find translation for source Spotify Apr 11 12:14:28 volumio go-librespot[2872]: time="2026-04-11T12:14:28+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Apr 11 12:14:28 volumio go-librespot[2872]: time="2026-04-11T12:14:28+01:00" level=debug msg="completed keyexchange" Apr 11 12:14:28 volumio go-librespot[2872]: time="2026-04-11T12:14:28+01:00" level=debug msg="completed challenge" Apr 11 12:14:28 volumio go-librespot[2872]: time="2026-04-11T12:14:28+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 11 12:14:29 volumio go-librespot[2872]: time="2026-04-11T12:14:29+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Apr 11 12:14:29 volumio go-librespot[2872]: time="2026-04-11T12:14:29+01:00" level=debug msg="completed keyexchange" Apr 11 12:14:29 volumio go-librespot[2872]: time="2026-04-11T12:14:29+01:00" level=debug msg="completed challenge" Apr 11 12:14:29 volumio go-librespot[2872]: time="2026-04-11T12:14:29+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 11 12:14:29 volumio go-librespot[2872]: time="2026-04-11T12:14:29+01:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Apr 11 12:14:29 volumio go-librespot[2872]: time="2026-04-11T12:14:29+01:00" level=debug msg="completed keyexchange" Apr 11 12:14:29 volumio go-librespot[2872]: time="2026-04-11T12:14:29+01:00" level=debug msg="completed challenge" Apr 11 12:14:30 volumio go-librespot[2872]: time="2026-04-11T12:14:30+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 11 12:14:30 volumio go-librespot[2872]: time="2026-04-11T12:14:30+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Apr 11 12:14:30 volumio go-librespot[2872]: time="2026-04-11T12:14:30+01:00" level=debug msg="completed keyexchange" Apr 11 12:14:30 volumio go-librespot[2872]: time="2026-04-11T12:14:30+01:00" level=debug msg="completed challenge" Apr 11 12:14:30 volumio go-librespot[2872]: time="2026-04-11T12:14:30+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 11 12:14:30 volumio volumio[1347]: info: go-librespot daemon successfully initialized Apr 11 12:14:31 volumio go-librespot[2872]: time="2026-04-11T12:14:31+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Apr 11 12:14:31 volumio go-librespot[2872]: time="2026-04-11T12:14:31+01:00" level=debug msg="completed keyexchange" Apr 11 12:14:31 volumio go-librespot[2872]: time="2026-04-11T12:14:31+01:00" level=debug msg="completed challenge" Apr 11 12:14:31 volumio go-librespot[2872]: time="2026-04-11T12:14:31+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 11 12:14:31 volumio go-librespot[2872]: time="2026-04-11T12:14:31+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Apr 11 12:14:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 11 12:14:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 11 12:14:33 volumio volumio[1347]: info: Initializing connection to go-librespot Websocket Apr 11 12:14:33 volumio volumio[1347]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 11 12:14:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Apr 11 12:14:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:14:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:14:34 volumio go-librespot[2903]: go-librespot daemon starting... Apr 11 12:14:34 volumio go-librespot[2904]: time="2026-04-11T12:14:34+01:00" level=info msg="running go-librespot 0.7.1" Apr 11 12:14:34 volumio go-librespot[2904]: time="2026-04-11T12:14:34+01:00" level=debug msg="app state loaded" Apr 11 12:14:34 volumio go-librespot[2904]: time="2026-04-11T12:14:34+01:00" level=debug msg="stored credentials not found" Apr 11 12:14:34 volumio go-librespot[2904]: time="2026-04-11T12:14:34+01:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 11 12:14:35 volumio go-librespot[2904]: time="2026-04-11T12:14:35+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 11 12:14:35 volumio go-librespot[2904]: time="2026-04-11T12:14:35+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 11 12:14:35 volumio go-librespot[2904]: time="2026-04-11T12:14:35+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 11 12:14:35 volumio go-librespot[2904]: time="2026-04-11T12:14:35+01:00" level=info msg="zeroconf server listening on port 43067" Apr 11 12:14:35 volumio go-librespot[2904]: time="2026-04-11T12:14:35+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Apr 11 12:14:35 volumio go-librespot[2904]: time="2026-04-11T12:14:35+01:00" level=debug msg="obtained new client token: AAA3p2iUhrI0/adZw4ZHBBC8K+V7DiniA5fW7z0QoAq0IM6VVmcMhFVdp1GhFjQHCffhTT89tXlicIVcDSuAVyZFckWTcM8U/R7r5goaFcUtSpMF9FdxXVvox4RkjZ9YvR+uq1bjCJwogFTpBP1zU1QvuR6vfhEowqmNevipbviluqmSvNzDDzWN5XHzYuIZUUhszZJMzWz2M2XECGHoR2fBXaYim/5y54wOrjXa+lFq1xc006c2" Apr 11 12:14:35 volumio go-librespot[2904]: time="2026-04-11T12:14:35+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Apr 11 12:14:35 volumio go-librespot[2904]: time="2026-04-11T12:14:35+01:00" level=debug msg="completed keyexchange" Apr 11 12:14:35 volumio go-librespot[2904]: time="2026-04-11T12:14:35+01:00" level=debug msg="completed challenge" Apr 11 12:14:35 volumio go-librespot[2904]: time="2026-04-11T12:14:35+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 11 12:14:35 volumio go-librespot[2904]: time="2026-04-11T12:14:35+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Apr 11 12:14:35 volumio go-librespot[2904]: time="2026-04-11T12:14:35+01:00" level=debug msg="completed keyexchange" Apr 11 12:14:35 volumio go-librespot[2904]: time="2026-04-11T12:14:35+01:00" level=debug msg="completed challenge" Apr 11 12:14:35 volumio go-librespot[2904]: time="2026-04-11T12:14:35+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 11 12:14:36 volumio go-librespot[2904]: time="2026-04-11T12:14:36+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Apr 11 12:14:36 volumio go-librespot[2904]: time="2026-04-11T12:14:36+01:00" level=debug msg="completed keyexchange" Apr 11 12:14:36 volumio go-librespot[2904]: time="2026-04-11T12:14:36+01:00" level=debug msg="completed challenge" Apr 11 12:14:36 volumio go-librespot[2904]: time="2026-04-11T12:14:36+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 11 12:14:36 volumio volumio[1347]: info: Initializing connection to go-librespot Websocket Apr 11 12:14:36 volumio go-librespot[2904]: time="2026-04-11T12:14:36+01:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Apr 11 12:14:36 volumio go-librespot[2904]: time="2026-04-11T12:14:36+01:00" level=debug msg="new websocket client" Apr 11 12:14:36 volumio volumio[1347]: info: Connection to go-librespot Websocket established Apr 11 12:14:37 volumio go-librespot[2904]: time="2026-04-11T12:14:37+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Apr 11 12:14:37 volumio go-librespot[2904]: time="2026-04-11T12:14:37+01:00" level=debug msg="completed keyexchange" Apr 11 12:14:37 volumio go-librespot[2904]: time="2026-04-11T12:14:37+01:00" level=debug msg="completed challenge" Apr 11 12:14:37 volumio go-librespot[2904]: time="2026-04-11T12:14:37+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 11 12:14:37 volumio go-librespot[2904]: time="2026-04-11T12:14:37+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Apr 11 12:14:38 volumio go-librespot[2904]: time="2026-04-11T12:14:38+01:00" level=debug msg="completed keyexchange" Apr 11 12:14:38 volumio go-librespot[2904]: time="2026-04-11T12:14:38+01:00" level=debug msg="completed challenge" Apr 11 12:14:38 volumio go-librespot[2904]: time="2026-04-11T12:14:38+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 11 12:14:38 volumio go-librespot[2904]: time="2026-04-11T12:14:38+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Apr 11 12:14:39 volumio go-librespot[2904]: time="2026-04-11T12:14:39+01:00" level=debug msg="completed keyexchange" Apr 11 12:14:39 volumio go-librespot[2904]: time="2026-04-11T12:14:39+01:00" level=debug msg="completed challenge" Apr 11 12:14:39 volumio go-librespot[2904]: time="2026-04-11T12:14:39+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 11 12:14:39 volumio go-librespot[2904]: time="2026-04-11T12:14:39+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Apr 11 12:14:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 11 12:14:39 volumio volumio[1347]: info: Connection to go-librespot Websocket closed Apr 11 12:14:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 11 12:14:39 volumio volumio[1347]: info: Getting Spotify volume Apr 11 12:14:39 volumio volumio[1347]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 12:14:39 volumio volumio[1347]: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 11 12:14:39 volumio volumio[1347]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Apr 11 12:14:39 volumio volumio[1347]: errno: -111, Apr 11 12:14:39 volumio volumio[1347]: code: 'ECONNREFUSED', Apr 11 12:14:39 volumio volumio[1347]: syscall: 'connect', Apr 11 12:14:39 volumio volumio[1347]: address: '127.0.0.1', Apr 11 12:14:39 volumio volumio[1347]: port: 9879, Apr 11 12:14:39 volumio volumio[1347]: response: undefined Apr 11 12:14:39 volumio volumio[1347]: } Apr 11 12:14:39 volumio volumio[1347]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 12:14:40 volumio sudo[2952]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-11 12:13' Apr 11 12:14:40 volumio sudo[2952]: 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="944c302b9fae4147798ef46fcbafd20f623942dc" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c4e22f5777dbc0e5cd93565495d861021c0e10b7" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Apr 7 12:54:58 UTC 2026" VOLUMIO_VERSION="4.136" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="13d97bd4a1450951734c24646d193670"