May 31 19:56:00 volumio go-librespot[1720]: time="2026-05-31T19:56:00+02:00" level=trace msg="sent dealer ping" May 31 19:56:00 volumio go-librespot[1720]: time="2026-05-31T19:56:00+02:00" level=trace msg="received dealer pong" May 31 19:56:04 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 19:56:04 volumio volumio[1196]: info: CURURI: music-library/USB/2721-1FF9/Music/Bon Iver May 31 19:56:04 volumio volumio[1196]: info: Preload queue cleared May 31 19:56:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 19:56:05 volumio volumio[1196]: info: CURURI: music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver May 31 19:56:05 volumio volumio[1196]: info: Preload queue cleared May 31 19:56:05 volumio volumio[1196]: info: Preloading song: music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/01 Perth.mp3 May 31 19:56:05 volumio volumio[1196]: info: Preloading song: music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/02 Minnesota, WI.mp3 May 31 19:56:05 volumio volumio[1196]: info: Preloading song: music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/03 Holocene.mp3 May 31 19:56:05 volumio volumio[1196]: info: Preloading song: music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/04 Towers.mp3 May 31 19:56:05 volumio volumio[1196]: info: Preloading song: music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/05 Michicant.mp3 May 31 19:56:05 volumio volumio[1196]: info: Preloading song: music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/06 Hinnom, TX.mp3 May 31 19:56:05 volumio volumio[1196]: info: Preloading song: music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/07 Wash.mp3 May 31 19:56:05 volumio volumio[1196]: info: Preloading song: music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/08 Calgary.mp3 May 31 19:56:05 volumio volumio[1196]: info: Preloading song: music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/09 Lisbon, OH.mp3 May 31 19:56:05 volumio volumio[1196]: info: Preloading song: music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/10 Beth-Rest.mp3 May 31 19:56:05 volumio volumio[1196]: info: Exploding uri music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/01 Perth.mp3 in service mpd May 31 19:56:05 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bon%20Iver/Bon%20Iver/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBon%20Iver%2FBon%20Iver%2F01%20Perth.mp3&metadata=false May 31 19:56:05 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bon Iver/Bon Iver/01 Perth.mp3 May 31 19:56:05 volumio volumio[1196]: info: Exploding uri music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/02 Minnesota, WI.mp3 in service mpd May 31 19:56:05 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bon%20Iver/Bon%20Iver/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBon%20Iver%2FBon%20Iver%2F02%20Minnesota%2C%20WI.mp3&metadata=false May 31 19:56:05 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bon Iver/Bon Iver/02 Minnesota, WI.mp3 May 31 19:56:05 volumio volumio[1196]: info: Exploding uri music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/03 Holocene.mp3 in service mpd May 31 19:56:05 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bon%20Iver/Bon%20Iver/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBon%20Iver%2FBon%20Iver%2F03%20Holocene.mp3&metadata=false May 31 19:56:05 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bon Iver/Bon Iver/03 Holocene.mp3 May 31 19:56:05 volumio volumio[1196]: info: Exploding uri music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/04 Towers.mp3 in service mpd May 31 19:56:05 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bon%20Iver/Bon%20Iver/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBon%20Iver%2FBon%20Iver%2F04%20Towers.mp3&metadata=false May 31 19:56:05 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bon Iver/Bon Iver/04 Towers.mp3 May 31 19:56:05 volumio volumio[1196]: info: Exploding uri music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/05 Michicant.mp3 in service mpd May 31 19:56:05 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bon%20Iver/Bon%20Iver/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBon%20Iver%2FBon%20Iver%2F05%20Michicant.mp3&metadata=false May 31 19:56:05 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bon Iver/Bon Iver/05 Michicant.mp3 May 31 19:56:05 volumio volumio[1196]: info: Exploding uri music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/06 Hinnom, TX.mp3 in service mpd May 31 19:56:05 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bon%20Iver/Bon%20Iver/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBon%20Iver%2FBon%20Iver%2F06%20Hinnom%2C%20TX.mp3&metadata=false May 31 19:56:05 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bon Iver/Bon Iver/06 Hinnom, TX.mp3 May 31 19:56:05 volumio volumio[1196]: info: Exploding uri music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/07 Wash.mp3 in service mpd May 31 19:56:05 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bon%20Iver/Bon%20Iver/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBon%20Iver%2FBon%20Iver%2F07%20Wash.mp3&metadata=false May 31 19:56:05 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bon Iver/Bon Iver/07 Wash.mp3 May 31 19:56:05 volumio volumio[1196]: info: Exploding uri music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/08 Calgary.mp3 in service mpd May 31 19:56:05 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bon%20Iver/Bon%20Iver/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBon%20Iver%2FBon%20Iver%2F08%20Calgary.mp3&metadata=false May 31 19:56:05 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bon Iver/Bon Iver/08 Calgary.mp3 May 31 19:56:05 volumio volumio[1196]: info: Exploding uri music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/09 Lisbon, OH.mp3 in service mpd May 31 19:56:05 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bon%20Iver/Bon%20Iver/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBon%20Iver%2FBon%20Iver%2F09%20Lisbon%2C%20OH.mp3&metadata=false May 31 19:56:05 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bon Iver/Bon Iver/09 Lisbon, OH.mp3 May 31 19:56:05 volumio volumio[1196]: info: Exploding uri music-library/USB/2721-1FF9/Music/Bon Iver/Bon Iver/10 Beth-Rest.mp3 in service mpd May 31 19:56:05 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bon%20Iver/Bon%20Iver/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBon%20Iver%2FBon%20Iver%2F10%20Beth-Rest.mp3&metadata=false May 31 19:56:05 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bon Iver/Bon Iver/10 Beth-Rest.mp3 May 31 19:56:10 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 19:56:10 volumio volumio[1196]: info: CURURI: music-library/USB/2721-1FF9/Music/Bonnie 'Prince' Billy May 31 19:56:10 volumio volumio[1196]: info: Preload queue cleared May 31 19:56:15 volumio volumio[1196]: info: Preload queue cleared May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::ClearQueue May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::stop May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::stPlaybackTimer May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::updateTrackBlock May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrackBlock May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::serviceStop May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::serviceStop May 31 19:56:15 volumio volumio[1196]: info: ControllerMpd::stop May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand stop May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::clearPlayQueue May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::saveQueue May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::volumioPushQueue May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::addQueueItems May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::addQueueItems May 31 19:56:15 volumio volumio[1196]: info: Preload queue cleared May 31 19:56:15 volumio volumio[1196]: info: Adding Item to queue: music-library/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music May 31 19:56:15 volumio volumio[1196]: info: Exploding uri music-library/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music in service mpd May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.247+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_STOPPED positionMs=0 volume=100 May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.247+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/1982 - Nebraska/01 - Nebraska (2).mp3" title="01 - Nebraska (2).mp3" May 31 19:56:15 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:15 volumio volumio[1196]: info: May 31 19:56:15 volumio volumio[1196]: ---------------------------- MPD announces state update: player May 31 19:56:15 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bonnie%20%22Prince%22%20Billy/Greatest%20Palace%20Music/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBonnie%20'Prince'%20Billy%2FBonnie%20'Prince'%20Billy%20-%20Sings%20Greatest%20Palace%20Music%2F01-New%20Partner.mp3&metadata=false May 31 19:56:15 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3 May 31 19:56:15 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bonnie%20%22Prince%22%20Billy/Greatest%20Palace%20Music/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBonnie%20'Prince'%20Billy%2FBonnie%20'Prince'%20Billy%20-%20Sings%20Greatest%20Palace%20Music%2F02-Ohio%20River%20Boat%20Song.mp3&metadata=false May 31 19:56:15 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/02-Ohio River Boat Song.mp3 May 31 19:56:15 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bonnie%20%22Prince%22%20Billy/Greatest%20Palace%20Music/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBonnie%20'Prince'%20Billy%2FBonnie%20'Prince'%20Billy%20-%20Sings%20Greatest%20Palace%20Music%2F03-Gulf%20Shores.mp3&metadata=false May 31 19:56:15 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/03-Gulf Shores.mp3 May 31 19:56:15 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bonnie%20%22Prince%22%20Billy/Greatest%20Palace%20Music/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBonnie%20'Prince'%20Billy%2FBonnie%20'Prince'%20Billy%20-%20Sings%20Greatest%20Palace%20Music%2F04-You%20Will%20Miss%20Me%20When%20I%20Burn.mp3&metadata=false May 31 19:56:15 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/04-You Will Miss Me When I Burn.mp3 May 31 19:56:15 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bonnie%20%22Prince%22%20Billy/Greatest%20Palace%20Music/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBonnie%20'Prince'%20Billy%2FBonnie%20'Prince'%20Billy%20-%20Sings%20Greatest%20Palace%20Music%2F05-The%20Brute%20Choir.mp3&metadata=false May 31 19:56:15 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/05-The Brute Choir.mp3 May 31 19:56:15 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bonnie%20%22Prince%22%20Billy/Greatest%20Palace%20Music/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBonnie%20'Prince'%20Billy%2FBonnie%20'Prince'%20Billy%20-%20Sings%20Greatest%20Palace%20Music%2F06-I%20Send%20My%20Love%20To%20You.mp3&metadata=false May 31 19:56:15 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/06-I Send My Love To You.mp3 May 31 19:56:15 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bonnie%20%22Prince%22%20Billy/Greatest%20Palace%20Music/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBonnie%20'Prince'%20Billy%2FBonnie%20'Prince'%20Billy%20-%20Sings%20Greatest%20Palace%20Music%2F07-More%20Brother%20Rides.mp3&metadata=false May 31 19:56:15 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/07-More Brother Rides.mp3 May 31 19:56:15 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bonnie%20%22Prince%22%20Billy/Greatest%20Palace%20Music/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBonnie%20'Prince'%20Billy%2FBonnie%20'Prince'%20Billy%20-%20Sings%20Greatest%20Palace%20Music%2F08-Agnes%2C%20Queen%20Of%20Sorrow.mp3&metadata=false May 31 19:56:15 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/08-Agnes, Queen Of Sorrow.mp3 May 31 19:56:15 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bonnie%20%22Prince%22%20Billy/Greatest%20Palace%20Music/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBonnie%20'Prince'%20Billy%2FBonnie%20'Prince'%20Billy%20-%20Sings%20Greatest%20Palace%20Music%2F09-Viva%20Ultra.mp3&metadata=false May 31 19:56:15 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/09-Viva Ultra.mp3 May 31 19:56:15 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bonnie%20%22Prince%22%20Billy/Greatest%20Palace%20Music/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBonnie%20'Prince'%20Billy%2FBonnie%20'Prince'%20Billy%20-%20Sings%20Greatest%20Palace%20Music%2F10-Pushkin.mp3&metadata=false May 31 19:56:15 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/10-Pushkin.mp3 May 31 19:56:15 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bonnie%20%22Prince%22%20Billy/Greatest%20Palace%20Music/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBonnie%20'Prince'%20Billy%2FBonnie%20'Prince'%20Billy%20-%20Sings%20Greatest%20Palace%20Music%2F11-Horses.mp3&metadata=false May 31 19:56:15 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/11-Horses.mp3 May 31 19:56:15 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bonnie%20%22Prince%22%20Billy/Greatest%20Palace%20Music/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBonnie%20'Prince'%20Billy%2FBonnie%20'Prince'%20Billy%20-%20Sings%20Greatest%20Palace%20Music%2F12-Riding.mp3&metadata=false May 31 19:56:15 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/12-Riding.mp3 May 31 19:56:15 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bonnie%20%22Prince%22%20Billy/Greatest%20Palace%20Music/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBonnie%20'Prince'%20Billy%2FBonnie%20'Prince'%20Billy%20-%20Sings%20Greatest%20Palace%20Music%2F13-West%20Palm%20Beach.mp3&metadata=false May 31 19:56:15 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/13-West Palm Beach.mp3 May 31 19:56:15 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bonnie%20%22Prince%22%20Billy/Greatest%20Palace%20Music/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBonnie%20'Prince'%20Billy%2FBonnie%20'Prince'%20Billy%20-%20Sings%20Greatest%20Palace%20Music%2F14-No%20More%20Workhorse%20Blues.mp3&metadata=false May 31 19:56:15 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/14-No More Workhorse Blues.mp3 May 31 19:56:15 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bonnie%20%22Prince%22%20Billy/Greatest%20Palace%20Music/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBonnie%20'Prince'%20Billy%2FBonnie%20'Prince'%20Billy%20-%20Sings%20Greatest%20Palace%20Music%2F15-I%20Am%20A%20Cinematographer.mp3&metadata=false May 31 19:56:15 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/15-I Am A Cinematographer.mp3 May 31 19:56:15 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=Bonnie%20%22Prince%22%20Billy/Greatest%20Palace%20Music/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2FBonnie%20'Prince'%20Billy%2FBonnie%20'Prince'%20Billy%20-%20Sings%20Greatest%20Palace%20Music%2F16-%5BHidden%20Track%5D.mp3&metadata=false May 31 19:56:15 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/16-[Hidden Track].mp3 May 31 19:56:15 volumio volumio[1196]: info: sendMpdCommand stop took 131 milliseconds May 31 19:56:15 volumio volumio[1196]: info: ControllerMpd::getState May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::volumioPushQueue May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::saveQueue May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::updateTrackBlock May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrackBlock May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::volumioPlay May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::play index 0 May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::stop May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::play index undefined May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::startPlaybackTimer May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::clearAddPlayTracks USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3 May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand stop May 31 19:56:15 volumio volumio[1196]: info: sendMpdCommand status took 55 milliseconds May 31 19:56:15 volumio volumio[1196]: info: sendMpdCommand stop took 14 milliseconds May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::parseState May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand clear May 31 19:56:15 volumio volumio[1196]: info: May 31 19:56:15 volumio volumio[1196]: ---------------------------- MPD announces state update: player May 31 19:56:15 volumio volumio[1196]: info: ControllerMpd::getState May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 31 19:56:15 volumio volumio[1196]: info: May 31 19:56:15 volumio volumio[1196]: ---------------------------- MPD announces state update: player May 31 19:56:15 volumio volumio[1196]: info: ControllerMpd::getState May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 31 19:56:15 volumio volumio[1196]: info: May 31 19:56:15 volumio volumio[1196]: ---------------------------- MPD announces system playlist update May 31 19:56:15 volumio volumio[1196]: info: Ignoring MPD Status Update May 31 19:56:15 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 37 milliseconds May 31 19:56:15 volumio volumio[1196]: info: sendMpdCommand clear took 37 milliseconds May 31 19:56:15 volumio volumio[1196]: info: sendMpdCommand status took 36 milliseconds May 31 19:56:15 volumio volumio[1196]: info: sendMpdCommand status took 31 milliseconds May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand add "USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::parseState May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::parseState May 31 19:56:15 volumio volumio[1196]: info: ControllerMpd::pushState May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Nebraska (2).mp3","artist":null,"album":null,"uri":"USB/2721-1FF9/Music/1982 - Nebraska/01 - Nebraska (2).mp3","trackType":"mp3"} May 31 19:56:15 volumio volumio[1196]: verbose: CURRENT POSITION 0 May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::syncState stateService stop May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus stop May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:15 volumio volumio[1196]: info: No code May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:15 volumio volumio[1196]: info: ControllerMpd::pushState May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 19:56:15 volumio volumio[1196]: verbose: CURRENT POSITION 0 May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::syncState stateService stop May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus stop May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:15 volumio volumio[1196]: info: No code May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:15 volumio volumio[1196]: info: ControllerMpd::pushState May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 19:56:15 volumio volumio[1196]: verbose: CURRENT POSITION 0 May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::syncState stateService stop May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus stop May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:15 volumio volumio[1196]: info: No code May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.602+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_STOPPED positionMs=50 volume=100 May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.603+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.608+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_STOPPED positionMs=50 volume=100 May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.610+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.613+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_STOPPED positionMs=50 volume=100 May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.614+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.619+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_STOPPED positionMs=50 volume=100 May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.620+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.624+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_STOPPED positionMs=50 volume=100 May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.625+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.631+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_STOPPED positionMs=50 volume=100 May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.632+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.636+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_STOPPED positionMs=50 volume=100 May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.637+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.641+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_STOPPED positionMs=50 volume=100 May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.642+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.646+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_STOPPED positionMs=50 volume=100 May 31 19:56:15 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:15.647+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:15 volumio volumio[1196]: info: ------------------------------ 461ms May 31 19:56:15 volumio volumio[1196]: info: ------------------------------ 340ms May 31 19:56:15 volumio volumio[1196]: info: ------------------------------ 343ms May 31 19:56:15 volumio volumio[1196]: info: May 31 19:56:15 volumio volumio[1196]: ---------------------------- MPD announces system playlist update May 31 19:56:15 volumio volumio[1196]: info: Ignoring MPD Status Update May 31 19:56:15 volumio volumio[1196]: info: May 31 19:56:15 volumio volumio[1196]: ---------------------------- MPD announces system playlist update May 31 19:56:15 volumio volumio[1196]: info: Ignoring MPD Status Update May 31 19:56:15 volumio volumio[1196]: error: updateQueue error: null May 31 19:56:15 volumio volumio[1196]: info: May 31 19:56:15 volumio volumio[1196]: ---------------------------- MPD announces system playlist update May 31 19:56:15 volumio volumio[1196]: info: Ignoring MPD Status Update May 31 19:56:15 volumio volumio[1196]: info: ------------------------------ 331ms May 31 19:56:15 volumio volumio[1196]: info: sendMpdCommand add "USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" took 319 milliseconds May 31 19:56:15 volumio volumio[1196]: info: ------------------------------ 13ms May 31 19:56:15 volumio volumio[1196]: info: ------------------------------ 12ms May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand play May 31 19:56:15 volumio volumio[1196]: info: May 31 19:56:15 volumio volumio[1196]: ---------------------------- MPD announces system playlist update May 31 19:56:15 volumio volumio[1196]: info: Ignoring MPD Status Update May 31 19:56:15 volumio volumio[1196]: info: May 31 19:56:15 volumio volumio[1196]: ---------------------------- MPD announces system playlist update May 31 19:56:15 volumio volumio[1196]: info: Ignoring MPD Status Update May 31 19:56:15 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:15 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:15 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:15 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:15 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:15 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:15 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:15 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:15 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:15 volumio volumio[1196]: info: May 31 19:56:15 volumio volumio[1196]: ---------------------------- MPD announces state update: player May 31 19:56:15 volumio volumio[1196]: info: ------------------------------ 85ms May 31 19:56:15 volumio volumio[1196]: info: sendMpdCommand play took 80 milliseconds May 31 19:56:15 volumio volumio[1196]: info: ------------------------------ 79ms May 31 19:56:15 volumio volumio[1196]: info: ------------------------------ 76ms May 31 19:56:15 volumio volumio[1196]: info: ControllerMpd::getState May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 31 19:56:15 volumio volumio[1196]: info: May 31 19:56:15 volumio volumio[1196]: ---------------------------- MPD announces state update: player May 31 19:56:15 volumio volumio[1196]: info: ControllerMpd::getState May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 31 19:56:15 volumio volumio[1196]: info: May 31 19:56:15 volumio volumio[1196]: ---------------------------- MPD announces state update: player May 31 19:56:15 volumio volumio[1196]: info: ControllerMpd::getState May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 31 19:56:15 volumio volumio[1196]: info: May 31 19:56:15 volumio volumio[1196]: ---------------------------- MPD announces state update: player May 31 19:56:15 volumio volumio[1196]: info: ControllerMpd::getState May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 31 19:56:15 volumio volumio[1196]: info: sendMpdCommand status took 16 milliseconds May 31 19:56:15 volumio volumio[1196]: info: sendMpdCommand status took 14 milliseconds May 31 19:56:15 volumio volumio[1196]: info: sendMpdCommand status took 13 milliseconds May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::parseState May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::parseState May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::parseState May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:56:15 volumio volumio[1196]: info: May 31 19:56:15 volumio volumio[1196]: ---------------------------- MPD announces state update: player May 31 19:56:15 volumio volumio[1196]: info: ControllerMpd::getState May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 31 19:56:15 volumio volumio[1196]: info: May 31 19:56:15 volumio volumio[1196]: ---------------------------- MPD announces state update: player May 31 19:56:15 volumio volumio[1196]: info: ControllerMpd::getState May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 31 19:56:15 volumio volumio[1196]: info: sendMpdCommand status took 24 milliseconds May 31 19:56:15 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 21 milliseconds May 31 19:56:15 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 22 milliseconds May 31 19:56:15 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 22 milliseconds May 31 19:56:15 volumio volumio[1196]: info: sendMpdCommand status took 20 milliseconds May 31 19:56:15 volumio volumio[1196]: info: sendMpdCommand status took 18 milliseconds May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::parseState May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::parseState May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::parseState May 31 19:56:15 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:56:15 volumio volumio[1196]: info: ControllerMpd::pushState May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"New Partner","artist":"Bonnie \"Prince\" Billy","album":"Greatest Palace Music","uri":"USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3","trackType":"mp3"} May 31 19:56:15 volumio volumio[1196]: verbose: CURRENT POSITION 0 May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus stop May 31 19:56:15 volumio volumio[1196]: info: ControllerMpd::pushState May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"New Partner","artist":"Bonnie \"Prince\" Billy","album":"Greatest Palace Music","uri":"USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3","trackType":"mp3"} May 31 19:56:15 volumio volumio[1196]: verbose: CURRENT POSITION 0 May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play May 31 19:56:15 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:15 volumio volumio[1196]: info: ControllerMpd::pushState May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"New Partner","artist":"Bonnie \"Prince\" Billy","album":"Greatest Palace Music","uri":"USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3","trackType":"mp3"} May 31 19:56:15 volumio volumio[1196]: verbose: CURRENT POSITION 0 May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play May 31 19:56:15 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:15 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:15 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:16 volumio volumio[1196]: info: ------------------------------ 144ms May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.024+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_PLAYING positionMs=871 volume=100 May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.025+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.032+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_PLAYING positionMs=871 volume=100 May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.033+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.040+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_PLAYING positionMs=871 volume=100 May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.041+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.048+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_PLAYING positionMs=871 volume=100 May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.049+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:16 volumio volumio[1196]: info: ------------------------------ 199ms May 31 19:56:16 volumio volumio[1196]: info: ------------------------------ 199ms May 31 19:56:16 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 170 milliseconds May 31 19:56:16 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 166 milliseconds May 31 19:56:16 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 166 milliseconds May 31 19:56:16 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo May 31 19:56:16 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo May 31 19:56:16 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo May 31 19:56:16 volumio volumio[1196]: info: ControllerMpd::pushState May 31 19:56:16 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 31 19:56:16 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:16 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"New Partner","artist":"Bonnie \"Prince\" Billy","album":"Greatest Palace Music","uri":"USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3","trackType":"mp3"} May 31 19:56:16 volumio volumio[1196]: verbose: CURRENT POSITION 0 May 31 19:56:16 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play May 31 19:56:16 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play May 31 19:56:16 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload May 31 19:56:16 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:16 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:16 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:16 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:16 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:16 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:16 volumio volumio[1196]: info: ControllerMpd::pushState May 31 19:56:16 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 31 19:56:16 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:16 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"New Partner","artist":"Bonnie \"Prince\" Billy","album":"Greatest Palace Music","uri":"USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3","trackType":"mp3"} May 31 19:56:16 volumio volumio[1196]: verbose: CURRENT POSITION 0 May 31 19:56:16 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play May 31 19:56:16 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play May 31 19:56:16 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload May 31 19:56:16 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:16 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:16 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:16 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:16 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:16 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:16 volumio volumio[1196]: info: ControllerMpd::pushState May 31 19:56:16 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 31 19:56:16 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:16 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"New Partner","artist":"Bonnie \"Prince\" Billy","album":"Greatest Palace Music","uri":"USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3","trackType":"mp3"} May 31 19:56:16 volumio volumio[1196]: verbose: CURRENT POSITION 0 May 31 19:56:16 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play May 31 19:56:16 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play May 31 19:56:16 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload May 31 19:56:16 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:16 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:16 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:16 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:16 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:16 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.146+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_PLAYING positionMs=871 volume=100 May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.148+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.151+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_PLAYING positionMs=871 volume=100 May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.152+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.154+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_PLAYING positionMs=871 volume=100 May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.155+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.157+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_PLAYING positionMs=871 volume=100 May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.159+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.159+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_PLAYING positionMs=871 volume=100 May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.159+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.164+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" state=STATUS_PLAYING positionMs=871 volume=100 May 31 19:56:16 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:16.164+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:16 volumio volumio[1196]: info: ------------------------------ 300ms May 31 19:56:16 volumio volumio[1196]: info: ------------------------------ 293ms May 31 19:56:16 volumio volumio[1196]: info: ------------------------------ 294ms May 31 19:56:16 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:16 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:16 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:16 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:16 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:16 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:16 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:16 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:16 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:16 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:18 volumio bluealsa[1024]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_5F_2B_C0_2F_6C_34, ...) May 31 19:56:19 volumio bluealsa[1024]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_7C_CA_11_8E_97_1B, ...) May 31 19:56:25 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 19:56:25 volumio volumio[1196]: info: CURURI: music-library May 31 19:56:25 volumio volumio[1196]: info: Preload queue cleared May 31 19:56:26 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:26.016+02:00 level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 May 31 19:56:26 volumio dbus-daemon[707]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.18" (uid=0 pid=1694 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.4" (uid=0 pid=953 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") May 31 19:56:27 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 19:56:27 volumio volumio[1196]: info: CURURI: albums:// May 31 19:56:27 volumio volumio[1196]: info: listAlbums - loading Albums from cache May 31 19:56:27 volumio volumio[1196]: info: Preload queue cleared May 31 19:56:29 volumio go-librespot[1720]: time="2026-05-31T19:56:29+02:00" level=trace msg="received accesspoint ping" May 31 19:56:29 volumio go-librespot[1720]: time="2026-05-31T19:56:29+02:00" level=trace msg="received accesspoint pong ack" May 31 19:56:30 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:30.251+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" latency=-302.556616ms timeout=3s volume=100 May 31 19:56:30 volumio volumio[1196]: info: VolumeController::SetAlsaVolume100 May 31 19:56:30 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:56:30 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:56:30 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:56:30 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:56:30 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:30.309+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" state=STATUS_PLAYING positionMs=14968 volume=100 May 31 19:56:30 volumio volumio5-onboarding[1694]: time=2026-05-31T19:56:30.310+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:56:30 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:56:30 volumio go-librespot[1720]: time="2026-05-31T19:56:30+02:00" level=trace msg="sent dealer ping" May 31 19:56:30 volumio go-librespot[1720]: time="2026-05-31T19:56:30+02:00" level=trace msg="received dealer pong" May 31 19:56:31 volumio bluealsa[1024]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_76_38_1F_9F_59_BB, ...) May 31 19:56:56 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 19:56:56 volumio volumio[1196]: info: CURURI: music-library May 31 19:56:56 volumio volumio[1196]: info: Preload queue cleared May 31 19:57:00 volumio go-librespot[1720]: time="2026-05-31T19:57:00+02:00" level=trace msg="sent dealer ping" May 31 19:57:00 volumio go-librespot[1720]: time="2026-05-31T19:57:00+02:00" level=trace msg="received dealer pong" May 31 19:57:03 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:03.564+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" latency=-288.941139ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE May 31 19:57:04 volumio sudo[15950]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 19:57:04 volumio sudo[15952]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 19:57:04 volumio sudo[15950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 19:57:04 volumio sudo[15952]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 19:57:04 volumio sudo[15952]: pam_unix(sudo:session): session closed for user root May 31 19:57:04 volumio sudo[15950]: pam_unix(sudo:session): session closed for user root May 31 19:57:04 volumio volumio[1196]: verbose: New Socket.io Connection to 192.168.0.202 from 192.168.0.205 UA: Mozilla/5.0 (Linux; Android 13; SM-G781B Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 31 19:57:04 volumio sudo[15956]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 19:57:04 volumio sudo[15956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 19:57:04 volumio sudo[15956]: pam_unix(sudo:session): session closed for user root May 31 19:57:04 volumio sudo[15958]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 19:57:04 volumio sudo[15958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 19:57:04 volumio sudo[15958]: pam_unix(sudo:session): session closed for user root May 31 19:57:04 volumio volumio[1196]: verbose: New Socket.io Connection to 192.168.0.202 from 192.168.0.205 UA: Mozilla/5.0 (Linux; Android 13; SM-G781B Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 31 19:57:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 19:57:05 volumio volumio[1196]: info: CoreCommandRouter::volumioGetVisibleSources May 31 19:57:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 19:57:05 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 31 19:57:05 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 19:57:05 volumio volumio[1196]: info: CoreCommandRouter::volumioGetQueue May 31 19:57:05 volumio volumio[1196]: info: CoreStateMachine::getQueue May 31 19:57:05 volumio volumio[1196]: info: CorePlayQueue::getQueue May 31 19:57:05 volumio volumio[1196]: info: Listing playlists May 31 19:57:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 19:57:05 volumio volumio[1196]: info: Received Get System Info May 31 19:57:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 19:57:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 19:57:05 volumio volumio[1196]: info: Discovery: Getting this device information May 31 19:57:05 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 31 19:57:05 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 19:57:05 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 31 19:57:05 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 31 19:57:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 19:57:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 31 19:57:06 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 19:57:06 volumio volumio[1196]: info: CURURI: music-library/USB May 31 19:57:06 volumio volumio[1196]: info: Preload queue cleared May 31 19:57:06 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 31 19:57:06 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 19:57:06 volumio volumio[1196]: info: Received Get System Info May 31 19:57:06 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 19:57:06 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 19:57:06 volumio volumio[1196]: info: Discovery: Getting this device information May 31 19:57:06 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 31 19:57:06 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:06 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 19:57:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 19:57:07 volumio volumio[1196]: info: CURURI: music-library/USB/2721-1FF9 May 31 19:57:07 volumio volumio[1196]: info: Preload queue cleared May 31 19:57:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 19:57:07 volumio volumio[1196]: info: Received Get System Info May 31 19:57:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 19:57:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 19:57:07 volumio volumio[1196]: info: Discovery: Getting this device information May 31 19:57:07 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 31 19:57:07 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 19:57:11 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 19:57:11 volumio volumio[1196]: info: CURURI: music-library May 31 19:57:11 volumio volumio[1196]: info: Preload queue cleared May 31 19:57:13 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:13.272+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" latency=-306.129556ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE May 31 19:57:13 volumio sudo[15977]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 19:57:13 volumio sudo[15979]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 19:57:13 volumio sudo[15979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 19:57:13 volumio sudo[15977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 19:57:13 volumio sudo[15979]: pam_unix(sudo:session): session closed for user root May 31 19:57:13 volumio sudo[15977]: pam_unix(sudo:session): session closed for user root May 31 19:57:13 volumio volumio[1196]: verbose: New Socket.io Connection to 192.168.0.202 from 192.168.0.205 UA: Mozilla/5.0 (Linux; Android 13; SM-G781B Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 31 19:57:14 volumio sudo[15983]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 19:57:14 volumio sudo[15983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 19:57:14 volumio sudo[15983]: pam_unix(sudo:session): session closed for user root May 31 19:57:14 volumio sudo[15985]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 19:57:14 volumio sudo[15985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 19:57:14 volumio sudo[15985]: pam_unix(sudo:session): session closed for user root May 31 19:57:14 volumio volumio[1196]: verbose: New Socket.io Connection to 192.168.0.202 from 192.168.0.205 UA: Mozilla/5.0 (Linux; Android 13; SM-G781B Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 31 19:57:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 19:57:14 volumio volumio[1196]: info: CoreCommandRouter::volumioGetVisibleSources May 31 19:57:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 19:57:14 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 31 19:57:14 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 19:57:14 volumio volumio[1196]: info: CoreCommandRouter::volumioGetQueue May 31 19:57:14 volumio volumio[1196]: info: CoreStateMachine::getQueue May 31 19:57:14 volumio volumio[1196]: info: CorePlayQueue::getQueue May 31 19:57:14 volumio volumio[1196]: info: Listing playlists May 31 19:57:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 19:57:14 volumio volumio[1196]: info: Received Get System Info May 31 19:57:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 19:57:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 19:57:14 volumio volumio[1196]: info: Discovery: Getting this device information May 31 19:57:14 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 31 19:57:14 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 19:57:14 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 31 19:57:14 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 31 19:57:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 19:57:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 31 19:57:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 19:57:15 volumio volumio[1196]: info: CURURI: music-library/USB May 31 19:57:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 31 19:57:15 volumio volumio[1196]: info: Preload queue cleared May 31 19:57:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 31 19:57:16 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 19:57:16 volumio volumio[1196]: info: Received Get System Info May 31 19:57:16 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 19:57:16 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 19:57:16 volumio volumio[1196]: info: Discovery: Getting this device information May 31 19:57:16 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 31 19:57:16 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:16 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 19:57:16 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 19:57:16 volumio volumio[1196]: info: CURURI: music-library/USB/2721-1FF9 May 31 19:57:16 volumio volumio[1196]: info: Preload queue cleared May 31 19:57:16 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 19:57:16 volumio volumio[1196]: info: Received Get System Info May 31 19:57:16 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 19:57:16 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 19:57:16 volumio volumio[1196]: info: Discovery: Getting this device information May 31 19:57:16 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 31 19:57:16 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:16 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 19:57:20 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 19:57:20 volumio volumio[1196]: info: CURURI: albums:// May 31 19:57:20 volumio volumio[1196]: info: listAlbums - loading Albums from cache May 31 19:57:20 volumio volumio[1196]: info: Preload queue cleared May 31 19:57:21 volumio volumio[1196]: info: MPD Database updated - AlbumList cache refreshed May 31 19:57:21 volumio volumio[1196]: info: May 31 19:57:21 volumio volumio[1196]: ---------------------------- MPD announces state update: database May 31 19:57:21 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 31 19:57:21 volumio volumio[1196]: info: May 31 19:57:21 volumio volumio[1196]: ---------------------------- MPD announces state update: update May 31 19:57:21 volumio volumio[1196]: info: ControllerMpd::getState May 31 19:57:21 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 31 19:57:21 volumio volumio[1196]: info: ControllerMpd::getState May 31 19:57:21 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 31 19:57:21 volumio volumio[1196]: info: MPD Database updated - AlbumList cache refreshed May 31 19:57:21 volumio volumio[1196]: info: May 31 19:57:21 volumio volumio[1196]: ---------------------------- MPD announces state update: database May 31 19:57:21 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 31 19:57:21 volumio volumio[1196]: info: May 31 19:57:21 volumio volumio[1196]: ---------------------------- MPD announces state update: update May 31 19:57:21 volumio volumio[1196]: info: ControllerMpd::getState May 31 19:57:21 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 31 19:57:21 volumio volumio[1196]: info: ControllerMpd::getState May 31 19:57:21 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 31 19:57:21 volumio volumio[1196]: info: MPD Database updated - AlbumList cache refreshed May 31 19:57:21 volumio volumio[1196]: info: May 31 19:57:21 volumio volumio[1196]: ---------------------------- MPD announces state update: database May 31 19:57:21 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 31 19:57:21 volumio volumio[1196]: info: May 31 19:57:21 volumio volumio[1196]: ---------------------------- MPD announces state update: update May 31 19:57:21 volumio volumio[1196]: info: ControllerMpd::getState May 31 19:57:21 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 31 19:57:21 volumio volumio[1196]: info: ControllerMpd::getState May 31 19:57:21 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 31 19:57:24 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 31 19:57:24 volumio upmpdcli[1522]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started May 31 19:57:30 volumio go-librespot[1720]: time="2026-05-31T19:57:30+02:00" level=trace msg="sent dealer ping" May 31 19:57:30 volumio go-librespot[1720]: time="2026-05-31T19:57:30+02:00" level=trace msg="received dealer pong" May 31 19:57:31 volumio volumio[1196]: info: sendMpdCommand status took 10231 milliseconds May 31 19:57:31 volumio volumio[1196]: info: sendMpdCommand status took 10231 milliseconds May 31 19:57:31 volumio volumio[1196]: info: sendMpdCommand status took 10231 milliseconds May 31 19:57:31 volumio volumio[1196]: verbose: ControllerMpd::parseState May 31 19:57:31 volumio volumio[1196]: info: Command Router : Notfying DB Updatefalse May 31 19:57:31 volumio volumio[1196]: info: CoreCommandRouter::Close All Modals sent May 31 19:57:31 volumio volumio[1196]: verbose: ControllerMpd::parseState May 31 19:57:31 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:57:31 volumio volumio[1196]: verbose: ControllerMpd::parseState May 31 19:57:31 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:57:31 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 19:57:31 volumio volumio[1196]: info: CURURI: artists:// May 31 19:57:32 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 19:57:32 volumio volumio[1196]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 31 19:57:32 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 31 19:57:32 volumio volumio[1196]: info: Received Get System Version May 31 19:57:32 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 19:57:32 volumio volumio[1196]: info: Received Get System Info May 31 19:57:32 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 19:57:32 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 19:57:32 volumio volumio[1196]: info: Discovery: Getting this device information May 31 19:57:32 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 31 19:57:32 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:32 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 19:57:38 volumio volumio[1196]: info: sendMpdCommand status took 17136 milliseconds May 31 19:57:38 volumio volumio[1196]: info: sendMpdCommand status took 17136 milliseconds May 31 19:57:38 volumio volumio[1196]: info: sendMpdCommand status took 17136 milliseconds May 31 19:57:38 volumio volumio[1196]: verbose: ControllerMpd::parseState May 31 19:57:38 volumio volumio[1196]: info: Command Router : Notfying DB Updatefalse May 31 19:57:38 volumio volumio[1196]: info: CoreCommandRouter::Close All Modals sent May 31 19:57:38 volumio volumio[1196]: verbose: ControllerMpd::parseState May 31 19:57:38 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:57:38 volumio volumio[1196]: verbose: ControllerMpd::parseState May 31 19:57:38 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:57:45 volumio volumio[1196]: info: sendMpdCommand status took 23916 milliseconds May 31 19:57:45 volumio volumio[1196]: info: sendMpdCommand status took 23916 milliseconds May 31 19:57:45 volumio volumio[1196]: info: sendMpdCommand status took 23917 milliseconds May 31 19:57:45 volumio volumio[1196]: verbose: ControllerMpd::parseState May 31 19:57:45 volumio volumio[1196]: info: Command Router : Notfying DB Updatefalse May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::Close All Modals sent May 31 19:57:45 volumio volumio[1196]: verbose: ControllerMpd::parseState May 31 19:57:45 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:57:45 volumio volumio[1196]: verbose: ControllerMpd::parseState May 31 19:57:45 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:57:45 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 13728 milliseconds May 31 19:57:45 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 13728 milliseconds May 31 19:57:45 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo May 31 19:57:45 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo May 31 19:57:45 volumio volumio[1196]: info: ControllerMpd::pushState May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 31 19:57:45 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:45 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67262,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"New Partner","artist":"Bonnie \"Prince\" Billy","album":"Greatest Palace Music","uri":"USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3","trackType":"mp3"} May 31 19:57:45 volumio volumio[1196]: verbose: CURRENT POSITION 0 May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play May 31 19:57:45 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:57:45 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:57:45 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:57:45 volumio volumio[1196]: info: ControllerMpd::pushState May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 31 19:57:45 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:45 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67262,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"New Partner","artist":"Bonnie \"Prince\" Billy","album":"Greatest Palace Music","uri":"USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3","trackType":"mp3"} May 31 19:57:45 volumio volumio[1196]: verbose: CURRENT POSITION 0 May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play May 31 19:57:45 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:57:45 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:57:45 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.376+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" state=STATUS_PLAYING positionMs=67262 volume=100 May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.376+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" state=STATUS_PLAYING positionMs=67262 volume=100 May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.377+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.378+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.382+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" state=STATUS_PLAYING positionMs=67262 volume=100 May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.384+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" state=STATUS_PLAYING positionMs=67262 volume=100 May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.386+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.388+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:57:45 volumio volumio[1196]: info: ------------------------------ 24050ms May 31 19:57:45 volumio volumio[1196]: info: ------------------------------ 24051ms May 31 19:57:45 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:57:45 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:57:45 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:57:45 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:57:45 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 7008 milliseconds May 31 19:57:45 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 7009 milliseconds May 31 19:57:45 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo May 31 19:57:45 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo May 31 19:57:45 volumio volumio[1196]: info: ControllerMpd::pushState May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 31 19:57:45 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:45 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68362,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"New Partner","artist":"Bonnie \"Prince\" Billy","album":"Greatest Palace Music","uri":"USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3","trackType":"mp3"} May 31 19:57:45 volumio volumio[1196]: verbose: CURRENT POSITION 0 May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play May 31 19:57:45 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:57:45 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:57:45 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:57:45 volumio volumio[1196]: info: ControllerMpd::pushState May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 31 19:57:45 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:45 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68362,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"New Partner","artist":"Bonnie \"Prince\" Billy","album":"Greatest Palace Music","uri":"USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3","trackType":"mp3"} May 31 19:57:45 volumio volumio[1196]: verbose: CURRENT POSITION 0 May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play May 31 19:57:45 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:57:45 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:57:45 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.673+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" state=STATUS_PLAYING positionMs=68362 volume=100 May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.674+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.676+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" state=STATUS_PLAYING positionMs=68362 volume=100 May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.678+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" state=STATUS_PLAYING positionMs=68362 volume=100 May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.678+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.680+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" state=STATUS_PLAYING positionMs=68362 volume=100 May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.680+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.681+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:57:45 volumio volumio[1196]: info: ------------------------------ 24340ms May 31 19:57:45 volumio volumio[1196]: info: ------------------------------ 24340ms May 31 19:57:45 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 406 milliseconds May 31 19:57:45 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 406 milliseconds May 31 19:57:45 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo May 31 19:57:45 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo May 31 19:57:45 volumio volumio[1196]: info: ControllerMpd::pushState May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 31 19:57:45 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:45 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69463,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"New Partner","artist":"Bonnie \"Prince\" Billy","album":"Greatest Palace Music","uri":"USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3","trackType":"mp3"} May 31 19:57:45 volumio volumio[1196]: verbose: CURRENT POSITION 0 May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play May 31 19:57:45 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:57:45 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:57:45 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:57:45 volumio volumio[1196]: info: ControllerMpd::pushState May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 31 19:57:45 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:45 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69463,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"New Partner","artist":"Bonnie \"Prince\" Billy","album":"Greatest Palace Music","uri":"USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3","trackType":"mp3"} May 31 19:57:45 volumio volumio[1196]: verbose: CURRENT POSITION 0 May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play May 31 19:57:45 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:57:45 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:57:45 volumio volumio[1196]: info: CoreStateMachine::pushState May 31 19:57:45 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 31 19:57:45 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.739+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" state=STATUS_PLAYING positionMs=69463 volume=100 May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.740+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" state=STATUS_PLAYING positionMs=69463 volume=100 May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.741+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" state=STATUS_PLAYING positionMs=69463 volume=100 May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.742+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.743+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.744+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" state=STATUS_PLAYING positionMs=69463 volume=100 May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.745+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:57:45 volumio volumio5-onboarding[1694]: time=2026-05-31T19:57:45.746+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.205:45660,00:00:00:00:00:00%01 @ 0x1ef2780" id="mnt/USB/2721-1FF9/Music/Bonnie 'Prince' Billy/Bonnie 'Prince' Billy - Sings Greatest Palace Music/01-New Partner.mp3" title="New Partner" May 31 19:57:45 volumio volumio[1196]: info: ------------------------------ 24400ms May 31 19:57:45 volumio volumio[1196]: info: ------------------------------ 24402ms May 31 19:57:45 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:57:45 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:57:45 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:57:45 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:57:45 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:57:45 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:57:45 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:57:45 volumio volumio[1196]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 19:57:45 volumio volumio[1196]: info: Preload queue cleared May 31 19:57:54 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 19:57:54 volumio volumio[1196]: info: CURURI: artists://2%20Pac May 31 19:57:54 volumio volumio[1196]: info: Preload queue cleared May 31 19:57:54 volumio volumio[1196]: info: Preload queue cleared May 31 19:57:54 volumio volumio[1196]: info: Preloading song: music-library/USB/2721-1FF9/Music/20 Years On MTV/20 Years On MTV - 1996/07 - 2pac - California Love.mp3 May 31 19:57:54 volumio volumio[1196]: info: Exploding uri music-library/USB/2721-1FF9/Music/20 Years On MTV/20 Years On MTV - 1996/07 - 2pac - California Love.mp3 in service mpd May 31 19:57:54 volumio volumio[1196]: info: ALBUMART /albumart?cacheid=736&web=2%20Pac/20%20Years%20Of%20MTV-German%20Charts/extralarge&path=%2Fmnt%2FUSB%2F2721-1FF9%2FMusic%2F20%20Years%20On%20MTV%2F20%20Years%20On%20MTV%20-%201996%2F07%20-%202pac%20-%20California%20Love.mp3&metadata=false May 31 19:57:54 volumio volumio[1196]: info: URI /mnt/USB/2721-1FF9/Music/20 Years On MTV/20 Years On MTV - 1996/07 - 2pac - California Love.mp3 May 31 19:57:55 volumio volumio[1196]: info: No valid Plugin REST Endpoint May 31 19:57:57 volumio volumio[1196]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 19:57:57 volumio volumio[1196]: TypeError: Cannot read properties of null (reading 'slice') May 31 19:57:57 volumio volumio[1196]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) May 31 19:57:57 volumio volumio[1196]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) May 31 19:57:57 volumio volumio[1196]: at Object.onceWrapper (node:events:629:26) May 31 19:57:57 volumio volumio[1196]: at ClientRequest.emit (node:events:514:28) May 31 19:57:57 volumio volumio[1196]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) May 31 19:57:57 volumio volumio[1196]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) May 31 19:57:57 volumio volumio[1196]: at TLSSocket.socketOnData (node:_http_client:535:22) May 31 19:57:57 volumio volumio[1196]: at TLSSocket.emit (node:events:514:28) May 31 19:57:57 volumio volumio[1196]: at addChunk (node:internal/streams/readable:343:12) May 31 19:57:57 volumio volumio[1196]: at readableAddChunk (node:internal/streams/readable:316:9) May 31 19:57:57 volumio volumio[1196]: at Readable.push (node:internal/streams/readable:253:10) May 31 19:57:57 volumio volumio[1196]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) May 31 19:57:57 volumio volumio[1196]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 19:57:59 volumio sudo[16074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-31 19:56' May 31 19:57:59 volumio sudo[16074]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"