May 27 12:04:01 volumio volumio[4238]: info: CoreCommandRouter::volumioSeek May 27 12:04:01 volumio volumio[4238]: info: CoreStateMachine::seek May 27 12:04:01 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:01 volumio volumio[4238]: info: TRACKBLOCK {"uri":"music-library/NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","service":"mpd","name":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=201&web=Leslie%20Cheung/REVISIT/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FMusic_1%2F%E3%80%90MORA%E3%80%91%E5%BC%A0%E5%9B%BD%E8%8D%A3%20--%20REVISIT&metadata=false","duration":274,"trackType":"flac","samplerate":"96 kHz","bitdepth":"24 bit","channels":2} May 27 12:04:01 volumio volumio[4238]: info: CoreStateMachine::startPlaybackTimer May 27 12:04:01 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:01 volumio volumio[4238]: info: ControllerMpd::seek May 27 12:04:01 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:01 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:01 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:01 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:01 volumio volumio[4238]: error: null May 27 12:04:01 volumio volumio[4238]: info: May 27 12:04:01 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:01 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:01 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:01 volumio volumio[4238]: info: May 27 12:04:01 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:01 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:01 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:01 volumio volumio[4238]: info: sendMpdCommand status took 10 milliseconds May 27 12:04:01 volumio volumio[4238]: info: sendMpdCommand status took 8 milliseconds May 27 12:04:01 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:01 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:01 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:01 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:01 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 12:04:01 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:01 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:01 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:01 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:01 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":141000,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","trackType":"flac"} May 27 12:04:01 volumio volumio[4238]: verbose: CURRENT POSITION 7 May 27 12:04:01 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:01 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:01 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:01 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:01 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:01 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:01 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:01 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:01 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:01 volumio volumio[4238]: info: ------------------------------ 41ms May 27 12:04:01 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 30 milliseconds May 27 12:04:01 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:01 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:01 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:01 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:01 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":141000,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","trackType":"flac"} May 27 12:04:01 volumio volumio[4238]: verbose: CURRENT POSITION 7 May 27 12:04:01 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:01 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:01 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:01 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:01 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:01 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:01 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:01 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:01 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:01 volumio volumio[4238]: info: ------------------------------ 60ms May 27 12:04:02 volumio volumio[4238]: info: CoreCommandRouter::volumioSeek May 27 12:04:02 volumio volumio[4238]: info: CoreStateMachine::seek May 27 12:04:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:02 volumio volumio[4238]: info: TRACKBLOCK {"uri":"music-library/NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","service":"mpd","name":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=201&web=Leslie%20Cheung/REVISIT/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FMusic_1%2F%E3%80%90MORA%E3%80%91%E5%BC%A0%E5%9B%BD%E8%8D%A3%20--%20REVISIT&metadata=false","duration":274,"trackType":"flac","samplerate":"96 kHz","bitdepth":"24 bit","channels":2} May 27 12:04:02 volumio volumio[4238]: info: CoreStateMachine::startPlaybackTimer May 27 12:04:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:02 volumio volumio[4238]: info: ControllerMpd::seek May 27 12:04:02 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:02 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:02 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:02 volumio volumio[4238]: error: null May 27 12:04:02 volumio volumio[4238]: info: May 27 12:04:02 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:02 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:02 volumio volumio[4238]: info: May 27 12:04:02 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:02 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:02 volumio volumio[4238]: info: sendMpdCommand status took 9 milliseconds May 27 12:04:02 volumio volumio[4238]: info: sendMpdCommand status took 2 milliseconds May 27 12:04:02 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:02 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:02 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 12:04:02 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:02 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:02 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:02 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":157000,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","trackType":"flac"} May 27 12:04:02 volumio volumio[4238]: verbose: CURRENT POSITION 7 May 27 12:04:02 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:02 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:02 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:02 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:02 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:02 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:02 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:02 volumio volumio[4238]: info: ------------------------------ 45ms May 27 12:04:02 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 35 milliseconds May 27 12:04:02 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:02 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:02 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:02 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":157000,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","trackType":"flac"} May 27 12:04:02 volumio volumio[4238]: verbose: CURRENT POSITION 7 May 27 12:04:02 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:02 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:02 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:02 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:02 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:02 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:02 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:02 volumio volumio[4238]: info: ------------------------------ 68ms May 27 12:04:06 volumio volumio[4238]: info: CoreCommandRouter::volumioSeek May 27 12:04:06 volumio volumio[4238]: info: CoreStateMachine::seek May 27 12:04:06 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:06 volumio volumio[4238]: info: TRACKBLOCK {"uri":"music-library/NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","service":"mpd","name":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=201&web=Leslie%20Cheung/REVISIT/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FMusic_1%2F%E3%80%90MORA%E3%80%91%E5%BC%A0%E5%9B%BD%E8%8D%A3%20--%20REVISIT&metadata=false","duration":274,"trackType":"flac","samplerate":"96 kHz","bitdepth":"24 bit","channels":2} May 27 12:04:06 volumio volumio[4238]: info: CoreStateMachine::startPlaybackTimer May 27 12:04:06 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:06 volumio volumio[4238]: info: ControllerMpd::seek May 27 12:04:06 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:06 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:06 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:06 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:06 volumio volumio[4238]: error: null May 27 12:04:06 volumio volumio[4238]: info: May 27 12:04:06 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:06 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:06 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:06 volumio volumio[4238]: info: May 27 12:04:06 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:06 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:06 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:06 volumio volumio[4238]: info: sendMpdCommand status took 11 milliseconds May 27 12:04:06 volumio volumio[4238]: info: sendMpdCommand status took 9 milliseconds May 27 12:04:06 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:06 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:06 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:06 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:06 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 12:04:06 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 1 milliseconds May 27 12:04:06 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:06 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:06 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:06 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:06 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:06 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":179000,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","trackType":"flac"} May 27 12:04:06 volumio volumio[4238]: verbose: CURRENT POSITION 7 May 27 12:04:06 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:06 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:06 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:06 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:06 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:06 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:06 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:06 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:06 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:06 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:06 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:06 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:06 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":179000,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","trackType":"flac"} May 27 12:04:06 volumio volumio[4238]: verbose: CURRENT POSITION 7 May 27 12:04:06 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:06 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:06 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:06 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:06 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:06 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:06 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:06 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:06 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:06 volumio volumio[4238]: info: ------------------------------ 63ms May 27 12:04:06 volumio volumio[4238]: info: ------------------------------ 62ms May 27 12:04:08 volumio volumio[4238]: info: CoreCommandRouter::volumioSeek May 27 12:04:08 volumio volumio[4238]: info: CoreStateMachine::seek May 27 12:04:08 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:08 volumio volumio[4238]: info: TRACKBLOCK {"uri":"music-library/NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","service":"mpd","name":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=201&web=Leslie%20Cheung/REVISIT/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FMusic_1%2F%E3%80%90MORA%E3%80%91%E5%BC%A0%E5%9B%BD%E8%8D%A3%20--%20REVISIT&metadata=false","duration":274,"trackType":"flac","samplerate":"96 kHz","bitdepth":"24 bit","channels":2} May 27 12:04:08 volumio volumio[4238]: info: CoreStateMachine::startPlaybackTimer May 27 12:04:08 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:08 volumio volumio[4238]: info: ControllerMpd::seek May 27 12:04:08 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:08 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:08 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:08 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:08 volumio volumio[4238]: error: null May 27 12:04:08 volumio volumio[4238]: info: May 27 12:04:08 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:08 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:08 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:08 volumio volumio[4238]: info: May 27 12:04:08 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:08 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:08 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:08 volumio volumio[4238]: info: sendMpdCommand status took 5 milliseconds May 27 12:04:08 volumio volumio[4238]: info: sendMpdCommand status took 4 milliseconds May 27 12:04:08 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:08 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:08 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:08 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:08 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 12:04:08 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 1 milliseconds May 27 12:04:08 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:08 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:08 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:08 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:08 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:08 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":195000,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","trackType":"flac"} May 27 12:04:08 volumio volumio[4238]: verbose: CURRENT POSITION 7 May 27 12:04:08 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:08 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:08 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:08 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:08 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:08 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:08 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:08 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:08 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:08 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:08 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:08 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:08 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":195000,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","trackType":"flac"} May 27 12:04:08 volumio volumio[4238]: verbose: CURRENT POSITION 7 May 27 12:04:08 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:08 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:08 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:08 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:08 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:08 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:08 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:08 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:08 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:08 volumio volumio[4238]: info: ------------------------------ 57ms May 27 12:04:08 volumio volumio[4238]: info: ------------------------------ 57ms May 27 12:04:09 volumio volumio[4238]: info: CoreCommandRouter::volumioSeek May 27 12:04:09 volumio volumio[4238]: info: CoreStateMachine::seek May 27 12:04:09 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:09 volumio volumio[4238]: info: TRACKBLOCK {"uri":"music-library/NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","service":"mpd","name":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=201&web=Leslie%20Cheung/REVISIT/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FMusic_1%2F%E3%80%90MORA%E3%80%91%E5%BC%A0%E5%9B%BD%E8%8D%A3%20--%20REVISIT&metadata=false","duration":274,"trackType":"flac","samplerate":"96 kHz","bitdepth":"24 bit","channels":2} May 27 12:04:09 volumio volumio[4238]: info: CoreStateMachine::startPlaybackTimer May 27 12:04:09 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:09 volumio volumio[4238]: info: ControllerMpd::seek May 27 12:04:09 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:09 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:09 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:09 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:09 volumio volumio[4238]: error: null May 27 12:04:09 volumio volumio[4238]: info: May 27 12:04:09 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:09 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:09 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:09 volumio volumio[4238]: info: May 27 12:04:09 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:09 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:09 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:09 volumio volumio[4238]: info: sendMpdCommand status took 5 milliseconds May 27 12:04:09 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:09 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:09 volumio volumio[4238]: info: sendMpdCommand status took 9 milliseconds May 27 12:04:09 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 5 milliseconds May 27 12:04:09 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:09 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:09 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:09 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:09 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:09 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:09 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":209000,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","trackType":"flac"} May 27 12:04:09 volumio volumio[4238]: verbose: CURRENT POSITION 7 May 27 12:04:09 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:09 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:09 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:09 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:09 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:09 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:09 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:09 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:09 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:09 volumio volumio[4238]: info: ------------------------------ 46ms May 27 12:04:09 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 33 milliseconds May 27 12:04:09 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:09 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:09 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:09 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:09 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":209000,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","trackType":"flac"} May 27 12:04:09 volumio volumio[4238]: verbose: CURRENT POSITION 7 May 27 12:04:09 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:09 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:09 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:09 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:09 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:09 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:09 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:09 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:09 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:09 volumio volumio[4238]: info: ------------------------------ 73ms May 27 12:04:11 volumio volumio[4238]: info: CoreCommandRouter::volumioSeek May 27 12:04:11 volumio volumio[4238]: info: CoreStateMachine::seek May 27 12:04:11 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:11 volumio volumio[4238]: info: TRACKBLOCK {"uri":"music-library/NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","service":"mpd","name":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=201&web=Leslie%20Cheung/REVISIT/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FMusic_1%2F%E3%80%90MORA%E3%80%91%E5%BC%A0%E5%9B%BD%E8%8D%A3%20--%20REVISIT&metadata=false","duration":274,"trackType":"flac","samplerate":"96 kHz","bitdepth":"24 bit","channels":2} May 27 12:04:11 volumio volumio[4238]: info: CoreStateMachine::startPlaybackTimer May 27 12:04:11 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:11 volumio volumio[4238]: info: ControllerMpd::seek May 27 12:04:11 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:11 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:11 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:11 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:11 volumio volumio[4238]: error: null May 27 12:04:11 volumio volumio[4238]: info: May 27 12:04:11 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:11 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:11 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:11 volumio volumio[4238]: info: May 27 12:04:11 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:11 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:11 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:11 volumio volumio[4238]: info: sendMpdCommand status took 6 milliseconds May 27 12:04:11 volumio volumio[4238]: info: sendMpdCommand status took 6 milliseconds May 27 12:04:11 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:11 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:11 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:11 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:11 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 12:04:11 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 1 milliseconds May 27 12:04:11 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:11 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:11 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:11 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:11 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:11 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":237000,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","trackType":"flac"} May 27 12:04:11 volumio volumio[4238]: verbose: CURRENT POSITION 7 May 27 12:04:11 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:11 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:11 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:11 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:11 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:11 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:11 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:11 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:11 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:11 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:11 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:11 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:11 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":237000,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","trackType":"flac"} May 27 12:04:11 volumio volumio[4238]: verbose: CURRENT POSITION 7 May 27 12:04:11 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:11 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:11 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:11 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:11 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:11 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:11 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:11 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:11 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:11 volumio volumio[4238]: info: ------------------------------ 62ms May 27 12:04:11 volumio volumio[4238]: info: ------------------------------ 62ms May 27 12:04:13 volumio volumio[4238]: info: CoreCommandRouter::volumioSeek May 27 12:04:13 volumio volumio[4238]: info: CoreStateMachine::seek May 27 12:04:13 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:13 volumio volumio[4238]: info: TRACKBLOCK {"uri":"music-library/NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","service":"mpd","name":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=201&web=Leslie%20Cheung/REVISIT/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FMusic_1%2F%E3%80%90MORA%E3%80%91%E5%BC%A0%E5%9B%BD%E8%8D%A3%20--%20REVISIT&metadata=false","duration":274,"trackType":"flac","samplerate":"96 kHz","bitdepth":"24 bit","channels":2} May 27 12:04:13 volumio volumio[4238]: info: CoreStateMachine::startPlaybackTimer May 27 12:04:13 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:13 volumio volumio[4238]: info: ControllerMpd::seek May 27 12:04:13 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:13 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:13 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:13 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:13 volumio volumio[4238]: error: null May 27 12:04:13 volumio volumio[4238]: info: May 27 12:04:13 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:13 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:13 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:13 volumio volumio[4238]: info: May 27 12:04:13 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:13 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:13 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:13 volumio volumio[4238]: info: sendMpdCommand status took 7 milliseconds May 27 12:04:13 volumio volumio[4238]: info: sendMpdCommand status took 7 milliseconds May 27 12:04:13 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:13 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:13 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:13 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:13 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 12:04:13 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 1 milliseconds May 27 12:04:13 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:13 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:13 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:13 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:13 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:13 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":250000,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","trackType":"flac"} May 27 12:04:13 volumio volumio[4238]: verbose: CURRENT POSITION 7 May 27 12:04:13 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:13 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:13 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:13 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:13 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:13 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:13 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:13 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:13 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:13 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:13 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:13 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:13 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":250000,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","trackType":"flac"} May 27 12:04:13 volumio volumio[4238]: verbose: CURRENT POSITION 7 May 27 12:04:13 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:13 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:13 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:13 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:13 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:13 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:13 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:13 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:13 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:13 volumio volumio[4238]: info: ------------------------------ 72ms May 27 12:04:13 volumio volumio[4238]: info: ------------------------------ 71ms May 27 12:04:14 volumio volumio[4238]: info: CoreCommandRouter::volumioNext May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::next May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::stop May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::stPlaybackTimer May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::updateTrackBlock May 27 12:04:14 volumio volumio[4238]: info: CorePlayQueue::getTrackBlock May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:14 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:14 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:14 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::serviceStop May 27 12:04:14 volumio volumio[4238]: info: CorePlayQueue::getTrack 7 May 27 12:04:14 volumio volumio[4238]: info: CoreCommandRouter::serviceStop May 27 12:04:14 volumio volumio[4238]: info: ControllerMpd::stop May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand stop May 27 12:04:14 volumio volumio[4238]: info: May 27 12:04:14 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:14 volumio volumio[4238]: info: sendMpdCommand stop took 85 milliseconds May 27 12:04:14 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::play index undefined May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:04:14 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::startPlaybackTimer May 27 12:04:14 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/9.夢到內河 A Rose's Spike.flac May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand stop May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::updateTrackBlock May 27 12:04:14 volumio volumio[4238]: info: CorePlayQueue::getTrackBlock May 27 12:04:14 volumio volumio[4238]: info: sendMpdCommand status took 4 milliseconds May 27 12:04:14 volumio volumio[4238]: info: sendMpdCommand stop took 2 milliseconds May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand clear May 27 12:04:14 volumio volumio[4238]: info: May 27 12:04:14 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:14 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:14 volumio volumio[4238]: info: May 27 12:04:14 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:04:14 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:04:14 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 7 milliseconds May 27 12:04:14 volumio volumio[4238]: info: sendMpdCommand clear took 8 milliseconds May 27 12:04:14 volumio volumio[4238]: info: sendMpdCommand status took 5 milliseconds May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/9.夢到內河 A Rose's Spike.flac" May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:14 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:14 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:14 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:14 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:14 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:14 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:14 volumio volumio[4238]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pei Ni Dao Shu The Sambass & Bossa","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/8.陪你倒數 The Sambass & Bossa.flac","trackType":"flac"} May 27 12:04:14 volumio volumio[4238]: verbose: CURRENT POSITION 8 May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::syncState stateService stop May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus stop May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:14 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:14 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:14 volumio volumio[4238]: info: No code May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:14 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:14 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:14 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:14 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:14 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:14 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:14 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:14 volumio volumio[4238]: 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 27 12:04:14 volumio volumio[4238]: verbose: CURRENT POSITION 8 May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::syncState stateService stop May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus stop May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:14 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:14 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:14 volumio volumio[4238]: info: No code May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:14 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:14 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:14 volumio volumio[4238]: info: ------------------------------ 86ms May 27 12:04:14 volumio volumio[4238]: info: ------------------------------ 78ms May 27 12:04:14 volumio volumio[4238]: info: May 27 12:04:14 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:04:14 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:04:14 volumio volumio[4238]: error: updateQueue error: null May 27 12:04:14 volumio volumio[4238]: info: May 27 12:04:14 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:04:14 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:04:14 volumio volumio[4238]: info: ------------------------------ 77ms May 27 12:04:14 volumio volumio[4238]: info: sendMpdCommand add "NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/9.夢到內河 A Rose's Spike.flac" took 73 milliseconds May 27 12:04:14 volumio volumio[4238]: info: ------------------------------ 2ms May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand play May 27 12:04:14 volumio volumio[4238]: info: May 27 12:04:14 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:04:14 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:04:14 volumio volumio[4238]: info: May 27 12:04:14 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:14 volumio volumio[4238]: info: ------------------------------ 31ms May 27 12:04:14 volumio volumio[4238]: info: sendMpdCommand play took 30 milliseconds May 27 12:04:14 volumio volumio[4238]: info: ------------------------------ 29ms May 27 12:04:14 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:14 volumio volumio[4238]: info: May 27 12:04:14 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:14 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:14 volumio volumio[4238]: info: May 27 12:04:14 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:14 volumio volumio[4238]: info: sendMpdCommand status took 60 milliseconds May 27 12:04:14 volumio volumio[4238]: info: sendMpdCommand status took 59 milliseconds May 27 12:04:14 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:14 volumio volumio[4238]: info: May 27 12:04:14 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:14 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:14 volumio volumio[4238]: info: sendMpdCommand status took 8 milliseconds May 27 12:04:14 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 9 milliseconds May 27 12:04:14 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 9 milliseconds May 27 12:04:14 volumio volumio[4238]: info: sendMpdCommand status took 7 milliseconds May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:14 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:14 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:14 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:14 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:14 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4614 Kbps","isStreaming":false,"title":"Meng Dao Nei He A Rose's Spike","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/9.夢到內河 A Rose's Spike.flac","trackType":"flac"} May 27 12:04:14 volumio volumio[4238]: verbose: CURRENT POSITION 8 May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus stop May 27 12:04:14 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:14 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:14 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:14 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4614 Kbps","isStreaming":false,"title":"Meng Dao Nei He A Rose's Spike","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/9.夢到內河 A Rose's Spike.flac","trackType":"flac"} May 27 12:04:14 volumio volumio[4238]: verbose: CURRENT POSITION 8 May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:14 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:14 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:14 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:14 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:14 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:14 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:14 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:14 volumio volumio[4238]: info: ------------------------------ 106ms May 27 12:04:15 volumio volumio[4238]: info: ------------------------------ 116ms May 27 12:04:15 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 60 milliseconds May 27 12:04:15 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 58 milliseconds May 27 12:04:15 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:15 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:15 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:15 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:15 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:15 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":994,"duration":292,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4614 Kbps","isStreaming":false,"title":"Meng Dao Nei He A Rose's Spike","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/9.夢到內河 A Rose's Spike.flac","trackType":"flac"} May 27 12:04:15 volumio volumio[4238]: verbose: CURRENT POSITION 8 May 27 12:04:15 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:15 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:15 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:15 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:15 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:15 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:15 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:15 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:15 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:15 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:15 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:15 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:15 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:15 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":994,"duration":292,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4614 Kbps","isStreaming":false,"title":"Meng Dao Nei He A Rose's Spike","artist":"Leslie Cheung","album":"REVISIT","uri":"NAS/Music/Music_1/【MORA】张国荣 -- REVISIT/9.夢到內河 A Rose's Spike.flac","trackType":"flac"} May 27 12:04:15 volumio volumio[4238]: verbose: CURRENT POSITION 8 May 27 12:04:15 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:15 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:15 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:15 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:15 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:15 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:15 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:15 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:15 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:15 volumio volumio[4238]: info: ------------------------------ 99ms May 27 12:04:15 volumio volumio[4238]: info: ------------------------------ 96ms May 27 12:04:23 volumio volumio[4238]: info: Preload queue cleared May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::volumioReplaceandPlayItems May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::ClearQueue May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::stop May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::stPlaybackTimer May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::updateTrackBlock May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::getTrackBlock May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::serviceStop May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::getTrack 8 May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::serviceStop May 27 12:04:23 volumio volumio[4238]: info: ControllerMpd::stop May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand stop May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::clearPlayQueue May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::saveQueue May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::volumioPushQueue May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::addQueueItems May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::addQueueItems May 27 12:04:23 volumio volumio[4238]: info: Preload queue cleared May 27 12:04:23 volumio volumio[4238]: info: Adding Item to queue: albums://Riccardo%20Muti%2CWiener%20Philharmoniker/Neujahrskonzert%202025%20%2F%20New%20Year's%20Concert%202025%20%2F%20Concert%20du%20Nouvel%20An%202025 May 27 12:04:23 volumio volumio[4238]: info: Exploding uri albums://Riccardo%20Muti%2CWiener%20Philharmoniker/Neujahrskonzert%202025%20%2F%20New%20Year's%20Concert%202025%20%2F%20Concert%20du%20Nouvel%20An%202025 in service mpd May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::volumioPushQueue May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::saveQueue May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::updateTrackBlock May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::getTrackBlock May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::volumioPlay May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::play index 0 May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::stop May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::play index undefined May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::startPlaybackTimer May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Music_1/2025维也纳新年音乐会 (里卡尔多·穆蒂,维也纳爱乐乐团)/01.自由进行曲 (Freiheits-Marsch, Op. 226).flac May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand stop May 27 12:04:23 volumio volumio[4238]: info: May 27 12:04:23 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:23 volumio volumio[4238]: info: sendMpdCommand stop took 108 milliseconds May 27 12:04:23 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:23 volumio volumio[4238]: info: May 27 12:04:23 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:23 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:23 volumio volumio[4238]: info: sendMpdCommand stop took 68 milliseconds May 27 12:04:23 volumio volumio[4238]: info: sendMpdCommand status took 3 milliseconds May 27 12:04:23 volumio volumio[4238]: info: sendMpdCommand status took 2 milliseconds May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand clear May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:23 volumio volumio[4238]: info: May 27 12:04:23 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:04:23 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:04:23 volumio volumio[4238]: info: May 27 12:04:23 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:04:23 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:04:23 volumio volumio[4238]: error: updateQueue error: null May 27 12:04:23 volumio volumio[4238]: info: sendMpdCommand clear took 4 milliseconds May 27 12:04:23 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 3 milliseconds May 27 12:04:23 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 3 milliseconds May 27 12:04:23 volumio volumio[4238]: info: ------------------------------ 2ms May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Music_1/2025维也纳新年音乐会 (里卡尔多·穆蒂,维也纳爱乐乐团)/01.自由进行曲 (Freiheits-Marsch, Op. 226).flac" May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:23 volumio volumio[4238]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 27 12:04:23 volumio volumio[4238]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 27 12:04:23 volumio volumio[4238]: info: ------------------------------ 14ms May 27 12:04:23 volumio volumio[4238]: info: ------------------------------ 12ms May 27 12:04:23 volumio volumio[4238]: info: May 27 12:04:23 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:04:23 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:04:23 volumio volumio[4238]: error: updateQueue error: null May 27 12:04:23 volumio volumio[4238]: info: May 27 12:04:23 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:04:23 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:04:23 volumio volumio[4238]: info: ------------------------------ 8ms May 27 12:04:23 volumio volumio[4238]: info: sendMpdCommand add "NAS/Music/Music_1/2025维也纳新年音乐会 (里卡尔多·穆蒂,维也纳爱乐乐团)/01.自由进行曲 (Freiheits-Marsch, Op. 226).flac" took 7 milliseconds May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand play May 27 12:04:23 volumio volumio[4238]: info: ------------------------------ 4ms May 27 12:04:23 volumio volumio[4238]: info: ------------------------------ 2ms May 27 12:04:23 volumio volumio[4238]: info: sendMpdCommand play took 5 milliseconds May 27 12:04:23 volumio volumio[4238]: info: May 27 12:04:23 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:23 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:23 volumio volumio[4238]: info: May 27 12:04:23 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:23 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:23 volumio volumio[4238]: info: May 27 12:04:23 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:23 volumio volumio[4238]: info: sendMpdCommand status took 70 milliseconds May 27 12:04:23 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:23 volumio volumio[4238]: info: sendMpdCommand status took 69 milliseconds May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:23 volumio volumio[4238]: info: May 27 12:04:23 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:23 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:23 volumio volumio[4238]: info: sendMpdCommand status took 6 milliseconds May 27 12:04:23 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 6 milliseconds May 27 12:04:23 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 12:04:23 volumio volumio[4238]: info: sendMpdCommand status took 3 milliseconds May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:23 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:23 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":161,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2194 Kbps","isStreaming":false,"title":"Freiheits-Marsch, Op. 226","artist":"Riccardo Muti,Wiener Philharmoniker","album":"Neujahrskonzert 2025 / New Year's Concert 2025 / Concert du Nouvel An 2025","uri":"NAS/Music/Music_1/2025维也纳新年音乐会 (里卡尔多·穆蒂,维也纳爱乐乐团)/01.自由进行曲 (Freiheits-Marsch, Op. 226).flac","trackType":"flac"} May 27 12:04:23 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus stop May 27 12:04:23 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:23 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":615,"duration":161,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2573 Kbps","isStreaming":false,"title":"Freiheits-Marsch, Op. 226","artist":"Riccardo Muti,Wiener Philharmoniker","album":"Neujahrskonzert 2025 / New Year's Concert 2025 / Concert du Nouvel An 2025","uri":"NAS/Music/Music_1/2025维也纳新年音乐会 (里卡尔多·穆蒂,维也纳爱乐乐团)/01.自由进行曲 (Freiheits-Marsch, Op. 226).flac","trackType":"flac"} May 27 12:04:23 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:23 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:23 volumio volumio[4238]: info: ------------------------------ 90ms May 27 12:04:23 volumio volumio[4238]: info: ------------------------------ 96ms May 27 12:04:23 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 20 milliseconds May 27 12:04:23 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 19 milliseconds May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:23 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:23 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:23 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":994,"duration":161,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2733 Kbps","isStreaming":false,"title":"Freiheits-Marsch, Op. 226","artist":"Riccardo Muti,Wiener Philharmoniker","album":"Neujahrskonzert 2025 / New Year's Concert 2025 / Concert du Nouvel An 2025","uri":"NAS/Music/Music_1/2025维也纳新年音乐会 (里卡尔多·穆蒂,维也纳爱乐乐团)/01.自由进行曲 (Freiheits-Marsch, Op. 226).flac","trackType":"flac"} May 27 12:04:23 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:23 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:23 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:23 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":994,"duration":161,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2733 Kbps","isStreaming":false,"title":"Freiheits-Marsch, Op. 226","artist":"Riccardo Muti,Wiener Philharmoniker","album":"Neujahrskonzert 2025 / New Year's Concert 2025 / Concert du Nouvel An 2025","uri":"NAS/Music/Music_1/2025维也纳新年音乐会 (里卡尔多·穆蒂,维也纳爱乐乐团)/01.自由进行曲 (Freiheits-Marsch, Op. 226).flac","trackType":"flac"} May 27 12:04:23 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:23 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:23 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:23 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:23 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:23 volumio volumio[4238]: info: ------------------------------ 51ms May 27 12:04:23 volumio volumio[4238]: info: ------------------------------ 47ms May 27 12:04:26 volumio volumio[4238]: info: Preload queue cleared May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::volumioReplaceandPlayItems May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::ClearQueue May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::stop May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::stPlaybackTimer May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::updateTrackBlock May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrackBlock May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::serviceStop May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::serviceStop May 27 12:04:26 volumio volumio[4238]: info: ControllerMpd::stop May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand stop May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::clearPlayQueue May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::saveQueue May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::volumioPushQueue May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::addQueueItems May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::addQueueItems May 27 12:04:26 volumio volumio[4238]: info: Preload queue cleared May 27 12:04:26 volumio volumio[4238]: info: Adding Item to queue: albums://Various%20Artists/One%20Point%20Real%20Hi-Res%20384 May 27 12:04:26 volumio volumio[4238]: info: Exploding uri albums://Various%20Artists/One%20Point%20Real%20Hi-Res%20384 in service mpd May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::volumioPushQueue May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::saveQueue May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::updateTrackBlock May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrackBlock May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::volumioPlay May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::play index 0 May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::stop May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::play index undefined May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::startPlaybackTimer May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Music_1/Best DXD录音集锦 ONE POINT Real Hi-Res 384KHz (384kHz DXD)/01.法兰多尔舞蹈, 阿莱城的姑娘组曲 No. 2.flac May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand stop May 27 12:04:26 volumio volumio[4238]: info: May 27 12:04:26 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:26 volumio volumio[4238]: info: sendMpdCommand stop took 80 milliseconds May 27 12:04:26 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:26 volumio volumio[4238]: info: sendMpdCommand stop took 64 milliseconds May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand clear May 27 12:04:26 volumio volumio[4238]: info: May 27 12:04:26 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:26 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:26 volumio volumio[4238]: info: May 27 12:04:26 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:04:26 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:04:26 volumio volumio[4238]: info: sendMpdCommand status took 4 milliseconds May 27 12:04:26 volumio volumio[4238]: info: sendMpdCommand clear took 3 milliseconds May 27 12:04:26 volumio volumio[4238]: info: sendMpdCommand status took 4 milliseconds May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Music_1/Best DXD录音集锦 ONE POINT Real Hi-Res 384KHz (384kHz DXD)/01.法兰多尔舞蹈, 阿莱城的姑娘组曲 No. 2.flac" May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:26 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:26 volumio volumio[4238]: 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 27 12:04:26 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::syncState stateService stop May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus stop May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:26 volumio volumio[4238]: info: No code May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:26 volumio volumio[4238]: info: ------------------------------ 18ms May 27 12:04:26 volumio volumio[4238]: info: May 27 12:04:26 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:04:26 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:04:26 volumio volumio[4238]: error: updateQueue error: null May 27 12:04:26 volumio volumio[4238]: info: May 27 12:04:26 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:04:26 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:04:26 volumio volumio[4238]: info: ------------------------------ 19ms May 27 12:04:26 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 19 milliseconds May 27 12:04:26 volumio volumio[4238]: info: sendMpdCommand add "NAS/Music/Music_1/Best DXD录音集锦 ONE POINT Real Hi-Res 384KHz (384kHz DXD)/01.法兰多尔舞蹈, 阿莱城的姑娘组曲 No. 2.flac" took 19 milliseconds May 27 12:04:26 volumio volumio[4238]: info: ------------------------------ 4ms May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand play May 27 12:04:26 volumio volumio[4238]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 27 12:04:26 volumio volumio[4238]: info: ------------------------------ 27ms May 27 12:04:26 volumio volumio[4238]: info: May 27 12:04:26 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:04:26 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:04:26 volumio volumio[4238]: info: ------------------------------ 10ms May 27 12:04:26 volumio volumio[4238]: info: sendMpdCommand play took 21 milliseconds May 27 12:04:26 volumio volumio[4238]: info: ------------------------------ 20ms May 27 12:04:26 volumio volumio[4238]: info: May 27 12:04:26 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:26 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:26 volumio volumio[4238]: info: May 27 12:04:26 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:26 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:26 volumio volumio[4238]: info: May 27 12:04:26 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:26 volumio volumio[4238]: info: sendMpdCommand status took 134 milliseconds May 27 12:04:26 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:26 volumio volumio[4238]: info: May 27 12:04:26 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:26 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:26 volumio volumio[4238]: info: sendMpdCommand status took 130 milliseconds May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:26 volumio volumio[4238]: info: sendMpdCommand status took 9 milliseconds May 27 12:04:26 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 8 milliseconds May 27 12:04:26 volumio volumio[4238]: info: sendMpdCommand status took 8 milliseconds May 27 12:04:26 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 7 milliseconds May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:26 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:26 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":"384 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Farandole, L’Arlésienne Suite No. 2","artist":"Norichika Iimori, Japan Century Symphony Orchestra","album":"One Point Real Hi-Res 384","uri":"NAS/Music/Music_1/Best DXD录音集锦 ONE POINT Real Hi-Res 384KHz (384kHz DXD)/01.法兰多尔舞蹈, 阿莱城的姑娘组曲 No. 2.flac","trackType":"flac"} May 27 12:04:26 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus stop May 27 12:04:26 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:26 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2,"duration":203,"samplerate":"384 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Farandole, L’Arlésienne Suite No. 2","artist":"Norichika Iimori, Japan Century Symphony Orchestra","album":"One Point Real Hi-Res 384","uri":"NAS/Music/Music_1/Best DXD录音集锦 ONE POINT Real Hi-Res 384KHz (384kHz DXD)/01.法兰多尔舞蹈, 阿莱城的姑娘组曲 No. 2.flac","trackType":"flac"} May 27 12:04:26 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:26 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:26 volumio volumio[4238]: info: ------------------------------ 165ms May 27 12:04:26 volumio volumio[4238]: info: ------------------------------ 162ms May 27 12:04:26 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 20 milliseconds May 27 12:04:26 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 20 milliseconds May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:26 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:26 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:26 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":44,"duration":203,"samplerate":"384 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"6237 Kbps","isStreaming":false,"title":"Farandole, L’Arlésienne Suite No. 2","artist":"Norichika Iimori, Japan Century Symphony Orchestra","album":"One Point Real Hi-Res 384","uri":"NAS/Music/Music_1/Best DXD录音集锦 ONE POINT Real Hi-Res 384KHz (384kHz DXD)/01.法兰多尔舞蹈, 阿莱城的姑娘组曲 No. 2.flac","trackType":"flac"} May 27 12:04:26 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:26 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:26 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:26 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49,"duration":203,"samplerate":"384 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"6597 Kbps","isStreaming":false,"title":"Farandole, L’Arlésienne Suite No. 2","artist":"Norichika Iimori, Japan Century Symphony Orchestra","album":"One Point Real Hi-Res 384","uri":"NAS/Music/Music_1/Best DXD录音集锦 ONE POINT Real Hi-Res 384KHz (384kHz DXD)/01.法兰多尔舞蹈, 阿莱城的姑娘组曲 No. 2.flac","trackType":"flac"} May 27 12:04:26 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:26 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:26 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:26 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:26 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:26 volumio volumio[4238]: info: ------------------------------ 67ms May 27 12:04:26 volumio volumio[4238]: info: ------------------------------ 64ms May 27 12:04:29 volumio volumio[4238]: info: Preload queue cleared May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::volumioReplaceandPlayItems May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::ClearQueue May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::stop May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::stPlaybackTimer May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::updateTrackBlock May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrackBlock May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::serviceStop May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::serviceStop May 27 12:04:29 volumio volumio[4238]: info: ControllerMpd::stop May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand stop May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::clearPlayQueue May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::saveQueue May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::volumioPushQueue May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::addQueueItems May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::addQueueItems May 27 12:04:29 volumio volumio[4238]: info: Preload queue cleared May 27 12:04:29 volumio volumio[4238]: info: Adding Item to queue: albums://Yo%20Kigoshi%20(cello)/One%20Point%20Real%20Hi-Res%20384Khz%20Anthology May 27 12:04:29 volumio volumio[4238]: info: Exploding uri albums://Yo%20Kigoshi%20(cello)/One%20Point%20Real%20Hi-Res%20384Khz%20Anthology in service mpd May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::volumioPushQueue May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::saveQueue May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::updateTrackBlock May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrackBlock May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::volumioPlay May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::play index 0 May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::stop May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::play index undefined May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::startPlaybackTimer May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Music_1/One Point Real Hi-Res 384kHz 录音集锦 (11.2MHz DSD)/01.Plerude, Suite No.1 G-dur BWV1007.dsf May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand stop May 27 12:04:29 volumio volumio[4238]: info: sendMpdCommand stop took 96 milliseconds May 27 12:04:29 volumio volumio[4238]: info: May 27 12:04:29 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:29 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:29 volumio volumio[4238]: info: sendMpdCommand stop took 76 milliseconds May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand clear May 27 12:04:29 volumio volumio[4238]: info: May 27 12:04:29 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:29 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:29 volumio volumio[4238]: info: May 27 12:04:29 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:04:29 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:04:29 volumio volumio[4238]: info: sendMpdCommand status took 6 milliseconds May 27 12:04:29 volumio volumio[4238]: info: sendMpdCommand clear took 5 milliseconds May 27 12:04:29 volumio volumio[4238]: info: sendMpdCommand status took 4 milliseconds May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Music_1/One Point Real Hi-Res 384kHz 录音集锦 (11.2MHz DSD)/01.Plerude, Suite No.1 G-dur BWV1007.dsf" May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:29 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:29 volumio volumio[4238]: 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 27 12:04:29 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::syncState stateService stop May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus stop May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:29 volumio volumio[4238]: info: No code May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:29 volumio volumio[4238]: info: ------------------------------ 21ms May 27 12:04:29 volumio volumio[4238]: info: May 27 12:04:29 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:04:29 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:04:29 volumio volumio[4238]: error: updateQueue error: null May 27 12:04:29 volumio volumio[4238]: info: May 27 12:04:29 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:04:29 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:04:29 volumio volumio[4238]: info: ------------------------------ 24ms May 27 12:04:29 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 21 milliseconds May 27 12:04:29 volumio volumio[4238]: info: sendMpdCommand add "NAS/Music/Music_1/One Point Real Hi-Res 384kHz 录音集锦 (11.2MHz DSD)/01.Plerude, Suite No.1 G-dur BWV1007.dsf" took 21 milliseconds May 27 12:04:29 volumio volumio[4238]: info: ------------------------------ 5ms May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand play May 27 12:04:29 volumio volumio[4238]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 27 12:04:29 volumio volumio[4238]: info: ------------------------------ 33ms May 27 12:04:29 volumio volumio[4238]: info: May 27 12:04:29 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:04:29 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:04:29 volumio volumio[4238]: info: ------------------------------ 22ms May 27 12:04:29 volumio volumio[4238]: info: sendMpdCommand play took 20 milliseconds May 27 12:04:29 volumio volumio[4238]: info: ------------------------------ 19ms May 27 12:04:29 volumio volumio[4238]: info: May 27 12:04:29 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:29 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:29 volumio volumio[4238]: info: May 27 12:04:29 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:29 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:29 volumio volumio[4238]: info: May 27 12:04:29 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:29 volumio volumio[4238]: info: sendMpdCommand status took 78 milliseconds May 27 12:04:29 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:29 volumio volumio[4238]: info: sendMpdCommand status took 80 milliseconds May 27 12:04:29 volumio volumio[4238]: info: sendMpdCommand status took 3 milliseconds May 27 12:04:29 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:29 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:29 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":152,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plerude, Suite No.1 G-dur BWV1007","artist":"Yo Kigoshi (cello)","album":"One Point Real Hi-Res 384Khz Anthology","uri":"NAS/Music/Music_1/One Point Real Hi-Res 384kHz 录音集锦 (11.2MHz DSD)/01.Plerude, Suite No.1 G-dur BWV1007.dsf","trackType":"dsf"} May 27 12:04:29 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus stop May 27 12:04:29 volumio volumio[4238]: info: ------------------------------ 90ms May 27 12:04:29 volumio volumio[4238]: info: May 27 12:04:29 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:04:29 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:04:29 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 8 milliseconds May 27 12:04:29 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 6 milliseconds May 27 12:04:29 volumio volumio[4238]: info: sendMpdCommand status took 1 milliseconds May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:04:29 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:29 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":18,"duration":152,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plerude, Suite No.1 G-dur BWV1007","artist":"Yo Kigoshi (cello)","album":"One Point Real Hi-Res 384Khz Anthology","uri":"NAS/Music/Music_1/One Point Real Hi-Res 384kHz 录音集锦 (11.2MHz DSD)/01.Plerude, Suite No.1 G-dur BWV1007.dsf","trackType":"dsf"} May 27 12:04:29 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:29 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:29 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:29 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":277,"duration":152,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plerude, Suite No.1 G-dur BWV1007","artist":"Yo Kigoshi (cello)","album":"One Point Real Hi-Res 384Khz Anthology","uri":"NAS/Music/Music_1/One Point Real Hi-Res 384kHz 录音集锦 (11.2MHz DSD)/01.Plerude, Suite No.1 G-dur BWV1007.dsf","trackType":"dsf"} May 27 12:04:29 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:29 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:29 volumio volumio[4238]: info: ------------------------------ 115ms May 27 12:04:29 volumio volumio[4238]: info: ------------------------------ 38ms May 27 12:04:29 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 26 milliseconds May 27 12:04:29 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:04:29 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:29 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":152,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plerude, Suite No.1 G-dur BWV1007","artist":"Yo Kigoshi (cello)","album":"One Point Real Hi-Res 384Khz Anthology","uri":"NAS/Music/Music_1/One Point Real Hi-Res 384kHz 录音集锦 (11.2MHz DSD)/01.Plerude, Suite No.1 G-dur BWV1007.dsf","trackType":"dsf"} May 27 12:04:29 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:04:29 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:29 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:04:29 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:29 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:04:29 volumio volumio[4238]: info: ------------------------------ 37ms May 27 12:04:39 volumio volumio[4238]: info: CoreCommandRouter::volumioGetState May 27 12:04:39 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:40 volumio volumio[4238]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 12:04:40 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 27 12:04:40 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 12:04:40 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 27 12:04:40 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 12:04:40 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 12:04:40 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 12:04:40 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 12:04:40 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 12:04:40 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 12:04:40 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 12:04:40 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 27 12:04:40 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 27 12:04:57 volumio volumio[4238]: info: CoreCommandRouter::volumioGetState May 27 12:04:57 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:04:58 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 27 12:04:58 volumio volumio[4238]: info: CURURI: albums:// May 27 12:04:58 volumio volumio[4238]: info: listAlbums - loading Albums from cache May 27 12:04:58 volumio volumio[4238]: info: Preload queue cleared May 27 12:05:02 volumio volumio[4238]: info: Preload queue cleared May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::volumioReplaceandPlayItems May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::ClearQueue May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::stop May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::stPlaybackTimer May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::updateTrackBlock May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrackBlock May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::serviceStop May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::serviceStop May 27 12:05:02 volumio volumio[4238]: info: ControllerMpd::stop May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand stop May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::clearPlayQueue May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::saveQueue May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::volumioPushQueue May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::addQueueItems May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::addQueueItems May 27 12:05:02 volumio volumio[4238]: info: Preload queue cleared May 27 12:05:02 volumio volumio[4238]: info: Adding Item to queue: albums://The%20Bassface%20Swing%20Trio%20feat.Bruno%20M%C3%BCller/Bossa%2C%20Ballads%20and%20Blues May 27 12:05:02 volumio volumio[4238]: info: Exploding uri albums://The%20Bassface%20Swing%20Trio%20feat.Bruno%20M%C3%BCller/Bossa%2C%20Ballads%20and%20Blues in service mpd May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::volumioPushQueue May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::saveQueue May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::updateTrackBlock May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrackBlock May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::volumioPlay May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::play index 0 May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::stop May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::play index undefined May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::startPlaybackTimer May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Music_1/Bossa, Ballads and Blues - 0132101/01. Estate DSD 64fs - 2ch.dsf May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand stop May 27 12:05:02 volumio volumio[4238]: info: May 27 12:05:02 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:05:02 volumio volumio[4238]: info: sendMpdCommand stop took 84 milliseconds May 27 12:05:02 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:05:02 volumio volumio[4238]: info: sendMpdCommand stop took 62 milliseconds May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand clear May 27 12:05:02 volumio volumio[4238]: info: May 27 12:05:02 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:05:02 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:05:02 volumio volumio[4238]: info: sendMpdCommand status took 4 milliseconds May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:05:02 volumio volumio[4238]: info: May 27 12:05:02 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:05:02 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:05:02 volumio volumio[4238]: info: sendMpdCommand clear took 5 milliseconds May 27 12:05:02 volumio volumio[4238]: info: sendMpdCommand status took 4 milliseconds May 27 12:05:02 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Music_1/Bossa, Ballads and Blues - 0132101/01. Estate DSD 64fs - 2ch.dsf" May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:05:02 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:02 volumio volumio[4238]: 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 27 12:05:02 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::syncState stateService stop May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus stop May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:02 volumio volumio[4238]: info: No code May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:02 volumio volumio[4238]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 27 12:05:02 volumio volumio[4238]: info: ------------------------------ 20ms May 27 12:05:02 volumio volumio[4238]: info: ------------------------------ 22ms May 27 12:05:02 volumio volumio[4238]: info: May 27 12:05:02 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:05:02 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:05:02 volumio volumio[4238]: error: updateQueue error: null May 27 12:05:02 volumio volumio[4238]: info: May 27 12:05:02 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:05:02 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:05:02 volumio volumio[4238]: info: ------------------------------ 18ms May 27 12:05:02 volumio volumio[4238]: info: sendMpdCommand add "NAS/Music/Music_1/Bossa, Ballads and Blues - 0132101/01. Estate DSD 64fs - 2ch.dsf" took 18 milliseconds May 27 12:05:02 volumio volumio[4238]: info: ------------------------------ 3ms May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand play May 27 12:05:02 volumio volumio[4238]: info: May 27 12:05:02 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:05:02 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:05:02 volumio volumio[4238]: info: ------------------------------ 7ms May 27 12:05:02 volumio volumio[4238]: info: sendMpdCommand play took 6 milliseconds May 27 12:05:02 volumio volumio[4238]: info: ------------------------------ 8ms May 27 12:05:02 volumio volumio[4238]: info: May 27 12:05:02 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:05:02 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:05:02 volumio volumio[4238]: info: May 27 12:05:02 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:05:02 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:05:02 volumio volumio[4238]: info: sendMpdCommand status took 108 milliseconds May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:05:02 volumio volumio[4238]: info: May 27 12:05:02 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:05:02 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:05:02 volumio volumio[4238]: info: May 27 12:05:02 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:05:02 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:05:02 volumio volumio[4238]: info: sendMpdCommand status took 108 milliseconds May 27 12:05:02 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 3 milliseconds May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:05:02 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:02 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":286,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Estate","artist":"The Bassface Swing Trio feat.Bruno Müller","album":"Bossa, Ballads and Blues","uri":"NAS/Music/Music_1/Bossa, Ballads and Blues - 0132101/01. Estate DSD 64fs - 2ch.dsf","trackType":"dsf"} May 27 12:05:02 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus stop May 27 12:05:02 volumio volumio[4238]: info: ------------------------------ 117ms May 27 12:05:02 volumio volumio[4238]: info: sendMpdCommand status took 4 milliseconds May 27 12:05:02 volumio volumio[4238]: info: sendMpdCommand status took 3 milliseconds May 27 12:05:02 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:05:02 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:02 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5,"duration":286,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Estate","artist":"The Bassface Swing Trio feat.Bruno Müller","album":"Bossa, Ballads and Blues","uri":"NAS/Music/Music_1/Bossa, Ballads and Blues - 0132101/01. Estate DSD 64fs - 2ch.dsf","trackType":"dsf"} May 27 12:05:02 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:05:02 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:02 volumio volumio[4238]: info: ------------------------------ 122ms May 27 12:05:02 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 10 milliseconds May 27 12:05:02 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 10 milliseconds May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:05:02 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:05:02 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:02 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":160,"duration":286,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Estate","artist":"The Bassface Swing Trio feat.Bruno Müller","album":"Bossa, Ballads and Blues","uri":"NAS/Music/Music_1/Bossa, Ballads and Blues - 0132101/01. Estate DSD 64fs - 2ch.dsf","trackType":"dsf"} May 27 12:05:02 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:05:02 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:02 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:02 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":263,"duration":286,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Estate","artist":"The Bassface Swing Trio feat.Bruno Müller","album":"Bossa, Ballads and Blues","uri":"NAS/Music/Music_1/Bossa, Ballads and Blues - 0132101/01. Estate DSD 64fs - 2ch.dsf","trackType":"dsf"} May 27 12:05:02 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:05:02 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:02 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:02 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:02 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:02 volumio volumio[4238]: info: ------------------------------ 28ms May 27 12:05:02 volumio volumio[4238]: info: ------------------------------ 28ms May 27 12:05:03 volumio volumio[4238]: info: Preload queue cleared May 27 12:05:03 volumio volumio[4238]: info: CoreCommandRouter::volumioReplaceandPlayItems May 27 12:05:03 volumio volumio[4238]: info: CoreStateMachine::ClearQueue May 27 12:05:03 volumio volumio[4238]: info: CoreStateMachine::stop May 27 12:05:03 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:05:03 volumio volumio[4238]: info: CoreStateMachine::stPlaybackTimer May 27 12:05:03 volumio volumio[4238]: info: CoreStateMachine::updateTrackBlock May 27 12:05:03 volumio volumio[4238]: info: CorePlayQueue::getTrackBlock May 27 12:05:03 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:03 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:03 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:05:03 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:03 volumio volumio[4238]: info: CoreStateMachine::serviceStop May 27 12:05:03 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:03 volumio volumio[4238]: info: CoreCommandRouter::serviceStop May 27 12:05:03 volumio volumio[4238]: info: ControllerMpd::stop May 27 12:05:03 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand stop May 27 12:05:03 volumio volumio[4238]: info: CorePlayQueue::clearPlayQueue May 27 12:05:03 volumio volumio[4238]: info: CorePlayQueue::saveQueue May 27 12:05:03 volumio volumio[4238]: info: CoreCommandRouter::volumioPushQueue May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::addQueueItems May 27 12:05:04 volumio volumio[4238]: info: CorePlayQueue::addQueueItems May 27 12:05:04 volumio volumio[4238]: info: Preload queue cleared May 27 12:05:04 volumio volumio[4238]: info: Adding Item to queue: albums://C%C3%A9line%20Dion/Courage%20(Deluxe%20Edition) May 27 12:05:04 volumio volumio[4238]: info: Exploding uri albums://C%C3%A9line%20Dion/Courage%20(Deluxe%20Edition) in service mpd May 27 12:05:04 volumio volumio[4238]: info: CoreCommandRouter::volumioPushQueue May 27 12:05:04 volumio volumio[4238]: info: CorePlayQueue::saveQueue May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::updateTrackBlock May 27 12:05:04 volumio volumio[4238]: info: CorePlayQueue::getTrackBlock May 27 12:05:04 volumio volumio[4238]: info: CoreCommandRouter::volumioPlay May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::play index 0 May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::stop May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::play index undefined May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:05:04 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::startPlaybackTimer May 27 12:05:04 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Music_1/Céline Dion《勇气 Courage (Deluxe Edition)》2020 FLAC 24-48 [索尼精选]/01. Flying On My Own.flac May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand stop May 27 12:05:04 volumio volumio[4238]: info: sendMpdCommand stop took 119 milliseconds May 27 12:05:04 volumio volumio[4238]: info: May 27 12:05:04 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:05:04 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:05:04 volumio volumio[4238]: info: May 27 12:05:04 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:05:04 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:05:04 volumio volumio[4238]: info: sendMpdCommand stop took 94 milliseconds May 27 12:05:04 volumio volumio[4238]: info: sendMpdCommand status took 1 milliseconds May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand clear May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:05:04 volumio volumio[4238]: info: May 27 12:05:04 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:05:04 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:05:04 volumio volumio[4238]: info: sendMpdCommand status took 3 milliseconds May 27 12:05:04 volumio volumio[4238]: info: sendMpdCommand clear took 2 milliseconds May 27 12:05:04 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Music_1/Céline Dion《勇气 Courage (Deluxe Edition)》2020 FLAC 24-48 [索尼精选]/01. Flying On My Own.flac" May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:05:04 volumio volumio[4238]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 27 12:05:04 volumio volumio[4238]: info: ------------------------------ 7ms May 27 12:05:04 volumio volumio[4238]: info: May 27 12:05:04 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:05:04 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:05:04 volumio volumio[4238]: error: updateQueue error: null May 27 12:05:04 volumio volumio[4238]: info: May 27 12:05:04 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:05:04 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:05:04 volumio volumio[4238]: info: ------------------------------ 5ms May 27 12:05:04 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 12:05:04 volumio volumio[4238]: info: sendMpdCommand add "NAS/Music/Music_1/Céline Dion《勇气 Courage (Deluxe Edition)》2020 FLAC 24-48 [索尼精选]/01. Flying On My Own.flac" took 4 milliseconds May 27 12:05:04 volumio volumio[4238]: info: ------------------------------ 3ms May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand play May 27 12:05:04 volumio volumio[4238]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 27 12:05:04 volumio volumio[4238]: info: ------------------------------ 10ms May 27 12:05:04 volumio volumio[4238]: info: May 27 12:05:04 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:05:04 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:05:04 volumio volumio[4238]: info: ------------------------------ 4ms May 27 12:05:04 volumio volumio[4238]: info: sendMpdCommand play took 4 milliseconds May 27 12:05:04 volumio volumio[4238]: info: ------------------------------ 3ms May 27 12:05:04 volumio volumio[4238]: info: May 27 12:05:04 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:05:04 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:05:04 volumio volumio[4238]: info: May 27 12:05:04 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:05:04 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:05:04 volumio volumio[4238]: info: May 27 12:05:04 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:05:04 volumio volumio[4238]: info: sendMpdCommand status took 139 milliseconds May 27 12:05:04 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:05:04 volumio volumio[4238]: info: May 27 12:05:04 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:05:04 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:05:04 volumio volumio[4238]: info: sendMpdCommand status took 142 milliseconds May 27 12:05:04 volumio volumio[4238]: info: sendMpdCommand status took 6 milliseconds May 27 12:05:04 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 6 milliseconds May 27 12:05:04 volumio volumio[4238]: info: sendMpdCommand status took 6 milliseconds May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:05:04 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:05:04 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:05:04 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:04 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":212,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1361 Kbps","isStreaming":false,"title":"Flying On My Own","artist":"Céline Dion","album":"Courage (Deluxe Edition)","uri":"NAS/Music/Music_1/Céline Dion《勇气 Courage (Deluxe Edition)》2020 FLAC 24-48 [索尼精选]/01. Flying On My Own.flac","trackType":"flac"} May 27 12:05:04 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus stop May 27 12:05:04 volumio volumio[4238]: info: ------------------------------ 149ms May 27 12:05:04 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 5 milliseconds May 27 12:05:04 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 5 milliseconds May 27 12:05:04 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:05:04 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:05:04 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:05:04 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:05:04 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:04 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":212,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1320 Kbps","isStreaming":false,"title":"Flying On My Own","artist":"Céline Dion","album":"Courage (Deluxe Edition)","uri":"NAS/Music/Music_1/Céline Dion《勇气 Courage (Deluxe Edition)》2020 FLAC 24-48 [索尼精选]/01. Flying On My Own.flac","trackType":"flac"} May 27 12:05:04 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:05:04 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:04 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:04 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:05:04 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:04 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:04 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:04 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:05:04 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:05:04 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:04 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":212,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1331 Kbps","isStreaming":false,"title":"Flying On My Own","artist":"Céline Dion","album":"Courage (Deluxe Edition)","uri":"NAS/Music/Music_1/Céline Dion《勇气 Courage (Deluxe Edition)》2020 FLAC 24-48 [索尼精选]/01. Flying On My Own.flac","trackType":"flac"} May 27 12:05:04 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:05:04 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:04 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:04 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:04 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:04 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:04 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:05:04 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:05:04 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:04 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":212,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1331 Kbps","isStreaming":false,"title":"Flying On My Own","artist":"Céline Dion","album":"Courage (Deluxe Edition)","uri":"NAS/Music/Music_1/Céline Dion《勇气 Courage (Deluxe Edition)》2020 FLAC 24-48 [索尼精选]/01. Flying On My Own.flac","trackType":"flac"} May 27 12:05:04 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:05:04 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:04 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:04 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:04 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:04 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:04 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:04 volumio volumio[4238]: info: ------------------------------ 166ms May 27 12:05:04 volumio volumio[4238]: info: ------------------------------ 31ms May 27 12:05:04 volumio volumio[4238]: info: ------------------------------ 30ms May 27 12:05:04 volumio volumio[4238]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/C%C3%A9line%20Dion/Courage%20(Deluxe%20Edition)/29bb2822-6e3e-47c0-8299-c8359edf58ac.jpg' May 27 12:05:05 volumio volumio[4238]: info: Preload queue cleared May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::volumioReplaceandPlayItems May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::ClearQueue May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::stop May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::stPlaybackTimer May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::updateTrackBlock May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrackBlock May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::serviceStop May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::serviceStop May 27 12:05:05 volumio volumio[4238]: info: ControllerMpd::stop May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand stop May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::clearPlayQueue May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::saveQueue May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::volumioPushQueue May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::addQueueItems May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::addQueueItems May 27 12:05:05 volumio volumio[4238]: info: Preload queue cleared May 27 12:05:05 volumio volumio[4238]: info: Adding Item to queue: albums://Various/Closer%20To%20The%20Music%20Vol.%201 May 27 12:05:05 volumio volumio[4238]: info: Exploding uri albums://Various/Closer%20To%20The%20Music%20Vol.%201 in service mpd May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::volumioPushQueue May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::saveQueue May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::updateTrackBlock May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrackBlock May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::volumioPlay May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::play index 0 May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::stop May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::play index undefined May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::startPlaybackTimer May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Music_1/Closer To The Music Vol. 1/1.Beat Hotel.flac May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand stop May 27 12:05:05 volumio volumio[4238]: info: May 27 12:05:05 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:05:05 volumio volumio[4238]: info: sendMpdCommand stop took 87 milliseconds May 27 12:05:05 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:05:05 volumio volumio[4238]: info: sendMpdCommand stop took 60 milliseconds May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand clear May 27 12:05:05 volumio volumio[4238]: info: May 27 12:05:05 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:05:05 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:05:05 volumio volumio[4238]: info: May 27 12:05:05 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:05:05 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:05:05 volumio volumio[4238]: info: sendMpdCommand status took 3 milliseconds May 27 12:05:05 volumio volumio[4238]: info: sendMpdCommand clear took 3 milliseconds May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Music_1/Closer To The Music Vol. 1/1.Beat Hotel.flac" May 27 12:05:05 volumio volumio[4238]: info: May 27 12:05:05 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:05:05 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:05:05 volumio volumio[4238]: error: updateQueue error: null May 27 12:05:05 volumio volumio[4238]: info: sendMpdCommand status took 4 milliseconds May 27 12:05:05 volumio volumio[4238]: info: ------------------------------ 4ms May 27 12:05:05 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 3 milliseconds May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:05:05 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:05 volumio volumio[4238]: 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 27 12:05:05 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::syncState stateService stop May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus stop May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:05 volumio volumio[4238]: info: No code May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:05 volumio volumio[4238]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 27 12:05:05 volumio volumio[4238]: info: ------------------------------ 19ms May 27 12:05:05 volumio volumio[4238]: info: ------------------------------ 21ms May 27 12:05:05 volumio volumio[4238]: info: May 27 12:05:05 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:05:05 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:05:05 volumio volumio[4238]: info: sendMpdCommand add "NAS/Music/Music_1/Closer To The Music Vol. 1/1.Beat Hotel.flac" took 18 milliseconds May 27 12:05:05 volumio volumio[4238]: info: ------------------------------ 17ms May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand play May 27 12:05:05 volumio volumio[4238]: info: May 27 12:05:05 volumio volumio[4238]: ---------------------------- MPD announces system playlist update May 27 12:05:05 volumio volumio[4238]: info: Ignoring MPD Status Update May 27 12:05:05 volumio volumio[4238]: info: ------------------------------ 10ms May 27 12:05:05 volumio volumio[4238]: info: sendMpdCommand play took 9 milliseconds May 27 12:05:05 volumio volumio[4238]: info: ------------------------------ 8ms May 27 12:05:05 volumio volumio[4238]: info: May 27 12:05:05 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:05:05 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:05:05 volumio volumio[4238]: info: May 27 12:05:05 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:05:05 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:05:05 volumio volumio[4238]: info: May 27 12:05:05 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:05:05 volumio volumio[4238]: info: sendMpdCommand status took 56 milliseconds May 27 12:05:05 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:05:05 volumio volumio[4238]: info: sendMpdCommand status took 56 milliseconds May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:05:05 volumio volumio[4238]: info: May 27 12:05:05 volumio volumio[4238]: ---------------------------- MPD announces state update: player May 27 12:05:05 volumio volumio[4238]: info: ControllerMpd::getState May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand status May 27 12:05:05 volumio volumio[4238]: info: sendMpdCommand status took 4 milliseconds May 27 12:05:05 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 12:05:05 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 12:05:05 volumio volumio[4238]: info: sendMpdCommand status took 4 milliseconds May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::parseState May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 12:05:05 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:05 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":416,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"979 Kbps","isStreaming":false,"title":"Beat Hotel","artist":"Allan Taylor","album":"Closer To The Music Vol. 1","uri":"NAS/Music/Music_1/Closer To The Music Vol. 1/1.Beat Hotel.flac","trackType":"flac"} May 27 12:05:05 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus stop May 27 12:05:05 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:05 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":606,"duration":416,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1024 Kbps","isStreaming":false,"title":"Beat Hotel","artist":"Allan Taylor","album":"Closer To The Music Vol. 1","uri":"NAS/Music/Music_1/Closer To The Music Vol. 1/1.Beat Hotel.flac","trackType":"flac"} May 27 12:05:05 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:05:05 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:05 volumio volumio[4238]: info: ------------------------------ 67ms May 27 12:05:05 volumio volumio[4238]: info: ------------------------------ 69ms May 27 12:05:05 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 9 milliseconds May 27 12:05:05 volumio volumio[4238]: info: sendMpdCommand playlistinfo took 9 milliseconds May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:05:05 volumio volumio[4238]: verbose: ControllerMpd::parseTrackInfo May 27 12:05:05 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:05 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":416,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1485 Kbps","isStreaming":false,"title":"Beat Hotel","artist":"Allan Taylor","album":"Closer To The Music Vol. 1","uri":"NAS/Music/Music_1/Closer To The Music Vol. 1/1.Beat Hotel.flac","trackType":"flac"} May 27 12:05:05 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:05:05 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:05 volumio volumio[4238]: info: ControllerMpd::pushState May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::servicePushState May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:05 volumio volumio[4238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":416,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1485 Kbps","isStreaming":false,"title":"Beat Hotel","artist":"Allan Taylor","album":"Closer To The Music Vol. 1","uri":"NAS/Music/Music_1/Closer To The Music Vol. 1/1.Beat Hotel.flac","trackType":"flac"} May 27 12:05:05 volumio volumio[4238]: verbose: CURRENT POSITION 0 May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::syncState stateService play May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::syncState currentStatus play May 27 12:05:05 volumio volumio[4238]: info: Received an update from plugin. extracting info from payload May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:05 volumio volumio[4238]: info: CoreStateMachine::pushState May 27 12:05:05 volumio volumio[4238]: info: CorePlayQueue::getTrack 0 May 27 12:05:05 volumio volumio[4238]: info: CoreCommandRouter::volumioPushState May 27 12:05:05 volumio volumio[4238]: info: ------------------------------ 25ms May 27 12:05:05 volumio volumio[4238]: info: ------------------------------ 23ms May 27 12:05:08 volumio volumio[4238]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 12:05:08 volumio volumio[4238]: Error: connect ETIMEDOUT 185.60.218.50:443 May 27 12:05:08 volumio volumio[4238]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { May 27 12:05:08 volumio volumio[4238]: errno: -110, May 27 12:05:08 volumio volumio[4238]: code: 'ETIMEDOUT', May 27 12:05:08 volumio volumio[4238]: syscall: 'connect', May 27 12:05:08 volumio volumio[4238]: address: '185.60.218.50', May 27 12:05:08 volumio volumio[4238]: port: 443 May 27 12:05:08 volumio volumio[4238]: } May 27 12:05:08 volumio volumio[4238]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 12:05:09 volumio sudo[25113]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-27 12:04' May 27 12:05:09 volumio sudo[25113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 12:05:09 volumio sudo[25113]: pam_unix(sudo:session): session closed for user root May 27 12:05:11 volumio volumio-remote-updater[751]: [2026-05-27 04:05:11] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 27 12:05:11 volumio volumio-remote-updater[751]: [2026-05-27 04:05:11] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 27 12:05:11 volumio volumio5-onboarding[2172]: time=2026-05-27T04:05:11.208Z level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" May 27 12:05:11 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 27 12:05:11 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 27 12:05:11 volumio systemd[1]: volumio.service: Consumed 1min 46.714s CPU time. May 27 12:05:11 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. May 27 12:05:11 volumio volumio5-onboarding[2172]: time=2026-05-27T04:05:11.328Z level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" May 27 12:05:11 volumio systemd[1]: dynamicswap.service: Deactivated successfully. May 27 12:05:11 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. May 27 12:05:11 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. May 27 12:05:11 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. May 27 12:05:11 volumio systemd[1]: volumio.service: Consumed 1min 46.714s CPU time. May 27 12:05:11 volumio systemd[1]: Started volumio.service - Volumio Backend Module. May 27 12:05:11 volumio systemd[1]: dynamicswap.service: Deactivated successfully. May 27 12:05:12 volumio volumio5-onboarding[2172]: time=2026-05-27T04:05:12.330Z level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" May 27 12:05:13 volumio volumio[25145]: info: ------------------------------------------- May 27 12:05:13 volumio volumio[25145]: info: ----- Volumio3 ---- May 27 12:05:13 volumio volumio[25145]: info: ------------------------------------------- May 27 12:05:13 volumio volumio[25145]: info: ----- System startup ---- May 27 12:05:13 volumio volumio[25145]: info: ------------------------------------------- May 27 12:05:13 volumio volumio[25145]: info: MYVOLUMIO Environment detected May 27 12:05:13 volumio volumio[25145]: info: Plugin folders cleanup May 27 12:05:13 volumio volumio[25145]: info: Scanning into folder /volumio/app/plugins/ May 27 12:05:13 volumio volumio[25145]: info: Scanning category audio_interface May 27 12:05:13 volumio volumio[25145]: info: Scanning category miscellanea May 27 12:05:13 volumio volumio[25145]: info: Scanning category music_service May 27 12:05:13 volumio volumio[25145]: info: Scanning category plugins.json May 27 12:05:13 volumio volumio[25145]: info: Scanning category system_controller May 27 12:05:13 volumio volumio[25145]: info: Scanning category user_interface May 27 12:05:13 volumio volumio[25145]: info: Scanning into folder /data/plugins/ May 27 12:05:13 volumio volumio[25145]: info: Plugin folders cleanup completed May 27 12:05:13 volumio volumio[25145]: info: ------------------------------------------- May 27 12:05:13 volumio volumio[25145]: info: ----- Core plugins startup ---- May 27 12:05:13 volumio volumio[25145]: info: ------------------------------------------- May 27 12:05:13 volumio volumio[25145]: info: Loading plugins from folder /volumio/app/plugins/ May 27 12:05:13 volumio volumio[25145]: info: Adding plugin upnp to MyMusic Plugins May 27 12:05:13 volumio volumio[25145]: info: Adding plugin airplay_emulation to MyMusic Plugins May 27 12:05:13 volumio volumio[25145]: info: Adding plugin upnp_browser to MyMusic Plugins May 27 12:05:13 volumio volumio[25145]: info: Loading plugins from folder /data/plugins/ May 27 12:05:13 volumio volumio[25145]: info: Loading plugin "system"... May 27 12:05:13 volumio volumio[25145]: info: Loading plugin "appearance"... May 27 12:05:14 volumio volumio[25145]: info: Loading plugin "network"... May 27 12:05:14 volumio volumio[25145]: info: Refreshing Cached IP Addresses May 27 12:05:14 volumio sudo[25175]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 27 12:05:14 volumio sudo[25175]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 12:05:14 volumio sudo[25177]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 12:05:14 volumio sudo[25177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 12:05:14 volumio sudo[25175]: pam_unix(sudo:session): session closed for user root May 27 12:05:14 volumio volumio[25145]: info: Loading plugin "services"... May 27 12:05:14 volumio sudo[25177]: pam_unix(sudo:session): session closed for user root May 27 12:05:14 volumio volumio[25145]: info: Loading plugin "volumio5onboarding"... May 27 12:05:14 volumio sudo[25184]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 27 12:05:14 volumio sudo[25184]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 12:05:14 volumio volumio[25145]: info: Loading plugin "alsa_controller"... May 27 12:05:14 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 27 12:05:14 volumio volumio[25145]: info: Loading plugin "wizard"... May 27 12:05:14 volumio volumio[25145]: info: Loading plugin "networkfs"... May 27 12:05:14 volumio volumio[25145]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds May 27 12:05:14 volumio volumio[25145]: info: Starting Udev Watcher for removable devices May 27 12:05:14 volumio volumio[25145]: info: Ignoring mount for partition: boot May 27 12:05:14 volumio volumio[25145]: info: Ignoring mount for partition: volumio May 27 12:05:14 volumio volumio[25145]: info: Ignoring mount for partition: volumio_data May 27 12:05:14 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 27 12:05:14 volumio volumio[25145]: info: Loading plugin "volumio_command_line_client"... May 27 12:05:14 volumio volumio[25145]: info: Loading plugin "upnp"... May 27 12:05:14 volumio volumio[25145]: info: [1779854714637] Starting Upmpd Daemon May 27 12:05:14 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 27 12:05:14 volumio volumio[25145]: info: Loading plugin "my_music"... May 27 12:05:14 volumio volumio[25145]: info: Loading plugin "mpd"... May 27 12:05:14 volumio volumio[25145]: info: Loading plugin "upnp_browser"... May 27 12:05:15 volumio sudo[25184]: pam_unix(sudo:session): session closed for user root May 27 12:05:15 volumio volumio[25145]: info: Starting UPNP Browser May 27 12:05:15 volumio volumio[25145]: info: Loading plugin "alarm-clock"... May 27 12:05:15 volumio volumio[25145]: info: Loading plugin "airplay_emulation"... May 27 12:05:15 volumio volumio[25145]: info: Starting Shairport Sync May 27 12:05:15 volumio volumio[25145]: info: Loading plugin "last_100"... May 27 12:05:15 volumio volumio[25145]: info: Loading plugin "webradio"... May 27 12:05:15 volumio volumio[25145]: info: Loading plugin "i2s_dacs"... May 27 12:05:15 volumio volumio[25145]: info: I2S DAC not set, start Auto-detection May 27 12:05:15 volumio volumio[25145]: info: Loading plugin "volumiodiscovery"... May 27 12:05:15 volumio volumio[25145]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 27 12:05:15 volumio node[25145]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 27 12:05:15 volumio volumio[25145]: *** WARNING *** Please fix your application to use the native API of Avahi! May 27 12:05:15 volumio node[25145]: *** WARNING *** Please fix your application to use the native API of Avahi! May 27 12:05:15 volumio volumio[25145]: *** WARNING *** For more information see May 27 12:05:15 volumio node[25145]: *** WARNING *** For more information see May 27 12:05:15 volumio volumio[25145]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 27 12:05:15 volumio node[25145]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 27 12:05:15 volumio volumio[25145]: *** WARNING *** Please fix your application to use the native API of Avahi! May 27 12:05:15 volumio node[25145]: *** WARNING *** Please fix your application to use the native API of Avahi! May 27 12:05:15 volumio volumio[25145]: *** WARNING *** For more information see May 27 12:05:15 volumio node[25145]: *** WARNING *** For more information see May 27 12:05:15 volumio volumio[25145]: info: Applying required configuration parameters for plugin volumiodiscovery May 27 12:05:15 volumio volumio[25145]: info: Discovery: Started advertising with name: Volumio May 27 12:05:15 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 27 12:05:15 volumio volumio[25145]: info: Loading plugin "outputs"... May 27 12:05:15 volumio volumio[25145]: info: Loading plugin "albumart"... May 27 12:05:15 volumio volumio[25145]: info: Plugin example_plugin is not enabled May 27 12:05:15 volumio volumio[25145]: info: Loading plugin "inputs"... May 27 12:05:15 volumio volumio[25145]: info: Loading plugin "updater_comm"... May 27 12:05:16 volumio volumio[25145]: info: Plugin mpdemulation is not enabled May 27 12:05:16 volumio volumio[25145]: info: Loading plugin "rest_api"... May 27 12:05:16 volumio volumio[25145]: info: Loading plugin "websocket"... May 27 12:05:16 volumio volumio[25145]: info: Starting Socket.io Server version 1.7.4 May 27 12:05:16 volumio volumio[25145]: info: Loading i18n strings for locale zh May 27 12:05:16 volumio volumio[25145]: Updating browse sources language May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::initPlayerControls May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 12:05:16 volumio volumio[25145]: Express server listening on port 3000 May 27 12:05:16 volumio volumio[25145]: [Metrics] WebUI: 3s 529.24ms May 27 12:05:16 volumio volumio[25145]: info: CoreStateMachine::resetVolumioState May 27 12:05:16 volumio volumio[25145]: info: CoreStateMachine::getcurrentVolume May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::volumioRetrievevolume May 27 12:05:16 volumio volumio[25145]: info: CoreStateMachine::pushState May 27 12:05:16 volumio volumio[25145]: info: CorePlayQueue::getTrack 0 May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::volumioPushState May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 12:05:16 volumio volumio-remote-updater[751]: [2026-05-27 04:05:16] [connect] Successful connection May 27 12:05:16 volumio volumio[25145]: info: Volumio Network Manager: Network status updated: 1 May 27 12:05:16 volumio volumio[25208]: Forking 3 albumart workers May 27 12:05:16 volumio volumio[25145]: verbose: New Socket.io Connection to 192.168.0.135 from 192.168.0.237 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Edg/146.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 May 27 12:05:16 volumio volumio[25145]: verbose: New Socket.io Connection to 192.168.0.135 from 192.168.0.237 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Edg/146.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 May 27 12:05:16 volumio volumio[25145]: info: Reloading queue from file May 27 12:05:16 volumio volumio[25145]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 May 27 12:05:16 volumio volumio[25145]: info: CoreStateMachine::setRepeat null single undefined May 27 12:05:16 volumio volumio[25145]: info: CoreStateMachine::pushState May 27 12:05:16 volumio volumio[25145]: info: CorePlayQueue::getTrack 0 May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::volumioPushState May 27 12:05:16 volumio volumio[25145]: info: CoreStateMachine::setRandom null May 27 12:05:16 volumio volumio[25145]: info: CoreStateMachine::pushState May 27 12:05:16 volumio volumio[25145]: info: CorePlayQueue::getTrack 0 May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::volumioPushState May 27 12:05:16 volumio volumio[25145]: info: Setting Device type: Raspberry PI May 27 12:05:16 volumio volumio-remote-updater[751]: [2026-05-27 04:05:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1779854716 101 May 27 12:05:16 volumio volumio[25145]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 May 27 12:05:16 volumio volumio[25145]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 May 27 12:05:16 volumio volumio[25145]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 May 27 12:05:16 volumio volumio[25145]: info: Received Get System Info May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 27 12:05:16 volumio volumio[25145]: info: Discovery: Getting this device information May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::volumioGetState May 27 12:05:16 volumio volumio[25145]: info: CorePlayQueue::getTrack 0 May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 27 12:05:16 volumio volumio[25145]: info: Completed loading Core Plugins May 27 12:05:16 volumio volumio[25145]: info: Preparing to generate the ALSA configuration file May 27 12:05:16 volumio volumio[25145]: info: Asound.conf file unchanged, so no further update is needed May 27 12:05:16 volumio volumio[25145]: info: Output device has changed, restarting MPD May 27 12:05:16 volumio volumio[25145]: info: Output device has changed, restarting Shairport Sync May 27 12:05:16 volumio sudo[25264]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 12:05:16 volumio sudo[25264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 12:05:16 volumio sudo[25264]: pam_unix(sudo:session): session closed for user root May 27 12:05:16 volumio sudo[25265]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 12:05:16 volumio volumio[25145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 12:05:16 volumio volumio[25145]: info: ___________ START PLUGINS ___________ May 27 12:05:16 volumio sudo[25265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 12:05:16 volumio volumio[25145]: info: ControllerMpd::onStart: Initializing MPD May 27 12:05:16 volumio volumio[25145]: info: Creating MPD Configuration file May 27 12:05:16 volumio sudo[25273]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 12:05:16 volumio sudo[25273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 27 12:05:16 volumio volumio[25145]: info: [1779854716667] CoreMusicLibrary::Adding element 媒体服务器 May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 12:05:16 volumio volumio[25145]: info: UPNP Browser: Client initialized successfully May 27 12:05:16 volumio sudo[25277]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 12:05:16 volumio sudo[25277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 12:05:16 volumio sudo[25275]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 12:05:16 volumio sudo[25275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 12:05:16 volumio sudo[25275]: pam_unix(sudo:session): session closed for user root May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 12:05:16 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 27 12:05:16 volumio sudo[25273]: pam_unix(sudo:session): session closed for user root May 27 12:05:16 volumio volumio[25145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 12:05:16 volumio volumio[25145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 27 12:05:16 volumio volumio[25145]: info: [1779854716799] CoreMusicLibrary::Adding element Last_100 May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 27 12:05:16 volumio volumio[25145]: info: [1779854716802] CoreMusicLibrary::Adding element Webradio May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 27 12:05:16 volumio volumio[25145]: info: Initializing BBC Radios May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 27 12:05:16 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 12:05:16 volumio volumio[25145]: info: Volumio Calling Home May 27 12:05:17 volumio systemd[1]: mpd.service: Deactivated successfully. May 27 12:05:17 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 27 12:05:17 volumio systemd[1]: mpd.service: Consumed 11.490s CPU time. May 27 12:05:17 volumio systemd[1]: mpd.socket: Deactivated successfully. May 27 12:05:17 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 27 12:05:17 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 27 12:05:17 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 27 12:05:17 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 27 12:05:17 volumio sudo[25306]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 27 12:05:17 volumio sudo[25306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 12:05:17 volumio sudo[25306]: pam_unix(sudo:session): session closed for user root May 27 12:05:17 volumio volumio[25145]: info: MPD Permissions set May 27 12:05:17 volumio volumio[25145]: info: MPD Permissions set May 27 12:05:17 volumio volumio[25145]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:17 volumio volumio5-onboarding[2172]: time=2026-05-27T04:05:17.414Z level=INFO msg="service successfully established" component=discovery/localnet May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:17 volumio volumio[25225]: Starting albumart workers May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 12:05:17 volumio volumio[25145]: info: Starting Shairport Sync May 27 12:05:17 volumio volumio[25145]: info: Starting Shairport Sync May 27 12:05:17 volumio volumio[25145]: info: Starting Shairport Sync May 27 12:05:17 volumio sudo[25323]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 12:05:17 volumio sudo[25323]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 12:05:17 volumio sudo[25328]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 12:05:17 volumio sudo[25328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 12:05:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 27 12:05:17 volumio sudo[25325]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 12:05:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 27 12:05:17 volumio sudo[25325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 12:05:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 27 12:05:17 volumio volumio[25145]: info: Discovery: adding 3dd17ff5-b380-49da-9c4f-e2db0c1f526e May 27 12:05:17 volumio volumio[25145]: info: Discovery: Found device Volumio May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::volumioGetState May 27 12:05:17 volumio volumio[25145]: info: CorePlayQueue::getTrack 0 May 27 12:05:17 volumio systemd[1]: shairport-sync.service: Consumed 2.912s CPU time. May 27 12:05:17 volumio volumio[25145]: info: Discovery: this is already registered, 3dd17ff5-b380-49da-9c4f-e2db0c1f526e May 27 12:05:17 volumio volumio[25145]: info: Discovery: Found device Volumio May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::volumioGetState May 27 12:05:17 volumio volumio[25145]: info: CorePlayQueue::getTrack 0 May 27 12:05:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 27 12:05:17 volumio sudo[25323]: pam_unix(sudo:session): session closed for user root May 27 12:05:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 27 12:05:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 27 12:05:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 27 12:05:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 27 12:05:17 volumio volumio[25145]: info: Volumio called home May 27 12:05:17 volumio sudo[25328]: pam_unix(sudo:session): session closed for user root May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::volumioGetState May 27 12:05:17 volumio volumio[25145]: info: CorePlayQueue::getTrack 0 May 27 12:05:17 volumio volumio[25224]: Starting albumart workers May 27 12:05:17 volumio sudo[25325]: pam_unix(sudo:session): session closed for user root May 27 12:05:17 volumio volumio[25145]: info: Shairport-Sync Started May 27 12:05:17 volumio volumio[25145]: Error adding Membership: Error: addMembership EINVAL May 27 12:05:17 volumio volumio[25145]: info: Shairport-Sync Started May 27 12:05:17 volumio volumio[25145]: info: Shairport-Sync Started May 27 12:05:17 volumio volumio[25226]: Starting albumart workers May 27 12:05:17 volumio volumio[25145]: verbose: New Socket.io Connection to 192.168.0.135 from 192.168.0.237 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Edg/146.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 May 27 12:05:17 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 27 12:05:18 volumio volumio[25145]: info: CoreCommandRouter::volumioGetVisibleSources May 27 12:05:18 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 12:05:18 volumio volumio[25145]: info: CoreCommandRouter::volumioGetState May 27 12:05:18 volumio volumio[25145]: info: CorePlayQueue::getTrack 0 May 27 12:05:18 volumio volumio[25145]: info: CoreCommandRouter::volumioGetState May 27 12:05:18 volumio volumio[25145]: info: CorePlayQueue::getTrack 0 May 27 12:05:18 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 27 12:05:18 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 27 12:05:18 volumio volumio[25145]: info: Received Get System Info May 27 12:05:18 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 27 12:05:18 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 27 12:05:18 volumio volumio[25145]: info: Discovery: Getting this device information May 27 12:05:18 volumio volumio[25145]: info: CoreCommandRouter::volumioGetState May 27 12:05:18 volumio volumio[25145]: info: CorePlayQueue::getTrack 0 May 27 12:05:18 volumio volumio[25145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 27 12:05:18 volumio volumio[25145]: info: CoreCommandRouter::volumioGetState May 27 12:05:18 volumio volumio[25145]: info: CorePlayQueue::getTrack 0 May 27 12:05:18 volumio volumio[25145]: info: Listing playlists May 27 12:05:18 volumio volumio[25145]: info: CoreCommandRouter::volumioGetQueue May 27 12:05:18 volumio volumio[25145]: info: CoreStateMachine::getQueue May 27 12:05:18 volumio volumio[25145]: info: CorePlayQueue::getQueue May 27 12:05:19 volumio volumio[25145]: info: Preload queue cleared May 27 12:05:19 volumio volumio[25145]: info: CoreCommandRouter::volumioReplaceandPlayItems May 27 12:05:19 volumio volumio[25145]: info: CoreStateMachine::ClearQueue May 27 12:05:19 volumio volumio[25145]: info: CoreStateMachine::stop May 27 12:05:19 volumio volumio[25145]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 12:05:19 volumio volumio[25145]: info: CorePlayQueue::clearPlayQueue May 27 12:05:19 volumio volumio[25145]: info: CorePlayQueue::saveQueue May 27 12:05:19 volumio volumio[25145]: info: CoreCommandRouter::volumioPushQueue May 27 12:05:19 volumio volumio[25145]: info: CoreStateMachine::addQueueItems May 27 12:05:19 volumio volumio[25145]: info: CorePlayQueue::addQueueItems May 27 12:05:19 volumio volumio[25145]: info: Preload queue cleared May 27 12:05:19 volumio volumio[25145]: info: Adding Item to queue: albums://Yo%20Kigoshi%20(cello)/One%20Point%20Real%20Hi-Res%20384Khz%20Anthology May 27 12:05:19 volumio volumio[25145]: info: Exploding uri albums://Yo%20Kigoshi%20(cello)/One%20Point%20Real%20Hi-Res%20384Khz%20Anthology in service mpd May 27 12:05:19 volumio volumio[25145]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 12:05:19 volumio volumio[25145]: TypeError: Cannot read properties of undefined (reading 'sendCommand') May 27 12:05:19 volumio volumio[25145]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2134:20) May 27 12:05:19 volumio volumio[25145]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) May 27 12:05:19 volumio volumio[25145]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) May 27 12:05:19 volumio volumio[25145]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) May 27 12:05:19 volumio volumio[25145]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) May 27 12:05:19 volumio volumio[25145]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:251:25) May 27 12:05:19 volumio volumio[25145]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:526:23) May 27 12:05:19 volumio volumio[25145]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) May 27 12:05:19 volumio volumio[25145]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) May 27 12:05:19 volumio volumio[25145]: at Socket.emit (node:events:514:28) May 27 12:05:19 volumio volumio[25145]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 27 12:05:19 volumio volumio[25145]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) May 27 12:05:19 volumio volumio[25145]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 12:05:19 volumio mpd[25308]: 2026-05-27T12:05:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 27 12:05:19 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 27 12:05:19 volumio sudo[25277]: pam_unix(sudo:session): session closed for user root May 27 12:05:19 volumio sudo[25265]: pam_unix(sudo:session): session closed for user root May 27 12:05:19 volumio sudo[25382]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-27 12:04' May 27 12:05:19 volumio sudo[25382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"