-- Logs begin at Wed 2025-05-07 18:49:36 CST, end at Wed 2025-05-07 19:45:57 CST. --
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: ALBUMART /albumart?cacheid=783&web=Cat%20Stevens/First%20Cuts/medium&path=%2Fmnt%2FNAS%2FCloudMusic%2FVolumio%2FCat%20Stevens%20-%20First%20Cuts%2F07.%20Baby%20Get%20Your%20Head%20Screwed%20On.flac&metadata=false
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: URI /mnt/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/07. Baby Get Your Head Screwed On.flac
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: Exploding uri music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/08. Lady.flac in service mpd
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: ALBUMART /albumart?cacheid=783&web=Cat%20Stevens/First%20Cuts/medium&path=%2Fmnt%2FNAS%2FCloudMusic%2FVolumio%2FCat%20Stevens%20-%20First%20Cuts%2F08.%20Lady.flac&metadata=false
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: URI /mnt/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/08. Lady.flac
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: Exploding uri music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/09. I'm Gonna Get Me A Gun.flac in service mpd
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: ALBUMART /albumart?cacheid=783&web=Cat%20Stevens/First%20Cuts/medium&path=%2Fmnt%2FNAS%2FCloudMusic%2FVolumio%2FCat%20Stevens%20-%20First%20Cuts%2F09.%20I'm%20Gonna%20Get%20Me%20A%20Gun.flac&metadata=false
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: URI /mnt/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/09. I'm Gonna Get Me A Gun.flac
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: Exploding uri music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/10. Kitty.flac in service mpd
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: ALBUMART /albumart?cacheid=783&web=Cat%20Stevens/First%20Cuts/medium&path=%2Fmnt%2FNAS%2FCloudMusic%2FVolumio%2FCat%20Stevens%20-%20First%20Cuts%2F10.%20Kitty.flac&metadata=false
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: URI /mnt/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/10. Kitty.flac
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: Exploding uri music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/11. Northern Wind.flac in service mpd
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: ALBUMART /albumart?cacheid=783&web=Cat%20Stevens/First%20Cuts/medium&path=%2Fmnt%2FNAS%2FCloudMusic%2FVolumio%2FCat%20Stevens%20-%20First%20Cuts%2F11.%20Northern%20Wind.flac&metadata=false
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: URI /mnt/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/11. Northern Wind.flac
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: Exploding uri music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/12. Blackness Of The Night.flac in service mpd
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: ALBUMART /albumart?cacheid=783&web=Cat%20Stevens/First%20Cuts/medium&path=%2Fmnt%2FNAS%2FCloudMusic%2FVolumio%2FCat%20Stevens%20-%20First%20Cuts%2F12.%20Blackness%20Of%20The%20Night.flac&metadata=false
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: URI /mnt/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/12. Blackness Of The Night.flac
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: Exploding uri music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/13. I'm So Sleepy.flac in service mpd
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: ALBUMART /albumart?cacheid=783&web=Cat%20Stevens/First%20Cuts/medium&path=%2Fmnt%2FNAS%2FCloudMusic%2FVolumio%2FCat%20Stevens%20-%20First%20Cuts%2F13.%20I'm%20So%20Sleepy.flac&metadata=false
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: URI /mnt/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/13. I'm So Sleepy.flac
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: Exploding uri music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/14. Come On Baby (Shift That Log).flac in service mpd
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: ALBUMART /albumart?cacheid=783&web=Cat%20Stevens/First%20Cuts/medium&path=%2Fmnt%2FNAS%2FCloudMusic%2FVolumio%2FCat%20Stevens%20-%20First%20Cuts%2F14.%20Come%20On%20Baby%20(Shift%20That%20Log).flac&metadata=false
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: URI /mnt/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/14. Come On Baby (Shift That Log).flac
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: Exploding uri music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/15. A Bad Night.flac in service mpd
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: ALBUMART /albumart?cacheid=783&web=Cat%20Stevens/First%20Cuts/medium&path=%2Fmnt%2FNAS%2FCloudMusic%2FVolumio%2FCat%20Stevens%20-%20First%20Cuts%2F15.%20A%20Bad%20Night.flac&metadata=false
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: URI /mnt/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/15. A Bad Night.flac
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: Exploding uri music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/16. Lovely City (When Do You Laugh).flac in service mpd
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: ALBUMART /albumart?cacheid=783&web=Cat%20Stevens/First%20Cuts/medium&path=%2Fmnt%2FNAS%2FCloudMusic%2FVolumio%2FCat%20Stevens%20-%20First%20Cuts%2F16.%20Lovely%20City%20(When%20Do%20You%20Laugh).flac&metadata=false
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: URI /mnt/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/16. Lovely City (When Do You Laugh).flac
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: Exploding uri music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/17. Here Comes My Wife.flac in service mpd
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: ALBUMART /albumart?cacheid=783&web=Cat%20Stevens/First%20Cuts/medium&path=%2Fmnt%2FNAS%2FCloudMusic%2FVolumio%2FCat%20Stevens%20-%20First%20Cuts%2F17.%20Here%20Comes%20My%20Wife.flac&metadata=false
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: URI /mnt/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/17. Here Comes My Wife.flac
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: Exploding uri music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/18. Where Are You.flac in service mpd
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: ALBUMART /albumart?cacheid=783&web=Cat%20Stevens/First%20Cuts/medium&path=%2Fmnt%2FNAS%2FCloudMusic%2FVolumio%2FCat%20Stevens%20-%20First%20Cuts%2F18.%20Where%20Are%20You.flac&metadata=false
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: URI /mnt/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/18. Where Are You.flac
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: Exploding uri music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/19. The View From The Top.flac in service mpd
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: Exploding uri music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/20. The First Cut Is The Deepest.flac in service mpd
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: Exploding uri music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/Cat Stevens - First Cuts.m3u8 in service mpd
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: ALBUMART /albumart?cacheid=783&web=Cat%20Stevens/First%20Cuts/medium&path=%2Fmnt%2FNAS%2FCloudMusic%2FVolumio%2FCat%20Stevens%20-%20First%20Cuts%2F19.%20The%20View%20From%20The%20Top.flac&metadata=false
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: URI /mnt/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/19. The View From The Top.flac
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: ALBUMART /albumart?cacheid=783&web=Cat%20Stevens/First%20Cuts/medium&path=%2Fmnt%2FNAS%2FCloudMusic%2FVolumio%2FCat%20Stevens%20-%20First%20Cuts%2F20.%20The%20First%20Cut%20Is%20The%20Deepest.flac&metadata=false
May 07 19:44:00 dac.x2-nvme volumio[1341]: info: URI /mnt/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/20. The First Cut Is The Deepest.flac
May 07 19:44:01 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Preload queue cleared
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::ClearQueue
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::stop
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::setConsumeUpdateService undefined
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CorePlayQueue::clearPlayQueue
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CorePlayQueue::saveQueue
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioPushQueue
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::addQueueItems
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CorePlayQueue::addQueueItems
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Preload queue cleared
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/01. Here Comes My Baby.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/01. Here Comes My Baby.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/02. Come On And Dance.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/02. Come On And Dance.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/03. I Love My Dog.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/03. I Love My Dog.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/04. Matthew & Son.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/04. Matthew & Son.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioPushQueue
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CorePlayQueue::saveQueue
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::updateTrackBlock
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrackBlock
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioPlay
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::play index 3
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::setConsumeUpdateService undefined
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::addQueueItems
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CorePlayQueue::addQueueItems
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Preload queue cleared
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/05. Granny.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/05. Granny.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/06. I've Found A Love.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/06. I've Found A Love.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/07. Baby Get Your Head Screwed On.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/07. Baby Get Your Head Screwed On.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/08. Lady.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/08. Lady.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/09. I'm Gonna Get Me A Gun.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/09. I'm Gonna Get Me A Gun.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/10. Kitty.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/10. Kitty.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/11. Northern Wind.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/11. Northern Wind.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/12. Blackness Of The Night.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/12. Blackness Of The Night.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/13. I'm So Sleepy.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/13. I'm So Sleepy.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/14. Come On Baby (Shift That Log).flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/14. Come On Baby (Shift That Log).flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/15. A Bad Night.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/15. A Bad Night.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/16. Lovely City (When Do You Laugh).flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/16. Lovely City (When Do You Laugh).flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/17. Here Comes My Wife.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/17. Here Comes My Wife.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/18. Where Are You.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/18. Where Are You.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/19. The View From The Top.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/19. The View From The Top.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/20. The First Cut Is The Deepest.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/20. The First Cut Is The Deepest.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Adding Item to queue: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/Cat Stevens - First Cuts.m3u8
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Using cached record of: music-library/NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/Cat Stevens - First Cuts.m3u8
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::stop
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::setConsumeUpdateService undefined
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioPushQueue
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CorePlayQueue::saveQueue
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::play index undefined
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::setConsumeUpdateService undefined
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::updateTrackBlock
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrackBlock
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::startPlaybackTimer
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:03 dac.x2-nvme volumio[1341]: verbose: ControllerMpd::clearAddPlayTracks NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/04. Matthew & Son.flac
May 07 19:44:03 dac.x2-nvme volumio[1341]: verbose: ControllerMpd::sendMpdCommand stop
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: sendMpdCommand stop took 2 milliseconds
May 07 19:44:03 dac.x2-nvme volumio[1341]: verbose: ControllerMpd::sendMpdCommand clear
May 07 19:44:03 dac.x2-nvme volumio[1341]: info:
May 07 19:44:03 dac.x2-nvme volumio[1341]: ---------------------------- MPD announces system playlist update
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Ignoring MPD Status Update
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: sendMpdCommand clear took 0 milliseconds
May 07 19:44:03 dac.x2-nvme volumio[1341]: verbose: ControllerMpd::sendMpdCommand add "NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/04. Matthew & Son.flac"
May 07 19:44:03 dac.x2-nvme volumio[1341]: error: updateQueue error: null
May 07 19:44:03 dac.x2-nvme volumio[1341]: info:
May 07 19:44:03 dac.x2-nvme volumio[1341]: ---------------------------- MPD announces system playlist update
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Ignoring MPD Status Update
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: ------------------------------ 1ms
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: sendMpdCommand add "NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/04. Matthew & Son.flac" took 0 milliseconds
May 07 19:44:03 dac.x2-nvme volumio[1341]: verbose: ControllerMpd::sendMpdCommand play
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: ------------------------------ 1ms
May 07 19:44:03 dac.x2-nvme volumio[1341]: error: Upnp client error: Error: This socket has been ended by the other party
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: sendMpdCommand play took 2 milliseconds
May 07 19:44:03 dac.x2-nvme volumio[1341]: info:
May 07 19:44:03 dac.x2-nvme volumio[1341]: ---------------------------- MPD announces state update: player
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: ControllerMpd::getState
May 07 19:44:03 dac.x2-nvme volumio[1341]: verbose: ControllerMpd::sendMpdCommand status
May 07 19:44:03 dac.x2-nvme volumio[1341]: info:
May 07 19:44:03 dac.x2-nvme volumio[1341]: ---------------------------- MPD announces state update: player
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: sendMpdCommand status took 16 milliseconds
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: ControllerMpd::getState
May 07 19:44:03 dac.x2-nvme volumio[1341]: verbose: ControllerMpd::sendMpdCommand status
May 07 19:44:03 dac.x2-nvme volumio[1341]: verbose: ControllerMpd::parseState
May 07 19:44:03 dac.x2-nvme volumio[1341]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: sendMpdCommand status took 1 milliseconds
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: sendMpdCommand playlistinfo took 1 milliseconds
May 07 19:44:03 dac.x2-nvme volumio[1341]: verbose: ControllerMpd::parseState
May 07 19:44:03 dac.x2-nvme volumio[1341]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 07 19:44:03 dac.x2-nvme volumio[1341]: verbose: ControllerMpd::parseTrackInfo
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: ControllerMpd::pushState
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::servicePushState
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:03 dac.x2-nvme volumio[1341]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"149 Kbps","isStreaming":false,"title":"Matthew & Son","artist":"Cat Stevens","album":"First Cuts","uri":"NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/04. Matthew & Son.flac","trackType":"flac"}
May 07 19:44:03 dac.x2-nvme volumio[1341]: verbose: CURRENT POSITION 3
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::syncState stateService play
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::syncState currentStatus stop
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: ------------------------------ 18ms
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: sendMpdCommand playlistinfo took 2 milliseconds
May 07 19:44:03 dac.x2-nvme volumio[1341]: verbose: ControllerMpd::parseTrackInfo
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: ControllerMpd::pushState
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::servicePushState
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:03 dac.x2-nvme volumio[1341]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"693 Kbps","isStreaming":false,"title":"Matthew & Son","artist":"Cat Stevens","album":"First Cuts","uri":"NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/04. Matthew & Son.flac","trackType":"flac"}
May 07 19:44:03 dac.x2-nvme volumio[1341]: verbose: CURRENT POSITION 3
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::syncState stateService play
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::syncState currentStatus play
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: Received an update from plugin. extracting info from payload
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::pushState
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioPushState
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreStateMachine::pushState
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioPushState
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: ------------------------------ 10ms
May 07 19:44:03 dac.x2-nvme volumio[1341]: info: touch_display: Setting screensaver timeout to 0 seconds.
May 07 19:44:05 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:44:06 dac.x2-nvme volumio[1341]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred.
May 07 19:44:06 dac.x2-nvme volumio[1341]: info: Retrying Login Due to Network Error
May 07 19:44:09 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:44:13 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:44:16 dac.x2-nvme volumio[1341]: info: MyVolumio login type: Token
May 07 19:44:17 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:44:21 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:44:21 dac.x2-nvme go-librespot[11229]: time="2025-05-07T19:44:21+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp 35.186.224.24:443: i/o timeout"
May 07 19:44:21 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
May 07 19:44:21 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
May 07 19:44:21 dac.x2-nvme volumio[1341]: (node:1341) UnhandledPromiseRejectionWarning: Error: socket hang up
May 07 19:44:21 dac.x2-nvme volumio[1341]: at connResetException (internal/errors.js:607:14)
May 07 19:44:21 dac.x2-nvme volumio[1341]: at Socket.socketOnEnd (_http_client.js:493:23)
May 07 19:44:21 dac.x2-nvme volumio[1341]: at Socket.emit (events.js:327:22)
May 07 19:44:21 dac.x2-nvme volumio[1341]: at endReadableNT (internal/streams/readable.js:1327:12)
May 07 19:44:21 dac.x2-nvme volumio[1341]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
May 07 19:44:21 dac.x2-nvme volumio[1341]: (node:1341) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 99)
May 07 19:44:21 dac.x2-nvme volumio[1341]: info: Connection to go-librespot Websocket closed
May 07 19:44:24 dac.x2-nvme volumio[1341]: info: Initializing connection to go-librespot Websocket
May 07 19:44:24 dac.x2-nvme volumio[1341]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
May 07 19:44:24 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
May 07 19:44:24 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 98.
May 07 19:44:24 dac.x2-nvme systemd[1]: Stopped go-librespot Daemon.
May 07 19:44:24 dac.x2-nvme systemd[1]: Started go-librespot Daemon.
May 07 19:44:24 dac.x2-nvme go-librespot[11349]: go-librespot daemon starting...
May 07 19:44:24 dac.x2-nvme go-librespot[11349]: time="2025-05-07T19:44:24+08:00" level=info msg="running go-librespot 0.2.0"
May 07 19:44:24 dac.x2-nvme go-librespot[11349]: time="2025-05-07T19:44:24+08:00" level=debug msg="app state loaded"
May 07 19:44:24 dac.x2-nvme go-librespot[11349]: time="2025-05-07T19:44:24+08:00" level=debug msg="stored credentials not found"
May 07 19:44:24 dac.x2-nvme go-librespot[11349]: time="2025-05-07T19:44:24+08:00" level=info msg="api server listening on 127.0.0.1:9879"
May 07 19:44:25 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:44:26 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:26 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:27 dac.x2-nvme volumio[1341]: info: Initializing connection to go-librespot Websocket
May 07 19:44:27 dac.x2-nvme go-librespot[11349]: time="2025-05-07T19:44:27+08:00" level=debug msg="new websocket client"
May 07 19:44:27 dac.x2-nvme volumio[1341]: info: Connection to go-librespot Websocket established
May 07 19:44:29 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:44:30 dac.x2-nvme sudo[11382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 07 19:44:30 dac.x2-nvme sudo[11382]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 19:44:30 dac.x2-nvme sudo[11382]: pam_unix(sudo:session): session closed for user root
May 07 19:44:30 dac.x2-nvme sudo[11385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 07 19:44:30 dac.x2-nvme sudo[11385]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 19:44:30 dac.x2-nvme sudo[11385]: pam_unix(sudo:session): session closed for user root
May 07 19:44:30 dac.x2-nvme volumio[1341]: verbose: New Socket.io Connection to 192.168.3.30 from 192.168.3.16 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: Received Get System Info
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: Discovery: Getting this device information
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetVisibleSources
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: Received Get System Info
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: Discovery: Getting this device information
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: Listing playlists
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetQueue
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreStateMachine::getQueue
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getQueue
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: Getting Spotify volume
May 07 19:44:30 dac.x2-nvme volumio[1341]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:30 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:33 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:44:34 dac.x2-nvme volumio[1341]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9
May 07 19:44:34 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:34 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:34 dac.x2-nvme sudo[11418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 07 19:44:34 dac.x2-nvme sudo[11418]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 19:44:34 dac.x2-nvme sudo[11418]: pam_unix(sudo:session): session closed for user root
May 07 19:44:34 dac.x2-nvme sudo[11421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 07 19:44:34 dac.x2-nvme sudo[11421]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 19:44:34 dac.x2-nvme sudo[11421]: pam_unix(sudo:session): session closed for user root
May 07 19:44:34 dac.x2-nvme volumio[1341]: verbose: New Socket.io Connection to 192.168.3.30 from 192.168.3.16 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10
May 07 19:44:34 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:34 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: Received Get System Info
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: Discovery: Getting this device information
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetVisibleSources
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: Received Get System Info
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: Discovery: Getting this device information
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: Listing playlists
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetQueue
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreStateMachine::getQueue
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getQueue
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: Received Get System Info
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: Discovery: Getting this device information
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioPause
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreStateMachine::pause
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreStateMachine::stPlaybackTimer
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreStateMachine::servicePause
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::servicePause
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: ControllerMpd::pause
May 07 19:44:35 dac.x2-nvme volumio[1341]: verbose: ControllerMpd::sendMpdCommand pause
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: sendMpdCommand pause took 2 milliseconds
May 07 19:44:35 dac.x2-nvme volumio[1341]: info:
May 07 19:44:35 dac.x2-nvme volumio[1341]: ---------------------------- MPD announces state update: player
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: ControllerMpd::getState
May 07 19:44:35 dac.x2-nvme volumio[1341]: verbose: ControllerMpd::sendMpdCommand status
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: sendMpdCommand status took 1 milliseconds
May 07 19:44:35 dac.x2-nvme volumio[1341]: verbose: ControllerMpd::parseState
May 07 19:44:35 dac.x2-nvme volumio[1341]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: sendMpdCommand playlistinfo took 0 milliseconds
May 07 19:44:35 dac.x2-nvme volumio[1341]: verbose: ControllerMpd::parseTrackInfo
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: ControllerMpd::pushState
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::servicePushState
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:35 dac.x2-nvme volumio[1341]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":32924,"duration":167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"734 Kbps","isStreaming":false,"title":"Matthew & Son","artist":"Cat Stevens","album":"First Cuts","uri":"NAS/CloudMusic/Volumio/Cat Stevens - First Cuts/04. Matthew & Son.flac","trackType":"flac"}
May 07 19:44:35 dac.x2-nvme volumio[1341]: verbose: CURRENT POSITION 3
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreStateMachine::syncState stateService pause
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreStateMachine::syncState currentStatus pause
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreStateMachine::pushState
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::volumioPushState
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: CoreStateMachine::stPlaybackTimer
May 07 19:44:35 dac.x2-nvme volumio[1341]: info: ------------------------------ 4ms
May 07 19:44:36 dac.x2-nvme volumio[1341]: info: touch_display: Setting screensaver timeout to 600 seconds.
May 07 19:44:36 dac.x2-nvme volumio[1341]: info: peppy_screensaver: Start PeppyMeter
May 07 19:44:37 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:44:40 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 07 19:44:41 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:44:41 dac.x2-nvme volumiologrotate[777]: ls: cannot access '/var/log/samba/log.wb-DAC.X2': No such file or directory
May 07 19:44:41 dac.x2-nvme volumiologrotate[777]: ls: cannot access 'NVME': No such file or directory
May 07 19:44:42 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::getUIConfigOnPlugin
May 07 19:44:42 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
May 07 19:44:43 dac.x2-nvme volumio[1341]: Disk list : []
May 07 19:44:43 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
May 07 19:44:43 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
May 07 19:44:43 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs
May 07 19:44:43 dac.x2-nvme volumio[1341]: info: Received Get System Version
May 07 19:44:43 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 07 19:44:44 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 07 19:44:45 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:44:46 dac.x2-nvme volumio[1341]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred.
May 07 19:44:46 dac.x2-nvme volumio[1341]: info: Retrying Login Due to Network Error
May 07 19:44:49 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:44:53 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:44:54 dac.x2-nvme go-librespot[11349]: time="2025-05-07T19:44:54+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp 35.186.224.24:443: i/o timeout"
May 07 19:44:54 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
May 07 19:44:54 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
May 07 19:44:54 dac.x2-nvme volumio[1341]: (node:1341) UnhandledPromiseRejectionWarning: Error: socket hang up
May 07 19:44:54 dac.x2-nvme volumio[1341]: at connResetException (internal/errors.js:607:14)
May 07 19:44:54 dac.x2-nvme volumio[1341]: at Socket.socketOnEnd (_http_client.js:493:23)
May 07 19:44:54 dac.x2-nvme volumio[1341]: at Socket.emit (events.js:327:22)
May 07 19:44:54 dac.x2-nvme volumio[1341]: at endReadableNT (internal/streams/readable.js:1327:12)
May 07 19:44:54 dac.x2-nvme volumio[1341]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
May 07 19:44:54 dac.x2-nvme volumio[1341]: (node:1341) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 100)
May 07 19:44:54 dac.x2-nvme volumio[1341]: info: Connection to go-librespot Websocket closed
May 07 19:44:56 dac.x2-nvme volumio[1341]: info: CALLMETHOD: system_controller system saveGeneralSettings [object Object]
May 07 19:44:56 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings
May 07 19:44:56 dac.x2-nvme volumio[1341]: info: System name has changed, restarting Shairport Sync
May 07 19:44:56 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 19:44:56 dac.x2-nvme volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 07 19:44:56 dac.x2-nvme volumio[1341]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 07 19:44:56 dac.x2-nvme volumio[1341]: info: Discovery: Restarting Advertising due to device name change
May 07 19:44:56 dac.x2-nvme sudo[11530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli
May 07 19:44:56 dac.x2-nvme sudo[11530]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 19:44:56 dac.x2-nvme sudo[11530]: pam_unix(sudo:session): session closed for user root
May 07 19:44:56 dac.x2-nvme volumio[1341]: info: Starting Shairport Sync
May 07 19:44:56 dac.x2-nvme systemd[1]: upmpdcli.service: Succeeded.
May 07 19:44:56 dac.x2-nvme sudo[11533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts
May 07 19:44:56 dac.x2-nvme sudo[11533]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 19:44:56 dac.x2-nvme sudo[11533]: pam_unix(sudo:session): session closed for user root
May 07 19:44:56 dac.x2-nvme volumio[1341]: info: Permissions for /etc/hosts set
May 07 19:44:56 dac.x2-nvme sudo[11538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 07 19:44:56 dac.x2-nvme sudo[11538]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 19:44:56 dac.x2-nvme sudo[11541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hostname dac.x2-8.8
May 07 19:44:56 dac.x2-nvme systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 07 19:44:56 dac.x2-nvme sudo[11541]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 19:44:56 dac.x2-8.8 sudo[11541]: pam_unix(sudo:session): session closed for user root
May 07 19:44:56 dac.x2-8.8 systemd[1]: shairport-sync.service: Succeeded.
May 07 19:44:56 dac.x2-8.8 volumio[1341]: info: Hostname now is dac.x2-8.8
May 07 19:44:56 dac.x2-8.8 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 07 19:44:56 dac.x2-8.8 sudo[11545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/
May 07 19:44:56 dac.x2-8.8 sudo[11545]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 19:44:56 dac.x2-8.8 volumio[1341]: info: New hostname set
May 07 19:44:56 dac.x2-8.8 volumio[1341]: info: Permissions for /etc/avahi/services/volumio.service
May 07 19:44:56 dac.x2-8.8 sudo[11545]: pam_unix(sudo:session): session closed for user root
May 07 19:44:56 dac.x2-8.8 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 07 19:44:56 dac.x2-8.8 volumio[1341]: info: Avahi name changed to dac.x2-8.8
May 07 19:44:56 dac.x2-8.8 avahi-daemon[775]: Files changed, reloading.
May 07 19:44:56 dac.x2-8.8 avahi-daemon[775]: Service group file /services/volumio.service changed, reloading.
May 07 19:44:56 dac.x2-8.8 sudo[11538]: pam_unix(sudo:session): session closed for user root
May 07 19:44:56 dac.x2-8.8 volumio[1341]: info: Shairport-Sync Started
May 07 19:44:56 dac.x2-8.8 volumio[1341]: info: MyVolumio login type: Token
May 07 19:44:57 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:44:57 dac.x2-8.8 avahi-daemon[775]: Service "DAC.X2 8.8" (/services/volumio.service) successfully established.
May 07 19:44:57 dac.x2-8.8 volumio[1341]: info: Discovery: A device disappeared from network
May 07 19:44:57 dac.x2-8.8 volumio[1341]: info: Initializing connection to go-librespot Websocket
May 07 19:44:57 dac.x2-8.8 volumio[1341]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
May 07 19:44:57 dac.x2-8.8 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
May 07 19:44:57 dac.x2-8.8 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 99.
May 07 19:44:57 dac.x2-8.8 systemd[1]: Stopped go-librespot Daemon.
May 07 19:44:57 dac.x2-8.8 systemd[1]: Started go-librespot Daemon.
May 07 19:44:57 dac.x2-8.8 go-librespot[11563]: go-librespot daemon starting...
May 07 19:44:57 dac.x2-8.8 go-librespot[11563]: time="2025-05-07T19:44:57+08:00" level=info msg="running go-librespot 0.2.0"
May 07 19:44:57 dac.x2-8.8 go-librespot[11563]: time="2025-05-07T19:44:57+08:00" level=debug msg="app state loaded"
May 07 19:44:57 dac.x2-8.8 go-librespot[11563]: time="2025-05-07T19:44:57+08:00" level=debug msg="stored credentials not found"
May 07 19:44:57 dac.x2-8.8 go-librespot[11563]: time="2025-05-07T19:44:57+08:00" level=info msg="api server listening on 127.0.0.1:9879"
May 07 19:45:00 dac.x2-8.8 volumio[1341]: info: Initializing connection to go-librespot Websocket
May 07 19:45:00 dac.x2-8.8 go-librespot[11563]: time="2025-05-07T19:45:00+08:00" level=debug msg="new websocket client"
May 07 19:45:00 dac.x2-8.8 volumio[1341]: info: Connection to go-librespot Websocket established
May 07 19:45:01 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:45:01 dac.x2-8.8 volumio[1341]: info: Discovery: Started advertising with name: DAC.X2 8.8
May 07 19:45:03 dac.x2-8.8 volumio[1341]: info: Getting Spotify volume
May 07 19:45:03 dac.x2-8.8 volumio[1341]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
May 07 19:45:03 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:45:03 dac.x2-8.8 volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:45:04 dac.x2-8.8 volumio[1341]: info: Discovery: adding d719f35d-b80d-44a9-8bcb-d834a6394d53
May 07 19:45:04 dac.x2-8.8 volumio[1341]: info: Discovery: Found device DAC.X2 8.8
May 07 19:45:04 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:45:04 dac.x2-8.8 volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:45:05 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:45:06 dac.x2-8.8 sudo[11576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf
May 07 19:45:06 dac.x2-8.8 sudo[11576]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 19:45:06 dac.x2-8.8 sudo[11576]: pam_unix(sudo:session): session closed for user root
May 07 19:45:06 dac.x2-8.8 volumio[1341]: info: smb.conf Permissions set
May 07 19:45:06 dac.x2-8.8 sudo[11579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL
May 07 19:45:06 dac.x2-8.8 sudo[11579]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 19:45:06 dac.x2-8.8 sudo[11579]: pam_unix(sudo:session): session closed for user root
May 07 19:45:06 dac.x2-8.8 volumio[1341]: info: Internal perms successfully set
May 07 19:45:06 dac.x2-8.8 sudo[11582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service
May 07 19:45:06 dac.x2-8.8 sudo[11582]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 19:45:06 dac.x2-8.8 nmbd[945]: [2025/05/07 19:45:06.712140, 0] ../source3/nmbd/nmbd.c:58(terminate)
May 07 19:45:06 dac.x2-8.8 nmbd[945]: Got SIGTERM: going down...
May 07 19:45:06 dac.x2-8.8 systemd[1]: Stopping Samba NMB Daemon...
May 07 19:45:06 dac.x2-8.8 systemd[1]: nmbd.service: Succeeded.
May 07 19:45:06 dac.x2-8.8 systemd[1]: Stopped Samba NMB Daemon.
May 07 19:45:06 dac.x2-8.8 sudo[11586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 07 19:45:06 dac.x2-8.8 sudo[11586]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 19:45:06 dac.x2-8.8 systemd[1]: Starting Samba NMB Daemon...
May 07 19:45:06 dac.x2-8.8 sudo[11586]: pam_unix(sudo:session): session closed for user root
May 07 19:45:06 dac.x2-8.8 sudo[11589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 07 19:45:06 dac.x2-8.8 sudo[11589]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 19:45:06 dac.x2-8.8 sudo[11589]: pam_unix(sudo:session): session closed for user root
May 07 19:45:06 dac.x2-8.8 sudo[11592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
May 07 19:45:06 dac.x2-8.8 sudo[11592]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 19:45:06 dac.x2-8.8 systemd[1]: Started UPnP Renderer front-end to MPD.
May 07 19:45:06 dac.x2-8.8 sudo[11592]: pam_unix(sudo:session): session closed for user root
May 07 19:45:06 dac.x2-8.8 volumio[1341]: info: Upmpdcli Daemon Started
May 07 19:45:06 dac.x2-8.8 volumio[11596]: writing RSA key
May 07 19:45:06 dac.x2-8.8 nmbd[11595]: [2025/05/07 19:45:06.792347, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns)
May 07 19:45:06 dac.x2-8.8 nmbd[11595]: started asyncdns process 11618
May 07 19:45:06 dac.x2-8.8 nmbd[11595]: [2025/05/07 19:45:06.793580, 0] ../lib/util/become_daemon.c:138(daemon_ready)
May 07 19:45:06 dac.x2-8.8 nmbd[11595]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
May 07 19:45:06 dac.x2-8.8 systemd[1]: Started Samba NMB Daemon.
May 07 19:45:06 dac.x2-8.8 sudo[11582]: pam_unix(sudo:session): session closed for user root
May 07 19:45:06 dac.x2-8.8 nmbd[11595]: [2025/05/07 19:45:06.799124, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
May 07 19:45:06 dac.x2-8.8 nmbd[11595]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.3.30 for name WORKGROUP<1d>.
May 07 19:45:06 dac.x2-8.8 nmbd[11595]: This response was from IP 192.168.3.31, reporting an IP address of 192.168.3.31.
May 07 19:45:06 dac.x2-8.8 sudo[11620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service
May 07 19:45:06 dac.x2-8.8 sudo[11620]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 19:45:06 dac.x2-8.8 systemd[1]: Stopping Samba SMB Daemon...
May 07 19:45:06 dac.x2-8.8 systemd[1]: smbd.service: Main process exited, code=killed, status=15/TERM
May 07 19:45:06 dac.x2-8.8 systemd[1]: smbd.service: Succeeded.
May 07 19:45:06 dac.x2-8.8 systemd[1]: Stopped Samba SMB Daemon.
May 07 19:45:06 dac.x2-8.8 systemd[1]: Starting Samba SMB Daemon...
May 07 19:45:06 dac.x2-8.8 winbindd[1278]: [2025/05/07 19:45:06.869033, 0] ../source3/winbindd/winbindd_samr.c:672(sam_sid_to_name)
May 07 19:45:06 dac.x2-8.8 winbindd[1278]: sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-1660749277-324904449-4228876893-501
May 07 19:45:06 dac.x2-8.8 winbindd[1278]: [2025/05/07 19:45:06.869518, 0] ../source3/winbindd/winbindd_samr.c:672(sam_sid_to_name)
May 07 19:45:06 dac.x2-8.8 winbindd[1278]: sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-1660749277-324904449-4228876893-514
May 07 19:45:06 dac.x2-8.8 smbd[11623]: [2025/05/07 19:45:06.873399, 0] ../lib/util/become_daemon.c:138(daemon_ready)
May 07 19:45:06 dac.x2-8.8 smbd[11623]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections
May 07 19:45:06 dac.x2-8.8 systemd[1]: Started Samba SMB Daemon.
May 07 19:45:06 dac.x2-8.8 sudo[11620]: pam_unix(sudo:session): session closed for user root
May 07 19:45:06 dac.x2-8.8 volumio[1341]: info: SAMBA Restarted
May 07 19:45:09 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:45:12 dac.x2-8.8 volumio-remote-updater[779]: No test mode
May 07 19:45:12 dac.x2-8.8 volumio-remote-updater[779]: No alpha test mode
May 07 19:45:13 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:45:15 dac.x2-8.8 volumio[1341]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Slow down CD read speed
- Fix NAS Mounting
NEW ADDITIONS
- Revert TIDAL Connect to working version
- Fix for USB DAC not recognized after OTA update
","title":"Update v3.804","updateavailable":true}
May 07 19:45:15 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
May 07 19:45:17 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:45:21 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:45:25 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:45:26 dac.x2-8.8 volumio[1341]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred.
May 07 19:45:26 dac.x2-8.8 volumio[1341]: info: Retrying Login Due to Network Error
May 07 19:45:27 dac.x2-8.8 go-librespot[11563]: time="2025-05-07T19:45:27+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp 35.186.224.24:443: i/o timeout"
May 07 19:45:27 dac.x2-8.8 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
May 07 19:45:27 dac.x2-8.8 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
May 07 19:45:27 dac.x2-8.8 volumio[1341]: (node:1341) UnhandledPromiseRejectionWarning: Error: socket hang up
May 07 19:45:27 dac.x2-8.8 volumio[1341]: at connResetException (internal/errors.js:607:14)
May 07 19:45:27 dac.x2-8.8 volumio[1341]: at Socket.socketOnEnd (_http_client.js:493:23)
May 07 19:45:27 dac.x2-8.8 volumio[1341]: at Socket.emit (events.js:327:22)
May 07 19:45:27 dac.x2-8.8 volumio[1341]: at endReadableNT (internal/streams/readable.js:1327:12)
May 07 19:45:27 dac.x2-8.8 volumio[1341]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
May 07 19:45:27 dac.x2-8.8 volumio[1341]: (node:1341) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 101)
May 07 19:45:27 dac.x2-8.8 volumio[1341]: info: Connection to go-librespot Websocket closed
May 07 19:45:29 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:45:30 dac.x2-8.8 volumio[1341]: info: Initializing connection to go-librespot Websocket
May 07 19:45:30 dac.x2-8.8 volumio[1341]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
May 07 19:45:31 dac.x2-8.8 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
May 07 19:45:31 dac.x2-8.8 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 100.
May 07 19:45:31 dac.x2-8.8 systemd[1]: Stopped go-librespot Daemon.
May 07 19:45:31 dac.x2-8.8 systemd[1]: Started go-librespot Daemon.
May 07 19:45:31 dac.x2-8.8 go-librespot[11696]: go-librespot daemon starting...
May 07 19:45:31 dac.x2-8.8 go-librespot[11696]: time="2025-05-07T19:45:31+08:00" level=info msg="running go-librespot 0.2.0"
May 07 19:45:31 dac.x2-8.8 go-librespot[11696]: time="2025-05-07T19:45:31+08:00" level=debug msg="app state loaded"
May 07 19:45:31 dac.x2-8.8 go-librespot[11696]: time="2025-05-07T19:45:31+08:00" level=debug msg="stored credentials not found"
May 07 19:45:31 dac.x2-8.8 go-librespot[11696]: time="2025-05-07T19:45:31+08:00" level=info msg="api server listening on 127.0.0.1:9879"
May 07 19:45:31 dac.x2-8.8 volumio[1341]: info: Update: [object Object]
May 07 19:45:31 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkSystemIntegrity
May 07 19:45:31 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 07 19:45:32 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::Close All Modals sent
May 07 19:45:33 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:45:33 dac.x2-8.8 volumio[1341]: info: Initializing connection to go-librespot Websocket
May 07 19:45:33 dac.x2-8.8 go-librespot[11696]: time="2025-05-07T19:45:33+08:00" level=debug msg="new websocket client"
May 07 19:45:33 dac.x2-8.8 volumio[1341]: info: Connection to go-librespot Websocket established
May 07 19:45:36 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::Close All Modals sent
May 07 19:45:36 dac.x2-8.8 volumio[1341]: info: MyVolumio login type: Token
May 07 19:45:36 dac.x2-8.8 volumio[1341]: info: Getting Spotify volume
May 07 19:45:37 dac.x2-8.8 volumio[1341]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
May 07 19:45:37 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:45:37 dac.x2-8.8 volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:45:37 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:45:41 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:45:41 dac.x2-8.8 volumiologrotate[777]: ls: cannot access '/var/log/samba/log.wb-DAC.X2': No such file or directory
May 07 19:45:41 dac.x2-8.8 volumiologrotate[777]: ls: cannot access 'NVME': No such file or directory
May 07 19:45:42 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::volumioGetState
May 07 19:45:42 dac.x2-8.8 volumio[1341]: info: CorePlayQueue::getTrack 3
May 07 19:45:45 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::getUIConfigOnPlugin
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: Retrieving Cloud Streaming UI
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: Getting Tidal Cloud Configuration
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::getUIConfigOnPlugin
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: Getting Qobuz Cloud Configuration
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: Asking plugin for UI Config
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::getUIConfigOnPlugin
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: Getting Spotify Cloud Configuration
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: Asking plugin for UI Config
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::getUIConfigOnPlugin
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: Saving Spotify Acccount
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: Got Tidal Cloud Configuration
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: Got it
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: Got it
May 07 19:45:46 dac.x2-8.8 volumio[1341]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::getUIConfigOnPlugin
May 07 19:45:46 dac.x2-8.8 volumio[1341]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::volumioGetBrowseSources
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::volumioGetBrowseSources
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::volumioGetBrowseSources
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:45:46 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
May 07 19:45:49 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:45:50 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:45:53 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:45:54 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:45:57 dac.x2-8.8 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 07 19:45:57 dac.x2-8.8 volumio[1341]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 07 19:45:57 dac.x2-8.8 volumio[1341]: Error: connect ETIMEDOUT 104.244.46.17:443
May 07 19:45:57 dac.x2-8.8 volumio[1341]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) {
May 07 19:45:57 dac.x2-8.8 volumio[1341]: errno: -110,
May 07 19:45:57 dac.x2-8.8 volumio[1341]: code: 'ETIMEDOUT',
May 07 19:45:57 dac.x2-8.8 volumio[1341]: syscall: 'connect',
May 07 19:45:57 dac.x2-8.8 volumio[1341]: address: '104.244.46.17',
May 07 19:45:57 dac.x2-8.8 volumio[1341]: port: 443
May 07 19:45:57 dac.x2-8.8 volumio[1341]: }
May 07 19:45:57 dac.x2-8.8 volumio[1341]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 07 19:45:57 dac.x2-8.8 sudo[11823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-07 19:44
May 07 19:45:57 dac.x2-8.8 sudo[11823]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
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="f6610a1100d2380eef33bd3004af43153c786f96"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET"
VOLUMIO_VERSION="3.785"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"