-- Logs begin at Sat 2024-05-11 19:20:21 CST, end at Sat 2024-05-11 20:46:32 CST. -- May 11 20:45:00 volumio volumio[14626]: info: sendMpdCommand status took 27273 milliseconds May 11 20:45:00 volumio volumio[14626]: info: sendMpdCommand status took 27284 milliseconds May 11 20:45:00 volumio volumio[14626]: info: sendMpdCommand status took 27283 milliseconds May 11 20:45:00 volumio volumio[14626]: verbose: ControllerMpd::parseState May 11 20:45:00 volumio volumio[14626]: info: Command Router : Notfying DB Updatefalse May 11 20:45:00 volumio volumio[14626]: info: CoreCommandRouter::Close All Modals sent May 11 20:45:00 volumio volumio[14626]: verbose: ControllerMpd::parseState May 11 20:45:00 volumio volumio[14626]: verbose: ControllerMpd::parseState May 11 20:45:00 volumio volumio[14626]: info: ControllerMpd::pushState May 11 20:45:00 volumio volumio[14626]: info: CoreCommandRouter::servicePushState May 11 20:45:00 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:00 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:00 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:00 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:00 volumio volumio[14626]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 11 20:45:00 volumio volumio[14626]: verbose: CURRENT POSITION 0 May 11 20:45:00 volumio volumio[14626]: info: CoreStateMachine::syncState stateService stop May 11 20:45:00 volumio volumio[14626]: info: CoreStateMachine::syncState currentStatus stop May 11 20:45:00 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:00 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:00 volumio volumio[14626]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 20:45:00 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:00 volumio volumio[14626]: info: No code May 11 20:45:00 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:00 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:00 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:00 volumio volumio[14626]: info: ControllerMpd::pushState May 11 20:45:00 volumio volumio[14626]: info: CoreCommandRouter::servicePushState May 11 20:45:00 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:00 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:00 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:00 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:00 volumio volumio[14626]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 11 20:45:00 volumio volumio[14626]: verbose: CURRENT POSITION 0 May 11 20:45:00 volumio volumio[14626]: info: CoreStateMachine::syncState stateService stop May 11 20:45:00 volumio volumio[14626]: info: CoreStateMachine::syncState currentStatus stop May 11 20:45:00 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:00 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:00 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:00 volumio volumio[14626]: info: No code May 11 20:45:00 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:00 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:00 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:00 volumio volumio[14626]: info: ------------------------------ 27528ms May 11 20:45:00 volumio volumio[14626]: info: ------------------------------ 27528ms May 11 20:45:09 volumio volumio[14626]: info: sendMpdCommand status took 35734 milliseconds May 11 20:45:09 volumio volumio[14626]: info: sendMpdCommand status took 35744 milliseconds May 11 20:45:09 volumio volumio[14626]: info: sendMpdCommand status took 35743 milliseconds May 11 20:45:09 volumio volumio[14626]: verbose: ControllerMpd::parseState May 11 20:45:09 volumio volumio[14626]: info: Command Router : Notfying DB Updatefalse May 11 20:45:09 volumio volumio[14626]: info: CoreCommandRouter::Close All Modals sent May 11 20:45:09 volumio volumio[14626]: verbose: ControllerMpd::parseState May 11 20:45:09 volumio volumio[14626]: verbose: ControllerMpd::parseState May 11 20:45:09 volumio volumio[14626]: info: ControllerMpd::pushState May 11 20:45:09 volumio volumio[14626]: info: CoreCommandRouter::servicePushState May 11 20:45:09 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:09 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:09 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:09 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:09 volumio volumio[14626]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 11 20:45:09 volumio volumio[14626]: verbose: CURRENT POSITION 0 May 11 20:45:09 volumio volumio[14626]: info: CoreStateMachine::syncState stateService stop May 11 20:45:09 volumio volumio[14626]: info: CoreStateMachine::syncState currentStatus stop May 11 20:45:09 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:09 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:09 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:09 volumio volumio[14626]: info: No code May 11 20:45:09 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:09 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:09 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:09 volumio volumio[14626]: info: ControllerMpd::pushState May 11 20:45:09 volumio volumio[14626]: info: CoreCommandRouter::servicePushState May 11 20:45:09 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:09 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:09 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:09 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:09 volumio volumio[14626]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 11 20:45:09 volumio volumio[14626]: verbose: CURRENT POSITION 0 May 11 20:45:09 volumio volumio[14626]: info: CoreStateMachine::syncState stateService stop May 11 20:45:09 volumio volumio[14626]: info: CoreStateMachine::syncState currentStatus stop May 11 20:45:09 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:09 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:09 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:09 volumio volumio[14626]: info: No code May 11 20:45:09 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:09 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:09 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:09 volumio volumio[14626]: info: ------------------------------ 36011ms May 11 20:45:09 volumio volumio[14626]: info: ------------------------------ 36011ms May 11 20:45:09 volumio volumio[14626]: info: sendMpdCommand status took 18056 milliseconds May 11 20:45:09 volumio volumio[14626]: verbose: ControllerMpd::parseState May 11 20:45:09 volumio volumio[14626]: info: Command Router : Notfying DB Updatefalse May 11 20:45:09 volumio volumio[14626]: info: CoreCommandRouter::Close All Modals sent May 11 20:45:09 volumio volumio[14626]: info: Preload queue cleared May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/01.Chasing Pavements (Live At Hotel Cafe).dsf May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/02.Melt My Heart To Stone (Live At Hotel Cafe).dsf May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/03.That's It, I Quit, I'm Movin On (Live At Hotel Cafe).dsf May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/04.Crazy For You (Live At Hotel Cafe) .dsf May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/05.Right As Rain (Live At Hotel Cafe cnhifi.com) .dsf May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/06.My Same (Live At Hotel Cafe).dsf May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/07.Make You Feel My Love (Live At Hotel Cafe).dsf May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/08.Daydreamer (Live At Hotel Cafe) .dsf May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/09.Hometown Glory (Live At Hotel Cafe) .dsf May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/10.Many Shades Of Black (Performed by The Raconteurs and Adele).dsf May 11 20:45:09 volumio volumio[14626]: info: Preload queue cleared May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/01.Chasing Pavements (Live At Hotel Cafe).dsf May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/02.Melt My Heart To Stone (Live At Hotel Cafe).dsf May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/03.That's It, I Quit, I'm Movin On (Live At Hotel Cafe).dsf May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/04.Crazy For You (Live At Hotel Cafe) .dsf May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/05.Right As Rain (Live At Hotel Cafe cnhifi.com) .dsf May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/06.My Same (Live At Hotel Cafe).dsf May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/07.Make You Feel My Love (Live At Hotel Cafe).dsf May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/08.Daydreamer (Live At Hotel Cafe) .dsf May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/09.Hometown Glory (Live At Hotel Cafe) .dsf May 11 20:45:09 volumio volumio[14626]: info: Preloading song: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/10.Many Shades Of Black (Performed by The Raconteurs and Adele).dsf May 11 20:45:09 volumio volumio[14626]: info: Exploding uri music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/01.Chasing Pavements (Live At Hotel Cafe).dsf in service mpd May 11 20:45:09 volumio volumio[14626]: info: Exploding uri music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/02.Melt My Heart To Stone (Live At Hotel Cafe).dsf in service mpd May 11 20:45:09 volumio volumio[14626]: info: Exploding uri music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/03.That's It, I Quit, I'm Movin On (Live At Hotel Cafe).dsf in service mpd May 11 20:45:09 volumio volumio[14626]: info: Exploding uri music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/04.Crazy For You (Live At Hotel Cafe) .dsf in service mpd May 11 20:45:09 volumio volumio[14626]: info: Exploding uri music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/05.Right As Rain (Live At Hotel Cafe cnhifi.com) .dsf in service mpd May 11 20:45:09 volumio volumio[14626]: info: Exploding uri music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/06.My Same (Live At Hotel Cafe).dsf in service mpd May 11 20:45:09 volumio volumio[14626]: info: Exploding uri music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/07.Make You Feel My Love (Live At Hotel Cafe).dsf in service mpd May 11 20:45:10 volumio volumio[14626]: info: Exploding uri music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/08.Daydreamer (Live At Hotel Cafe) .dsf in service mpd May 11 20:45:10 volumio volumio[14626]: info: Exploding uri music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/09.Hometown Glory (Live At Hotel Cafe) .dsf in service mpd May 11 20:45:10 volumio volumio[14626]: info: Exploding uri music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/10.Many Shades Of Black (Performed by The Raconteurs and Adele).dsf in service mpd May 11 20:45:17 volumio volumio[14626]: info: sendMpdCommand status took 26377 milliseconds May 11 20:45:17 volumio volumio[14626]: info: sendMpdCommand status took 26377 milliseconds May 11 20:45:17 volumio volumio[14626]: info: sendMpdCommand status took 26371 milliseconds May 11 20:45:17 volumio volumio[14626]: info: sendMpdCommand status took 26371 milliseconds May 11 20:45:17 volumio volumio[14626]: verbose: ControllerMpd::parseState May 11 20:45:17 volumio volumio[14626]: info: Command Router : Notfying DB Updatefalse May 11 20:45:17 volumio volumio[14626]: info: CoreCommandRouter::Close All Modals sent May 11 20:45:17 volumio volumio[14626]: verbose: ControllerMpd::parseState May 11 20:45:17 volumio volumio[14626]: verbose: ControllerMpd::parseState May 11 20:45:17 volumio volumio[14626]: verbose: ControllerMpd::parseState May 11 20:45:17 volumio volumio[14626]: info: ControllerMpd::pushState May 11 20:45:17 volumio volumio[14626]: info: CoreCommandRouter::servicePushState May 11 20:45:17 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:17 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:17 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:17 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:17 volumio volumio[14626]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 11 20:45:17 volumio volumio[14626]: verbose: CURRENT POSITION 0 May 11 20:45:17 volumio volumio[14626]: info: CoreStateMachine::syncState stateService stop May 11 20:45:17 volumio volumio[14626]: info: CoreStateMachine::syncState currentStatus stop May 11 20:45:17 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:17 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:17 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:17 volumio volumio[14626]: info: No code May 11 20:45:17 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:17 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:17 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:17 volumio volumio[14626]: info: ControllerMpd::pushState May 11 20:45:17 volumio volumio[14626]: info: CoreCommandRouter::servicePushState May 11 20:45:17 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:17 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:17 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:17 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:17 volumio volumio[14626]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 11 20:45:17 volumio volumio[14626]: verbose: CURRENT POSITION 0 May 11 20:45:17 volumio volumio[14626]: info: CoreStateMachine::syncState stateService stop May 11 20:45:17 volumio volumio[14626]: info: CoreStateMachine::syncState currentStatus stop May 11 20:45:17 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:17 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:17 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:17 volumio volumio[14626]: info: No code May 11 20:45:17 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:17 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:17 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:17 volumio volumio[14626]: info: ControllerMpd::pushState May 11 20:45:17 volumio volumio[14626]: info: CoreCommandRouter::servicePushState May 11 20:45:17 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:17 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:17 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:17 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:17 volumio volumio[14626]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 11 20:45:17 volumio volumio[14626]: verbose: CURRENT POSITION 0 May 11 20:45:17 volumio volumio[14626]: info: CoreStateMachine::syncState stateService stop May 11 20:45:17 volumio volumio[14626]: info: CoreStateMachine::syncState currentStatus stop May 11 20:45:17 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:17 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:17 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:17 volumio volumio[14626]: info: No code May 11 20:45:17 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:17 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:17 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:18 volumio volumio[14626]: info: ------------------------------ 26652ms May 11 20:45:18 volumio volumio[14626]: info: ------------------------------ 26643ms May 11 20:45:18 volumio volumio[14626]: info: ------------------------------ 26644ms May 11 20:45:18 volumio volumio[14626]: info: ALBUMART /albumart?cacheid=812&web=Adele/19%20live/extralarge&path=%2Fmnt%2FNAS%2Fnas%2Fdsd512%2FM-0039-%5BDSD512%5D%20Adele%20-%2019%20live%202024-11-17%20DSD512%2F01.Chasing%20Pavements%20(Live%20At%20Hotel%20Cafe).dsf&metadata=false May 11 20:45:18 volumio volumio[14626]: info: URI /mnt/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/01.Chasing Pavements (Live At Hotel Cafe).dsf May 11 20:45:18 volumio volumio[14626]: info: ALBUMART /albumart?cacheid=812&web=Adele/19%20live/extralarge&path=%2Fmnt%2FNAS%2Fnas%2Fdsd512%2FM-0039-%5BDSD512%5D%20Adele%20-%2019%20live%202024-11-17%20DSD512%2F02.Melt%20My%20Heart%20To%20Stone%20(Live%20At%20Hotel%20Cafe).dsf&metadata=false May 11 20:45:18 volumio volumio[14626]: info: URI /mnt/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/02.Melt My Heart To Stone (Live At Hotel Cafe).dsf May 11 20:45:18 volumio volumio[14626]: info: ALBUMART /albumart?cacheid=812&web=Adele/19%20live/extralarge&path=%2Fmnt%2FNAS%2Fnas%2Fdsd512%2FM-0039-%5BDSD512%5D%20Adele%20-%2019%20live%202024-11-17%20DSD512%2F03.That's%20It%2C%20I%20Quit%2C%20I'm%20Movin%20On%20(Live%20At%20Hotel%20Cafe).dsf&metadata=false May 11 20:45:18 volumio volumio[14626]: info: URI /mnt/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/03.That's It, I Quit, I'm Movin On (Live At Hotel Cafe).dsf May 11 20:45:18 volumio volumio[14626]: info: ALBUMART /albumart?cacheid=812&web=Adele/19%20live/extralarge&path=%2Fmnt%2FNAS%2Fnas%2Fdsd512%2FM-0039-%5BDSD512%5D%20Adele%20-%2019%20live%202024-11-17%20DSD512%2F04.Crazy%20For%20You%20(Live%20At%20Hotel%20Cafe)%20.dsf&metadata=false May 11 20:45:18 volumio volumio[14626]: info: URI /mnt/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/04.Crazy For You (Live At Hotel Cafe) .dsf May 11 20:45:18 volumio volumio[14626]: info: ALBUMART /albumart?cacheid=812&web=Adele/19%20live/extralarge&path=%2Fmnt%2FNAS%2Fnas%2Fdsd512%2FM-0039-%5BDSD512%5D%20Adele%20-%2019%20live%202024-11-17%20DSD512%2F05.Right%20As%20Rain%20(Live%20At%20Hotel%20Cafe%20cnhifi.com)%20.dsf&metadata=false May 11 20:45:18 volumio volumio[14626]: info: URI /mnt/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/05.Right As Rain (Live At Hotel Cafe cnhifi.com) .dsf May 11 20:45:18 volumio volumio[14626]: info: ALBUMART /albumart?cacheid=812&web=Adele/19%20live/extralarge&path=%2Fmnt%2FNAS%2Fnas%2Fdsd512%2FM-0039-%5BDSD512%5D%20Adele%20-%2019%20live%202024-11-17%20DSD512%2F06.My%20Same%20(Live%20At%20Hotel%20Cafe).dsf&metadata=false May 11 20:45:18 volumio volumio[14626]: info: URI /mnt/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/06.My Same (Live At Hotel Cafe).dsf May 11 20:45:18 volumio volumio[14626]: info: ALBUMART /albumart?cacheid=812&web=Adele/19%20live/extralarge&path=%2Fmnt%2FNAS%2Fnas%2Fdsd512%2FM-0039-%5BDSD512%5D%20Adele%20-%2019%20live%202024-11-17%20DSD512%2F07.Make%20You%20Feel%20My%20Love%20(Live%20At%20Hotel%20Cafe).dsf&metadata=false May 11 20:45:18 volumio volumio[14626]: info: URI /mnt/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/07.Make You Feel My Love (Live At Hotel Cafe).dsf May 11 20:45:18 volumio volumio[14626]: info: ALBUMART /albumart?cacheid=812&web=Adele/19%20live/extralarge&path=%2Fmnt%2FNAS%2Fnas%2Fdsd512%2FM-0039-%5BDSD512%5D%20Adele%20-%2019%20live%202024-11-17%20DSD512%2F08.Daydreamer%20(Live%20At%20Hotel%20Cafe)%20.dsf&metadata=false May 11 20:45:18 volumio volumio[14626]: info: URI /mnt/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/08.Daydreamer (Live At Hotel Cafe) .dsf May 11 20:45:18 volumio volumio[14626]: info: ALBUMART /albumart?cacheid=812&web=Adele/19%20live/extralarge&path=%2Fmnt%2FNAS%2Fnas%2Fdsd512%2FM-0039-%5BDSD512%5D%20Adele%20-%2019%20live%202024-11-17%20DSD512%2F09.Hometown%20Glory%20(Live%20At%20Hotel%20Cafe)%20.dsf&metadata=false May 11 20:45:18 volumio volumio[14626]: info: URI /mnt/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/09.Hometown Glory (Live At Hotel Cafe) .dsf May 11 20:45:18 volumio volumio[14626]: info: ALBUMART /albumart?cacheid=812&web=Adele/19%20live/extralarge&path=%2Fmnt%2FNAS%2Fnas%2Fdsd512%2FM-0039-%5BDSD512%5D%20Adele%20-%2019%20live%202024-11-17%20DSD512%2F10.Many%20Shades%20Of%20Black%20(Performed%20by%20The%20Raconteurs%20and%20Adele).dsf&metadata=false May 11 20:45:18 volumio volumio[14626]: info: URI /mnt/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/10.Many Shades Of Black (Performed by The Raconteurs and Adele).dsf May 11 20:45:19 volumio volumio-remote-updater[437]: No test mode May 11 20:45:19 volumio volumio-remote-updater[437]: No alpha test mode May 11 20:45:19 volumio volumio[14626]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 11 20:45:21 volumio volumio[14626]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 11 20:45:21 volumio volumio[14626]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 11 20:45:37 volumio volumio[14626]: info: Preload queue cleared May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::volumioReplaceandPlayItems May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::ClearQueue May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::stop May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::clearPlayQueue May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::saveQueue May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::volumioPushQueue May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::addQueueItems May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::addQueueItems May 11 20:45:37 volumio volumio[14626]: info: Preload queue cleared May 11 20:45:37 volumio volumio[14626]: info: Adding Item to queue: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/01.Chasing Pavements (Live At Hotel Cafe).dsf May 11 20:45:37 volumio volumio[14626]: info: Using cached record of: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/01.Chasing Pavements (Live At Hotel Cafe).dsf May 11 20:45:37 volumio volumio[14626]: info: Adding Item to queue: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/02.Melt My Heart To Stone (Live At Hotel Cafe).dsf May 11 20:45:37 volumio volumio[14626]: info: Using cached record of: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/02.Melt My Heart To Stone (Live At Hotel Cafe).dsf May 11 20:45:37 volumio volumio[14626]: info: Adding Item to queue: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/03.That's It, I Quit, I'm Movin On (Live At Hotel Cafe).dsf May 11 20:45:37 volumio volumio[14626]: info: Using cached record of: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/03.That's It, I Quit, I'm Movin On (Live At Hotel Cafe).dsf May 11 20:45:37 volumio volumio[14626]: info: Adding Item to queue: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/04.Crazy For You (Live At Hotel Cafe) .dsf May 11 20:45:37 volumio volumio[14626]: info: Using cached record of: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/04.Crazy For You (Live At Hotel Cafe) .dsf May 11 20:45:37 volumio volumio[14626]: info: Adding Item to queue: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/05.Right As Rain (Live At Hotel Cafe cnhifi.com) .dsf May 11 20:45:37 volumio volumio[14626]: info: Using cached record of: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/05.Right As Rain (Live At Hotel Cafe cnhifi.com) .dsf May 11 20:45:37 volumio volumio[14626]: info: Adding Item to queue: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/06.My Same (Live At Hotel Cafe).dsf May 11 20:45:37 volumio volumio[14626]: info: Using cached record of: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/06.My Same (Live At Hotel Cafe).dsf May 11 20:45:37 volumio volumio[14626]: info: Adding Item to queue: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/07.Make You Feel My Love (Live At Hotel Cafe).dsf May 11 20:45:37 volumio volumio[14626]: info: Using cached record of: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/07.Make You Feel My Love (Live At Hotel Cafe).dsf May 11 20:45:37 volumio volumio[14626]: info: Adding Item to queue: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/08.Daydreamer (Live At Hotel Cafe) .dsf May 11 20:45:37 volumio volumio[14626]: info: Using cached record of: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/08.Daydreamer (Live At Hotel Cafe) .dsf May 11 20:45:37 volumio volumio[14626]: info: Adding Item to queue: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/09.Hometown Glory (Live At Hotel Cafe) .dsf May 11 20:45:37 volumio volumio[14626]: info: Using cached record of: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/09.Hometown Glory (Live At Hotel Cafe) .dsf May 11 20:45:37 volumio volumio[14626]: info: Adding Item to queue: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/10.Many Shades Of Black (Performed by The Raconteurs and Adele).dsf May 11 20:45:37 volumio volumio[14626]: info: Using cached record of: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/10.Many Shades Of Black (Performed by The Raconteurs and Adele).dsf May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::volumioPushQueue May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::saveQueue May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::updateTrackBlock May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrackBlock May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::volumioPlay May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::play index 0 May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::stop May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::play index undefined May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::startPlaybackTimer May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::clearAddPlayTracks NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/01.Chasing Pavements (Live At Hotel Cafe).dsf May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::sendMpdCommand stop May 11 20:45:37 volumio volumio[14626]: info: sendMpdCommand stop took 14 milliseconds May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::sendMpdCommand clear May 11 20:45:37 volumio volumio[14626]: info: May 11 20:45:37 volumio volumio[14626]: ---------------------------- MPD announces system playlist update May 11 20:45:37 volumio volumio[14626]: info: Ignoring MPD Status Update May 11 20:45:37 volumio volumio[14626]: info: sendMpdCommand clear took 11 milliseconds May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::sendMpdCommand add "NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/01.Chasing Pavements (Live At Hotel Cafe).dsf" May 11 20:45:37 volumio volumio[14626]: info: May 11 20:45:37 volumio volumio[14626]: ---------------------------- MPD announces system playlist update May 11 20:45:37 volumio volumio[14626]: info: Ignoring MPD Status Update May 11 20:45:37 volumio volumio[14626]: info: May 11 20:45:37 volumio volumio[14626]: ---------------------------- MPD announces system playlist update May 11 20:45:37 volumio volumio[14626]: info: Ignoring MPD Status Update May 11 20:45:37 volumio volumio[14626]: error: updateQueue error: null May 11 20:45:37 volumio volumio[14626]: info: May 11 20:45:37 volumio volumio[14626]: ---------------------------- MPD announces system playlist update May 11 20:45:37 volumio volumio[14626]: info: Ignoring MPD Status Update May 11 20:45:37 volumio volumio[14626]: info: ------------------------------ 19ms May 11 20:45:37 volumio volumio[14626]: info: sendMpdCommand add "NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/01.Chasing Pavements (Live At Hotel Cafe).dsf" took 15 milliseconds May 11 20:45:37 volumio volumio[14626]: info: ------------------------------ 14ms May 11 20:45:37 volumio volumio[14626]: info: ------------------------------ 12ms May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::sendMpdCommand play May 11 20:45:37 volumio volumio[14626]: info: May 11 20:45:37 volumio volumio[14626]: ---------------------------- MPD announces system playlist update May 11 20:45:37 volumio volumio[14626]: info: Ignoring MPD Status Update May 11 20:45:37 volumio volumio[14626]: info: May 11 20:45:37 volumio volumio[14626]: ---------------------------- MPD announces system playlist update May 11 20:45:37 volumio volumio[14626]: info: Ignoring MPD Status Update May 11 20:45:37 volumio volumio[14626]: info: May 11 20:45:37 volumio volumio[14626]: ---------------------------- MPD announces state update: player May 11 20:45:37 volumio volumio[14626]: info: ------------------------------ 88ms May 11 20:45:37 volumio volumio[14626]: info: sendMpdCommand play took 82 milliseconds May 11 20:45:37 volumio volumio[14626]: info: ControllerMpd::getState May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::sendMpdCommand status May 11 20:45:37 volumio volumio[14626]: info: ------------------------------ 84ms May 11 20:45:37 volumio volumio[14626]: info: ------------------------------ 79ms May 11 20:45:37 volumio volumio[14626]: info: May 11 20:45:37 volumio volumio[14626]: ---------------------------- MPD announces state update: player May 11 20:45:37 volumio volumio[14626]: info: ControllerMpd::getState May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::sendMpdCommand status May 11 20:45:37 volumio volumio[14626]: info: May 11 20:45:37 volumio volumio[14626]: ---------------------------- MPD announces state update: player May 11 20:45:37 volumio volumio[14626]: info: ControllerMpd::getState May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::sendMpdCommand status May 11 20:45:37 volumio volumio[14626]: info: May 11 20:45:37 volumio volumio[14626]: ---------------------------- MPD announces state update: player May 11 20:45:37 volumio volumio[14626]: info: ControllerMpd::getState May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::sendMpdCommand status May 11 20:45:37 volumio volumio[14626]: info: sendMpdCommand status took 21 milliseconds May 11 20:45:37 volumio volumio[14626]: info: sendMpdCommand status took 16 milliseconds May 11 20:45:37 volumio volumio[14626]: info: sendMpdCommand status took 14 milliseconds May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::parseState May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::parseState May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::parseState May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 20:45:37 volumio volumio[14626]: info: May 11 20:45:37 volumio volumio[14626]: ---------------------------- MPD announces state update: player May 11 20:45:37 volumio volumio[14626]: info: ControllerMpd::getState May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::sendMpdCommand status May 11 20:45:37 volumio volumio[14626]: info: May 11 20:45:37 volumio volumio[14626]: ---------------------------- MPD announces state update: player May 11 20:45:37 volumio volumio[14626]: info: ControllerMpd::getState May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::sendMpdCommand status May 11 20:45:37 volumio volumio[14626]: info: sendMpdCommand status took 36 milliseconds May 11 20:45:37 volumio volumio[14626]: info: sendMpdCommand playlistinfo took 26 milliseconds May 11 20:45:37 volumio volumio[14626]: info: sendMpdCommand playlistinfo took 25 milliseconds May 11 20:45:37 volumio volumio[14626]: info: sendMpdCommand playlistinfo took 25 milliseconds May 11 20:45:37 volumio volumio[14626]: info: sendMpdCommand status took 17 milliseconds May 11 20:45:37 volumio volumio[14626]: info: sendMpdCommand status took 14 milliseconds May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::parseState May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::parseTrackInfo May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::parseState May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::parseState May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 20:45:37 volumio volumio[14626]: info: ControllerMpd::pushState May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::servicePushState May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:37 volumio volumio[14626]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"22.58 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.Chasing Pavements (Live At Hotel Cafe)","artist":"Adele","album":"19 live","uri":"NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/01.Chasing Pavements (Live At Hotel Cafe).dsf","trackType":"dsf"} May 11 20:45:37 volumio volumio[14626]: verbose: CURRENT POSITION 0 May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::syncState stateService play May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::syncState currentStatus stop May 11 20:45:37 volumio volumio[14626]: info: ControllerMpd::pushState May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::servicePushState May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:37 volumio volumio[14626]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"22.58 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.Chasing Pavements (Live At Hotel Cafe)","artist":"Adele","album":"19 live","uri":"NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/01.Chasing Pavements (Live At Hotel Cafe).dsf","trackType":"dsf"} May 11 20:45:37 volumio volumio[14626]: verbose: CURRENT POSITION 0 May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::syncState stateService play May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::syncState currentStatus play May 11 20:45:37 volumio volumio[14626]: info: Received an update from plugin. extracting info from payload May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:37 volumio volumio[14626]: info: ControllerMpd::pushState May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::servicePushState May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:37 volumio volumio[14626]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"22.58 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.Chasing Pavements (Live At Hotel Cafe)","artist":"Adele","album":"19 live","uri":"NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/01.Chasing Pavements (Live At Hotel Cafe).dsf","trackType":"dsf"} May 11 20:45:37 volumio volumio[14626]: verbose: CURRENT POSITION 0 May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::syncState stateService play May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::syncState currentStatus play May 11 20:45:37 volumio volumio[14626]: info: Received an update from plugin. extracting info from payload May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:37 volumio volumio[14626]: info: ------------------------------ 154ms May 11 20:45:37 volumio volumio[14626]: info: ------------------------------ 292ms May 11 20:45:37 volumio volumio[14626]: info: ------------------------------ 290ms May 11 20:45:37 volumio volumio[14626]: info: sendMpdCommand playlistinfo took 248 milliseconds May 11 20:45:37 volumio volumio[14626]: info: sendMpdCommand playlistinfo took 242 milliseconds May 11 20:45:37 volumio volumio[14626]: info: sendMpdCommand playlistinfo took 242 milliseconds May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::parseTrackInfo May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::parseTrackInfo May 11 20:45:37 volumio volumio[14626]: verbose: ControllerMpd::parseTrackInfo May 11 20:45:37 volumio volumio[14626]: info: ControllerMpd::pushState May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::servicePushState May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:37 volumio volumio[14626]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"22.58 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.Chasing Pavements (Live At Hotel Cafe)","artist":"Adele","album":"19 live","uri":"NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/01.Chasing Pavements (Live At Hotel Cafe).dsf","trackType":"dsf"} May 11 20:45:37 volumio volumio[14626]: verbose: CURRENT POSITION 0 May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::syncState stateService play May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::syncState currentStatus play May 11 20:45:37 volumio volumio[14626]: info: Received an update from plugin. extracting info from payload May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:37 volumio volumio[14626]: info: ControllerMpd::pushState May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::servicePushState May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:37 volumio volumio[14626]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"22.58 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.Chasing Pavements (Live At Hotel Cafe)","artist":"Adele","album":"19 live","uri":"NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/01.Chasing Pavements (Live At Hotel Cafe).dsf","trackType":"dsf"} May 11 20:45:37 volumio volumio[14626]: verbose: CURRENT POSITION 0 May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::syncState stateService play May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::syncState currentStatus play May 11 20:45:37 volumio volumio[14626]: info: Received an update from plugin. extracting info from payload May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:37 volumio volumio[14626]: info: ControllerMpd::pushState May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::servicePushState May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:37 volumio volumio[14626]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"22.58 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.Chasing Pavements (Live At Hotel Cafe)","artist":"Adele","album":"19 live","uri":"NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/01.Chasing Pavements (Live At Hotel Cafe).dsf","trackType":"dsf"} May 11 20:45:37 volumio volumio[14626]: verbose: CURRENT POSITION 0 May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::syncState stateService play May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::syncState currentStatus play May 11 20:45:37 volumio volumio[14626]: info: Received an update from plugin. extracting info from payload May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:37 volumio volumio[14626]: info: CoreStateMachine::pushState May 11 20:45:37 volumio volumio[14626]: info: CorePlayQueue::getTrack 0 May 11 20:45:37 volumio volumio[14626]: info: CoreCommandRouter::volumioPushState May 11 20:45:38 volumio volumio[14626]: info: ------------------------------ 482ms May 11 20:45:38 volumio volumio[14626]: info: ------------------------------ 462ms May 11 20:45:38 volumio volumio[14626]: info: ------------------------------ 458ms May 11 20:45:39 volumio kernel: sched: RT throttling activated May 11 20:46:10 volumio volumio[14626]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 20:46:10 volumio volumio[14626]: Error: connect ETIMEDOUT 157.240.8.50:80 May 11 20:46:10 volumio volumio[14626]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 11 20:46:10 volumio volumio[14626]: errno: -110, May 11 20:46:10 volumio volumio[14626]: code: 'ETIMEDOUT', May 11 20:46:10 volumio volumio[14626]: syscall: 'connect', May 11 20:46:10 volumio volumio[14626]: address: '157.240.8.50', May 11 20:46:10 volumio volumio[14626]: port: 80 May 11 20:46:10 volumio volumio[14626]: } May 11 20:46:10 volumio volumio[14626]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 20:46:11 volumio sudo[14953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-11 20:45 May 11 20:46:11 volumio sudo[14953]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:46:11 volumio sudo[14953]: pam_unix(sudo:session): session closed for user root May 11 20:46:14 volumio volumio-remote-updater[437]: [2024-05-11 20:46:14] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 11 20:46:14 volumio volumio-remote-updater[437]: [2024-05-11 20:46:14] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 11 20:46:14 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 11 20:46:14 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 11 20:46:14 volumio systemd[1]: Started dynamicswap service. May 11 20:46:14 volumio systemd[1]: dynamicswap.service: Succeeded. May 11 20:46:14 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 11 20:46:14 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. May 11 20:46:14 volumio systemd[1]: Stopped Volumio Backend Module. May 11 20:46:14 volumio systemd[1]: Started Volumio Backend Module. May 11 20:46:14 volumio systemd[1]: Started dynamicswap service. May 11 20:46:14 volumio systemd[1]: dynamicswap.service: Succeeded. May 11 20:46:17 volumio volumio[14966]: info: ------------------------------------------- May 11 20:46:17 volumio volumio[14966]: info: ----- Volumio3 ---- May 11 20:46:17 volumio volumio[14966]: info: ------------------------------------------- May 11 20:46:17 volumio volumio[14966]: info: ----- System startup ---- May 11 20:46:17 volumio volumio[14966]: info: ------------------------------------------- May 11 20:46:19 volumio volumio[14966]: info: MYVOLUMIO Environment detected May 11 20:46:19 volumio volumio[14966]: info: Plugin folders cleanup May 11 20:46:19 volumio volumio[14966]: info: Scanning into folder /volumio/app/plugins/ May 11 20:46:19 volumio volumio[14966]: info: Scanning category audio_interface May 11 20:46:19 volumio volumio[14966]: info: Scanning category miscellanea May 11 20:46:19 volumio volumio[14966]: info: Scanning category music_service May 11 20:46:19 volumio volumio[14966]: info: Scanning category plugins.json May 11 20:46:19 volumio volumio[14966]: info: Scanning category system_controller May 11 20:46:19 volumio volumio[14966]: info: Scanning category user_interface May 11 20:46:19 volumio volumio[14966]: info: Scanning into folder /data/plugins/ May 11 20:46:19 volumio volumio[14966]: info: Plugin folders cleanup completed May 11 20:46:19 volumio volumio[14966]: info: ------------------------------------------- May 11 20:46:19 volumio volumio[14966]: info: ----- Core plugins startup ---- May 11 20:46:19 volumio volumio[14966]: info: ------------------------------------------- May 11 20:46:19 volumio volumio[14966]: info: Loading plugins from folder /volumio/app/plugins/ May 11 20:46:19 volumio volumio[14966]: info: Adding plugin upnp to MyMusic Plugins May 11 20:46:19 volumio volumio[14966]: info: Adding plugin airplay_emulation to MyMusic Plugins May 11 20:46:19 volumio volumio[14966]: info: Adding plugin upnp_browser to MyMusic Plugins May 11 20:46:19 volumio volumio[14966]: info: Loading plugins from folder /data/plugins/ May 11 20:46:19 volumio volumio[14966]: info: Loading plugin "system"... May 11 20:46:19 volumio volumio[14966]: info: Loading plugin "appearance"... May 11 20:46:19 volumio volumio-remote-updater[437]: [2024-05-11 20:46:19] [connect] Successful connection May 11 20:46:21 volumio volumio[14966]: info: Loading plugin "network"... May 11 20:46:21 volumio volumio[14966]: info: Refreshing Cached IP Addresses May 11 20:46:21 volumio volumio[14966]: info: Loading plugin "services"... May 11 20:46:21 volumio volumio[14966]: info: Loading plugin "alsa_controller"... May 11 20:46:21 volumio sudo[14995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 11 20:46:21 volumio sudo[14995]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:46:21 volumio sudo[14993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 11 20:46:21 volumio sudo[14993]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:46:21 volumio sudo[14995]: pam_unix(sudo:session): session closed for user root May 11 20:46:21 volumio sudo[14993]: pam_unix(sudo:session): session closed for user root May 11 20:46:21 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 20:46:21 volumio volumio[14966]: info: Loading plugin "wizard"... May 11 20:46:21 volumio volumio[14966]: info: Loading plugin "networkfs"... May 11 20:46:21 volumio volumio[14966]: info: Cannot mount NAS nas at system boot, trial number 1 ,retrying in 5 seconds May 11 20:46:21 volumio volumio[14966]: info: Starting Udev Watcher for removable devices May 11 20:46:21 volumio volumio[14966]: info: Ignoring mount for partition: boot May 11 20:46:21 volumio volumio[14966]: info: Ignoring mount for partition: volumio May 11 20:46:21 volumio volumio[14966]: info: Ignoring mount for partition: volumio_data May 11 20:46:21 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 11 20:46:21 volumio volumio[14966]: info: Loading plugin "volumio_command_line_client"... May 11 20:46:21 volumio volumio[14966]: info: Loading plugin "upnp"... May 11 20:46:21 volumio volumio[14966]: info: [1715431581800] Starting Upmpd Daemon May 11 20:46:21 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 11 20:46:21 volumio volumio[14966]: info: Loading plugin "my_music"... May 11 20:46:21 volumio volumio[14966]: info: Loading plugin "mpd"... May 11 20:46:22 volumio volumio[14966]: info: Loading plugin "upnp_browser"... May 11 20:46:22 volumio volumio[14966]: info: Loading plugin "alarm-clock"... May 11 20:46:23 volumio volumio[14966]: info: Loading plugin "airplay_emulation"... May 11 20:46:23 volumio volumio[14966]: info: Starting Shairport Sync May 11 20:46:23 volumio volumio[14966]: info: Loading plugin "last_100"... May 11 20:46:23 volumio volumio[14966]: info: Loading plugin "webradio"... May 11 20:46:23 volumio volumio[14966]: info: Loading plugin "i2s_dacs"... May 11 20:46:23 volumio volumio[14966]: info: I2S DAC not set, start Auto-detection May 11 20:46:23 volumio volumio[14966]: info: Loading plugin "volumiodiscovery"... May 11 20:46:23 volumio volumio[14966]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 11 20:46:23 volumio node[14966]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 11 20:46:23 volumio volumio[14966]: *** WARNING *** Please fix your application to use the native API of Avahi! May 11 20:46:23 volumio node[14966]: *** WARNING *** Please fix your application to use the native API of Avahi! May 11 20:46:23 volumio volumio[14966]: *** WARNING *** For more information see May 11 20:46:23 volumio node[14966]: *** WARNING *** For more information see May 11 20:46:23 volumio volumio[14966]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 11 20:46:23 volumio node[14966]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 11 20:46:23 volumio volumio[14966]: *** WARNING *** Please fix your application to use the native API of Avahi! May 11 20:46:23 volumio node[14966]: *** WARNING *** Please fix your application to use the native API of Avahi! May 11 20:46:23 volumio volumio[14966]: *** WARNING *** For more information see May 11 20:46:23 volumio node[14966]: *** WARNING *** For more information see May 11 20:46:23 volumio volumio[14966]: info: Applying required configuration parameters for plugin volumiodiscovery May 11 20:46:23 volumio volumio[14966]: info: Discovery: Started advertising with name: Volumio May 11 20:46:23 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 11 20:46:23 volumio volumio[14966]: info: Loading plugin "outputs"... May 11 20:46:23 volumio volumio[14966]: info: Loading plugin "albumart"... May 11 20:46:23 volumio volumio[14966]: info: Plugin example_plugin is not enabled May 11 20:46:23 volumio volumio[14966]: info: Loading plugin "inputs"... May 11 20:46:23 volumio volumio[14966]: info: Loading plugin "updater_comm"... May 11 20:46:24 volumio volumio[14966]: info: Plugin mpdemulation is not enabled May 11 20:46:24 volumio volumio[14966]: info: Loading plugin "rest_api"... May 11 20:46:24 volumio volumio[14966]: info: Loading plugin "websocket"... May 11 20:46:24 volumio volumio[14966]: info: Loading i18n strings for locale zh May 11 20:46:24 volumio volumio[14966]: Updating browse sources language May 11 20:46:24 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 20:46:24 volumio volumio[14966]: Forking 1 albumart workers May 11 20:46:24 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 20:46:24 volumio volumio[14966]: info: CoreCommandRouter::initPlayerControls May 11 20:46:24 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:24 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:24 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:24 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:24 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 20:46:24 volumio volumio[14966]: Express server listening on port 3000 May 11 20:46:24 volumio volumio[14966]: [Metrics] WebUI: 7s 831.96ms May 11 20:46:24 volumio volumio[14966]: info: Setting Device type: x86 May 11 20:46:24 volumio volumio[14966]: info: CoreStateMachine::resetVolumioState May 11 20:46:24 volumio volumio[14966]: info: CoreStateMachine::getcurrentVolume May 11 20:46:24 volumio volumio[14966]: info: CoreCommandRouter::volumioRetrievevolume May 11 20:46:24 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:46:25 volumio volumio[14966]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 11 20:46:25 volumio volumio[14966]: info: Completed loading Core Plugins May 11 20:46:25 volumio volumio[14966]: info: Preparing to generate the ALSA configuration file May 11 20:46:25 volumio volumio[14966]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 11 20:46:25 volumio volumio[14966]: verbose: New Socket.io Connection to 192.168.10.100 from 192.168.10.108 UA: okhttp/4.9.2 Total Clients: 1 May 11 20:46:25 volumio volumio[14966]: verbose: New Socket.io Connection to 192.168.10.100 from 192.168.10.108 UA: okhttp/4.9.2 Total Clients: 2 May 11 20:46:25 volumio volumio[14966]: verbose: New Socket.io Connection to 192.168.10.100 from 192.168.10.108 UA: okhttp/4.9.2 Total Clients: 3 May 11 20:46:25 volumio volumio[14966]: verbose: New Socket.io Connection to 192.168.10.100 from 192.168.10.108 UA: okhttp/4.9.2 Total Clients: 4 May 11 20:46:25 volumio volumio[14966]: verbose: New Socket.io Connection to 192.168.10.100 from 192.168.10.108 UA: okhttp/4.9.2 Total Clients: 5 May 11 20:46:25 volumio volumio[14966]: verbose: New Socket.io Connection to 192.168.10.100 from 192.168.10.108 UA: okhttp/4.9.2 Total Clients: 6 May 11 20:46:26 volumio volumio[14966]: verbose: New Socket.io Connection to 192.168.10.100 from 192.168.10.108 UA: okhttp/4.9.2 Total Clients: 7 May 11 20:46:26 volumio volumio[14966]: verbose: New Socket.io Connection to 192.168.10.100 from 192.168.10.108 UA: okhttp/4.9.2 Total Clients: 8 May 11 20:46:26 volumio volumio[14966]: verbose: New Socket.io Connection to 192.168.10.100 from 192.168.10.108 UA: okhttp/4.9.2 Total Clients: 9 May 11 20:46:26 volumio volumio[14966]: verbose: New Socket.io Connection to 192.168.10.100 from 192.168.10.108 UA: okhttp/4.9.2 Total Clients: 10 May 11 20:46:26 volumio volumio[14966]: Starting albumart workers May 11 20:46:26 volumio volumio[14966]: info: Reloading queue from file May 11 20:46:26 volumio volumio[14966]: verbose: New Socket.io Connection to 192.168.10.100 from 192.168.10.108 UA: okhttp/4.9.2 Total Clients: 11 May 11 20:46:26 volumio volumio[14966]: verbose: New Socket.io Connection to 192.168.10.100 from 192.168.10.108 UA: okhttp/4.9.2 Total Clients: 12 May 11 20:46:26 volumio volumio[14966]: info: Asound.conf file unchanged, so no further update is needed May 11 20:46:26 volumio volumio[14966]: info: Output device has changed, restarting MPD May 11 20:46:26 volumio volumio[14966]: info: Output device has changed, restarting Shairport Sync May 11 20:46:26 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:26 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:46:27 volumio sudo[15027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 11 20:46:27 volumio sudo[15027]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:46:27 volumio sudo[15025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 11 20:46:27 volumio sudo[15025]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:46:27 volumio sudo[15025]: pam_unix(sudo:session): session closed for user root May 11 20:46:27 volumio systemd[1]: Stopping Music Player Daemon... May 11 20:46:27 volumio volumio[14966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 11 20:46:27 volumio volumio[14966]: info: ___________ START PLUGINS ___________ May 11 20:46:27 volumio volumio[14966]: info: ControllerMpd::onStart: Initializing MPD May 11 20:46:27 volumio volumio[14966]: info: Creating MPD Configuration file May 11 20:46:27 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 20:46:27 volumio volumio[14966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 11 20:46:27 volumio volumio[14966]: info: [1715431587242] CoreMusicLibrary::Adding element 媒体服务器 May 11 20:46:27 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 20:46:27 volumio systemd[1]: mpd.service: Succeeded. May 11 20:46:27 volumio sudo[15036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 11 20:46:27 volumio systemd[1]: Stopped Music Player Daemon. May 11 20:46:27 volumio sudo[15036]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:46:27 volumio sudo[15034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 11 20:46:27 volumio systemd[1]: Starting Music Player Daemon... May 11 20:46:27 volumio sudo[15034]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:46:27 volumio sudo[15034]: pam_unix(sudo:session): session closed for user root May 11 20:46:27 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 11 20:46:27 volumio systemd[1]: mpd.service: Succeeded. May 11 20:46:27 volumio systemd[1]: Stopped Music Player Daemon. May 11 20:46:27 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:27 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:46:27 volumio systemd[1]: Starting Music Player Daemon... May 11 20:46:27 volumio volumio[14966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 11 20:46:27 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:27 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:46:27 volumio volumio[14966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 11 20:46:27 volumio volumio[14966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 11 20:46:27 volumio volumio[14966]: info: [1715431587763] CoreMusicLibrary::Adding element Last_100 May 11 20:46:27 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 20:46:27 volumio volumio[14966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 11 20:46:27 volumio volumio[14966]: info: [1715431587769] CoreMusicLibrary::Adding element Webradio May 11 20:46:27 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 20:46:27 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 20:46:27 volumio volumio[14966]: info: Volumio Calling Home May 11 20:46:28 volumio volumio[14966]: info: CoreStateMachine::setRepeat null single undefined May 11 20:46:28 volumio volumio[14966]: info: CoreStateMachine::pushState May 11 20:46:28 volumio volumio[14966]: info: CorePlayQueue::getTrack 0 May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::volumioPushState May 11 20:46:28 volumio volumio[14966]: info: CoreStateMachine::setRandom null May 11 20:46:28 volumio volumio[14966]: info: CoreStateMachine::pushState May 11 20:46:28 volumio volumio[14966]: info: CorePlayQueue::getTrack 0 May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::volumioPushState May 11 20:46:28 volumio volumio[14966]: info: VolumeController:: Volume=58 Mute =false May 11 20:46:28 volumio volumio[14966]: info: CoreStateMachine::pushState May 11 20:46:28 volumio volumio[14966]: info: CorePlayQueue::getTrack 0 May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::volumioPushState May 11 20:46:28 volumio volumio[14966]: info: CoreStateMachine::updateTrackBlock May 11 20:46:28 volumio volumio[14966]: info: CorePlayQueue::getTrackBlock May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::volumioRetrievevolume May 11 20:46:28 volumio volumio[14966]: info: Cannot mount NAS nas at system boot, trial number 2 ,retrying in 5 seconds May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::volumioGetState May 11 20:46:28 volumio volumio[14966]: info: CorePlayQueue::getTrack 0 May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 11 20:46:28 volumio volumio[14966]: info: MPD Permissions set May 11 20:46:28 volumio volumio[14966]: info: MPD Permissions set May 11 20:46:28 volumio volumio[14966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 13 May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:46:28 volumio volumio[14966]: info: Discovery: adding cd9403ff-8a2d-4e46-a3a2-b1331691ce51 May 11 20:46:28 volumio volumio[14966]: info: Discovery: Found device Volumio May 11 20:46:28 volumio volumio[14966]: info: CoreCommandRouter::volumioGetState May 11 20:46:28 volumio volumio[14966]: info: CorePlayQueue::getTrack 0 May 11 20:46:29 volumio volumio[14966]: info: VolumeController:: Volume=58 Mute =false May 11 20:46:29 volumio volumio[14966]: info: CoreStateMachine::pushState May 11 20:46:29 volumio volumio[14966]: info: CorePlayQueue::getTrack 0 May 11 20:46:29 volumio volumio[14966]: info: CoreCommandRouter::volumioPushState May 11 20:46:29 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 11 20:46:29 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 11 20:46:29 volumio volumio[14966]: info: CoreCommandRouter::volumioGetVisibleSources May 11 20:46:29 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 20:46:29 volumio volumio[14966]: info: CoreCommandRouter::volumioGetState May 11 20:46:29 volumio volumio[14966]: info: CorePlayQueue::getTrack 0 May 11 20:46:29 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 11 20:46:29 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 11 20:46:29 volumio volumio[14966]: info: Received Get System Info May 11 20:46:29 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 20:46:29 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 20:46:29 volumio volumio[14966]: info: Discovery: Getting this device information May 11 20:46:29 volumio volumio[14966]: info: CoreCommandRouter::volumioGetState May 11 20:46:29 volumio volumio[14966]: info: CorePlayQueue::getTrack 0 May 11 20:46:29 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 20:46:29 volumio volumio[14966]: info: CoreCommandRouter::volumioGetState May 11 20:46:29 volumio volumio[14966]: info: CorePlayQueue::getTrack 0 May 11 20:46:29 volumio volumio[14966]: info: Listing playlists May 11 20:46:29 volumio volumio[14966]: info: CoreCommandRouter::volumioGetQueue May 11 20:46:29 volumio volumio[14966]: info: CoreStateMachine::getQueue May 11 20:46:29 volumio volumio[14966]: info: CorePlayQueue::getQueue May 11 20:46:29 volumio volumio[14966]: info: Starting Shairport Sync May 11 20:46:29 volumio volumio[14966]: info: Starting Shairport Sync May 11 20:46:29 volumio volumio[14966]: info: Starting Shairport Sync May 11 20:46:29 volumio sudo[15074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 11 20:46:29 volumio sudo[15074]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:46:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 11 20:46:29 volumio systemd[1]: shairport-sync.service: Succeeded. May 11 20:46:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 11 20:46:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 11 20:46:29 volumio sudo[15074]: pam_unix(sudo:session): session closed for user root May 11 20:46:29 volumio sudo[15076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 11 20:46:29 volumio sudo[15076]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:46:29 volumio sudo[15079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 11 20:46:29 volumio sudo[15079]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:46:29 volumio volumio[14966]: info: Volumio called home May 11 20:46:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 11 20:46:29 volumio systemd[1]: shairport-sync.service: Succeeded. May 11 20:46:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 11 20:46:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 11 20:46:29 volumio sudo[15076]: pam_unix(sudo:session): session closed for user root May 11 20:46:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 11 20:46:29 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 11 20:46:29 volumio systemd[1]: shairport-sync.service: Succeeded. May 11 20:46:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 11 20:46:30 volumio volumio[14966]: info: VolumeController::SetAlsaVolume0 May 11 20:46:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 11 20:46:30 volumio sudo[15079]: pam_unix(sudo:session): session closed for user root May 11 20:46:30 volumio volumio[14966]: info: CoreStateMachine::pushState May 11 20:46:30 volumio volumio[14966]: info: CorePlayQueue::getTrack 0 May 11 20:46:30 volumio volumio[14966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 20:46:30 volumio volumio[14966]: info: CoreCommandRouter::volumioPushState May 11 20:46:30 volumio volumio[14966]: info: Shairport-Sync Started May 11 20:46:30 volumio volumio[14966]: Error adding Membership: Error: addMembership EINVAL May 11 20:46:30 volumio volumio[14966]: info: Shairport-Sync Started May 11 20:46:30 volumio volumio[14966]: info: Shairport-Sync Started May 11 20:46:30 volumio volumio[14966]: info: CoreCommandRouter::volumioGetState May 11 20:46:30 volumio volumio[14966]: info: CorePlayQueue::getTrack 0 May 11 20:46:31 volumio mpd[15040]: May 11 20:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 11 20:46:31 volumio volumio[14966]: info: Preload queue cleared May 11 20:46:31 volumio volumio[14966]: info: CoreCommandRouter::volumioReplaceandPlayItems May 11 20:46:31 volumio volumio[14966]: info: CoreStateMachine::ClearQueue May 11 20:46:31 volumio volumio[14966]: info: CoreStateMachine::stop May 11 20:46:31 volumio volumio[14966]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 20:46:31 volumio volumio[14966]: info: CorePlayQueue::clearPlayQueue May 11 20:46:31 volumio volumio[14966]: info: CorePlayQueue::saveQueue May 11 20:46:31 volumio volumio[14966]: info: CoreCommandRouter::volumioPushQueue May 11 20:46:31 volumio volumio[14966]: info: CoreStateMachine::addQueueItems May 11 20:46:31 volumio volumio[14966]: info: CorePlayQueue::addQueueItems May 11 20:46:31 volumio volumio[14966]: info: Preload queue cleared May 11 20:46:31 volumio volumio[14966]: info: Adding Item to queue: music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/01.Chasing Pavements (Live At Hotel Cafe).dsf May 11 20:46:31 volumio volumio[14966]: info: Exploding uri music-library/NAS/nas/dsd512/M-0039-[DSD512] Adele - 19 live 2024-11-17 DSD512/01.Chasing Pavements (Live At Hotel Cafe).dsf in service mpd May 11 20:46:31 volumio volumio[14966]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 20:46:31 volumio volumio[14966]: TypeError: Cannot read property 'then' of undefined May 11 20:46:31 volumio volumio[14966]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) May 11 20:46:31 volumio volumio[14966]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) May 11 20:46:31 volumio volumio[14966]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) May 11 20:46:31 volumio volumio[14966]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) May 11 20:46:31 volumio volumio[14966]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) May 11 20:46:31 volumio volumio[14966]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) May 11 20:46:31 volumio volumio[14966]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) May 11 20:46:31 volumio volumio[14966]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:501:23) May 11 20:46:31 volumio volumio[14966]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17) May 11 20:46:31 volumio volumio[14966]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33) May 11 20:46:31 volumio volumio[14966]: at Socket.emit (events.js:400:28) May 11 20:46:31 volumio volumio[14966]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 11 20:46:31 volumio volumio[14966]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 11 20:46:31 volumio volumio[14966]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 20:46:31 volumio systemd[1]: Started Music Player Daemon. May 11 20:46:31 volumio sudo[15036]: pam_unix(sudo:session): session closed for user root May 11 20:46:31 volumio sudo[15027]: pam_unix(sudo:session): session closed for user root May 11 20:46:32 volumio volumio-remote-updater[437]: [2024-05-11 20:46:32] [connect] Successful connection May 11 20:46:32 volumio sudo[15102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-11 20:45 May 11 20:46:32 volumio sudo[15102]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"