Feb 25 23:51:01 volumio volumio[1427]: info: Exploding uri music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/01 - Strangers by Nature.flac in service mpd Feb 25 23:51:01 volumio volumio[1427]: info: Exploding uri music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/02 - Easy on Me.flac in service mpd Feb 25 23:51:01 volumio volumio[1427]: info: Exploding uri music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/03 - My Little Love.flac in service mpd Feb 25 23:51:01 volumio volumio[1427]: info: Exploding uri music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/04 - Cry Your Heart Out.flac in service mpd Feb 25 23:51:01 volumio volumio[1427]: info: Exploding uri music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/05 - Oh My God.flac in service mpd Feb 25 23:51:01 volumio volumio[1427]: info: Exploding uri music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/06 - Can I Get It.flac in service mpd Feb 25 23:51:01 volumio volumio[1427]: info: Exploding uri music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/07 - I Drink Wine.flac in service mpd Feb 25 23:51:01 volumio volumio[1427]: info: Exploding uri music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/08 - All Night Parking (with Erroll Garner) (Interlude).flac in service mpd Feb 25 23:51:01 volumio volumio[1427]: info: Exploding uri music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/09 - Woman Like Me.flac in service mpd Feb 25 23:51:01 volumio volumio[1427]: info: Exploding uri music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/10 - Hold On.flac in service mpd Feb 25 23:51:01 volumio volumio[1427]: info: Exploding uri music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/11 - To Be Loved.flac in service mpd Feb 25 23:51:01 volumio volumio[1427]: info: Exploding uri music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/12 - Love Is a Game.flac in service mpd Feb 25 23:51:01 volumio volumio[1427]: info: Exploding uri music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/13 - Wild Wild West.flac in service mpd Feb 25 23:51:01 volumio volumio[1427]: info: Exploding uri music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/14 - Can’t Be Together.flac in service mpd Feb 25 23:51:01 volumio volumio[1427]: info: Exploding uri music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/15 - Easy on Me (with Chris Stapleton).flac in service mpd Feb 25 23:51:01 volumio volumio[1427]: info: Exploding uri music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/30 (Target Exclusive).m3u in service mpd Feb 25 23:51:01 volumio volumio[1427]: info: Preload queue cleared Feb 25 23:51:01 volumio volumio[1427]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 25 23:51:01 volumio volumio[1427]: info: CoreStateMachine::ClearQueue Feb 25 23:51:01 volumio volumio[1427]: info: CoreStateMachine::stop Feb 25 23:51:01 volumio volumio[1427]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 23:51:01 volumio volumio[1427]: info: CorePlayQueue::clearPlayQueue Feb 25 23:51:01 volumio volumio[1427]: info: CorePlayQueue::saveQueue Feb 25 23:51:01 volumio volumio[1427]: info: CoreCommandRouter::volumioPushQueue Feb 25 23:51:01 volumio volumio[1427]: info: CoreStateMachine::addQueueItems Feb 25 23:51:01 volumio volumio[1427]: info: CorePlayQueue::addQueueItems Feb 25 23:51:01 volumio volumio[1427]: info: Preload queue cleared Feb 25 23:51:01 volumio volumio[1427]: info: Adding Item to queue: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/01 - Strangers by Nature.flac Feb 25 23:51:01 volumio volumio[1427]: info: Exploding uri music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/01 - Strangers by Nature.flac in service mpd Feb 25 23:51:01 volumio volumio[1427]: info: ALBUMART /albumart?cacheid=417&web=Adele/30/extralarge&path=%2Fmnt%2FNAS%2FNAS_Tony%2FADELE%2FAdele%20-%2030%20(Target%20Exclusive)%20(2021)%2F01%20-%20Strangers%20by%20Nature.flac&metadata=false Feb 25 23:51:01 volumio volumio[1427]: info: URI /mnt/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/01 - Strangers by Nature.flac Feb 25 23:51:01 volumio volumio[1427]: info: ALBUMART /albumart?cacheid=417&web=Adele/30/extralarge&path=%2Fmnt%2FNAS%2FNAS_Tony%2FADELE%2FAdele%20-%2030%20(Target%20Exclusive)%20(2021)%2F02%20-%20Easy%20on%20Me.flac&metadata=false Feb 25 23:51:01 volumio volumio[1427]: info: URI /mnt/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/02 - Easy on Me.flac Feb 25 23:51:01 volumio volumio[1427]: info: ALBUMART /albumart?cacheid=417&web=Adele/30/extralarge&path=%2Fmnt%2FNAS%2FNAS_Tony%2FADELE%2FAdele%20-%2030%20(Target%20Exclusive)%20(2021)%2F03%20-%20My%20Little%20Love.flac&metadata=false Feb 25 23:51:01 volumio volumio[1427]: info: URI /mnt/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/03 - My Little Love.flac Feb 25 23:51:01 volumio volumio[1427]: info: ALBUMART /albumart?cacheid=417&web=Adele/30/extralarge&path=%2Fmnt%2FNAS%2FNAS_Tony%2FADELE%2FAdele%20-%2030%20(Target%20Exclusive)%20(2021)%2F04%20-%20Cry%20Your%20Heart%20Out.flac&metadata=false Feb 25 23:51:01 volumio volumio[1427]: info: URI /mnt/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/04 - Cry Your Heart Out.flac Feb 25 23:51:01 volumio volumio[1427]: info: ALBUMART /albumart?cacheid=417&web=Adele/30/extralarge&path=%2Fmnt%2FNAS%2FNAS_Tony%2FADELE%2FAdele%20-%2030%20(Target%20Exclusive)%20(2021)%2F05%20-%20Oh%20My%20God.flac&metadata=false Feb 25 23:51:01 volumio volumio[1427]: info: URI /mnt/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/05 - Oh My God.flac Feb 25 23:51:01 volumio volumio[1427]: info: ALBUMART /albumart?cacheid=417&web=Adele/30/extralarge&path=%2Fmnt%2FNAS%2FNAS_Tony%2FADELE%2FAdele%20-%2030%20(Target%20Exclusive)%20(2021)%2F06%20-%20Can%20I%20Get%20It.flac&metadata=false Feb 25 23:51:01 volumio volumio[1427]: info: URI /mnt/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/06 - Can I Get It.flac Feb 25 23:51:01 volumio volumio[1427]: info: ALBUMART /albumart?cacheid=417&web=Adele/30/extralarge&path=%2Fmnt%2FNAS%2FNAS_Tony%2FADELE%2FAdele%20-%2030%20(Target%20Exclusive)%20(2021)%2F07%20-%20I%20Drink%20Wine.flac&metadata=false Feb 25 23:51:01 volumio volumio[1427]: info: URI /mnt/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/07 - I Drink Wine.flac Feb 25 23:51:01 volumio volumio[1427]: info: ALBUMART /albumart?cacheid=417&web=Adele/30/extralarge&path=%2Fmnt%2FNAS%2FNAS_Tony%2FADELE%2FAdele%20-%2030%20(Target%20Exclusive)%20(2021)%2F08%20-%20All%20Night%20Parking%20(with%20Erroll%20Garner)%20(Interlude).flac&metadata=false Feb 25 23:51:01 volumio volumio[1427]: info: URI /mnt/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/08 - All Night Parking (with Erroll Garner) (Interlude).flac Feb 25 23:51:01 volumio volumio[1427]: info: ALBUMART /albumart?cacheid=417&web=Adele/30/extralarge&path=%2Fmnt%2FNAS%2FNAS_Tony%2FADELE%2FAdele%20-%2030%20(Target%20Exclusive)%20(2021)%2F09%20-%20Woman%20Like%20Me.flac&metadata=false Feb 25 23:51:01 volumio volumio[1427]: info: URI /mnt/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/09 - Woman Like Me.flac Feb 25 23:51:01 volumio volumio[1427]: info: ALBUMART /albumart?cacheid=417&web=Adele/30/extralarge&path=%2Fmnt%2FNAS%2FNAS_Tony%2FADELE%2FAdele%20-%2030%20(Target%20Exclusive)%20(2021)%2F10%20-%20Hold%20On.flac&metadata=false Feb 25 23:51:01 volumio volumio[1427]: info: URI /mnt/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/10 - Hold On.flac Feb 25 23:51:01 volumio volumio[1427]: info: ALBUMART /albumart?cacheid=417&web=Adele/30/extralarge&path=%2Fmnt%2FNAS%2FNAS_Tony%2FADELE%2FAdele%20-%2030%20(Target%20Exclusive)%20(2021)%2F11%20-%20To%20Be%20Loved.flac&metadata=false Feb 25 23:51:01 volumio volumio[1427]: info: URI /mnt/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/11 - To Be Loved.flac Feb 25 23:51:01 volumio volumio[1427]: info: ALBUMART /albumart?cacheid=417&web=Adele/30/extralarge&path=%2Fmnt%2FNAS%2FNAS_Tony%2FADELE%2FAdele%20-%2030%20(Target%20Exclusive)%20(2021)%2F12%20-%20Love%20Is%20a%20Game.flac&metadata=false Feb 25 23:51:01 volumio volumio[1427]: info: URI /mnt/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/12 - Love Is a Game.flac Feb 25 23:51:01 volumio volumio[1427]: info: ALBUMART /albumart?cacheid=417&web=Adele/30/extralarge&path=%2Fmnt%2FNAS%2FNAS_Tony%2FADELE%2FAdele%20-%2030%20(Target%20Exclusive)%20(2021)%2F13%20-%20Wild%20Wild%20West.flac&metadata=false Feb 25 23:51:01 volumio volumio[1427]: info: URI /mnt/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/13 - Wild Wild West.flac Feb 25 23:51:01 volumio volumio[1427]: info: ALBUMART /albumart?cacheid=417&web=Adele/30/extralarge&path=%2Fmnt%2FNAS%2FNAS_Tony%2FADELE%2FAdele%20-%2030%20(Target%20Exclusive)%20(2021)%2F14%20-%20Can%E2%80%99t%20Be%20Together.flac&metadata=false Feb 25 23:51:01 volumio volumio[1427]: info: URI /mnt/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/14 - Can’t Be Together.flac Feb 25 23:51:01 volumio volumio[1427]: info: ALBUMART /albumart?cacheid=417&web=Adele/30/extralarge&path=%2Fmnt%2FNAS%2FNAS_Tony%2FADELE%2FAdele%20-%2030%20(Target%20Exclusive)%20(2021)%2F15%20-%20Easy%20on%20Me%20(with%20Chris%20Stapleton).flac&metadata=false Feb 25 23:51:01 volumio volumio[1427]: info: URI /mnt/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/15 - Easy on Me (with Chris Stapleton).flac Feb 25 23:51:01 volumio volumio[1427]: info: ALBUMART /albumart?cacheid=417&web=Adele/30/extralarge&path=%2Fmnt%2FNAS%2FNAS_Tony%2FADELE%2FAdele%20-%2030%20(Target%20Exclusive)%20(2021)%2F01%20-%20Strangers%20by%20Nature.flac&metadata=false Feb 25 23:51:01 volumio volumio[1427]: info: URI /mnt/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/01 - Strangers by Nature.flac Feb 25 23:51:01 volumio volumio[1427]: info: CoreCommandRouter::volumioPushQueue Feb 25 23:51:01 volumio volumio[1427]: info: CorePlayQueue::saveQueue Feb 25 23:51:01 volumio volumio[1427]: info: CoreStateMachine::updateTrackBlock Feb 25 23:51:01 volumio volumio[1427]: info: CorePlayQueue::getTrackBlock Feb 25 23:51:01 volumio volumio[1427]: info: CoreCommandRouter::volumioPlay Feb 25 23:51:01 volumio volumio[1427]: info: CoreStateMachine::play index 0 Feb 25 23:51:01 volumio volumio[1427]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 23:51:01 volumio volumio[1427]: info: CoreStateMachine::addQueueItems Feb 25 23:51:01 volumio volumio[1427]: info: CorePlayQueue::addQueueItems Feb 25 23:51:01 volumio volumio[1427]: info: Preload queue cleared Feb 25 23:51:01 volumio volumio[1427]: info: Adding Item to queue: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/02 - Easy on Me.flac Feb 25 23:51:01 volumio volumio[1427]: info: Using cached record of: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/02 - Easy on Me.flac Feb 25 23:51:01 volumio volumio[1427]: info: Adding Item to queue: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/03 - My Little Love.flac Feb 25 23:51:01 volumio volumio[1427]: info: Using cached record of: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/03 - My Little Love.flac Feb 25 23:51:01 volumio volumio[1427]: info: Adding Item to queue: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/04 - Cry Your Heart Out.flac Feb 25 23:51:01 volumio volumio[1427]: info: Using cached record of: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/04 - Cry Your Heart Out.flac Feb 25 23:51:01 volumio volumio[1427]: info: Adding Item to queue: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/05 - Oh My God.flac Feb 25 23:51:01 volumio volumio[1427]: info: Using cached record of: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/05 - Oh My God.flac Feb 25 23:51:01 volumio volumio[1427]: info: Adding Item to queue: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/06 - Can I Get It.flac Feb 25 23:51:01 volumio volumio[1427]: info: Using cached record of: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/06 - Can I Get It.flac Feb 25 23:51:01 volumio volumio[1427]: info: Adding Item to queue: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/07 - I Drink Wine.flac Feb 25 23:51:01 volumio volumio[1427]: info: Using cached record of: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/07 - I Drink Wine.flac Feb 25 23:51:01 volumio volumio[1427]: info: Adding Item to queue: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/08 - All Night Parking (with Erroll Garner) (Interlude).flac Feb 25 23:51:01 volumio volumio[1427]: info: Using cached record of: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/08 - All Night Parking (with Erroll Garner) (Interlude).flac Feb 25 23:51:01 volumio volumio[1427]: info: Adding Item to queue: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/09 - Woman Like Me.flac Feb 25 23:51:01 volumio volumio[1427]: info: Using cached record of: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/09 - Woman Like Me.flac Feb 25 23:51:01 volumio volumio[1427]: info: Adding Item to queue: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/10 - Hold On.flac Feb 25 23:51:01 volumio volumio[1427]: info: Using cached record of: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/10 - Hold On.flac Feb 25 23:51:01 volumio volumio[1427]: info: Adding Item to queue: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/11 - To Be Loved.flac Feb 25 23:51:01 volumio volumio[1427]: info: Using cached record of: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/11 - To Be Loved.flac Feb 25 23:51:01 volumio volumio[1427]: info: Adding Item to queue: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/12 - Love Is a Game.flac Feb 25 23:51:01 volumio volumio[1427]: info: Using cached record of: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/12 - Love Is a Game.flac Feb 25 23:51:01 volumio volumio[1427]: info: Adding Item to queue: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/13 - Wild Wild West.flac Feb 25 23:51:01 volumio volumio[1427]: info: Using cached record of: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/13 - Wild Wild West.flac Feb 25 23:51:01 volumio volumio[1427]: info: Adding Item to queue: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/14 - Can’t Be Together.flac Feb 25 23:51:01 volumio volumio[1427]: info: Using cached record of: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/14 - Can’t Be Together.flac Feb 25 23:51:01 volumio volumio[1427]: info: Adding Item to queue: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/15 - Easy on Me (with Chris Stapleton).flac Feb 25 23:51:01 volumio volumio[1427]: info: Using cached record of: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/15 - Easy on Me (with Chris Stapleton).flac Feb 25 23:51:01 volumio volumio[1427]: info: Adding Item to queue: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/30 (Target Exclusive).m3u Feb 25 23:51:01 volumio volumio[1427]: info: Using cached record of: music-library/NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/30 (Target Exclusive).m3u Feb 25 23:51:01 volumio volumio[1427]: info: CoreStateMachine::stop Feb 25 23:51:01 volumio volumio[1427]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 23:51:01 volumio volumio[1427]: info: CoreCommandRouter::volumioPushQueue Feb 25 23:51:01 volumio volumio[1427]: info: CorePlayQueue::saveQueue Feb 25 23:51:01 volumio volumio[1427]: info: CoreStateMachine::play index undefined Feb 25 23:51:01 volumio volumio[1427]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 23:51:01 volumio volumio[1427]: info: CoreStateMachine::updateTrackBlock Feb 25 23:51:01 volumio volumio[1427]: info: CorePlayQueue::getTrackBlock Feb 25 23:51:01 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:01 volumio volumio[1427]: info: CoreStateMachine::startPlaybackTimer Feb 25 23:51:01 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:01 volumio volumio[1427]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/01 - Strangers by Nature.flac Feb 25 23:51:01 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand stop Feb 25 23:51:01 volumio volumio[1427]: info: sendMpdCommand stop took 2 milliseconds Feb 25 23:51:01 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand clear Feb 25 23:51:01 volumio volumio[1427]: info: Feb 25 23:51:01 volumio volumio[1427]: ---------------------------- MPD announces system playlist update Feb 25 23:51:01 volumio volumio[1427]: info: Ignoring MPD Status Update Feb 25 23:51:01 volumio volumio[1427]: info: sendMpdCommand clear took 5 milliseconds Feb 25 23:51:01 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/01 - Strangers by Nature.flac" Feb 25 23:51:01 volumio volumio[1427]: info: Feb 25 23:51:01 volumio volumio[1427]: ---------------------------- MPD announces system playlist update Feb 25 23:51:01 volumio volumio[1427]: info: Ignoring MPD Status Update Feb 25 23:51:01 volumio volumio[1427]: info: Feb 25 23:51:01 volumio volumio[1427]: ---------------------------- MPD announces system playlist update Feb 25 23:51:01 volumio volumio[1427]: info: Ignoring MPD Status Update Feb 25 23:51:01 volumio volumio[1427]: error: updateQueue error: null Feb 25 23:51:01 volumio volumio[1427]: info: Feb 25 23:51:01 volumio volumio[1427]: ---------------------------- MPD announces system playlist update Feb 25 23:51:01 volumio volumio[1427]: info: Ignoring MPD Status Update Feb 25 23:51:01 volumio volumio[1427]: info: ------------------------------ 3ms Feb 25 23:51:01 volumio volumio[1427]: info: sendMpdCommand add "NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/01 - Strangers by Nature.flac" took 3 milliseconds Feb 25 23:51:01 volumio volumio[1427]: info: ------------------------------ 2ms Feb 25 23:51:01 volumio volumio[1427]: info: ------------------------------ 2ms Feb 25 23:51:01 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand play Feb 25 23:51:01 volumio volumio[1427]: info: Feb 25 23:51:01 volumio volumio[1427]: ---------------------------- MPD announces system playlist update Feb 25 23:51:01 volumio volumio[1427]: info: Ignoring MPD Status Update Feb 25 23:51:01 volumio volumio[1427]: info: Feb 25 23:51:01 volumio volumio[1427]: ---------------------------- MPD announces system playlist update Feb 25 23:51:01 volumio volumio[1427]: info: Ignoring MPD Status Update Feb 25 23:51:01 volumio volumio[1427]: info: ------------------------------ 4ms Feb 25 23:51:01 volumio volumio[1427]: info: sendMpdCommand play took 3 milliseconds Feb 25 23:51:01 volumio volumio[1427]: info: ------------------------------ 1ms Feb 25 23:51:01 volumio volumio[1427]: info: ------------------------------ 1ms Feb 25 23:51:14 volumio volumio[1427]: info: Feb 25 23:51:14 volumio volumio[1427]: ---------------------------- MPD announces state update: player Feb 25 23:51:14 volumio volumio[1427]: info: ControllerMpd::getState Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand status Feb 25 23:51:14 volumio volumio[1427]: info: Feb 25 23:51:14 volumio volumio[1427]: ---------------------------- MPD announces state update: player Feb 25 23:51:14 volumio volumio[1427]: info: ControllerMpd::getState Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand status Feb 25 23:51:14 volumio volumio[1427]: info: Feb 25 23:51:14 volumio volumio[1427]: ---------------------------- MPD announces state update: player Feb 25 23:51:14 volumio volumio[1427]: info: ControllerMpd::getState Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand status Feb 25 23:51:14 volumio volumio[1427]: info: Feb 25 23:51:14 volumio volumio[1427]: ---------------------------- MPD announces state update: player Feb 25 23:51:14 volumio volumio[1427]: info: ControllerMpd::getState Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand status Feb 25 23:51:14 volumio volumio[1427]: info: Feb 25 23:51:14 volumio volumio[1427]: ---------------------------- MPD announces state update: player Feb 25 23:51:14 volumio volumio[1427]: info: ControllerMpd::getState Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand status Feb 25 23:51:14 volumio volumio[1427]: info: Feb 25 23:51:14 volumio volumio[1427]: ---------------------------- MPD announces state update: player Feb 25 23:51:14 volumio volumio[1427]: info: sendMpdCommand status took 3 milliseconds Feb 25 23:51:14 volumio volumio[1427]: info: ControllerMpd::getState Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand status Feb 25 23:51:14 volumio volumio[1427]: info: sendMpdCommand status took 3 milliseconds Feb 25 23:51:14 volumio volumio[1427]: info: sendMpdCommand status took 3 milliseconds Feb 25 23:51:14 volumio volumio[1427]: info: sendMpdCommand status took 1 milliseconds Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::parseState Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::parseState Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::parseState Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::parseState Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 23:51:14 volumio volumio[1427]: info: sendMpdCommand status took 3 milliseconds Feb 25 23:51:14 volumio volumio[1427]: info: sendMpdCommand status took 2 milliseconds Feb 25 23:51:14 volumio volumio[1427]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 25 23:51:14 volumio volumio[1427]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 25 23:51:14 volumio volumio[1427]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 25 23:51:14 volumio volumio[1427]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::parseState Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::parseState Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::parseTrackInfo Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::parseTrackInfo Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::parseTrackInfo Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::parseTrackInfo Feb 25 23:51:14 volumio volumio[1427]: info: ControllerMpd::pushState Feb 25 23:51:14 volumio volumio[1427]: info: CoreCommandRouter::servicePushState Feb 25 23:51:14 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:14 volumio volumio[1427]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3347,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"750 Kbps","isStreaming":false,"title":"Strangers by Nature","artist":"Adele","album":"30","uri":"NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/01 - Strangers by Nature.flac","trackType":"flac"} Feb 25 23:51:14 volumio volumio[1427]: verbose: CURRENT POSITION 0 Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::syncState stateService play Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::syncState currentStatus stop Feb 25 23:51:14 volumio volumio[1427]: info: ControllerMpd::pushState Feb 25 23:51:14 volumio volumio[1427]: info: CoreCommandRouter::servicePushState Feb 25 23:51:14 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:14 volumio volumio[1427]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3347,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"750 Kbps","isStreaming":false,"title":"Strangers by Nature","artist":"Adele","album":"30","uri":"NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/01 - Strangers by Nature.flac","trackType":"flac"} Feb 25 23:51:14 volumio volumio[1427]: verbose: CURRENT POSITION 0 Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::syncState stateService play Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::syncState currentStatus play Feb 25 23:51:14 volumio volumio[1427]: info: Received an update from plugin. extracting info from payload Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::pushState Feb 25 23:51:14 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:14 volumio volumio[1427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 23:51:14 volumio volumio[1427]: info: CoreCommandRouter::volumioPushState Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::pushState Feb 25 23:51:14 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:14 volumio volumio[1427]: info: CoreCommandRouter::volumioPushState Feb 25 23:51:14 volumio volumio[1427]: info: ControllerMpd::pushState Feb 25 23:51:14 volumio volumio[1427]: info: CoreCommandRouter::servicePushState Feb 25 23:51:14 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:14 volumio volumio[1427]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3347,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"750 Kbps","isStreaming":false,"title":"Strangers by Nature","artist":"Adele","album":"30","uri":"NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/01 - Strangers by Nature.flac","trackType":"flac"} Feb 25 23:51:14 volumio volumio[1427]: verbose: CURRENT POSITION 0 Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::syncState stateService play Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::syncState currentStatus play Feb 25 23:51:14 volumio volumio[1427]: info: Received an update from plugin. extracting info from payload Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::pushState Feb 25 23:51:14 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:14 volumio volumio[1427]: info: CoreCommandRouter::volumioPushState Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::pushState Feb 25 23:51:14 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:14 volumio volumio[1427]: info: CoreCommandRouter::volumioPushState Feb 25 23:51:14 volumio volumio[1427]: info: ControllerMpd::pushState Feb 25 23:51:14 volumio volumio[1427]: info: CoreCommandRouter::servicePushState Feb 25 23:51:14 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:14 volumio volumio[1427]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3347,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"750 Kbps","isStreaming":false,"title":"Strangers by Nature","artist":"Adele","album":"30","uri":"NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/01 - Strangers by Nature.flac","trackType":"flac"} Feb 25 23:51:14 volumio volumio[1427]: verbose: CURRENT POSITION 0 Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::syncState stateService play Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::syncState currentStatus play Feb 25 23:51:14 volumio volumio[1427]: info: Received an update from plugin. extracting info from payload Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::pushState Feb 25 23:51:14 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:14 volumio volumio[1427]: info: CoreCommandRouter::volumioPushState Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::pushState Feb 25 23:51:14 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:14 volumio volumio[1427]: info: CoreCommandRouter::volumioPushState Feb 25 23:51:14 volumio volumio[1427]: info: ------------------------------ 17ms Feb 25 23:51:14 volumio volumio[1427]: info: ------------------------------ 28ms Feb 25 23:51:14 volumio volumio[1427]: info: ------------------------------ 29ms Feb 25 23:51:14 volumio volumio[1427]: info: ------------------------------ 28ms Feb 25 23:51:14 volumio volumio[1427]: info: sendMpdCommand playlistinfo took 23 milliseconds Feb 25 23:51:14 volumio volumio[1427]: info: sendMpdCommand playlistinfo took 24 milliseconds Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::parseTrackInfo Feb 25 23:51:14 volumio volumio[1427]: verbose: ControllerMpd::parseTrackInfo Feb 25 23:51:14 volumio volumio[1427]: info: ControllerMpd::pushState Feb 25 23:51:14 volumio volumio[1427]: info: CoreCommandRouter::servicePushState Feb 25 23:51:14 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:14 volumio volumio[1427]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3347,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"750 Kbps","isStreaming":false,"title":"Strangers by Nature","artist":"Adele","album":"30","uri":"NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/01 - Strangers by Nature.flac","trackType":"flac"} Feb 25 23:51:14 volumio volumio[1427]: verbose: CURRENT POSITION 0 Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::syncState stateService play Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::syncState currentStatus play Feb 25 23:51:14 volumio volumio[1427]: info: Received an update from plugin. extracting info from payload Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::pushState Feb 25 23:51:14 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:14 volumio volumio[1427]: info: CoreCommandRouter::volumioPushState Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::pushState Feb 25 23:51:14 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:14 volumio volumio[1427]: info: CoreCommandRouter::volumioPushState Feb 25 23:51:14 volumio volumio[1427]: info: ControllerMpd::pushState Feb 25 23:51:14 volumio volumio[1427]: info: CoreCommandRouter::servicePushState Feb 25 23:51:14 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:14 volumio volumio[1427]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3347,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"750 Kbps","isStreaming":false,"title":"Strangers by Nature","artist":"Adele","album":"30","uri":"NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/01 - Strangers by Nature.flac","trackType":"flac"} Feb 25 23:51:14 volumio volumio[1427]: verbose: CURRENT POSITION 0 Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::syncState stateService play Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::syncState currentStatus play Feb 25 23:51:14 volumio volumio[1427]: info: Received an update from plugin. extracting info from payload Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::pushState Feb 25 23:51:14 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:14 volumio volumio[1427]: info: CoreCommandRouter::volumioPushState Feb 25 23:51:14 volumio volumio[1427]: info: CoreStateMachine::pushState Feb 25 23:51:14 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:14 volumio volumio[1427]: info: CoreCommandRouter::volumioPushState Feb 25 23:51:14 volumio volumio[1427]: info: ------------------------------ 42ms Feb 25 23:51:14 volumio volumio[1427]: info: ------------------------------ 42ms Feb 25 23:51:14 volumio volumio[1427]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 25 23:51:14 volumio volumio[1427]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 25 23:51:14 volumio volumio[1427]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 25 23:51:14 volumio volumio[1427]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 25 23:51:14 volumio volumio[1427]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 25 23:51:14 volumio volumio[1427]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 25 23:51:14 volumio volumio[1427]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 25 23:51:14 volumio volumio[1427]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 25 23:51:14 volumio volumio[1427]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 25 23:51:14 volumio volumio[1427]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 25 23:51:16 volumio volumio[1427]: info: CoreCommandRouter::volumioPause Feb 25 23:51:16 volumio volumio[1427]: info: CoreStateMachine::pause Feb 25 23:51:16 volumio volumio[1427]: info: CoreStateMachine::stPlaybackTimer Feb 25 23:51:16 volumio volumio[1427]: info: CoreStateMachine::servicePause Feb 25 23:51:16 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:16 volumio volumio[1427]: info: CoreCommandRouter::servicePause Feb 25 23:51:16 volumio volumio[1427]: info: ControllerMpd::pause Feb 25 23:51:16 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand pause Feb 25 23:51:16 volumio volumio[1427]: info: Feb 25 23:51:16 volumio volumio[1427]: ---------------------------- MPD announces state update: player Feb 25 23:51:16 volumio volumio[1427]: info: sendMpdCommand pause took 525 milliseconds Feb 25 23:51:16 volumio volumio[1427]: info: ControllerMpd::getState Feb 25 23:51:16 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand status Feb 25 23:51:16 volumio volumio[1427]: info: Feb 25 23:51:16 volumio volumio[1427]: ---------------------------- MPD announces state update: player Feb 25 23:51:16 volumio volumio[1427]: info: ControllerMpd::getState Feb 25 23:51:16 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand status Feb 25 23:51:16 volumio volumio[1427]: info: Feb 25 23:51:16 volumio volumio[1427]: ---------------------------- MPD announces state update: player Feb 25 23:51:16 volumio volumio[1427]: info: ControllerMpd::getState Feb 25 23:51:16 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand status Feb 25 23:51:16 volumio volumio[1427]: info: sendMpdCommand status took 6 milliseconds Feb 25 23:51:16 volumio volumio[1427]: info: sendMpdCommand status took 3 milliseconds Feb 25 23:51:16 volumio volumio[1427]: info: sendMpdCommand status took 2 milliseconds Feb 25 23:51:16 volumio volumio[1427]: verbose: ControllerMpd::parseState Feb 25 23:51:16 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 23:51:16 volumio volumio[1427]: verbose: ControllerMpd::parseState Feb 25 23:51:16 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 23:51:16 volumio volumio[1427]: verbose: ControllerMpd::parseState Feb 25 23:51:16 volumio volumio[1427]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 23:51:16 volumio volumio[1427]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 25 23:51:16 volumio volumio[1427]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 25 23:51:16 volumio volumio[1427]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 25 23:51:16 volumio volumio[1427]: verbose: ControllerMpd::parseTrackInfo Feb 25 23:51:16 volumio volumio[1427]: verbose: ControllerMpd::parseTrackInfo Feb 25 23:51:16 volumio volumio[1427]: verbose: ControllerMpd::parseTrackInfo Feb 25 23:51:16 volumio volumio[1427]: info: ControllerMpd::pushState Feb 25 23:51:16 volumio volumio[1427]: info: CoreCommandRouter::servicePushState Feb 25 23:51:16 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:16 volumio volumio[1427]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5226,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"792 Kbps","isStreaming":false,"title":"Strangers by Nature","artist":"Adele","album":"30","uri":"NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/01 - Strangers by Nature.flac","trackType":"flac"} Feb 25 23:51:16 volumio volumio[1427]: verbose: CURRENT POSITION 0 Feb 25 23:51:16 volumio volumio[1427]: info: CoreStateMachine::syncState stateService pause Feb 25 23:51:16 volumio volumio[1427]: info: CoreStateMachine::syncState currentStatus pause Feb 25 23:51:16 volumio volumio[1427]: info: CoreStateMachine::pushState Feb 25 23:51:16 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:16 volumio volumio[1427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 23:51:16 volumio volumio[1427]: info: CoreCommandRouter::volumioPushState Feb 25 23:51:16 volumio volumio[1427]: info: CoreStateMachine::stPlaybackTimer Feb 25 23:51:16 volumio volumio[1427]: info: ControllerMpd::pushState Feb 25 23:51:16 volumio volumio[1427]: info: CoreCommandRouter::servicePushState Feb 25 23:51:16 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:16 volumio volumio[1427]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5226,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"792 Kbps","isStreaming":false,"title":"Strangers by Nature","artist":"Adele","album":"30","uri":"NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/01 - Strangers by Nature.flac","trackType":"flac"} Feb 25 23:51:16 volumio volumio[1427]: verbose: CURRENT POSITION 0 Feb 25 23:51:16 volumio volumio[1427]: info: CoreStateMachine::syncState stateService pause Feb 25 23:51:16 volumio volumio[1427]: info: CoreStateMachine::syncState currentStatus pause Feb 25 23:51:16 volumio volumio[1427]: info: CoreStateMachine::pushState Feb 25 23:51:16 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:16 volumio volumio[1427]: info: CoreCommandRouter::volumioPushState Feb 25 23:51:16 volumio volumio[1427]: info: CoreStateMachine::stPlaybackTimer Feb 25 23:51:16 volumio volumio[1427]: info: ControllerMpd::pushState Feb 25 23:51:16 volumio volumio[1427]: info: CoreCommandRouter::servicePushState Feb 25 23:51:16 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:16 volumio volumio[1427]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5226,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"792 Kbps","isStreaming":false,"title":"Strangers by Nature","artist":"Adele","album":"30","uri":"NAS/NAS_Tony/ADELE/Adele - 30 (Target Exclusive) (2021)/01 - Strangers by Nature.flac","trackType":"flac"} Feb 25 23:51:16 volumio volumio[1427]: verbose: CURRENT POSITION 0 Feb 25 23:51:16 volumio volumio[1427]: info: CoreStateMachine::syncState stateService pause Feb 25 23:51:16 volumio volumio[1427]: info: CoreStateMachine::syncState currentStatus pause Feb 25 23:51:16 volumio volumio[1427]: info: CoreStateMachine::pushState Feb 25 23:51:16 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:51:16 volumio volumio[1427]: info: CoreCommandRouter::volumioPushState Feb 25 23:51:16 volumio volumio[1427]: info: CoreStateMachine::stPlaybackTimer Feb 25 23:51:16 volumio volumio[1427]: info: ------------------------------ 22ms Feb 25 23:51:16 volumio volumio[1427]: info: ------------------------------ 19ms Feb 25 23:51:16 volumio volumio[1427]: info: ------------------------------ 19ms Feb 25 23:51:16 volumio volumio[1427]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 25 23:51:16 volumio volumio[1427]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 25 23:51:16 volumio volumio[1427]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 25 23:52:32 volumio volumio[1427]: info: CoreCommandRouter::volumioGetState Feb 25 23:52:32 volumio volumio[1427]: info: CorePlayQueue::getTrack 0 Feb 25 23:52:36 volumio volumio[1427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 23:52:36 volumio volumio[1427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 25 23:52:39 volumio volumio[1427]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 23:52:48 volumio volumio[1427]: info: Received OAUTH Data Feb 25 23:52:48 volumio volumio[1427]: info: Executing Spotify Oauth Login Feb 25 23:52:48 volumio volumio[1427]: info: Saving Spotify Refresh Token Feb 25 23:52:48 volumio volumio[1427]: info: New Spotify access tokenBQBOLDY4J6... Feb 25 23:52:48 volumio volumio[1427]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 25 23:52:48 volumio sudo[9476]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 23:52:48 volumio sudo[9476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 23:52:48 volumio sudo[9476]: pam_unix(sudo:session): session closed for user root Feb 25 23:52:48 volumio sudo[9478]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 23:52:48 volumio sudo[9478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 23:52:48 volumio sudo[9478]: pam_unix(sudo:session): session closed for user root Feb 25 23:52:48 volumio volumio[1427]: SPOTIFY: User informations: {"country":"IT","display_name":"bepidamel","email":"bepidamel@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/bepidamel"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/bepidamel","id":"bepidamel","images":[],"product":"premium","type":"user","uri":"spotify:user:bepidamel"} Feb 25 23:52:48 volumio volumio[1427]: info: Creating Spotify config file Feb 25 23:52:48 volumio volumio[1427]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 23:52:48 volumio volumio[1427]: info: Spotify config file written Feb 25 23:52:48 volumio volumio[1427]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.1.75 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 25 23:52:48 volumio sudo[9482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 25 23:52:48 volumio sudo[9482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 23:52:48 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 25 23:52:48 volumio systemd[1]: go-librespot-daemon.service: Killing process 1774 (go-librespot) with signal SIGKILL. Feb 25 23:52:48 volumio systemd[1]: go-librespot-daemon.service: Killing process 1778 (go-librespot) with signal SIGKILL. Feb 25 23:52:48 volumio volumio[1427]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Feb 25 23:52:48 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 25 23:52:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 23:52:48 volumio volumio[1427]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 23:52:48 volumio volumio[1427]: Error: socket hang up Feb 25 23:52:48 volumio volumio[1427]: at connResetException (node:internal/errors:720:14) Feb 25 23:52:48 volumio volumio[1427]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 25 23:52:48 volumio volumio[1427]: at Socket.emit (node:events:526:35) Feb 25 23:52:48 volumio volumio[1427]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 25 23:52:48 volumio volumio[1427]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 25 23:52:48 volumio volumio[1427]: code: 'ECONNRESET', Feb 25 23:52:48 volumio volumio[1427]: response: undefined Feb 25 23:52:48 volumio volumio[1427]: } Feb 25 23:52:48 volumio volumio[1427]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 23:52:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 23:52:48 volumio go-librespot[9484]: go-librespot daemon starting... Feb 25 23:52:48 volumio sudo[9482]: pam_unix(sudo:session): session closed for user root Feb 25 23:52:48 volumio go-librespot[9490]: time="2026-02-25T23:52:48+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 23:52:48 volumio go-librespot[9490]: time="2026-02-25T23:52:48+01:00" level=debug msg="app state loaded" Feb 25 23:52:48 volumio go-librespot[9490]: time="2026-02-25T23:52:48+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 23:52:48 volumio go-librespot[9490]: time="2026-02-25T23:52:48+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 23:52:48 volumio go-librespot[9490]: time="2026-02-25T23:52:48+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 25 23:52:48 volumio go-librespot[9490]: time="2026-02-25T23:52:48+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 25 23:52:48 volumio go-librespot[9490]: time="2026-02-25T23:52:48+01:00" level=info msg="zeroconf server listening on port 44677" Feb 25 23:52:48 volumio go-librespot[9490]: time="2026-02-25T23:52:48+01:00" level=debug msg="obtained new client token: AACz5XJT6TNMNfeh8SD5Xoc+aTh2R1sJfOgIGS3HQVOKK9MjF0fbE8KnMyNDigzdtnAZdA326g49+NiQZansjHG6kb4Qx1pEErYtQUPCYcVhwZFFg7WzDb3ptsV0mUv6fidGB6/2zQgJVfKIrWzi/N3mPevubz3wInV09HXrsc3Y8teIF0jqLEcICuXalSMJ3j6z4AQ0gIDEXspYLwKBNWX4O3nXAVjxmKOnU/y8gacmZBJebx5MWhR2" Feb 25 23:52:48 volumio go-librespot[9490]: time="2026-02-25T23:52:48+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 23:52:48 volumio sudo[9510]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 23:51' Feb 25 23:52:48 volumio sudo[9510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 23:52:48 volumio go-librespot[9490]: time="2026-02-25T23:52:48+01:00" level=debug msg="completed keyexchange" Feb 25 23:52:48 volumio go-librespot[9490]: time="2026-02-25T23:52:48+01:00" level=debug msg="completed challenge" PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"