-- Logs begin at Sat 2026-03-28 10:06:59 CET, end at Sun 2026-03-29 13:44:50 CEST. -- Mar 29 13:43:03 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 13:43:03 volumio volumio[1092]: info: CURURI: music-library/USB/Audio-1B/Audio1/B/Beach Boys Mar 29 13:43:03 volumio volumio[1092]: info: Preload queue cleared Mar 29 13:43:07 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 13:43:07 volumio volumio[1092]: info: CURURI: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds Mar 29 13:43:07 volumio volumio[1092]: info: Preload queue cleared Mar 29 13:43:07 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav Mar 29 13:43:07 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/You Still Believe In Me.wav Mar 29 13:43:07 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/That's Not Me.wav Mar 29 13:43:07 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Don't Talk (Put Your Head On My Shoulder).wav Mar 29 13:43:07 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/I'm Waiting For The Day.wav Mar 29 13:43:07 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Let's Go Away For Awhile.wav Mar 29 13:43:07 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Sloop John B.wav Mar 29 13:43:07 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/God Only Knows.wav Mar 29 13:43:07 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/I Know There's An Answer.wav Mar 29 13:43:07 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Here Today.wav Mar 29 13:43:07 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/I Just Wasn't Made For These Times.wav Mar 29 13:43:07 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Pet Sounds.wav Mar 29 13:43:07 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Caroline No.wav Mar 29 13:43:07 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Unreleased Backgrounds [Bonus Track].wav Mar 29 13:43:07 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Hang On To Your Ego [Bonus Track].wav Mar 29 13:43:07 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Trombone Dixie [Bonus Track].wav Mar 29 13:43:07 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav in service mpd Mar 29 13:43:07 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=The%20Beach%20Boys/Pet%20Sounds/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeach%20Boys%2FPet%20Sounds%2FWouldn't%20It%20Be%20Nice.wav&metadata=false Mar 29 13:43:07 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav Mar 29 13:43:07 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/You Still Believe In Me.wav in service mpd Mar 29 13:43:07 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=The%20Beach%20Boys/Pet%20Sounds/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeach%20Boys%2FPet%20Sounds%2FYou%20Still%20Believe%20In%20Me.wav&metadata=false Mar 29 13:43:07 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/You Still Believe In Me.wav Mar 29 13:43:07 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/That's Not Me.wav in service mpd Mar 29 13:43:07 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=The%20Beach%20Boys/Pet%20Sounds/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeach%20Boys%2FPet%20Sounds%2FThat's%20Not%20Me.wav&metadata=false Mar 29 13:43:07 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/That's Not Me.wav Mar 29 13:43:07 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Don't Talk (Put Your Head On My Shoulder).wav in service mpd Mar 29 13:43:07 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=The%20Beach%20Boys/Pet%20Sounds/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeach%20Boys%2FPet%20Sounds%2FDon't%20Talk%20(Put%20Your%20Head%20On%20My%20Shoulder).wav&metadata=false Mar 29 13:43:07 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Don't Talk (Put Your Head On My Shoulder).wav Mar 29 13:43:07 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/I'm Waiting For The Day.wav in service mpd Mar 29 13:43:07 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=The%20Beach%20Boys/Pet%20Sounds/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeach%20Boys%2FPet%20Sounds%2FI'm%20Waiting%20For%20The%20Day.wav&metadata=false Mar 29 13:43:07 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/I'm Waiting For The Day.wav Mar 29 13:43:07 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Let's Go Away For Awhile.wav in service mpd Mar 29 13:43:07 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=The%20Beach%20Boys/Pet%20Sounds/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeach%20Boys%2FPet%20Sounds%2FLet's%20Go%20Away%20For%20Awhile.wav&metadata=false Mar 29 13:43:07 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Let's Go Away For Awhile.wav Mar 29 13:43:07 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Sloop John B.wav in service mpd Mar 29 13:43:07 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=The%20Beach%20Boys/Pet%20Sounds/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeach%20Boys%2FPet%20Sounds%2FSloop%20John%20B.wav&metadata=false Mar 29 13:43:07 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Sloop John B.wav Mar 29 13:43:07 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/God Only Knows.wav in service mpd Mar 29 13:43:07 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=The%20Beach%20Boys/Pet%20Sounds/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeach%20Boys%2FPet%20Sounds%2FGod%20Only%20Knows.wav&metadata=false Mar 29 13:43:07 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/God Only Knows.wav Mar 29 13:43:07 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/I Know There's An Answer.wav in service mpd Mar 29 13:43:07 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=The%20Beach%20Boys/Pet%20Sounds/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeach%20Boys%2FPet%20Sounds%2FI%20Know%20There's%20An%20Answer.wav&metadata=false Mar 29 13:43:07 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/I Know There's An Answer.wav Mar 29 13:43:08 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Here Today.wav in service mpd Mar 29 13:43:08 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=The%20Beach%20Boys/Pet%20Sounds/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeach%20Boys%2FPet%20Sounds%2FHere%20Today.wav&metadata=false Mar 29 13:43:08 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Here Today.wav Mar 29 13:43:08 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/I Just Wasn't Made For These Times.wav in service mpd Mar 29 13:43:08 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=The%20Beach%20Boys/Pet%20Sounds/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeach%20Boys%2FPet%20Sounds%2FI%20Just%20Wasn't%20Made%20For%20These%20Times.wav&metadata=false Mar 29 13:43:08 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/I Just Wasn't Made For These Times.wav Mar 29 13:43:08 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Pet Sounds.wav in service mpd Mar 29 13:43:08 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=The%20Beach%20Boys/Pet%20Sounds/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeach%20Boys%2FPet%20Sounds%2FPet%20Sounds.wav&metadata=false Mar 29 13:43:08 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Pet Sounds.wav Mar 29 13:43:08 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Caroline No.wav in service mpd Mar 29 13:43:08 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=The%20Beach%20Boys/Pet%20Sounds/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeach%20Boys%2FPet%20Sounds%2FCaroline%20No.wav&metadata=false Mar 29 13:43:08 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Caroline No.wav Mar 29 13:43:08 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Unreleased Backgrounds [Bonus Track].wav in service mpd Mar 29 13:43:08 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=The%20Beach%20Boys/Pet%20Sounds/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeach%20Boys%2FPet%20Sounds%2FUnreleased%20Backgrounds%20%5BBonus%20Track%5D.wav&metadata=false Mar 29 13:43:08 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Unreleased Backgrounds [Bonus Track].wav Mar 29 13:43:08 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Hang On To Your Ego [Bonus Track].wav in service mpd Mar 29 13:43:08 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=The%20Beach%20Boys/Pet%20Sounds/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeach%20Boys%2FPet%20Sounds%2FHang%20On%20To%20Your%20Ego%20%5BBonus%20Track%5D.wav&metadata=false Mar 29 13:43:08 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Hang On To Your Ego [Bonus Track].wav Mar 29 13:43:08 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Trombone Dixie [Bonus Track].wav in service mpd Mar 29 13:43:08 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=The%20Beach%20Boys/Pet%20Sounds/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeach%20Boys%2FPet%20Sounds%2FTrombone%20Dixie%20%5BBonus%20Track%5D.wav&metadata=false Mar 29 13:43:08 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Trombone Dixie [Bonus Track].wav Mar 29 13:43:14 volumio volumio[1092]: info: Preload queue cleared Mar 29 13:43:14 volumio volumio[1092]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 13:43:14 volumio volumio[1092]: info: CoreStateMachine::addQueueItems Mar 29 13:43:14 volumio volumio[1092]: info: CorePlayQueue::addQueueItems Mar 29 13:43:14 volumio volumio[1092]: info: Preload queue cleared Mar 29 13:43:14 volumio volumio[1092]: info: Adding Item to queue: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav Mar 29 13:43:14 volumio volumio[1092]: info: Using cached record of: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav Mar 29 13:43:14 volumio volumio[1092]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:43:14 volumio volumio[1092]: info: CorePlayQueue::saveQueue Mar 29 13:43:14 volumio volumio[1092]: info: CoreStateMachine::updateTrackBlock Mar 29 13:43:14 volumio volumio[1092]: info: CorePlayQueue::getTrackBlock Mar 29 13:43:14 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Mar 29 13:43:14 volumio volumio[1092]: info: CoreStateMachine::play index 6119 Mar 29 13:43:14 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:43:14 volumio volumio[1092]: info: CoreStateMachine::stop Mar 29 13:43:14 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:43:14 volumio volumio[1092]: info: CoreStateMachine::play index undefined Mar 29 13:43:14 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:43:14 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:14 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:43:14 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:14 volumio volumio[1092]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 13:43:14 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 13:43:14 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 13:43:14 volumio volumio[1092]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Mar 29 13:43:14 volumio volumio[1092]: verbose: ControllerMpd::clearAddPlayTracks USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav Mar 29 13:43:14 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:43:14 volumio volumio[1092]: info: sendMpdCommand stop took 98 milliseconds Mar 29 13:43:14 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 13:43:14 volumio volumio[1092]: info: Mar 29 13:43:14 volumio volumio[1092]: ---------------------------- MPD announces system playlist update Mar 29 13:43:14 volumio volumio[1092]: info: Ignoring MPD Status Update Mar 29 13:43:14 volumio volumio[1092]: info: sendMpdCommand clear took 411 milliseconds Mar 29 13:43:14 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand add "USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav" Mar 29 13:43:14 volumio volumio[1092]: error: updateQueue error: null Mar 29 13:43:14 volumio volumio[1092]: info: Mar 29 13:43:14 volumio volumio[1092]: ---------------------------- MPD announces system playlist update Mar 29 13:43:14 volumio volumio[1092]: info: Ignoring MPD Status Update Mar 29 13:43:14 volumio volumio[1092]: info: ------------------------------ 16ms Mar 29 13:43:14 volumio volumio[1092]: info: sendMpdCommand add "USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav" took 14 milliseconds Mar 29 13:43:14 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Mar 29 13:43:15 volumio volumio[1092]: info: Mar 29 13:43:15 volumio volumio[1092]: ---------------------------- MPD announces state update: player Mar 29 13:43:15 volumio volumio[1092]: info: ------------------------------ 706ms Mar 29 13:43:15 volumio volumio[1092]: info: sendMpdCommand play took 703 milliseconds Mar 29 13:43:15 volumio volumio[1092]: info: ControllerMpd::getState Mar 29 13:43:15 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:43:15 volumio volumio[1092]: info: Mar 29 13:43:15 volumio volumio[1092]: ---------------------------- MPD announces state update: player Mar 29 13:43:15 volumio volumio[1092]: info: ControllerMpd::getState Mar 29 13:43:15 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:43:15 volumio volumio[1092]: info: sendMpdCommand status took 20 milliseconds Mar 29 13:43:15 volumio volumio[1092]: verbose: ControllerMpd::parseState Mar 29 13:43:15 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:43:15 volumio volumio[1092]: info: sendMpdCommand status took 22 milliseconds Mar 29 13:43:15 volumio volumio[1092]: info: sendMpdCommand playlistinfo took 18 milliseconds Mar 29 13:43:15 volumio volumio[1092]: verbose: ControllerMpd::parseState Mar 29 13:43:15 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:43:15 volumio volumio[1092]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:43:15 volumio volumio[1092]: info: ControllerMpd::pushState Mar 29 13:43:15 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:43:15 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:15 volumio volumio[1092]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":145,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wouldn't It Be Nice","artist":"The Beach Boys","album":"Pet Sounds","uri":"USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav","trackType":"wav"} Mar 29 13:43:15 volumio volumio[1092]: verbose: CURRENT POSITION 6119 Mar 29 13:43:15 volumio volumio[1092]: info: CoreStateMachine::syncState stateService play Mar 29 13:43:15 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:43:15 volumio volumio[1092]: info: ------------------------------ 49ms Mar 29 13:43:15 volumio volumio[1092]: info: sendMpdCommand playlistinfo took 21 milliseconds Mar 29 13:43:15 volumio volumio[1092]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:43:15 volumio volumio[1092]: info: ControllerMpd::pushState Mar 29 13:43:15 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:43:15 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:15 volumio volumio[1092]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":145,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wouldn't It Be Nice","artist":"The Beach Boys","album":"Pet Sounds","uri":"USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav","trackType":"wav"} Mar 29 13:43:15 volumio volumio[1092]: verbose: CURRENT POSITION 6119 Mar 29 13:43:15 volumio volumio[1092]: info: CoreStateMachine::syncState stateService play Mar 29 13:43:15 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:43:15 volumio volumio[1092]: info: Received an update from plugin. extracting info from payload Mar 29 13:43:15 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:15 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:15 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:43:15 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:15 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:15 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:15 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:15 volumio volumio[1092]: info: ------------------------------ 59ms Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_music , getSuperSearchEnabledSourcesList Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 13:43:16 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:16 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:16 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:16 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:16 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:16 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:16 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:16 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:16 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:16 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:16 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:16 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:16 volumio volumio[1092]: info: Preload queue cleared Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 13:43:16 volumio volumio[1092]: info: CoreStateMachine::addQueueItems Mar 29 13:43:16 volumio volumio[1092]: info: CorePlayQueue::addQueueItems Mar 29 13:43:16 volumio volumio[1092]: info: Preload queue cleared Mar 29 13:43:16 volumio volumio[1092]: info: Adding Item to queue: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav Mar 29 13:43:16 volumio volumio[1092]: info: Using cached record of: music-library/USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:43:16 volumio volumio[1092]: info: CorePlayQueue::saveQueue Mar 29 13:43:16 volumio volumio[1092]: info: CoreStateMachine::updateTrackBlock Mar 29 13:43:16 volumio volumio[1092]: info: CorePlayQueue::getTrackBlock Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Mar 29 13:43:16 volumio volumio[1092]: info: CoreStateMachine::play index 6119 Mar 29 13:43:16 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:43:16 volumio volumio[1092]: info: CoreStateMachine::stop Mar 29 13:43:16 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:43:16 volumio volumio[1092]: info: CoreStateMachine::stPlaybackTimer Mar 29 13:43:16 volumio volumio[1092]: info: CoreStateMachine::updateTrackBlock Mar 29 13:43:16 volumio volumio[1092]: info: CorePlayQueue::getTrackBlock Mar 29 13:43:16 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:16 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:16 volumio volumio[1092]: info: CoreStateMachine::serviceStop Mar 29 13:43:16 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:16 volumio volumio[1092]: info: CoreCommandRouter::serviceStop Mar 29 13:43:16 volumio volumio[1092]: info: ControllerMpd::stop Mar 29 13:43:16 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:43:18 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:18 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:18 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:18 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:18 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:18 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:18 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:18 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:18 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:18 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:18 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:18 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:18 volumio volumio[1092]: info: METAVOLUMIO: No new tracks added to queue, Starting fallback strategies Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::volumioGetQueue Mar 29 13:43:18 volumio volumio[1092]: info: CoreStateMachine::getQueue Mar 29 13:43:18 volumio volumio[1092]: info: CorePlayQueue::getQueue Mar 29 13:43:18 volumio volumio[1092]: info: METAVOLUMIO: Executing Random Queue Item Fallback Strategy with index: 4529 Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 13:43:18 volumio volumio[1092]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Mar 29 13:43:18 volumio volumio[1092]: info: Mar 29 13:43:18 volumio volumio[1092]: ---------------------------- MPD announces state update: player Mar 29 13:43:18 volumio volumio[1092]: info: sendMpdCommand stop took 1847 milliseconds Mar 29 13:43:18 volumio volumio[1092]: info: ControllerMpd::getState Mar 29 13:43:18 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:43:18 volumio volumio[1092]: info: CoreStateMachine::play index undefined Mar 29 13:43:18 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:43:18 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:18 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:43:18 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 13:43:18 volumio volumio[1092]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Mar 29 13:43:18 volumio volumio[1092]: verbose: ControllerMpd::clearAddPlayTracks USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav Mar 29 13:43:18 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:43:18 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:18 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:18 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:18 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:18 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:18 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:18 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:18 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:18 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:18 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:18 volumio volumio[1092]: info: sendMpdCommand status took 21 milliseconds Mar 29 13:43:18 volumio volumio[1092]: info: sendMpdCommand stop took 18 milliseconds Mar 29 13:43:18 volumio volumio[1092]: verbose: ControllerMpd::parseState Mar 29 13:43:18 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:43:18 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 13:43:19 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:19 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:19 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:19 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:19 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:19 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:19 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:19 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:19 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:19 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:19 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:19 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:19 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:20 volumio volumio[1092]: info: Mar 29 13:43:20 volumio volumio[1092]: ---------------------------- MPD announces system playlist update Mar 29 13:43:20 volumio volumio[1092]: info: Ignoring MPD Status Update Mar 29 13:43:20 volumio volumio[1092]: info: sendMpdCommand playlistinfo took 2375 milliseconds Mar 29 13:43:20 volumio volumio[1092]: info: sendMpdCommand clear took 2376 milliseconds Mar 29 13:43:20 volumio volumio[1092]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:43:20 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand add "USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav" Mar 29 13:43:20 volumio volumio[1092]: info: ControllerMpd::pushState Mar 29 13:43:20 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:43:20 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:20 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:20 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:43:20 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:20 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:20 volumio volumio[1092]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wouldn't It Be Nice","artist":"The Beach Boys","album":"Pet Sounds","uri":"USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav","trackType":"wav"} Mar 29 13:43:20 volumio volumio[1092]: verbose: CURRENT POSITION 6119 Mar 29 13:43:20 volumio volumio[1092]: info: CoreStateMachine::syncState stateService stop Mar 29 13:43:20 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:43:20 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:20 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:20 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:20 volumio volumio[1092]: info: No code Mar 29 13:43:20 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:20 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:20 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:20 volumio volumio[1092]: info: ------------------------------ 2421ms Mar 29 13:43:20 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:20 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:20 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:20 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:20 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:20 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:20 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:20 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:20 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:20 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:20 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:20 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:20 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:20 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:20 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:20 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:20 volumio volumio[1092]: info: METAVOLUMIO: No new tracks added to queue, Starting fallback strategies Mar 29 13:43:20 volumio volumio[1092]: info: METAVOLUMIO: Executing Random Fallback Strategy Mar 29 13:43:20 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 13:43:20 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , getRandomLocalTrack Mar 29 13:43:20 volumio volumio[1092]: info: listAlbums - loading Albums from cache Mar 29 13:43:23 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:23 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:23 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:23 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:23 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:23 volumio volumio[1092]: info: METAVOLUMIO: No new tracks added to queue, Starting fallback strategies Mar 29 13:43:23 volumio volumio[1092]: info: METAVOLUMIO: Infinity playback mode stopping, exhausted all strategies to continue Mar 29 13:43:23 volumio volumio[1092]: error: updateQueue error: null Mar 29 13:43:23 volumio volumio[1092]: info: Mar 29 13:43:23 volumio volumio[1092]: ---------------------------- MPD announces system playlist update Mar 29 13:43:23 volumio volumio[1092]: info: Ignoring MPD Status Update Mar 29 13:43:23 volumio volumio[1092]: info: ------------------------------ 2563ms Mar 29 13:43:23 volumio volumio[1092]: info: sendMpdCommand add "USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav" took 2557 milliseconds Mar 29 13:43:23 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Mar 29 13:43:23 volumio volumio[1092]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 13:43:23 volumio volumio[1092]: info: CoreStateMachine::addQueueItems Mar 29 13:43:23 volumio volumio[1092]: info: CorePlayQueue::addQueueItems Mar 29 13:43:23 volumio volumio[1092]: info: Preload queue cleared Mar 29 13:43:23 volumio volumio[1092]: info: Adding Item to queue: music-library/USB/Audio-1B/Audio2/Verzamel Pop/De Jaren 70/1973/cd2/Alright, Alright, Alright.wav Mar 29 13:43:23 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio2/Verzamel Pop/De Jaren 70/1973/cd2/Alright, Alright, Alright.wav in service mpd Mar 29 13:43:23 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=Mungo%20Jerry/Radio%202%20De%20Jaren%20'70%20-%20Deel%204%20-%201973%20-%20(CD2)/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio2%2FVerzamel%20Pop%2FDe%20Jaren%2070%2F1973%2Fcd2%2FAlright%2C%20Alright%2C%20Alright.wav&metadata=false Mar 29 13:43:23 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio2/Verzamel Pop/De Jaren 70/1973/cd2/Alright, Alright, Alright.wav Mar 29 13:43:23 volumio volumio[1092]: info: ------------------------------ 7ms Mar 29 13:43:23 volumio volumio[1092]: info: sendMpdCommand play took 5 milliseconds Mar 29 13:43:23 volumio volumio[1092]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:43:23 volumio volumio[1092]: info: CorePlayQueue::saveQueue Mar 29 13:43:23 volumio volumio[1092]: info: CoreStateMachine::updateTrackBlock Mar 29 13:43:23 volumio volumio[1092]: info: CorePlayQueue::getTrackBlock Mar 29 13:43:23 volumio volumio[1092]: info: Mar 29 13:43:23 volumio volumio[1092]: ---------------------------- MPD announces state update: player Mar 29 13:43:23 volumio volumio[1092]: info: ControllerMpd::getState Mar 29 13:43:23 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:43:23 volumio volumio[1092]: info: Mar 29 13:43:23 volumio volumio[1092]: ---------------------------- MPD announces state update: player Mar 29 13:43:23 volumio volumio[1092]: info: ControllerMpd::getState Mar 29 13:43:23 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:43:23 volumio volumio[1092]: info: sendMpdCommand status took 144 milliseconds Mar 29 13:43:23 volumio volumio[1092]: verbose: ControllerMpd::parseState Mar 29 13:43:23 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:43:24 volumio volumio[1092]: info: sendMpdCommand status took 335 milliseconds Mar 29 13:43:24 volumio volumio[1092]: info: sendMpdCommand playlistinfo took 333 milliseconds Mar 29 13:43:24 volumio volumio[1092]: verbose: ControllerMpd::parseState Mar 29 13:43:24 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:43:24 volumio volumio[1092]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:43:24 volumio volumio[1092]: info: ControllerMpd::pushState Mar 29 13:43:24 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:43:24 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:24 volumio volumio[1092]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1075,"duration":145,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wouldn't It Be Nice","artist":"The Beach Boys","album":"Pet Sounds","uri":"USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav","trackType":"wav"} Mar 29 13:43:24 volumio volumio[1092]: verbose: CURRENT POSITION 6119 Mar 29 13:43:24 volumio volumio[1092]: info: CoreStateMachine::syncState stateService play Mar 29 13:43:24 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:43:24 volumio volumio[1092]: info: ------------------------------ 483ms Mar 29 13:43:24 volumio volumio[1092]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 29 13:43:24 volumio volumio[1092]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:43:24 volumio volumio[1092]: info: ControllerMpd::pushState Mar 29 13:43:24 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:43:24 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:24 volumio volumio[1092]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1258,"duration":145,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wouldn't It Be Nice","artist":"The Beach Boys","album":"Pet Sounds","uri":"USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav","trackType":"wav"} Mar 29 13:43:24 volumio volumio[1092]: verbose: CURRENT POSITION 6119 Mar 29 13:43:24 volumio volumio[1092]: info: CoreStateMachine::syncState stateService play Mar 29 13:43:24 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:43:24 volumio volumio[1092]: info: Received an update from plugin. extracting info from payload Mar 29 13:43:24 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:24 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:24 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:43:24 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:24 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:24 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:24 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:24 volumio volumio[1092]: info: ------------------------------ 358ms Mar 29 13:43:24 volumio go-librespot[2071]: time="2026-03-29T13:43:24+02:00" level=trace msg="sent dealer ping" Mar 29 13:43:24 volumio go-librespot[2071]: time="2026-03-29T13:43:24+02:00" level=trace msg="received dealer pong" Mar 29 13:43:37 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 13:43:37 volumio volumio[1092]: info: CURURI: music-library/USB/Audio-1B/Audio1/B/Beck Jeff Mar 29 13:43:37 volumio volumio[1092]: info: Preload queue cleared Mar 29 13:43:39 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 13:43:39 volumio volumio[1092]: info: CURURI: music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP Mar 29 13:43:39 volumio volumio[1092]: info: Preload queue cleared Mar 29 13:43:39 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac Mar 29 13:43:39 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 02 She's A Woman.flac Mar 29 13:43:39 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 03 Constipaded Duck.flac Mar 29 13:43:39 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 04 AIR Blower.flac Mar 29 13:43:39 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 05 Scatterbrain.flac Mar 29 13:43:39 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/02 - 01 Couse We Ended As Lovers.flac Mar 29 13:43:39 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/02 - 02 Thelonius.flac Mar 29 13:43:39 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/02 - 03 Freeway Jam.flac Mar 29 13:43:39 volumio volumio[1092]: info: Preloading song: music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/02 - 04 Diamond Dus.flac Mar 29 13:43:39 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac in service mpd Mar 29 13:43:39 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=Jeff%20Beck/Blow%20By%20Blow/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeck%20Jeff%2FBlow%20By%20Blow%20LP%2F01%20-%2001%20You%20Know%20What%20I%20Mean.flac&metadata=false Mar 29 13:43:39 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac Mar 29 13:43:39 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 02 She's A Woman.flac in service mpd Mar 29 13:43:39 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=Jeff%20Beck/Blow%20By%20Blow/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeck%20Jeff%2FBlow%20By%20Blow%20LP%2F01%20-%2002%20She's%20A%20Woman.flac&metadata=false Mar 29 13:43:39 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 02 She's A Woman.flac Mar 29 13:43:39 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 03 Constipaded Duck.flac in service mpd Mar 29 13:43:39 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=Jeff%20Beck/Blow%20By%20Blow/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeck%20Jeff%2FBlow%20By%20Blow%20LP%2F01%20-%2003%20Constipaded%20Duck.flac&metadata=false Mar 29 13:43:39 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 03 Constipaded Duck.flac Mar 29 13:43:39 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 04 AIR Blower.flac in service mpd Mar 29 13:43:39 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=Jeff%20Beck/Blow%20By%20Blow/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeck%20Jeff%2FBlow%20By%20Blow%20LP%2F01%20-%2004%20AIR%20Blower.flac&metadata=false Mar 29 13:43:39 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 04 AIR Blower.flac Mar 29 13:43:39 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 05 Scatterbrain.flac in service mpd Mar 29 13:43:39 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=Jeff%20Beck/Blow%20By%20Blow/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeck%20Jeff%2FBlow%20By%20Blow%20LP%2F01%20-%2005%20Scatterbrain.flac&metadata=false Mar 29 13:43:39 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 05 Scatterbrain.flac Mar 29 13:43:39 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/02 - 01 Couse We Ended As Lovers.flac in service mpd Mar 29 13:43:39 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=Jeff%20Beck/Blow%20By%20Blow/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeck%20Jeff%2FBlow%20By%20Blow%20LP%2F02%20-%2001%20Couse%20We%20Ended%20As%20Lovers.flac&metadata=false Mar 29 13:43:39 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/02 - 01 Couse We Ended As Lovers.flac Mar 29 13:43:39 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/02 - 02 Thelonius.flac in service mpd Mar 29 13:43:39 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=Jeff%20Beck/Blow%20By%20Blow/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeck%20Jeff%2FBlow%20By%20Blow%20LP%2F02%20-%2002%20Thelonius.flac&metadata=false Mar 29 13:43:39 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/02 - 02 Thelonius.flac Mar 29 13:43:39 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/02 - 03 Freeway Jam.flac in service mpd Mar 29 13:43:39 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=Jeff%20Beck/Blow%20By%20Blow/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeck%20Jeff%2FBlow%20By%20Blow%20LP%2F02%20-%2003%20Freeway%20Jam.flac&metadata=false Mar 29 13:43:39 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/02 - 03 Freeway Jam.flac Mar 29 13:43:39 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/02 - 04 Diamond Dus.flac in service mpd Mar 29 13:43:39 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=Jeff%20Beck/Blow%20By%20Blow/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio1%2FB%2FBeck%20Jeff%2FBlow%20By%20Blow%20LP%2F02%20-%2004%20Diamond%20Dus.flac&metadata=false Mar 29 13:43:39 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/02 - 04 Diamond Dus.flac Mar 29 13:43:42 volumio volumio[1092]: info: Preload queue cleared Mar 29 13:43:42 volumio volumio[1092]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 13:43:42 volumio volumio[1092]: info: CoreStateMachine::addQueueItems Mar 29 13:43:42 volumio volumio[1092]: info: CorePlayQueue::addQueueItems Mar 29 13:43:42 volumio volumio[1092]: info: Preload queue cleared Mar 29 13:43:42 volumio volumio[1092]: info: Adding Item to queue: music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac Mar 29 13:43:42 volumio volumio[1092]: info: Using cached record of: music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac Mar 29 13:43:42 volumio volumio[1092]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:43:42 volumio volumio[1092]: info: CorePlayQueue::saveQueue Mar 29 13:43:42 volumio volumio[1092]: info: CoreStateMachine::updateTrackBlock Mar 29 13:43:42 volumio volumio[1092]: info: CorePlayQueue::getTrackBlock Mar 29 13:43:42 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Mar 29 13:43:42 volumio volumio[1092]: info: CoreStateMachine::play index 6121 Mar 29 13:43:42 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:43:42 volumio volumio[1092]: info: CoreStateMachine::stop Mar 29 13:43:42 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:43:42 volumio volumio[1092]: info: CoreStateMachine::stPlaybackTimer Mar 29 13:43:42 volumio volumio[1092]: info: CoreStateMachine::updateTrackBlock Mar 29 13:43:42 volumio volumio[1092]: info: CorePlayQueue::getTrackBlock Mar 29 13:43:42 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:42 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:42 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:43:42 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:42 volumio volumio[1092]: info: CoreStateMachine::serviceStop Mar 29 13:43:42 volumio volumio[1092]: info: CorePlayQueue::getTrack 6119 Mar 29 13:43:42 volumio volumio[1092]: info: CoreCommandRouter::serviceStop Mar 29 13:43:42 volumio volumio[1092]: info: ControllerMpd::stop Mar 29 13:43:42 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:43:43 volumio volumio[1092]: info: Mar 29 13:43:43 volumio volumio[1092]: ---------------------------- MPD announces state update: player Mar 29 13:43:43 volumio volumio[1092]: info: sendMpdCommand stop took 50 milliseconds Mar 29 13:43:43 volumio volumio[1092]: info: ControllerMpd::getState Mar 29 13:43:43 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:43:43 volumio volumio[1092]: info: CoreStateMachine::play index undefined Mar 29 13:43:43 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:43:43 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:43 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:43:43 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:43 volumio volumio[1092]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 13:43:43 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 13:43:43 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 13:43:43 volumio volumio[1092]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Mar 29 13:43:43 volumio volumio[1092]: verbose: ControllerMpd::clearAddPlayTracks USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac Mar 29 13:43:43 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:43:43 volumio volumio[1092]: info: sendMpdCommand status took 444 milliseconds Mar 29 13:43:43 volumio volumio[1092]: info: sendMpdCommand stop took 439 milliseconds Mar 29 13:43:43 volumio volumio[1092]: verbose: ControllerMpd::parseState Mar 29 13:43:43 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:43:43 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 13:43:43 volumio volumio[1092]: info: Mar 29 13:43:43 volumio volumio[1092]: ---------------------------- MPD announces system playlist update Mar 29 13:43:43 volumio volumio[1092]: info: Ignoring MPD Status Update Mar 29 13:43:43 volumio volumio[1092]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 29 13:43:43 volumio volumio[1092]: info: sendMpdCommand clear took 4 milliseconds Mar 29 13:43:43 volumio volumio[1092]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:43:43 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand add "USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac" Mar 29 13:43:43 volumio volumio[1092]: info: ControllerMpd::pushState Mar 29 13:43:43 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:43:43 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:43 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:43 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:43:43 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:43 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:43 volumio volumio[1092]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wouldn't It Be Nice","artist":"The Beach Boys","album":"Pet Sounds","uri":"USB/Audio-1B/Audio1/B/Beach Boys/Pet Sounds/Wouldn't It Be Nice.wav","trackType":"wav"} Mar 29 13:43:43 volumio volumio[1092]: verbose: CURRENT POSITION 6121 Mar 29 13:43:43 volumio volumio[1092]: info: CoreStateMachine::syncState stateService stop Mar 29 13:43:43 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:43:43 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:43 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:43 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:43 volumio volumio[1092]: info: No code Mar 29 13:43:43 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:43 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:43 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:43 volumio volumio[1092]: info: ------------------------------ 473ms Mar 29 13:43:43 volumio volumio[1092]: error: updateQueue error: null Mar 29 13:43:43 volumio volumio[1092]: info: Mar 29 13:43:43 volumio volumio[1092]: ---------------------------- MPD announces system playlist update Mar 29 13:43:43 volumio volumio[1092]: info: Ignoring MPD Status Update Mar 29 13:43:43 volumio volumio[1092]: info: ------------------------------ 28ms Mar 29 13:43:43 volumio volumio[1092]: info: sendMpdCommand add "USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac" took 22 milliseconds Mar 29 13:43:43 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Mar 29 13:43:43 volumio volumio[1092]: info: Mar 29 13:43:43 volumio volumio[1092]: ---------------------------- MPD announces state update: player Mar 29 13:43:43 volumio volumio[1092]: info: ------------------------------ 18ms Mar 29 13:43:43 volumio volumio[1092]: info: sendMpdCommand play took 17 milliseconds Mar 29 13:43:43 volumio volumio[1092]: info: ControllerMpd::getState Mar 29 13:43:43 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:43:43 volumio volumio[1092]: info: Mar 29 13:43:43 volumio volumio[1092]: ---------------------------- MPD announces state update: player Mar 29 13:43:43 volumio volumio[1092]: info: ControllerMpd::getState Mar 29 13:43:43 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:43:43 volumio volumio[1092]: info: sendMpdCommand status took 2 milliseconds Mar 29 13:43:43 volumio volumio[1092]: verbose: ControllerMpd::parseState Mar 29 13:43:43 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:43:43 volumio volumio[1092]: info: sendMpdCommand status took 3 milliseconds Mar 29 13:43:43 volumio volumio[1092]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:43:43 volumio volumio[1092]: verbose: ControllerMpd::parseState Mar 29 13:43:43 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:43:43 volumio volumio[1092]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:43:43 volumio volumio[1092]: info: ControllerMpd::pushState Mar 29 13:43:43 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:43:43 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:43 volumio volumio[1092]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":242,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1447 Kbps","isStreaming":false,"title":"01 - 01 You Know What I Mean","artist":"Jeff Beck","album":"Blow By Blow","uri":"USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac","trackType":"flac"} Mar 29 13:43:43 volumio volumio[1092]: verbose: CURRENT POSITION 6121 Mar 29 13:43:43 volumio volumio[1092]: info: CoreStateMachine::syncState stateService play Mar 29 13:43:43 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:43:43 volumio volumio[1092]: info: ------------------------------ 10ms Mar 29 13:43:43 volumio volumio[1092]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 29 13:43:43 volumio volumio[1092]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:43:43 volumio volumio[1092]: info: ControllerMpd::pushState Mar 29 13:43:43 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:43:43 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:43 volumio volumio[1092]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":242,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1447 Kbps","isStreaming":false,"title":"01 - 01 You Know What I Mean","artist":"Jeff Beck","album":"Blow By Blow","uri":"USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac","trackType":"flac"} Mar 29 13:43:43 volumio volumio[1092]: verbose: CURRENT POSITION 6121 Mar 29 13:43:43 volumio volumio[1092]: info: CoreStateMachine::syncState stateService play Mar 29 13:43:43 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:43:43 volumio volumio[1092]: info: Received an update from plugin. extracting info from payload Mar 29 13:43:43 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:43 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:43 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:43:43 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:43 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:43 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:43 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:43 volumio volumio[1092]: info: ------------------------------ 20ms Mar 29 13:43:45 volumio volumio[1092]: info: Preload queue cleared Mar 29 13:43:45 volumio volumio[1092]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 13:43:45 volumio volumio[1092]: info: CoreStateMachine::addQueueItems Mar 29 13:43:45 volumio volumio[1092]: info: CorePlayQueue::addQueueItems Mar 29 13:43:45 volumio volumio[1092]: info: Preload queue cleared Mar 29 13:43:45 volumio volumio[1092]: info: Adding Item to queue: music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac Mar 29 13:43:45 volumio volumio[1092]: info: Using cached record of: music-library/USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac Mar 29 13:43:45 volumio volumio[1092]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:43:45 volumio volumio[1092]: info: CorePlayQueue::saveQueue Mar 29 13:43:45 volumio volumio[1092]: info: CoreStateMachine::updateTrackBlock Mar 29 13:43:45 volumio volumio[1092]: info: CorePlayQueue::getTrackBlock Mar 29 13:43:45 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Mar 29 13:43:45 volumio volumio[1092]: info: CoreStateMachine::play index 6121 Mar 29 13:43:45 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::stop Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::stPlaybackTimer Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::updateTrackBlock Mar 29 13:43:46 volumio volumio[1092]: info: CorePlayQueue::getTrackBlock Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:46 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:46 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:43:46 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::serviceStop Mar 29 13:43:46 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:46 volumio volumio[1092]: info: CoreCommandRouter::serviceStop Mar 29 13:43:46 volumio volumio[1092]: info: ControllerMpd::stop Mar 29 13:43:46 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:43:46 volumio volumio[1092]: info: Mar 29 13:43:46 volumio volumio[1092]: ---------------------------- MPD announces state update: player Mar 29 13:43:46 volumio volumio[1092]: info: sendMpdCommand stop took 94 milliseconds Mar 29 13:43:46 volumio volumio[1092]: info: ControllerMpd::getState Mar 29 13:43:46 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::play index undefined Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:43:46 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:43:46 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:46 volumio volumio[1092]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 13:43:46 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 13:43:46 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 13:43:46 volumio volumio[1092]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Mar 29 13:43:46 volumio volumio[1092]: verbose: ControllerMpd::clearAddPlayTracks USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac Mar 29 13:43:46 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:43:46 volumio volumio[1092]: info: sendMpdCommand status took 355 milliseconds Mar 29 13:43:46 volumio volumio[1092]: info: sendMpdCommand stop took 349 milliseconds Mar 29 13:43:46 volumio volumio[1092]: verbose: ControllerMpd::parseState Mar 29 13:43:46 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:43:46 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 13:43:46 volumio volumio[1092]: info: Mar 29 13:43:46 volumio volumio[1092]: ---------------------------- MPD announces system playlist update Mar 29 13:43:46 volumio volumio[1092]: info: Ignoring MPD Status Update Mar 29 13:43:46 volumio volumio[1092]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 29 13:43:46 volumio volumio[1092]: info: sendMpdCommand clear took 7 milliseconds Mar 29 13:43:46 volumio volumio[1092]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:43:46 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand add "USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac" Mar 29 13:43:46 volumio volumio[1092]: info: ControllerMpd::pushState Mar 29 13:43:46 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:46 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:46 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:43:46 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:46 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:46 volumio volumio[1092]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - 01 You Know What I Mean","artist":"Jeff Beck","album":"Blow By Blow","uri":"USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac","trackType":"flac"} Mar 29 13:43:46 volumio volumio[1092]: verbose: CURRENT POSITION 6121 Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::syncState stateService stop Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:46 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:46 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:46 volumio volumio[1092]: info: No code Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:46 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:46 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:46 volumio volumio[1092]: info: ------------------------------ 393ms Mar 29 13:43:46 volumio volumio[1092]: error: updateQueue error: null Mar 29 13:43:46 volumio volumio[1092]: info: Mar 29 13:43:46 volumio volumio[1092]: ---------------------------- MPD announces system playlist update Mar 29 13:43:46 volumio volumio[1092]: info: Ignoring MPD Status Update Mar 29 13:43:46 volumio volumio[1092]: info: ------------------------------ 38ms Mar 29 13:43:46 volumio volumio[1092]: info: sendMpdCommand add "USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac" took 33 milliseconds Mar 29 13:43:46 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Mar 29 13:43:46 volumio volumio[1092]: info: Mar 29 13:43:46 volumio volumio[1092]: ---------------------------- MPD announces state update: player Mar 29 13:43:46 volumio volumio[1092]: info: ------------------------------ 60ms Mar 29 13:43:46 volumio volumio[1092]: info: sendMpdCommand play took 57 milliseconds Mar 29 13:43:46 volumio volumio[1092]: info: ControllerMpd::getState Mar 29 13:43:46 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:43:46 volumio volumio[1092]: info: Mar 29 13:43:46 volumio volumio[1092]: ---------------------------- MPD announces state update: player Mar 29 13:43:46 volumio volumio[1092]: info: ControllerMpd::getState Mar 29 13:43:46 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:43:46 volumio volumio[1092]: info: sendMpdCommand status took 5 milliseconds Mar 29 13:43:46 volumio volumio[1092]: verbose: ControllerMpd::parseState Mar 29 13:43:46 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:43:46 volumio volumio[1092]: info: sendMpdCommand status took 5 milliseconds Mar 29 13:43:46 volumio volumio[1092]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 13:43:46 volumio volumio[1092]: verbose: ControllerMpd::parseState Mar 29 13:43:46 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:43:46 volumio volumio[1092]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:43:46 volumio volumio[1092]: info: ControllerMpd::pushState Mar 29 13:43:46 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:43:46 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:46 volumio volumio[1092]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":242,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1447 Kbps","isStreaming":false,"title":"01 - 01 You Know What I Mean","artist":"Jeff Beck","album":"Blow By Blow","uri":"USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac","trackType":"flac"} Mar 29 13:43:46 volumio volumio[1092]: verbose: CURRENT POSITION 6121 Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::syncState stateService play Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:43:46 volumio volumio[1092]: info: ------------------------------ 22ms Mar 29 13:43:46 volumio volumio[1092]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 29 13:43:46 volumio volumio[1092]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:43:46 volumio volumio[1092]: info: ControllerMpd::pushState Mar 29 13:43:46 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:43:46 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:46 volumio volumio[1092]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":242,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1447 Kbps","isStreaming":false,"title":"01 - 01 You Know What I Mean","artist":"Jeff Beck","album":"Blow By Blow","uri":"USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac","trackType":"flac"} Mar 29 13:43:46 volumio volumio[1092]: verbose: CURRENT POSITION 6121 Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::syncState stateService play Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:43:46 volumio volumio[1092]: info: Received an update from plugin. extracting info from payload Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:46 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:46 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:43:46 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:46 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:43:46 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:43:46 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:43:46 volumio volumio[1092]: info: ------------------------------ 39ms Mar 29 13:43:47 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:47 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:47 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:47 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:47 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:47 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:47 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:47 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:47 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:47 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:47 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:47 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:47 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:47 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:50 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:50 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:50 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:50 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:50 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:50 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:50 volumio volumio[1092]: info: METAVOLUMIO: No new tracks added to queue, Starting fallback strategies Mar 29 13:43:50 volumio volumio[1092]: info: CoreCommandRouter::volumioGetQueue Mar 29 13:43:50 volumio volumio[1092]: info: CoreStateMachine::getQueue Mar 29 13:43:50 volumio volumio[1092]: info: CorePlayQueue::getQueue Mar 29 13:43:50 volumio volumio[1092]: info: METAVOLUMIO: Executing Random Queue Item Fallback Strategy with index: 2144 Mar 29 13:43:50 volumio volumio[1092]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 13:43:50 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 13:43:50 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 13:43:50 volumio volumio[1092]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:51 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:53 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:53 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:53 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:53 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:53 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:53 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:53 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:53 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:53 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:53 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:53 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:53 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:53 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:53 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:53 volumio volumio[1092]: info: METAVOLUMIO: No new tracks added to queue, Starting fallback strategies Mar 29 13:43:53 volumio volumio[1092]: info: METAVOLUMIO: Executing Random Fallback Strategy Mar 29 13:43:53 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 13:43:53 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , getRandomLocalTrack Mar 29 13:43:53 volumio volumio[1092]: info: listAlbums - loading Albums from cache Mar 29 13:43:54 volumio go-librespot[2071]: time="2026-03-29T13:43:54+02:00" level=trace msg="sent dealer ping" Mar 29 13:43:55 volumio go-librespot[2071]: time="2026-03-29T13:43:55+02:00" level=trace msg="received dealer pong" Mar 29 13:43:56 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:56 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:56 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:56 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:56 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:56 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:56 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:56 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:56 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:56 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:56 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:56 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:56 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:56 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:56 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:56 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:56 volumio volumio[1092]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 13:43:56 volumio volumio[1092]: info: CoreStateMachine::addQueueItems Mar 29 13:43:56 volumio volumio[1092]: info: CorePlayQueue::addQueueItems Mar 29 13:43:56 volumio volumio[1092]: info: Preload queue cleared Mar 29 13:43:56 volumio volumio[1092]: info: Adding Item to queue: music-library/USB/Audio-1B/Audio2/Verzamel Pop/Top100s/2005 Billboard Top 100/2005-048 Nelly Featuring Tim McGraw - Over And Over.mp3 Mar 29 13:43:56 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio2/Verzamel Pop/Top100s/2005 Billboard Top 100/2005-048 Nelly Featuring Tim McGraw - Over And Over.mp3 in service mpd Mar 29 13:43:56 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:56 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:56 volumio volumio[1092]: info: METAVOLUMIO: No new tracks added to queue, Starting fallback strategies Mar 29 13:43:56 volumio volumio[1092]: info: CoreCommandRouter::volumioGetQueue Mar 29 13:43:56 volumio volumio[1092]: info: CoreStateMachine::getQueue Mar 29 13:43:56 volumio volumio[1092]: info: CorePlayQueue::getQueue Mar 29 13:43:56 volumio volumio[1092]: info: METAVOLUMIO: Executing Random Queue Item Fallback Strategy with index: 841 Mar 29 13:43:56 volumio volumio[1092]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 13:43:56 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 13:43:56 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 13:43:56 volumio volumio[1092]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Mar 29 13:43:56 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=Nelly%20Featuring%20Tim%20McGraw/BillBoard%20Top%20USA%20Singles%202005/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio2%2FVerzamel%20Pop%2FTop100s%2F2005%20Billboard%20Top%20100%2F2005-048%20Nelly%20Featuring%20Tim%20McGraw%20-%20Over%20And%20Over.mp3&metadata=false Mar 29 13:43:56 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio2/Verzamel Pop/Top100s/2005 Billboard Top 100/2005-048 Nelly Featuring Tim McGraw - Over And Over.mp3 Mar 29 13:43:56 volumio volumio[1092]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:43:56 volumio volumio[1092]: info: CorePlayQueue::saveQueue Mar 29 13:43:56 volumio volumio[1092]: info: CoreStateMachine::updateTrackBlock Mar 29 13:43:56 volumio volumio[1092]: info: CorePlayQueue::getTrackBlock Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:43:59 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:59 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:59 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:59 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:59 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:59 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:43:59 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:00 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:00 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:00 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:00 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:00 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:00 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:00 volumio volumio[1092]: info: METAVOLUMIO: No new tracks added to queue, Starting fallback strategies Mar 29 13:44:00 volumio volumio[1092]: info: METAVOLUMIO: Executing Random Fallback Strategy Mar 29 13:44:00 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 13:44:00 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , getRandomLocalTrack Mar 29 13:44:00 volumio volumio[1092]: info: listAlbums - loading Albums from cache Mar 29 13:44:00 volumio volumio[1092]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 13:44:00 volumio volumio[1092]: info: CoreStateMachine::addQueueItems Mar 29 13:44:00 volumio volumio[1092]: info: CorePlayQueue::addQueueItems Mar 29 13:44:00 volumio volumio[1092]: info: Preload queue cleared Mar 29 13:44:00 volumio volumio[1092]: info: Adding Item to queue: music-library/USB/Audio-1B/Audio2/Burmester/Demo HQ Vol1 CD/Full Moon In Paris.wav Mar 29 13:44:00 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio2/Burmester/Demo HQ Vol1 CD/Full Moon In Paris.wav in service mpd Mar 29 13:44:00 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=Amber%20Rubarth/Burmester%20Selection%20Vol.%201%20%5BHiQualityCD%5D/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio2%2FBurmester%2FDemo%20HQ%20Vol1%20CD%2FFull%20Moon%20In%20Paris.wav&metadata=false Mar 29 13:44:00 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio2/Burmester/Demo HQ Vol1 CD/Full Moon In Paris.wav Mar 29 13:44:00 volumio volumio[1092]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:44:00 volumio volumio[1092]: info: CorePlayQueue::saveQueue Mar 29 13:44:00 volumio volumio[1092]: info: CoreStateMachine::updateTrackBlock Mar 29 13:44:00 volumio volumio[1092]: info: CorePlayQueue::getTrackBlock Mar 29 13:44:02 volumio go-librespot[2071]: time="2026-03-29T13:44:02+02:00" level=trace msg="received accesspoint ping" Mar 29 13:44:02 volumio go-librespot[2071]: time="2026-03-29T13:44:02+02:00" level=trace msg="received accesspoint pong ack" Mar 29 13:44:03 volumio volumio[1092]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 29 13:44:03 volumio ntpd[13505]: ntpd exiting on signal 15 (Terminated) Mar 29 13:44:03 volumio systemd[1]: Stopping Network Time Service... Mar 29 13:44:03 volumio ntpd[13505]: 193.148.248.213 local addr 192.168.136.72 -> Mar 29 13:44:03 volumio volumio[1092]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 29 13:44:03 volumio ntpd[13505]: 172.233.59.169 local addr 192.168.136.72 -> Mar 29 13:44:03 volumio ntpd[13505]: 174.138.107.7 local addr 192.168.136.72 -> Mar 29 13:44:03 volumio ntpd[13505]: 94.198.159.15 local addr 192.168.136.72 -> Mar 29 13:44:03 volumio ntpd[13505]: 178.239.19.59 local addr 192.168.136.72 -> Mar 29 13:44:03 volumio ntpd[13505]: 141.144.246.224 local addr 192.168.136.72 -> Mar 29 13:44:03 volumio ntpd[13505]: 94.198.159.11 local addr 192.168.136.72 -> Mar 29 13:44:03 volumio ntpd[13505]: 178.239.19.57 local addr 192.168.136.72 -> Mar 29 13:44:03 volumio ntpd[13505]: 103.204.195.5 local addr 192.168.136.72 -> Mar 29 13:44:03 volumio ntpd[13505]: 5.101.84.95 local addr 192.168.136.72 -> Mar 29 13:44:03 volumio ntpd[13505]: 103.149.168.180 local addr 192.168.136.72 -> Mar 29 13:44:03 volumio ntpd[13505]: 86.85.103.243 local addr 192.168.136.72 -> Mar 29 13:44:03 volumio volumio[1092]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 29 13:44:03 volumio systemd[1]: ntp.service: Succeeded. Mar 29 13:44:03 volumio systemd[1]: Stopped Network Time Service. Mar 29 13:44:03 volumio volumio[1092]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 29 13:44:03 volumio systemd[1]: Starting Network Time Service... Mar 29 13:44:03 volumio volumio[1092]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 29 13:44:03 volumio ntpd[14452]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 29 13:44:03 volumio ntpd[14452]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Mar 29 13:44:03 volumio systemd[1]: Started Network Time Service. Mar 29 13:44:03 volumio ntpd[14458]: proto: precision = 0.583 usec (-21) Mar 29 13:44:03 volumio ntpd[14458]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 29 13:44:03 volumio ntpd[14458]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 29 13:44:03 volumio ntpd[14458]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1188 days ago Mar 29 13:44:03 volumio ntpd[14458]: Listen and drop on 0 v6wildcard [::]:123 Mar 29 13:44:03 volumio ntpd[14458]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 29 13:44:03 volumio ntpd[14458]: Listen normally on 2 lo 127.0.0.1:123 Mar 29 13:44:03 volumio ntpd[14458]: Listen normally on 3 eth0 192.168.136.72:123 Mar 29 13:44:03 volumio ntpd[14458]: Listen normally on 4 wlan0 192.168.136.79:123 Mar 29 13:44:03 volumio ntpd[14458]: Listening on routing socket on fd #21 for interface updates Mar 29 13:44:03 volumio ntpd[14458]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Mar 29 13:44:03 volumio ntpd[14458]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Mar 29 13:44:03 volumio ntpd[14458]: ntpd exiting on signal 15 (Terminated) Mar 29 13:44:03 volumio systemd[1]: Stopping Network Time Service... Mar 29 13:44:03 volumio volumio[1092]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 29 13:44:03 volumio systemd[1]: ntp.service: Succeeded. Mar 29 13:44:03 volumio systemd[1]: Stopped Network Time Service. Mar 29 13:44:03 volumio volumio[1092]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 29 13:44:03 volumio systemd[1]: Starting Network Time Service... Mar 29 13:44:03 volumio volumio[1092]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 29 13:44:03 volumio ntpd[14495]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 29 13:44:03 volumio ntpd[14495]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Mar 29 13:44:03 volumio systemd[1]: Started Network Time Service. Mar 29 13:44:03 volumio ntpd[14501]: proto: precision = 0.583 usec (-21) Mar 29 13:44:03 volumio ntpd[14501]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 29 13:44:03 volumio ntpd[14501]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 29 13:44:03 volumio ntpd[14501]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1188 days ago Mar 29 13:44:03 volumio ntpd[14501]: Listen and drop on 0 v6wildcard [::]:123 Mar 29 13:44:03 volumio ntpd[14501]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 29 13:44:03 volumio ntpd[14501]: Listen normally on 2 lo 127.0.0.1:123 Mar 29 13:44:03 volumio ntpd[14501]: Listen normally on 3 eth0 192.168.136.72:123 Mar 29 13:44:03 volumio ntpd[14501]: Listen normally on 4 wlan0 192.168.136.79:123 Mar 29 13:44:03 volumio ntpd[14501]: Listening on routing socket on fd #21 for interface updates Mar 29 13:44:03 volumio ntpd[14501]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Mar 29 13:44:03 volumio ntpd[14501]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Mar 29 13:44:04 volumio ntpd[14501]: Soliciting pool server 172.233.59.169 Mar 29 13:44:05 volumio ntpd[14501]: Soliciting pool server 162.159.200.1 Mar 29 13:44:05 volumio ntpd[14501]: Soliciting pool server 217.217.243.78 Mar 29 13:44:06 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 13:44:06 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 13:44:06 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 13:44:06 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 13:44:06 volumio ntpd[14501]: Soliciting pool server 178.239.19.61 Mar 29 13:44:06 volumio ntpd[14501]: Soliciting pool server 178.238.99.189 Mar 29 13:44:06 volumio sudo[14520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 13:44:06 volumio sudo[14520]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 13:44:06 volumio sudo[14525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 13:44:06 volumio sudo[14520]: pam_unix(sudo:session): session closed for user root Mar 29 13:44:06 volumio sudo[14525]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 13:44:06 volumio sudo[14525]: pam_unix(sudo:session): session closed for user root Mar 29 13:44:06 volumio volumio[1092]: verbose: New Socket.io Connection to 192.168.136.72 from 192.168.136.69 UA: Mozilla/5.0 (Linux; Android 16; SM-G990B2 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 29 13:44:06 volumio sudo[14540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 13:44:06 volumio sudo[14540]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 13:44:06 volumio sudo[14540]: pam_unix(sudo:session): session closed for user root Mar 29 13:44:06 volumio sudo[14545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 13:44:06 volumio sudo[14545]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 13:44:06 volumio sudo[14545]: pam_unix(sudo:session): session closed for user root Mar 29 13:44:07 volumio volumio[1092]: verbose: New Socket.io Connection to 192.168.136.72 from 192.168.136.69 UA: Mozilla/5.0 (Linux; Android 16; SM-G990B2 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 29 13:44:07 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 13:44:07 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 13:44:07 volumio volumio[1092]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 13:44:07 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 13:44:07 volumio volumio[1092]: info: CoreCommandRouter::volumioGetState Mar 29 13:44:07 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:44:07 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 13:44:07 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 13:44:07 volumio volumio[1092]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 29 13:44:07 volumio volumio[1092]: info: Received Get System Info Mar 29 13:44:07 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 13:44:07 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 13:44:07 volumio volumio[1092]: info: Discovery: Getting this device information Mar 29 13:44:07 volumio volumio[1092]: info: CoreCommandRouter::volumioGetState Mar 29 13:44:07 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:44:07 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 13:44:07 volumio volumio[1092]: info: CoreCommandRouter::volumioGetState Mar 29 13:44:07 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:44:07 volumio volumio[1092]: info: Listing playlists Mar 29 13:44:07 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 13:44:07 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 13:44:07 volumio ntpd[14501]: Soliciting pool server 164.92.156.55 Mar 29 13:44:07 volumio ntpd[14501]: Soliciting pool server 45.9.2.181 Mar 29 13:44:07 volumio ntpd[14501]: Soliciting pool server 178.239.19.60 Mar 29 13:44:08 volumio ntpd[14501]: Soliciting pool server 85.121.54.197 Mar 29 13:44:08 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 13:44:08 volumio volumio[1092]: info: Received Get System Info Mar 29 13:44:08 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 13:44:08 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 13:44:08 volumio volumio[1092]: info: Discovery: Getting this device information Mar 29 13:44:08 volumio volumio[1092]: info: CoreCommandRouter::volumioGetState Mar 29 13:44:08 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:44:08 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 13:44:08 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 29 13:44:10 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 13:44:10 volumio volumio[1092]: info: Received Get System Info Mar 29 13:44:10 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 13:44:10 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 13:44:10 volumio volumio[1092]: info: Discovery: Getting this device information Mar 29 13:44:10 volumio volumio[1092]: info: CoreCommandRouter::volumioGetState Mar 29 13:44:10 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:44:10 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 13:44:16 volumio volumio[1092]: info: CoreCommandRouter::volumioNext Mar 29 13:44:16 volumio volumio[1092]: info: CoreStateMachine::next Mar 29 13:44:16 volumio volumio[1092]: info: CoreStateMachine::stop Mar 29 13:44:16 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:44:16 volumio volumio[1092]: info: CoreStateMachine::stPlaybackTimer Mar 29 13:44:16 volumio volumio[1092]: info: CoreStateMachine::updateTrackBlock Mar 29 13:44:16 volumio volumio[1092]: info: CorePlayQueue::getTrackBlock Mar 29 13:44:16 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:44:16 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:44:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:44:16 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:44:16 volumio volumio[1092]: info: CoreStateMachine::serviceStop Mar 29 13:44:16 volumio volumio[1092]: info: CorePlayQueue::getTrack 6121 Mar 29 13:44:16 volumio volumio[1092]: info: CoreCommandRouter::serviceStop Mar 29 13:44:16 volumio volumio[1092]: info: ControllerMpd::stop Mar 29 13:44:16 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:44:16 volumio volumio[1092]: info: Mar 29 13:44:16 volumio volumio[1092]: ---------------------------- MPD announces state update: player Mar 29 13:44:16 volumio volumio[1092]: info: sendMpdCommand stop took 19 milliseconds Mar 29 13:44:16 volumio volumio[1092]: info: ControllerMpd::getState Mar 29 13:44:16 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:44:16 volumio volumio[1092]: info: CoreStateMachine::play index undefined Mar 29 13:44:16 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:44:16 volumio volumio[1092]: info: CorePlayQueue::getTrack 6122 Mar 29 13:44:16 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:44:16 volumio volumio[1092]: info: CorePlayQueue::getTrack 6122 Mar 29 13:44:16 volumio volumio[1092]: verbose: ControllerMpd::clearAddPlayTracks USB/Audio-1B/Audio2/Verzamel Pop/Top100s/2005 Billboard Top 100/2005-048 Nelly Featuring Tim McGraw - Over And Over.mp3 Mar 29 13:44:16 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:44:16 volumio volumio[1092]: info: CoreStateMachine::updateTrackBlock Mar 29 13:44:16 volumio volumio[1092]: info: CorePlayQueue::getTrackBlock Mar 29 13:44:16 volumio volumio[1092]: info: sendMpdCommand status took 4 milliseconds Mar 29 13:44:16 volumio volumio[1092]: info: sendMpdCommand stop took 2 milliseconds Mar 29 13:44:16 volumio volumio[1092]: verbose: ControllerMpd::parseState Mar 29 13:44:16 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:44:16 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 13:44:16 volumio volumio[1092]: info: Mar 29 13:44:16 volumio volumio[1092]: ---------------------------- MPD announces system playlist update Mar 29 13:44:16 volumio volumio[1092]: info: Ignoring MPD Status Update Mar 29 13:44:16 volumio volumio[1092]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 29 13:44:16 volumio volumio[1092]: info: sendMpdCommand clear took 2 milliseconds Mar 29 13:44:16 volumio volumio[1092]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:44:16 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand add "USB/Audio-1B/Audio2/Verzamel Pop/Top100s/2005 Billboard Top 100/2005-048 Nelly Featuring Tim McGraw - Over And Over.mp3" Mar 29 13:44:16 volumio volumio[1092]: info: ControllerMpd::pushState Mar 29 13:44:16 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:44:16 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:44:16 volumio volumio[1092]: info: CorePlayQueue::getTrack 6122 Mar 29 13:44:16 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:44:16 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:44:16 volumio volumio[1092]: info: CorePlayQueue::getTrack 6122 Mar 29 13:44:16 volumio volumio[1092]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - 01 You Know What I Mean","artist":"Jeff Beck","album":"Blow By Blow","uri":"USB/Audio-1B/Audio1/B/Beck Jeff/Blow By Blow LP/01 - 01 You Know What I Mean.flac","trackType":"flac"} Mar 29 13:44:16 volumio volumio[1092]: verbose: CURRENT POSITION 6122 Mar 29 13:44:16 volumio volumio[1092]: info: CoreStateMachine::syncState stateService stop Mar 29 13:44:16 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:44:16 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:44:16 volumio volumio[1092]: info: CorePlayQueue::getTrack 6122 Mar 29 13:44:16 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:44:16 volumio volumio[1092]: info: No code Mar 29 13:44:16 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:44:16 volumio volumio[1092]: info: CorePlayQueue::getTrack 6122 Mar 29 13:44:16 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:44:16 volumio volumio[1092]: info: ------------------------------ 30ms Mar 29 13:44:16 volumio volumio[1092]: error: updateQueue error: null Mar 29 13:44:16 volumio volumio[1092]: info: Mar 29 13:44:16 volumio volumio[1092]: ---------------------------- MPD announces system playlist update Mar 29 13:44:16 volumio volumio[1092]: info: Ignoring MPD Status Update Mar 29 13:44:16 volumio volumio[1092]: info: ------------------------------ 23ms Mar 29 13:44:16 volumio volumio[1092]: info: sendMpdCommand add "USB/Audio-1B/Audio2/Verzamel Pop/Top100s/2005 Billboard Top 100/2005-048 Nelly Featuring Tim McGraw - Over And Over.mp3" took 20 milliseconds Mar 29 13:44:16 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Mar 29 13:44:16 volumio volumio[1092]: info: Mar 29 13:44:16 volumio volumio[1092]: ---------------------------- MPD announces state update: player Mar 29 13:44:16 volumio volumio[1092]: info: ------------------------------ 26ms Mar 29 13:44:16 volumio volumio[1092]: info: sendMpdCommand play took 23 milliseconds Mar 29 13:44:16 volumio volumio[1092]: info: ControllerMpd::getState Mar 29 13:44:16 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:44:16 volumio volumio[1092]: info: sendMpdCommand status took 2 milliseconds Mar 29 13:44:16 volumio volumio[1092]: verbose: ControllerMpd::parseState Mar 29 13:44:16 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:44:16 volumio volumio[1092]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 29 13:44:16 volumio volumio[1092]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:44:16 volumio volumio[1092]: info: ControllerMpd::pushState Mar 29 13:44:16 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:44:16 volumio volumio[1092]: info: CorePlayQueue::getTrack 6122 Mar 29 13:44:16 volumio volumio[1092]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Over And Over","artist":"Nelly Featuring Tim McGraw","album":"BillBoard Top USA Singles 2005","uri":"USB/Audio-1B/Audio2/Verzamel Pop/Top100s/2005 Billboard Top 100/2005-048 Nelly Featuring Tim McGraw - Over And Over.mp3","trackType":"mp3"} Mar 29 13:44:16 volumio volumio[1092]: verbose: CURRENT POSITION 6122 Mar 29 13:44:16 volumio volumio[1092]: info: CoreStateMachine::syncState stateService play Mar 29 13:44:16 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:44:16 volumio volumio[1092]: info: ------------------------------ 14ms Mar 29 13:44:17 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 13:44:21 volumio volumio[1092]: info: CoreCommandRouter::volumioNext Mar 29 13:44:21 volumio volumio[1092]: info: CoreStateMachine::next Mar 29 13:44:21 volumio volumio[1092]: info: CoreStateMachine::stop Mar 29 13:44:21 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:44:21 volumio volumio[1092]: info: CoreStateMachine::stPlaybackTimer Mar 29 13:44:21 volumio volumio[1092]: info: CoreStateMachine::updateTrackBlock Mar 29 13:44:21 volumio volumio[1092]: info: CorePlayQueue::getTrackBlock Mar 29 13:44:21 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:44:21 volumio volumio[1092]: info: CorePlayQueue::getTrack 6122 Mar 29 13:44:21 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:44:21 volumio volumio[1092]: info: CoreStateMachine::serviceStop Mar 29 13:44:21 volumio volumio[1092]: info: CorePlayQueue::getTrack 6122 Mar 29 13:44:21 volumio volumio[1092]: info: CoreCommandRouter::serviceStop Mar 29 13:44:21 volumio volumio[1092]: info: ControllerMpd::stop Mar 29 13:44:21 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:44:21 volumio volumio[1092]: info: Mar 29 13:44:21 volumio volumio[1092]: ---------------------------- MPD announces state update: player Mar 29 13:44:21 volumio volumio[1092]: info: sendMpdCommand stop took 8 milliseconds Mar 29 13:44:21 volumio volumio[1092]: info: ControllerMpd::getState Mar 29 13:44:21 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:44:21 volumio volumio[1092]: info: CoreStateMachine::play index undefined Mar 29 13:44:21 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:44:21 volumio volumio[1092]: info: CorePlayQueue::getTrack 6123 Mar 29 13:44:21 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:44:21 volumio volumio[1092]: info: CorePlayQueue::getTrack 6123 Mar 29 13:44:21 volumio volumio[1092]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 13:44:21 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 13:44:21 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 13:44:21 volumio volumio[1092]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Mar 29 13:44:21 volumio volumio[1092]: verbose: ControllerMpd::clearAddPlayTracks USB/Audio-1B/Audio2/Burmester/Demo HQ Vol1 CD/Full Moon In Paris.wav Mar 29 13:44:21 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:44:21 volumio volumio[1092]: info: CoreStateMachine::updateTrackBlock Mar 29 13:44:21 volumio volumio[1092]: info: CorePlayQueue::getTrackBlock Mar 29 13:44:21 volumio volumio[1092]: info: sendMpdCommand status took 24 milliseconds Mar 29 13:44:21 volumio volumio[1092]: info: sendMpdCommand stop took 11 milliseconds Mar 29 13:44:21 volumio volumio[1092]: verbose: ControllerMpd::parseState Mar 29 13:44:21 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:44:21 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 13:44:21 volumio volumio[1092]: info: Mar 29 13:44:21 volumio volumio[1092]: ---------------------------- MPD announces system playlist update Mar 29 13:44:21 volumio volumio[1092]: info: Ignoring MPD Status Update Mar 29 13:44:21 volumio volumio[1092]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 13:44:21 volumio volumio[1092]: info: sendMpdCommand clear took 2 milliseconds Mar 29 13:44:21 volumio volumio[1092]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:44:21 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand add "USB/Audio-1B/Audio2/Burmester/Demo HQ Vol1 CD/Full Moon In Paris.wav" Mar 29 13:44:21 volumio volumio[1092]: info: ControllerMpd::pushState Mar 29 13:44:21 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:44:21 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:44:21 volumio volumio[1092]: info: CorePlayQueue::getTrack 6123 Mar 29 13:44:21 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:44:21 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:44:21 volumio volumio[1092]: info: CorePlayQueue::getTrack 6123 Mar 29 13:44:21 volumio volumio[1092]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Over And Over","artist":"Nelly Featuring Tim McGraw","album":"BillBoard Top USA Singles 2005","uri":"USB/Audio-1B/Audio2/Verzamel Pop/Top100s/2005 Billboard Top 100/2005-048 Nelly Featuring Tim McGraw - Over And Over.mp3","trackType":"mp3"} Mar 29 13:44:21 volumio volumio[1092]: verbose: CURRENT POSITION 6123 Mar 29 13:44:21 volumio volumio[1092]: info: CoreStateMachine::syncState stateService stop Mar 29 13:44:21 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:44:21 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:44:21 volumio volumio[1092]: info: CorePlayQueue::getTrack 6123 Mar 29 13:44:21 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:44:21 volumio volumio[1092]: info: No code Mar 29 13:44:21 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:44:21 volumio volumio[1092]: info: CorePlayQueue::getTrack 6123 Mar 29 13:44:21 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:44:21 volumio volumio[1092]: info: ------------------------------ 44ms Mar 29 13:44:21 volumio volumio[1092]: error: updateQueue error: null Mar 29 13:44:21 volumio volumio[1092]: info: Mar 29 13:44:21 volumio volumio[1092]: ---------------------------- MPD announces system playlist update Mar 29 13:44:21 volumio volumio[1092]: info: Ignoring MPD Status Update Mar 29 13:44:21 volumio volumio[1092]: info: ------------------------------ 19ms Mar 29 13:44:21 volumio volumio[1092]: info: sendMpdCommand add "USB/Audio-1B/Audio2/Burmester/Demo HQ Vol1 CD/Full Moon In Paris.wav" took 18 milliseconds Mar 29 13:44:21 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Mar 29 13:44:21 volumio volumio[1092]: info: Mar 29 13:44:21 volumio volumio[1092]: ---------------------------- MPD announces state update: player Mar 29 13:44:21 volumio volumio[1092]: info: ------------------------------ 15ms Mar 29 13:44:21 volumio volumio[1092]: info: sendMpdCommand play took 14 milliseconds Mar 29 13:44:21 volumio volumio[1092]: info: ControllerMpd::getState Mar 29 13:44:21 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:44:21 volumio volumio[1092]: info: sendMpdCommand status took 3 milliseconds Mar 29 13:44:21 volumio volumio[1092]: verbose: ControllerMpd::parseState Mar 29 13:44:21 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:44:21 volumio volumio[1092]: info: sendMpdCommand playlistinfo took 237 milliseconds Mar 29 13:44:21 volumio volumio[1092]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:44:21 volumio volumio[1092]: info: ControllerMpd::pushState Mar 29 13:44:21 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:44:21 volumio volumio[1092]: info: CorePlayQueue::getTrack 6123 Mar 29 13:44:21 volumio volumio[1092]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":204,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Full Moon In Paris","artist":"Amber Rubarth","album":"Burmester Selection Vol. 1 [HiQualityCD]","uri":"USB/Audio-1B/Audio2/Burmester/Demo HQ Vol1 CD/Full Moon In Paris.wav","trackType":"wav"} Mar 29 13:44:21 volumio volumio[1092]: verbose: CURRENT POSITION 6123 Mar 29 13:44:21 volumio volumio[1092]: info: CoreStateMachine::syncState stateService play Mar 29 13:44:21 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:44:21 volumio volumio[1092]: info: ------------------------------ 244ms Mar 29 13:44:24 volumio go-librespot[2071]: time="2026-03-29T13:44:24+02:00" level=trace msg="sent dealer ping" Mar 29 13:44:25 volumio go-librespot[2071]: time="2026-03-29T13:44:25+02:00" level=trace msg="received dealer pong" Mar 29 13:44:25 volumio volumio[1092]: info: CoreCommandRouter::volumioNext Mar 29 13:44:25 volumio volumio[1092]: info: CoreStateMachine::next Mar 29 13:44:25 volumio volumio[1092]: info: CoreStateMachine::stop Mar 29 13:44:25 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:44:25 volumio volumio[1092]: info: CoreStateMachine::stPlaybackTimer Mar 29 13:44:25 volumio volumio[1092]: info: CoreStateMachine::updateTrackBlock Mar 29 13:44:25 volumio volumio[1092]: info: CorePlayQueue::getTrackBlock Mar 29 13:44:25 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:44:25 volumio volumio[1092]: info: CorePlayQueue::getTrack 6123 Mar 29 13:44:25 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:44:25 volumio volumio[1092]: info: CoreStateMachine::serviceStop Mar 29 13:44:25 volumio volumio[1092]: info: CorePlayQueue::getTrack 6123 Mar 29 13:44:25 volumio volumio[1092]: info: CoreCommandRouter::serviceStop Mar 29 13:44:25 volumio volumio[1092]: info: ControllerMpd::stop Mar 29 13:44:25 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:44:25 volumio volumio[1092]: info: Mar 29 13:44:25 volumio volumio[1092]: ---------------------------- MPD announces state update: player Mar 29 13:44:25 volumio volumio[1092]: info: sendMpdCommand stop took 9 milliseconds Mar 29 13:44:25 volumio volumio[1092]: info: ControllerMpd::getState Mar 29 13:44:25 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:44:25 volumio volumio[1092]: info: CoreStateMachine::play index undefined Mar 29 13:44:25 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:44:25 volumio volumio[1092]: info: CorePlayQueue::getTrack 6124 Mar 29 13:44:25 volumio volumio[1092]: info: sendMpdCommand status took 2 milliseconds Mar 29 13:44:25 volumio volumio[1092]: verbose: ControllerMpd::parseState Mar 29 13:44:25 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:44:25 volumio volumio[1092]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:44:25 volumio volumio[1092]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:44:25 volumio volumio[1092]: info: ControllerMpd::pushState Mar 29 13:44:25 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:44:25 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:44:25 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Mar 29 13:44:25 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:44:25 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:44:25 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Mar 29 13:44:25 volumio volumio[1092]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Full Moon In Paris","artist":"Amber Rubarth","album":"Burmester Selection Vol. 1 [HiQualityCD]","uri":"USB/Audio-1B/Audio2/Burmester/Demo HQ Vol1 CD/Full Moon In Paris.wav","trackType":"wav"} Mar 29 13:44:25 volumio volumio[1092]: verbose: CURRENT POSITION 0 Mar 29 13:44:25 volumio volumio[1092]: info: CoreStateMachine::syncState stateService stop Mar 29 13:44:25 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:44:25 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:44:25 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Mar 29 13:44:25 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:44:25 volumio volumio[1092]: info: No code Mar 29 13:44:25 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:44:25 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Mar 29 13:44:25 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:44:25 volumio volumio[1092]: info: ------------------------------ 17ms Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:26 volumio volumio[1092]: info: CoreCommandRouter::volumioNext Mar 29 13:44:26 volumio volumio[1092]: info: CoreStateMachine::next Mar 29 13:44:26 volumio volumio[1092]: info: CoreStateMachine::stop Mar 29 13:44:26 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:44:26 volumio volumio[1092]: info: CoreStateMachine::play index undefined Mar 29 13:44:26 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:44:26 volumio volumio[1092]: info: CorePlayQueue::getTrack 1 Mar 29 13:44:26 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:44:26 volumio volumio[1092]: info: CorePlayQueue::getTrack 1 Mar 29 13:44:26 volumio volumio[1092]: info: [1774784666809] ControllerSpotify::clearAddPlayTrack Mar 29 13:44:26 volumio volumio[1092]: info: Sending Spotify command with payload to local API: /player/play Mar 29 13:44:26 volumio volumio[1092]: info: CoreStateMachine::updateTrackBlock Mar 29 13:44:26 volumio volumio[1092]: info: CorePlayQueue::getTrackBlock Mar 29 13:44:26 volumio go-librespot[2071]: time="2026-03-29T13:44:26+02:00" level=debug msg="renewing login5 access token" Mar 29 13:44:27 volumio go-librespot[2071]: time="2026-03-29T13:44:27+02:00" level=info msg="authenticated Login5" username="qx*********************0w" Mar 29 13:44:27 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 13:44:27 volumio volumio[1092]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 13:44:27 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 13:44:27 volumio volumio[1092]: info: Received Get System Version Mar 29 13:44:27 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 13:44:27 volumio volumio[1092]: info: Received Get System Info Mar 29 13:44:27 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 13:44:27 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 13:44:27 volumio volumio[1092]: info: Discovery: Getting this device information Mar 29 13:44:27 volumio volumio[1092]: info: CoreCommandRouter::volumioGetState Mar 29 13:44:27 volumio volumio[1092]: info: CorePlayQueue::getTrack 1 Mar 29 13:44:27 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 13:44:27 volumio go-librespot[2071]: time="2026-03-29T13:44:27+02:00" level=debug msg="resolved context of track" uri="spotify:track:3ZpQiJ78LKINrW9SQTgbXd" Mar 29 13:44:27 volumio go-librespot[2071]: time="2026-03-29T13:44:27+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:3ZpQiJ78LKINrW9SQTgbXd" Mar 29 13:44:27 volumio go-librespot[2071]: time="2026-03-29T13:44:27+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:3ZpQiJ78LKINrW9SQTgbXd" Mar 29 13:44:27 volumio go-librespot[2071]: time="2026-03-29T13:44:27+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 29 13:44:27 volumio go-librespot[2071]: time="2026-03-29T13:44:27+02:00" level=trace msg="emitting websocket event: will_play" Mar 29 13:44:27 volumio volumio[1092]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:3ZpQiJ78LKINrW9SQTgbXd","uri":"spotify:track:3ZpQiJ78LKINrW9SQTgbXd","play_origin":"go-librespot"}} Mar 29 13:44:27 volumio go-librespot[2071]: time="2026-03-29T13:44:27+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Mar 29 13:44:27 volumio go-librespot[2071]: time="2026-03-29T13:44:27+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 29 13:44:27 volumio go-librespot[2071]: time="2026-03-29T13:44:27+02:00" level=debug msg="selected format OGG_VORBIS_320 (d7b2d0faa70a095f0a3879779a3407fb26558935)" uri="spotify:track:3ZpQiJ78LKINrW9SQTgbXd" Mar 29 13:44:27 volumio go-librespot[2071]: time="2026-03-29T13:44:27+02:00" level=debug msg="requested aes key for file d7b2d0faa70a095f0a3879779a3407fb26558935, gid: 3ZpQiJ78LKINrW9SQTgbXd" Mar 29 13:44:28 volumio go-librespot[2071]: time="2026-03-29T13:44:28+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Mar 29 13:44:28 volumio go-librespot[2071]: time="2026-03-29T13:44:28+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1700" Mar 29 13:44:28 volumio go-librespot[2071]: time="2026-03-29T13:44:28+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 29 13:44:28 volumio go-librespot[2071]: time="2026-03-29T13:44:28+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:3ZpQiJ78LKINrW9SQTgbXd" Mar 29 13:44:28 volumio volumio[1092]: info: CoreCommandRouter::volumioNext Mar 29 13:44:28 volumio volumio[1092]: info: CoreStateMachine::next Mar 29 13:44:28 volumio volumio[1092]: info: CoreStateMachine::stop Mar 29 13:44:28 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:44:28 volumio volumio[1092]: info: CoreStateMachine::play index undefined Mar 29 13:44:28 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:44:28 volumio volumio[1092]: info: CorePlayQueue::getTrack 2 Mar 29 13:44:28 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:44:28 volumio volumio[1092]: info: CorePlayQueue::getTrack 2 Mar 29 13:44:28 volumio volumio[1092]: info: [1774784668409] ControllerSpotify::clearAddPlayTrack Mar 29 13:44:28 volumio volumio[1092]: info: Sending Spotify command with payload to local API: /player/play Mar 29 13:44:28 volumio volumio[1092]: info: CoreStateMachine::updateTrackBlock Mar 29 13:44:28 volumio volumio[1092]: info: CorePlayQueue::getTrackBlock Mar 29 13:44:28 volumio go-librespot[2071]: time="2026-03-29T13:44:28+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1303" Mar 29 13:44:29 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:29 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:29 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:29 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:29 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:29 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:29 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:29 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:29 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:29 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:29 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:29 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:29 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:29 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:29 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:29 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:29 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:29 volumio volumio[1092]: info: METAVOLUMIO: No new tracks added to queue, Starting fallback strategies Mar 29 13:44:29 volumio volumio[1092]: info: CoreCommandRouter::volumioGetQueue Mar 29 13:44:29 volumio volumio[1092]: info: CoreStateMachine::getQueue Mar 29 13:44:29 volumio volumio[1092]: info: CorePlayQueue::getQueue Mar 29 13:44:29 volumio volumio[1092]: info: METAVOLUMIO: Executing Random Queue Item Fallback Strategy with index: 3290 Mar 29 13:44:29 volumio volumio[1092]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 13:44:29 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 13:44:29 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 13:44:29 volumio volumio[1092]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Mar 29 13:44:30 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:30 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:30 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:30 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:30 volumio volumio[1092]: info: All cached search sources collected Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:30 volumio go-librespot[2071]: time="2026-03-29T13:44:30+02:00" level=debug msg="fetched first chunk of 23, total size is 11879636 bytes" uri="spotify:track:3ZpQiJ78LKINrW9SQTgbXd" Mar 29 13:44:30 volumio go-librespot[2071]: time="2026-03-29T13:44:30+02:00" level=warning msg="reducing track normalisation factor to prevent clipping, please add negative pregain to avoid" Mar 29 13:44:30 volumio go-librespot[2071]: time="2026-03-29T13:44:30+02:00" level=debug msg="created new output device" Mar 29 13:44:30 volumio go-librespot[2071]: time="2026-03-29T13:44:30+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames, PCM format = FLOAT_LE" Mar 29 13:44:30 volumio go-librespot[2071]: time="2026-03-29T13:44:30+02:00" level=info msg="loaded track \"All I Wanna Do\" (paused: false, position: 0ms, duration: 272106ms, prefetched: false)" uri="spotify:track:3ZpQiJ78LKINrW9SQTgbXd" Mar 29 13:44:30 volumio go-librespot[2071]: time="2026-03-29T13:44:30+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 29 13:44:30 volumio go-librespot[2071]: time="2026-03-29T13:44:30+02:00" level=trace msg="scheduling prefetch in 242s" Mar 29 13:44:30 volumio go-librespot[2071]: time="2026-03-29T13:44:30+02:00" level=trace msg="emitting websocket event: metadata" Mar 29 13:44:30 volumio volumio[1092]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3ZpQiJ78LKINrW9SQTgbXd","name":"All I Wanna Do","artist_names":["Sheryl Crow"],"album_name":"Tuesday Night Music Club","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02b478a8ce3aac61a3675aac2a","position":0,"duration":272106,"release_date":"year:1993 month:8 day:3","track_number":9,"disc_number":1}} Mar 29 13:44:30 volumio go-librespot[2071]: time="2026-03-29T13:44:30+02:00" level=debug msg="resolved context of track" uri="spotify:track:754kgU5rWscRTfvlsuEwFp" Mar 29 13:44:30 volumio go-librespot[2071]: time="2026-03-29T13:44:30+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:754kgU5rWscRTfvlsuEwFp" Mar 29 13:44:30 volumio go-librespot[2071]: time="2026-03-29T13:44:30+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:754kgU5rWscRTfvlsuEwFp" Mar 29 13:44:31 volumio go-librespot[2071]: time="2026-03-29T13:44:31+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 29 13:44:31 volumio go-librespot[2071]: time="2026-03-29T13:44:31+02:00" level=trace msg="emitting websocket event: will_play" Mar 29 13:44:31 volumio volumio[1092]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:754kgU5rWscRTfvlsuEwFp","uri":"spotify:track:754kgU5rWscRTfvlsuEwFp","play_origin":"go-librespot"}} Mar 29 13:44:31 volumio go-librespot[2071]: time="2026-03-29T13:44:31+02:00" level=debug msg="selected format OGG_VORBIS_320 (9a0a42c46a0a7bb59c9fe2ea8a5423bc02fae826)" uri="spotify:track:754kgU5rWscRTfvlsuEwFp" Mar 29 13:44:31 volumio go-librespot[2071]: time="2026-03-29T13:44:31+02:00" level=debug msg="requested aes key for file 9a0a42c46a0a7bb59c9fe2ea8a5423bc02fae826, gid: 754kgU5rWscRTfvlsuEwFp" Mar 29 13:44:31 volumio go-librespot[2071]: time="2026-03-29T13:44:31+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:754kgU5rWscRTfvlsuEwFp" Mar 29 13:44:31 volumio volumio[1092]: info: CoreCommandRouter::volumioGetQueue Mar 29 13:44:31 volumio volumio[1092]: info: CoreStateMachine::getQueue Mar 29 13:44:31 volumio volumio[1092]: info: CorePlayQueue::getQueue Mar 29 13:44:32 volumio go-librespot[2071]: time="2026-03-29T13:44:32+02:00" level=debug msg="fetched chunk 1/22, size: 524288" uri="spotify:track:3ZpQiJ78LKINrW9SQTgbXd" Mar 29 13:44:32 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 13:44:32 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 13:44:32 volumio volumio[1092]: info: Discovery: Getting this device information Mar 29 13:44:32 volumio volumio[1092]: info: CoreCommandRouter::volumioGetState Mar 29 13:44:32 volumio volumio[1092]: info: CorePlayQueue::getTrack 2 Mar 29 13:44:32 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 13:44:32 volumio volumio[1092]: verbose: New Socket.io Connection to 192.168.136.79:3000 from 192.168.136.69 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Mar 29 13:44:32 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 13:44:32 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 13:44:32 volumio go-librespot[2071]: time="2026-03-29T13:44:32+02:00" level=debug msg="fetched chunk 3/22, size: 524288" uri="spotify:track:3ZpQiJ78LKINrW9SQTgbXd" Mar 29 13:44:33 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:33 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:33 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:33 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:33 volumio volumio[1092]: info: METAVOLUMIO: No new tracks added to queue, Starting fallback strategies Mar 29 13:44:33 volumio volumio[1092]: info: METAVOLUMIO: Executing Random Fallback Strategy Mar 29 13:44:33 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 13:44:33 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , getRandomLocalTrack Mar 29 13:44:33 volumio volumio[1092]: info: listAlbums - loading Albums from cache Mar 29 13:44:33 volumio volumio[1092]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 13:44:33 volumio volumio[1092]: info: CoreStateMachine::addQueueItems Mar 29 13:44:33 volumio volumio[1092]: info: CorePlayQueue::addQueueItems Mar 29 13:44:33 volumio volumio[1092]: info: Preload queue cleared Mar 29 13:44:33 volumio volumio[1092]: info: Adding Item to queue: music-library/USB/Audio-1B/Audio2/Verzamel Pop/Greatest Hits Of The 70s/CD1/02 Ready Steady.flac Mar 29 13:44:33 volumio volumio[1092]: info: Exploding uri music-library/USB/Audio-1B/Audio2/Verzamel Pop/Greatest Hits Of The 70s/CD1/02 Ready Steady.flac in service mpd Mar 29 13:44:33 volumio volumio[1092]: info: ALBUMART /albumart?cacheid=538&web=Generation%20X/Greatest%20Hits%20of%20the%2070%E2%80%99s/extralarge&path=%2Fmnt%2FUSB%2FAudio-1B%2FAudio2%2FVerzamel%20Pop%2FGreatest%20Hits%20Of%20The%2070s%2FCD1%2F02%20Ready%20Steady.flac&metadata=false Mar 29 13:44:33 volumio volumio[1092]: info: URI /mnt/USB/Audio-1B/Audio2/Verzamel Pop/Greatest Hits Of The 70s/CD1/02 Ready Steady.flac Mar 29 13:44:33 volumio volumio[1092]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:44:33 volumio volumio[1092]: info: CorePlayQueue::saveQueue Mar 29 13:44:33 volumio volumio[1092]: info: CoreStateMachine::updateTrackBlock Mar 29 13:44:33 volumio volumio[1092]: info: CorePlayQueue::getTrackBlock Mar 29 13:44:33 volumio go-librespot[2071]: time="2026-03-29T13:44:33+02:00" level=debug msg="fetched first chunk of 17, total size is 8850509 bytes" uri="spotify:track:754kgU5rWscRTfvlsuEwFp" Mar 29 13:44:33 volumio go-librespot[2071]: time="2026-03-29T13:44:33+02:00" level=info msg="loaded track \"Kiss Me\" (paused: false, position: 0ms, duration: 208680ms, prefetched: false)" uri="spotify:track:754kgU5rWscRTfvlsuEwFp" Mar 29 13:44:33 volumio go-librespot[2071]: time="2026-03-29T13:44:33+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 29 13:44:33 volumio go-librespot[2071]: time="2026-03-29T13:44:33+02:00" level=trace msg="scheduling prefetch in 178s" Mar 29 13:44:33 volumio go-librespot[2071]: time="2026-03-29T13:44:33+02:00" level=trace msg="emitting websocket event: metadata" Mar 29 13:44:33 volumio go-librespot[2071]: time="2026-03-29T13:44:33+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 29 13:44:33 volumio go-librespot[2071]: time="2026-03-29T13:44:33+02:00" level=trace msg="emitting websocket event: playing" Mar 29 13:44:33 volumio volumio[1092]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:754kgU5rWscRTfvlsuEwFp","name":"Kiss Me","artist_names":["Sixpence None The Richer"],"album_name":"Sixpence None The Richer","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02cfc5eea6cfd77e89ed3ac5a4","position":0,"duration":208680,"release_date":"year:1997 month:11 day:22","track_number":4,"disc_number":1}} Mar 29 13:44:33 volumio volumio[1092]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:754kgU5rWscRTfvlsuEwFp","uri":"spotify:track:754kgU5rWscRTfvlsuEwFp","resume":false,"play_origin":"go-librespot"}} Mar 29 13:44:33 volumio volumio[1092]: SPOTIFY: PUSH STATE SPOTIFY Mar 29 13:44:33 volumio volumio[1092]: SPOTIFY: {"status":"play","service":"spop","title":"Kiss Me","artist":"Sixpence None The Richer","album":"Sixpence None The Richer","albumart":"https://i.scdn.co/image/ab67616d00001e02cfc5eea6cfd77e89ed3ac5a4","uri":"spotify:track:754kgU5rWscRTfvlsuEwFp","trackType":"spotify","seek":0,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 29 13:44:33 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:44:33 volumio volumio[1092]: info: CorePlayQueue::getTrack 2 Mar 29 13:44:33 volumio volumio[1092]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Kiss Me","artist":"Sixpence None The Richer","album":"Sixpence None The Richer","albumart":"https://i.scdn.co/image/ab67616d00001e02cfc5eea6cfd77e89ed3ac5a4","uri":"spotify:track:754kgU5rWscRTfvlsuEwFp","trackType":"spotify","seek":0,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 29 13:44:33 volumio volumio[1092]: verbose: CURRENT POSITION 2 Mar 29 13:44:33 volumio volumio[1092]: info: CoreStateMachine::syncState stateService play Mar 29 13:44:33 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:44:34 volumio go-librespot[2071]: time="2026-03-29T13:44:34+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 29 13:44:34 volumio go-librespot[2071]: time="2026-03-29T13:44:34+02:00" level=trace msg="emitting websocket event: playing" Mar 29 13:44:34 volumio volumio[1092]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:754kgU5rWscRTfvlsuEwFp","uri":"spotify:track:754kgU5rWscRTfvlsuEwFp","resume":false,"play_origin":"go-librespot"}} Mar 29 13:44:34 volumio volumio[1092]: SPOTIFY: PUSH STATE SPOTIFY Mar 29 13:44:34 volumio volumio[1092]: SPOTIFY: {"status":"play","service":"spop","title":"Kiss Me","artist":"Sixpence None The Richer","album":"Sixpence None The Richer","albumart":"https://i.scdn.co/image/ab67616d00001e02cfc5eea6cfd77e89ed3ac5a4","uri":"spotify:track:754kgU5rWscRTfvlsuEwFp","trackType":"spotify","seek":0,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 29 13:44:34 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:44:34 volumio volumio[1092]: info: CorePlayQueue::getTrack 2 Mar 29 13:44:34 volumio volumio[1092]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Kiss Me","artist":"Sixpence None The Richer","album":"Sixpence None The Richer","albumart":"https://i.scdn.co/image/ab67616d00001e02cfc5eea6cfd77e89ed3ac5a4","uri":"spotify:track:754kgU5rWscRTfvlsuEwFp","trackType":"spotify","seek":0,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 29 13:44:34 volumio volumio[1092]: verbose: CURRENT POSITION 2 Mar 29 13:44:34 volumio volumio[1092]: info: CoreStateMachine::syncState stateService play Mar 29 13:44:34 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:44:34 volumio volumio[1092]: info: Received an update from plugin. extracting info from payload Mar 29 13:44:34 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:44:34 volumio volumio[1092]: info: CorePlayQueue::getTrack 2 Mar 29 13:44:34 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:44:34 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:44:34 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:44:34 volumio volumio[1092]: info: CorePlayQueue::getTrack 2 Mar 29 13:44:34 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:44:34 volumio volumio[1092]: SPOTIFY: PUSH STATE SPOTIFY Mar 29 13:44:34 volumio volumio[1092]: SPOTIFY: {"status":"play","service":"spop","title":"Kiss Me","artist":"Sixpence None The Richer","album":"Sixpence None The Richer","albumart":"https://i.scdn.co/image/ab67616d00001e02cfc5eea6cfd77e89ed3ac5a4","uri":"spotify:track:754kgU5rWscRTfvlsuEwFp","trackType":"spotify","seek":0,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 29 13:44:34 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:44:34 volumio volumio[1092]: info: CorePlayQueue::getTrack 2 Mar 29 13:44:34 volumio volumio[1092]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Kiss Me","artist":"Sixpence None The Richer","album":"Sixpence None The Richer","albumart":"https://i.scdn.co/image/ab67616d00001e02cfc5eea6cfd77e89ed3ac5a4","uri":"spotify:track:754kgU5rWscRTfvlsuEwFp","trackType":"spotify","seek":0,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 29 13:44:34 volumio volumio[1092]: verbose: CURRENT POSITION 2 Mar 29 13:44:34 volumio volumio[1092]: info: CoreStateMachine::syncState stateService play Mar 29 13:44:34 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:44:34 volumio volumio[1092]: info: Received an update from plugin. extracting info from payload Mar 29 13:44:34 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:44:34 volumio volumio[1092]: info: CorePlayQueue::getTrack 2 Mar 29 13:44:34 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:44:34 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:44:34 volumio volumio[1092]: info: CorePlayQueue::getTrack 2 Mar 29 13:44:34 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:44:34 volumio go-librespot[2071]: time="2026-03-29T13:44:34+02:00" level=debug msg="fetched chunk 2/22, size: 524288" uri="spotify:track:3ZpQiJ78LKINrW9SQTgbXd" Mar 29 13:44:34 volumio volumio[1092]: SPOTIFY: PUSH STATE SPOTIFY Mar 29 13:44:34 volumio volumio[1092]: SPOTIFY: {"status":"play","service":"spop","title":"Kiss Me","artist":"Sixpence None The Richer","album":"Sixpence None The Richer","albumart":"https://i.scdn.co/image/ab67616d00001e02cfc5eea6cfd77e89ed3ac5a4","uri":"spotify:track:754kgU5rWscRTfvlsuEwFp","trackType":"spotify","seek":0,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 29 13:44:34 volumio volumio[1092]: info: CoreCommandRouter::servicePushState Mar 29 13:44:34 volumio volumio[1092]: info: CorePlayQueue::getTrack 2 Mar 29 13:44:34 volumio volumio[1092]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Kiss Me","artist":"Sixpence None The Richer","album":"Sixpence None The Richer","albumart":"https://i.scdn.co/image/ab67616d00001e02cfc5eea6cfd77e89ed3ac5a4","uri":"spotify:track:754kgU5rWscRTfvlsuEwFp","trackType":"spotify","seek":0,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 29 13:44:34 volumio volumio[1092]: verbose: CURRENT POSITION 2 Mar 29 13:44:34 volumio volumio[1092]: info: CoreStateMachine::syncState stateService play Mar 29 13:44:34 volumio volumio[1092]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:44:34 volumio volumio[1092]: info: Received an update from plugin. extracting info from payload Mar 29 13:44:34 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:44:34 volumio volumio[1092]: info: CorePlayQueue::getTrack 2 Mar 29 13:44:34 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:44:34 volumio volumio[1092]: info: CoreStateMachine::pushState Mar 29 13:44:34 volumio volumio[1092]: info: CorePlayQueue::getTrack 2 Mar 29 13:44:34 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Mar 29 13:44:35 volumio go-librespot[2071]: time="2026-03-29T13:44:35+02:00" level=debug msg="fetched chunk 2/16, size: 524288" uri="spotify:track:754kgU5rWscRTfvlsuEwFp" Mar 29 13:44:35 volumio go-librespot[2071]: time="2026-03-29T13:44:35+02:00" level=debug msg="fetched chunk 1/16, size: 524288" uri="spotify:track:754kgU5rWscRTfvlsuEwFp" Mar 29 13:44:36 volumio go-librespot[2071]: time="2026-03-29T13:44:36+02:00" level=debug msg="fetched chunk 3/16, size: 524288" uri="spotify:track:754kgU5rWscRTfvlsuEwFp" Mar 29 13:44:39 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 13:44:39 volumio volumio[1092]: info: CURURI: music-library Mar 29 13:44:39 volumio volumio[1092]: info: Preload queue cleared Mar 29 13:44:47 volumio go-librespot[2071]: time="2026-03-29T13:44:47+02:00" level=debug msg="fetched chunk 4/16, size: 524288" uri="spotify:track:754kgU5rWscRTfvlsuEwFp" Mar 29 13:44:49 volumio volumio[1092]: Searching plugin music_service/mpd Mar 29 13:44:49 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 13:44:49 volumio volumio[1092]: info: All search sources collected, pushing search results Mar 29 13:44:50 volumio volumio[1092]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 13:44:50 volumio volumio[1092]: TypeError: Cannot read property 'slice' of null Mar 29 13:44:50 volumio volumio[1092]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 29 13:44:50 volumio volumio[1092]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 29 13:44:50 volumio volumio[1092]: at Object.onceWrapper (events.js:520:26) Mar 29 13:44:50 volumio volumio[1092]: at ClientRequest.emit (events.js:400:28) Mar 29 13:44:50 volumio volumio[1092]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Mar 29 13:44:50 volumio volumio[1092]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Mar 29 13:44:50 volumio volumio[1092]: at TLSSocket.socketOnData (_http_client.js:515:22) Mar 29 13:44:50 volumio volumio[1092]: at TLSSocket.emit (events.js:400:28) Mar 29 13:44:50 volumio volumio[1092]: at addChunk (internal/streams/readable.js:293:12) Mar 29 13:44:50 volumio volumio[1092]: at readableAddChunk (internal/streams/readable.js:267:9) Mar 29 13:44:50 volumio volumio[1092]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Mar 29 13:44:50 volumio volumio[1092]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Mar 29 13:44:50 volumio volumio[1092]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 13:44:50 volumio sudo[14633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-29 13:43 Mar 29 13:44:50 volumio sudo[14633]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:03:54 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="35e446ff98e90abcfcab1bb2733dfd3a"