Jun 17 12:44:19 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioGetState Jun 17 12:44:19 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:21 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioGetQueue Jun 17 12:44:21 volu-alfa volumio[4024]: info: CoreStateMachine::getQueue Jun 17 12:44:21 volu-alfa volumio[4024]: info: CorePlayQueue::getQueue Jun 17 12:44:25 volu-alfa volumio[4024]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 12:44:25 volu-alfa volumio[4024]: info: CURURI: music-library Jun 17 12:44:26 volu-alfa volumio[4024]: info: Preload queue cleared Jun 17 12:44:27 volu-alfa volumio[4024]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 12:44:27 volu-alfa volumio[4024]: info: CURURI: music-library/USB Jun 17 12:44:27 volu-alfa volumio[4024]: info: Preload queue cleared Jun 17 12:44:28 volu-alfa volumio[4024]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 12:44:28 volu-alfa volumio[4024]: info: CURURI: music-library/USB/2203-6DCD Jun 17 12:44:28 volu-alfa volumio[4024]: info: Preload queue cleared Jun 17 12:44:28 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/11 พุทธวจน อานิสงส์การฟังธรรม.mp3 Jun 17 12:44:28 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Never Diminishing Passion.wav Jun 17 12:44:28 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Just The Way I Am.wav Jun 17 12:44:28 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Shanghai by Night.wav Jun 17 12:44:28 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Smoke Gets Into Your Eyes.wav Jun 17 12:44:28 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Teach The World To Sing.wav Jun 17 12:44:28 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/You Are The Sunshine of My Heart.wav Jun 17 12:44:28 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Love You 10,000 Years.wav Jun 17 12:44:28 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/11 พุทธวจน อานิสงส์การฟังธรรม.mp3 in service mpd Jun 17 12:44:28 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2F11%20%E0%B8%9E%E0%B8%B8%E0%B8%97%E0%B8%98%E0%B8%A7%E0%B8%88%E0%B8%99%20%E0%B8%AD%E0%B8%B2%E0%B8%99%E0%B8%B4%E0%B8%AA%E0%B8%87%E0%B8%AA%E0%B9%8C%E0%B8%81%E0%B8%B2%E0%B8%A3%E0%B8%9F%E0%B8%B1%E0%B8%87%E0%B8%98%E0%B8%A3%E0%B8%A3%E0%B8%A1.mp3&metadata=false Jun 17 12:44:28 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/11 พุทธวจน อานิสงส์การฟังธรรม.mp3 Jun 17 12:44:28 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Never Diminishing Passion.wav in service mpd Jun 17 12:44:28 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Just The Way I Am.wav in service mpd Jun 17 12:44:28 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=TIS/AGIC%20EXSEED/medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FNever%20Diminishing%20Passion.wav&metadata=false Jun 17 12:44:28 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Never Diminishing Passion.wav Jun 17 12:44:28 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=TIS/AGIC%20EXSEED/medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FJust%20The%20Way%20I%20Am.wav&metadata=false Jun 17 12:44:28 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Just The Way I Am.wav Jun 17 12:44:28 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Shanghai by Night.wav in service mpd Jun 17 12:44:28 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Smoke Gets Into Your Eyes.wav in service mpd Jun 17 12:44:28 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Teach The World To Sing.wav in service mpd Jun 17 12:44:28 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=TIS/AGIC%20EXSEED/medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FShanghai%20by%20Night.wav&metadata=false Jun 17 12:44:28 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Shanghai by Night.wav Jun 17 12:44:28 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=TIS/AGIC%20EXSEED/medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FSmoke%20Gets%20Into%20Your%20Eyes.wav&metadata=false Jun 17 12:44:28 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Smoke Gets Into Your Eyes.wav Jun 17 12:44:28 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=TIS/AGIC%20EXSEED/medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FTeach%20The%20World%20To%20Sing.wav&metadata=false Jun 17 12:44:28 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Teach The World To Sing.wav Jun 17 12:44:28 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/You Are The Sunshine of My Heart.wav in service mpd Jun 17 12:44:28 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=TIS/AGIC%20EXSEED/medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FYou%20Are%20The%20Sunshine%20of%20My%20Heart.wav&metadata=false Jun 17 12:44:28 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/You Are The Sunshine of My Heart.wav Jun 17 12:44:28 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Love You 10,000 Years.wav in service mpd Jun 17 12:44:28 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=TIS/AGIC%20EXSEED/medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FLove%20You%2010%2C000%20Years.wav&metadata=false Jun 17 12:44:28 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Love You 10,000 Years.wav Jun 17 12:44:33 volu-alfa volumio[4024]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 12:44:33 volu-alfa volumio[4024]: info: CURURI: music-library Jun 17 12:44:33 volu-alfa volumio[4024]: info: Preload queue cleared Jun 17 12:44:34 volu-alfa volumio[4024]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 12:44:34 volu-alfa volumio[4024]: info: CURURI: music-library/USB Jun 17 12:44:34 volu-alfa volumio[4024]: info: Preload queue cleared Jun 17 12:44:34 volu-alfa volumio[4024]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 12:44:34 volu-alfa volumio[4024]: info: CURURI: music-library/USB/2203-6DCD Jun 17 12:44:34 volu-alfa volumio[4024]: info: Preload queue cleared Jun 17 12:44:45 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:45 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:45 volu-alfa volumio[4024]: info: Prefetching next song Jun 17 12:44:45 volu-alfa volumio[4024]: info: DOING PREFETCH IN MPD Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand add "USB/2203-6DCD/01. กาลครั้งหนึ่ง...ของความรัก [G-0559145]/01. ลมรัก.wav" Jun 17 12:44:45 volu-alfa volumio[4024]: info: Jun 17 12:44:45 volu-alfa volumio[4024]: ---------------------------- MPD announces system playlist update Jun 17 12:44:45 volu-alfa volumio[4024]: info: Ignoring MPD Status Update Jun 17 12:44:45 volu-alfa volumio[4024]: info: sendMpdCommand add "USB/2203-6DCD/01. กาลครั้งหนึ่ง...ของความรัก [G-0559145]/01. ลมรัก.wav" took 5 milliseconds Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 17 12:44:45 volu-alfa volumio[4024]: info: Jun 17 12:44:45 volu-alfa volumio[4024]: ---------------------------- MPD announces system playlist update Jun 17 12:44:45 volu-alfa volumio[4024]: info: Ignoring MPD Status Update Jun 17 12:44:45 volu-alfa volumio[4024]: info: Jun 17 12:44:45 volu-alfa volumio[4024]: ---------------------------- MPD announces system playlist update Jun 17 12:44:45 volu-alfa volumio[4024]: info: Ignoring MPD Status Update Jun 17 12:44:45 volu-alfa volumio[4024]: info: Jun 17 12:44:45 volu-alfa volumio[4024]: ---------------------------- MPD announces state update: options Jun 17 12:44:45 volu-alfa volumio[4024]: info: ------------------------------ 11ms Jun 17 12:44:45 volu-alfa volumio[4024]: info: sendMpdCommand consume 1 took 8 milliseconds Jun 17 12:44:45 volu-alfa volumio[4024]: info: ControllerMpd::getState Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand status Jun 17 12:44:45 volu-alfa volumio[4024]: info: ------------------------------ 8ms Jun 17 12:44:45 volu-alfa volumio[4024]: info: ------------------------------ 7ms Jun 17 12:44:45 volu-alfa volumio[4024]: info: Jun 17 12:44:45 volu-alfa volumio[4024]: ---------------------------- MPD announces state update: options Jun 17 12:44:45 volu-alfa volumio[4024]: info: ControllerMpd::getState Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand status Jun 17 12:44:45 volu-alfa volumio[4024]: info: Jun 17 12:44:45 volu-alfa volumio[4024]: ---------------------------- MPD announces state update: options Jun 17 12:44:45 volu-alfa volumio[4024]: info: ControllerMpd::getState Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand status Jun 17 12:44:45 volu-alfa volumio[4024]: info: sendMpdCommand status took 6 milliseconds Jun 17 12:44:45 volu-alfa volumio[4024]: info: sendMpdCommand status took 5 milliseconds Jun 17 12:44:45 volu-alfa volumio[4024]: info: sendMpdCommand status took 3 milliseconds Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: ControllerMpd::parseState Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: ControllerMpd::parseState Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: ControllerMpd::parseState Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 12:44:45 volu-alfa volumio[4024]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 17 12:44:45 volu-alfa volumio[4024]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 17 12:44:45 volu-alfa volumio[4024]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: ControllerMpd::parseTrackInfo Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: ControllerMpd::parseTrackInfo Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: ControllerMpd::parseTrackInfo Jun 17 12:44:45 volu-alfa volumio[4024]: info: ControllerMpd::pushState Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreCommandRouter::servicePushState Jun 17 12:44:45 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":177762,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01. ลมรัก.wav","artist":null,"album":null,"uri":"USB/2203-6DCD/01. กาลครั้งหนึ่ง...ของความรัก [G-0559145]/01. ลมรัก.wav","trackType":"wav"} Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: CURRENT POSITION 0 Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreStateMachine::syncState stateService play Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreStateMachine::syncState currentStatus play Jun 17 12:44:45 volu-alfa volumio[4024]: info: Received an update from plugin. extracting info from payload Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:44:45 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:44:45 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:44:45 volu-alfa volumio[4024]: info: ControllerMpd::pushState Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreCommandRouter::servicePushState Jun 17 12:44:45 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":177762,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01. ลมรัก.wav","artist":null,"album":null,"uri":"USB/2203-6DCD/01. กาลครั้งหนึ่ง...ของความรัก [G-0559145]/01. ลมรัก.wav","trackType":"wav"} Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: CURRENT POSITION 0 Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreStateMachine::syncState stateService play Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreStateMachine::syncState currentStatus play Jun 17 12:44:45 volu-alfa volumio[4024]: info: Received an update from plugin. extracting info from payload Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:44:45 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:44:45 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:44:45 volu-alfa volumio[4024]: info: ControllerMpd::pushState Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreCommandRouter::servicePushState Jun 17 12:44:45 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":177762,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01. ลมรัก.wav","artist":null,"album":null,"uri":"USB/2203-6DCD/01. กาลครั้งหนึ่ง...ของความรัก [G-0559145]/01. ลมรัก.wav","trackType":"wav"} Jun 17 12:44:45 volu-alfa volumio[4024]: verbose: CURRENT POSITION 0 Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreStateMachine::syncState stateService play Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreStateMachine::syncState currentStatus play Jun 17 12:44:45 volu-alfa volumio[4024]: info: Received an update from plugin. extracting info from payload Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:44:45 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:44:45 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:45 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:44:45 volu-alfa volumio[4024]: info: ------------------------------ 103ms Jun 17 12:44:45 volu-alfa volumio[4024]: info: ------------------------------ 101ms Jun 17 12:44:45 volu-alfa volumio[4024]: info: ------------------------------ 100ms Jun 17 12:44:49 volu-alfa volumio[4024]: info: CoreStateMachine::startPlaybackTimer Jun 17 12:44:49 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:50 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:44:50 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:50 volu-alfa volumio[4024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 12:44:50 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:44:50 volu-alfa volumio[4024]: info: Jun 17 12:44:50 volu-alfa volumio[4024]: ---------------------------- MPD announces system playlist update Jun 17 12:44:50 volu-alfa volumio[4024]: info: Ignoring MPD Status Update Jun 17 12:44:50 volu-alfa volumio[4024]: info: Jun 17 12:44:50 volu-alfa volumio[4024]: ---------------------------- MPD announces state update: player Jun 17 12:44:50 volu-alfa volumio[4024]: info: ControllerMpd::getState Jun 17 12:44:50 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand status Jun 17 12:44:50 volu-alfa volumio[4024]: info: Jun 17 12:44:50 volu-alfa volumio[4024]: ---------------------------- MPD announces system playlist update Jun 17 12:44:50 volu-alfa volumio[4024]: info: Ignoring MPD Status Update Jun 17 12:44:50 volu-alfa volumio[4024]: info: Jun 17 12:44:50 volu-alfa volumio[4024]: ---------------------------- MPD announces state update: player Jun 17 12:44:50 volu-alfa volumio[4024]: info: ControllerMpd::getState Jun 17 12:44:50 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand status Jun 17 12:44:50 volu-alfa volumio[4024]: info: Jun 17 12:44:50 volu-alfa volumio[4024]: ---------------------------- MPD announces system playlist update Jun 17 12:44:50 volu-alfa volumio[4024]: info: Ignoring MPD Status Update Jun 17 12:44:50 volu-alfa volumio[4024]: info: Jun 17 12:44:50 volu-alfa volumio[4024]: ---------------------------- MPD announces state update: player Jun 17 12:44:50 volu-alfa volumio[4024]: info: ControllerMpd::getState Jun 17 12:44:50 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand status Jun 17 12:44:50 volu-alfa volumio[4024]: info: ------------------------------ 8ms Jun 17 12:44:50 volu-alfa volumio[4024]: info: sendMpdCommand status took 7 milliseconds Jun 17 12:44:50 volu-alfa volumio[4024]: info: ------------------------------ 6ms Jun 17 12:44:50 volu-alfa volumio[4024]: verbose: ControllerMpd::parseState Jun 17 12:44:50 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 12:44:50 volu-alfa volumio[4024]: info: sendMpdCommand status took 8 milliseconds Jun 17 12:44:50 volu-alfa volumio[4024]: info: ------------------------------ 7ms Jun 17 12:44:50 volu-alfa volumio[4024]: info: sendMpdCommand status took 5 milliseconds Jun 17 12:44:50 volu-alfa volumio[4024]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 17 12:44:50 volu-alfa volumio[4024]: verbose: ControllerMpd::parseState Jun 17 12:44:50 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 12:44:50 volu-alfa volumio[4024]: verbose: ControllerMpd::parseState Jun 17 12:44:50 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 12:44:50 volu-alfa volumio[4024]: verbose: ControllerMpd::parseTrackInfo Jun 17 12:44:50 volu-alfa volumio[4024]: info: ControllerMpd::pushState Jun 17 12:44:50 volu-alfa volumio[4024]: info: CoreCommandRouter::servicePushState Jun 17 12:44:50 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:50 volu-alfa volumio[4024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01. ลมรัก.wav","artist":null,"album":null,"uri":"USB/2203-6DCD/01. กาลครั้งหนึ่ง...ของความรัก [G-0559145]/01. ลมรัก.wav","trackType":"wav"} Jun 17 12:44:50 volu-alfa volumio[4024]: verbose: CURRENT POSITION 0 Jun 17 12:44:50 volu-alfa volumio[4024]: info: CoreStateMachine::syncState stateService play Jun 17 12:44:50 volu-alfa volumio[4024]: info: CoreStateMachine::syncState currentStatus play Jun 17 12:44:50 volu-alfa volumio[4024]: info: Received an update from plugin. extracting info from payload Jun 17 12:44:50 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:44:50 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:50 volu-alfa volumio[4024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 12:44:50 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:44:50 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:44:50 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:50 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:44:50 volu-alfa volumio[4024]: info: ------------------------------ 40ms Jun 17 12:44:50 volu-alfa volumio[4024]: info: sendMpdCommand playlistinfo took 32 milliseconds Jun 17 12:44:50 volu-alfa volumio[4024]: info: sendMpdCommand playlistinfo took 32 milliseconds Jun 17 12:44:50 volu-alfa volumio[4024]: verbose: ControllerMpd::parseTrackInfo Jun 17 12:44:50 volu-alfa volumio[4024]: verbose: ControllerMpd::parseTrackInfo Jun 17 12:44:50 volu-alfa volumio[4024]: info: ControllerMpd::pushState Jun 17 12:44:50 volu-alfa volumio[4024]: info: CoreCommandRouter::servicePushState Jun 17 12:44:50 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:50 volu-alfa volumio[4024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01. ลมรัก.wav","artist":null,"album":null,"uri":"USB/2203-6DCD/01. กาลครั้งหนึ่ง...ของความรัก [G-0559145]/01. ลมรัก.wav","trackType":"wav"} Jun 17 12:44:50 volu-alfa volumio[4024]: verbose: CURRENT POSITION 0 Jun 17 12:44:50 volu-alfa volumio[4024]: info: CoreStateMachine::syncState stateService play Jun 17 12:44:50 volu-alfa volumio[4024]: info: CoreStateMachine::syncState currentStatus play Jun 17 12:44:50 volu-alfa volumio[4024]: info: Received an update from plugin. extracting info from payload Jun 17 12:44:50 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:44:50 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:50 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:44:50 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:44:50 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:50 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:44:51 volu-alfa volumio[4024]: info: ControllerMpd::pushState Jun 17 12:44:51 volu-alfa volumio[4024]: info: CoreCommandRouter::servicePushState Jun 17 12:44:51 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:51 volu-alfa volumio[4024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01. ลมรัก.wav","artist":null,"album":null,"uri":"USB/2203-6DCD/01. กาลครั้งหนึ่ง...ของความรัก [G-0559145]/01. ลมรัก.wav","trackType":"wav"} Jun 17 12:44:51 volu-alfa volumio[4024]: verbose: CURRENT POSITION 0 Jun 17 12:44:51 volu-alfa volumio[4024]: info: CoreStateMachine::syncState stateService play Jun 17 12:44:51 volu-alfa volumio[4024]: info: CoreStateMachine::syncState currentStatus play Jun 17 12:44:51 volu-alfa volumio[4024]: info: Received an update from plugin. extracting info from payload Jun 17 12:44:51 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:44:51 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:51 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:44:51 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:44:51 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:51 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:44:51 volu-alfa volumio[4024]: info: ------------------------------ 104ms Jun 17 12:44:51 volu-alfa volumio[4024]: info: ------------------------------ 101ms Jun 17 12:44:51 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioGetState Jun 17 12:44:51 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:44:51 volu-alfa volumio[4024]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 12:44:51 volu-alfa volumio[4024]: info: CURURI: music-library/USB/2203-6DCD/Audiophile Vocal – Best of Songs and Best of Voices (2012) Jun 17 12:44:51 volu-alfa volumio[4024]: info: Preload queue cleared Jun 17 12:44:57 volu-alfa volumio[4024]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 12:44:57 volu-alfa volumio[4024]: info: CURURI: music-library/USB/2203-6DCD/Danill Hertz CD1 Jun 17 12:44:57 volu-alfa volumio[4024]: info: Preload queue cleared Jun 17 12:44:57 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Danill Hertz CD1/01 Various Artist - The very thought of you.wav Jun 17 12:44:57 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Danill Hertz CD1/02 Various Artist - Big jazz band 1.wav Jun 17 12:44:57 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Danill Hertz CD1/03 Various Artist - Puccini Tosca.wav Jun 17 12:44:57 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Danill Hertz CD1/04 Various Artist - Autumn in Newyork.wav Jun 17 12:44:57 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Danill Hertz CD1/05 Various Artist - My Funny Valentine.wav Jun 17 12:44:57 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Danill Hertz CD1/06 Various Artist - RRM Track.wav Jun 17 12:44:57 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Danill Hertz CD1/07 Various Artist - Gu Ching 1.wav Jun 17 12:44:57 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Danill Hertz CD1/08 Various Artist - I Thought about you.wav Jun 17 12:44:57 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Danill Hertz CD1/09 Various Artist - Isn't she lovely.wav Jun 17 12:44:57 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Danill Hertz CD1/10 Various Artist - Back The Six shubler Chorales.wav Jun 17 12:44:57 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Danill Hertz CD1/11 Various Artist - Green Lord, Let me know mine end.wav Jun 17 12:44:57 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Danill Hertz CD1/12 Various Artist - Resonace prelude in D minor.wav Jun 17 12:44:57 volu-alfa volumio[4024]: info: Preloading song: music-library/USB/2203-6DCD/Danill Hertz CD1/13 Various Artist - Simon 8 lds.wav Jun 17 12:44:58 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Danill Hertz CD1/01 Various Artist - The very thought of you.wav in service mpd Jun 17 12:44:58 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F01%20Various%20Artist%20-%20The%20very%20thought%20of%20you.wav&metadata=false Jun 17 12:44:58 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/01 Various Artist - The very thought of you.wav Jun 17 12:44:58 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Danill Hertz CD1/02 Various Artist - Big jazz band 1.wav in service mpd Jun 17 12:44:58 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F02%20Various%20Artist%20-%20Big%20jazz%20band%201.wav&metadata=false Jun 17 12:44:58 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/02 Various Artist - Big jazz band 1.wav Jun 17 12:44:58 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Danill Hertz CD1/03 Various Artist - Puccini Tosca.wav in service mpd Jun 17 12:44:58 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F03%20Various%20Artist%20-%20Puccini%20Tosca.wav&metadata=false Jun 17 12:44:58 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/03 Various Artist - Puccini Tosca.wav Jun 17 12:44:58 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Danill Hertz CD1/04 Various Artist - Autumn in Newyork.wav in service mpd Jun 17 12:44:58 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F04%20Various%20Artist%20-%20Autumn%20in%20Newyork.wav&metadata=false Jun 17 12:44:58 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/04 Various Artist - Autumn in Newyork.wav Jun 17 12:44:58 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Danill Hertz CD1/05 Various Artist - My Funny Valentine.wav in service mpd Jun 17 12:44:58 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F05%20Various%20Artist%20-%20My%20Funny%20Valentine.wav&metadata=false Jun 17 12:44:58 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/05 Various Artist - My Funny Valentine.wav Jun 17 12:44:58 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Danill Hertz CD1/06 Various Artist - RRM Track.wav in service mpd Jun 17 12:44:58 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F06%20Various%20Artist%20-%20RRM%20Track.wav&metadata=false Jun 17 12:44:58 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/06 Various Artist - RRM Track.wav Jun 17 12:44:58 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Danill Hertz CD1/07 Various Artist - Gu Ching 1.wav in service mpd Jun 17 12:44:58 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F07%20Various%20Artist%20-%20Gu%20Ching%201.wav&metadata=false Jun 17 12:44:58 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/07 Various Artist - Gu Ching 1.wav Jun 17 12:44:58 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Danill Hertz CD1/08 Various Artist - I Thought about you.wav in service mpd Jun 17 12:44:58 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F08%20Various%20Artist%20-%20I%20Thought%20about%20you.wav&metadata=false Jun 17 12:44:58 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/08 Various Artist - I Thought about you.wav Jun 17 12:44:58 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Danill Hertz CD1/09 Various Artist - Isn't she lovely.wav in service mpd Jun 17 12:44:58 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F09%20Various%20Artist%20-%20Isn't%20she%20lovely.wav&metadata=false Jun 17 12:44:58 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/09 Various Artist - Isn't she lovely.wav Jun 17 12:44:58 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Danill Hertz CD1/10 Various Artist - Back The Six shubler Chorales.wav in service mpd Jun 17 12:44:58 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F10%20Various%20Artist%20-%20Back%20The%20Six%20shubler%20Chorales.wav&metadata=false Jun 17 12:44:58 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/10 Various Artist - Back The Six shubler Chorales.wav Jun 17 12:44:58 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Danill Hertz CD1/11 Various Artist - Green Lord, Let me know mine end.wav in service mpd Jun 17 12:44:58 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F11%20Various%20Artist%20-%20Green%20Lord%2C%20Let%20me%20know%20mine%20end.wav&metadata=false Jun 17 12:44:58 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/11 Various Artist - Green Lord, Let me know mine end.wav Jun 17 12:44:58 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Danill Hertz CD1/12 Various Artist - Resonace prelude in D minor.wav in service mpd Jun 17 12:44:58 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F12%20Various%20Artist%20-%20Resonace%20prelude%20in%20D%20minor.wav&metadata=false Jun 17 12:44:58 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/12 Various Artist - Resonace prelude in D minor.wav Jun 17 12:44:58 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Danill Hertz CD1/13 Various Artist - Simon 8 lds.wav in service mpd Jun 17 12:44:58 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F13%20Various%20Artist%20-%20Simon%208%20lds.wav&metadata=false Jun 17 12:44:58 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/13 Various Artist - Simon 8 lds.wav Jun 17 12:45:02 volu-alfa volumio[4024]: info: Preload queue cleared Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::ClearQueue Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::stop Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::stPlaybackTimer Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::updateTrackBlock Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrackBlock Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::serviceStop Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::serviceStop Jun 17 12:45:02 volu-alfa volumio[4024]: info: ControllerMpd::stop Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::clearPlayQueue Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::saveQueue Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushQueue Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::addQueueItems Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::addQueueItems Jun 17 12:45:02 volu-alfa volumio[4024]: info: Preload queue cleared Jun 17 12:45:02 volu-alfa volumio[4024]: info: Adding Item to queue: music-library/USB/2203-6DCD/Danill Hertz CD1 Jun 17 12:45:02 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Danill Hertz CD1 in service mpd Jun 17 12:45:02 volu-alfa volumio[4024]: info: Jun 17 12:45:02 volu-alfa volumio[4024]: ---------------------------- MPD announces state update: player Jun 17 12:45:02 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F01%20Various%20Artist%20-%20The%20very%20thought%20of%20you.wav&metadata=false Jun 17 12:45:02 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/01 Various Artist - The very thought of you.wav Jun 17 12:45:02 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F02%20Various%20Artist%20-%20Big%20jazz%20band%201.wav&metadata=false Jun 17 12:45:02 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/02 Various Artist - Big jazz band 1.wav Jun 17 12:45:02 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F03%20Various%20Artist%20-%20Puccini%20Tosca.wav&metadata=false Jun 17 12:45:02 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/03 Various Artist - Puccini Tosca.wav Jun 17 12:45:02 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F04%20Various%20Artist%20-%20Autumn%20in%20Newyork.wav&metadata=false Jun 17 12:45:02 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/04 Various Artist - Autumn in Newyork.wav Jun 17 12:45:02 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F05%20Various%20Artist%20-%20My%20Funny%20Valentine.wav&metadata=false Jun 17 12:45:02 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/05 Various Artist - My Funny Valentine.wav Jun 17 12:45:02 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F06%20Various%20Artist%20-%20RRM%20Track.wav&metadata=false Jun 17 12:45:02 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/06 Various Artist - RRM Track.wav Jun 17 12:45:02 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F07%20Various%20Artist%20-%20Gu%20Ching%201.wav&metadata=false Jun 17 12:45:02 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/07 Various Artist - Gu Ching 1.wav Jun 17 12:45:02 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F08%20Various%20Artist%20-%20I%20Thought%20about%20you.wav&metadata=false Jun 17 12:45:02 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/08 Various Artist - I Thought about you.wav Jun 17 12:45:02 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F09%20Various%20Artist%20-%20Isn't%20she%20lovely.wav&metadata=false Jun 17 12:45:02 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/09 Various Artist - Isn't she lovely.wav Jun 17 12:45:02 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F10%20Various%20Artist%20-%20Back%20The%20Six%20shubler%20Chorales.wav&metadata=false Jun 17 12:45:02 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/10 Various Artist - Back The Six shubler Chorales.wav Jun 17 12:45:02 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F11%20Various%20Artist%20-%20Green%20Lord%2C%20Let%20me%20know%20mine%20end.wav&metadata=false Jun 17 12:45:02 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/11 Various Artist - Green Lord, Let me know mine end.wav Jun 17 12:45:02 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F12%20Various%20Artist%20-%20Resonace%20prelude%20in%20D%20minor.wav&metadata=false Jun 17 12:45:02 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/12 Various Artist - Resonace prelude in D minor.wav Jun 17 12:45:02 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=//medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FDanill%20Hertz%20CD1%2F13%20Various%20Artist%20-%20Simon%208%20lds.wav&metadata=false Jun 17 12:45:02 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Danill Hertz CD1/13 Various Artist - Simon 8 lds.wav Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand stop took 29 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: info: ControllerMpd::getState Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand status Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushQueue Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::saveQueue Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::updateTrackBlock Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrackBlock Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPlay Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::play index 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::stop Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::play index undefined Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::startPlaybackTimer Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::clearAddPlayTracks USB/2203-6DCD/Danill Hertz CD1/01 Various Artist - The very thought of you.wav Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 12:45:02 volu-alfa volumio[4024]: info: Jun 17 12:45:02 volu-alfa volumio[4024]: ---------------------------- MPD announces state update: player Jun 17 12:45:02 volu-alfa volumio[4024]: info: ControllerMpd::getState Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand status Jun 17 12:45:02 volu-alfa volumio[4024]: info: Jun 17 12:45:02 volu-alfa volumio[4024]: ---------------------------- MPD announces state update: player Jun 17 12:45:02 volu-alfa volumio[4024]: info: ControllerMpd::getState Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand status Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand status took 23 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand stop took 7 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand status took 6 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand status took 4 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::parseState Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::parseState Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::parseState Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 12:45:02 volu-alfa volumio[4024]: info: Jun 17 12:45:02 volu-alfa volumio[4024]: ---------------------------- MPD announces system playlist update Jun 17 12:45:02 volu-alfa volumio[4024]: info: Ignoring MPD Status Update Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand clear took 7 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::parseTrackInfo Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand add "USB/2203-6DCD/Danill Hertz CD1/01 Various Artist - The very thought of you.wav" Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::parseTrackInfo Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::parseTrackInfo Jun 17 12:45:02 volu-alfa volumio[4024]: info: ControllerMpd::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::servicePushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01. ลมรัก.wav","artist":null,"album":null,"uri":"USB/2203-6DCD/01. กาลครั้งหนึ่ง...ของความรัก [G-0559145]/01. ลมรัก.wav","trackType":"wav"} Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: CURRENT POSITION 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::syncState stateService stop Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::syncState currentStatus stop Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: No code Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:45:02 volu-alfa volumio[4024]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 17 12:45:02 volu-alfa volumio[4024]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 17 12:45:02 volu-alfa volumio[4024]: info: ------------------------------ 80ms Jun 17 12:45:02 volu-alfa volumio[4024]: info: ------------------------------ 54ms Jun 17 12:45:02 volu-alfa volumio[4024]: info: ------------------------------ 53ms Jun 17 12:45:02 volu-alfa volumio[4024]: info: Jun 17 12:45:02 volu-alfa volumio[4024]: ---------------------------- MPD announces system playlist update Jun 17 12:45:02 volu-alfa volumio[4024]: info: Ignoring MPD Status Update Jun 17 12:45:02 volu-alfa volumio[4024]: info: Jun 17 12:45:02 volu-alfa volumio[4024]: ---------------------------- MPD announces system playlist update Jun 17 12:45:02 volu-alfa volumio[4024]: info: Ignoring MPD Status Update Jun 17 12:45:02 volu-alfa volumio[4024]: error: updateQueue error: null Jun 17 12:45:02 volu-alfa volumio[4024]: info: Jun 17 12:45:02 volu-alfa volumio[4024]: ---------------------------- MPD announces system playlist update Jun 17 12:45:02 volu-alfa volumio[4024]: info: Ignoring MPD Status Update Jun 17 12:45:02 volu-alfa volumio[4024]: info: ------------------------------ 48ms Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand add "USB/2203-6DCD/Danill Hertz CD1/01 Various Artist - The very thought of you.wav" took 46 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: info: ------------------------------ 6ms Jun 17 12:45:02 volu-alfa volumio[4024]: info: ------------------------------ 5ms Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand play Jun 17 12:45:02 volu-alfa volumio[4024]: info: Jun 17 12:45:02 volu-alfa volumio[4024]: ---------------------------- MPD announces system playlist update Jun 17 12:45:02 volu-alfa volumio[4024]: info: Ignoring MPD Status Update Jun 17 12:45:02 volu-alfa volumio[4024]: info: Jun 17 12:45:02 volu-alfa volumio[4024]: ---------------------------- MPD announces system playlist update Jun 17 12:45:02 volu-alfa volumio[4024]: info: Ignoring MPD Status Update Jun 17 12:45:02 volu-alfa volumio[4024]: info: ------------------------------ 22ms Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand play took 21 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: info: ------------------------------ 20ms Jun 17 12:45:02 volu-alfa volumio[4024]: info: ------------------------------ 19ms Jun 17 12:45:02 volu-alfa volumio[4024]: info: Jun 17 12:45:02 volu-alfa volumio[4024]: ---------------------------- MPD announces state update: player Jun 17 12:45:02 volu-alfa volumio[4024]: info: ControllerMpd::getState Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand status Jun 17 12:45:02 volu-alfa volumio[4024]: info: Jun 17 12:45:02 volu-alfa volumio[4024]: ---------------------------- MPD announces state update: player Jun 17 12:45:02 volu-alfa volumio[4024]: info: ControllerMpd::getState Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand status Jun 17 12:45:02 volu-alfa volumio[4024]: info: Jun 17 12:45:02 volu-alfa volumio[4024]: ---------------------------- MPD announces state update: player Jun 17 12:45:02 volu-alfa volumio[4024]: info: ControllerMpd::getState Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand status Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand status took 8 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::parseState Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 12:45:02 volu-alfa volumio[4024]: info: Jun 17 12:45:02 volu-alfa volumio[4024]: ---------------------------- MPD announces state update: player Jun 17 12:45:02 volu-alfa volumio[4024]: info: ControllerMpd::getState Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand status Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand status took 9 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand status took 8 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::parseState Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::parseState Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::parseTrackInfo Jun 17 12:45:02 volu-alfa volumio[4024]: info: ControllerMpd::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::servicePushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01 Various Artist - The very thought of you.wav","artist":null,"album":null,"uri":"USB/2203-6DCD/Danill Hertz CD1/01 Various Artist - The very thought of you.wav","trackType":"wav"} Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: CURRENT POSITION 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::syncState stateService play Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::syncState currentStatus stop Jun 17 12:45:02 volu-alfa volumio[4024]: info: ------------------------------ 18ms Jun 17 12:45:02 volu-alfa volumio[4024]: info: Jun 17 12:45:02 volu-alfa volumio[4024]: ---------------------------- MPD announces state update: player Jun 17 12:45:02 volu-alfa volumio[4024]: info: ControllerMpd::getState Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand status Jun 17 12:45:02 volu-alfa volumio[4024]: info: Jun 17 12:45:02 volu-alfa volumio[4024]: ---------------------------- MPD announces state update: player Jun 17 12:45:02 volu-alfa volumio[4024]: info: ControllerMpd::getState Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand status Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand status took 12 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand status took 8 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand status took 6 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::parseState Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::parseTrackInfo Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::parseTrackInfo Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::parseState Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::parseState Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 12:45:02 volu-alfa volumio[4024]: info: ControllerMpd::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::servicePushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":160,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01 Various Artist - The very thought of you.wav","artist":null,"album":null,"uri":"USB/2203-6DCD/Danill Hertz CD1/01 Various Artist - The very thought of you.wav","trackType":"wav"} Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: CURRENT POSITION 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::syncState stateService play Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::syncState currentStatus play Jun 17 12:45:02 volu-alfa volumio[4024]: info: Received an update from plugin. extracting info from payload Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: ControllerMpd::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::servicePushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":206,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01 Various Artist - The very thought of you.wav","artist":null,"album":null,"uri":"USB/2203-6DCD/Danill Hertz CD1/01 Various Artist - The very thought of you.wav","trackType":"wav"} Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: CURRENT POSITION 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::syncState stateService play Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::syncState currentStatus play Jun 17 12:45:02 volu-alfa volumio[4024]: info: Received an update from plugin. extracting info from payload Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: ------------------------------ 82ms Jun 17 12:45:02 volu-alfa volumio[4024]: info: ------------------------------ 81ms Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand playlistinfo took 57 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand playlistinfo took 56 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: info: sendMpdCommand playlistinfo took 56 milliseconds Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::parseTrackInfo Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::parseTrackInfo Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: ControllerMpd::parseTrackInfo Jun 17 12:45:02 volu-alfa volumio[4024]: info: ControllerMpd::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::servicePushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01 Various Artist - The very thought of you.wav","artist":null,"album":null,"uri":"USB/2203-6DCD/Danill Hertz CD1/01 Various Artist - The very thought of you.wav","trackType":"wav"} Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: CURRENT POSITION 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::syncState stateService play Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::syncState currentStatus play Jun 17 12:45:02 volu-alfa volumio[4024]: info: Received an update from plugin. extracting info from payload Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: ControllerMpd::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::servicePushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01 Various Artist - The very thought of you.wav","artist":null,"album":null,"uri":"USB/2203-6DCD/Danill Hertz CD1/01 Various Artist - The very thought of you.wav","trackType":"wav"} Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: CURRENT POSITION 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::syncState stateService play Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::syncState currentStatus play Jun 17 12:45:02 volu-alfa volumio[4024]: info: Received an update from plugin. extracting info from payload Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: ControllerMpd::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::servicePushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01 Various Artist - The very thought of you.wav","artist":null,"album":null,"uri":"USB/2203-6DCD/Danill Hertz CD1/01 Various Artist - The very thought of you.wav","trackType":"wav"} Jun 17 12:45:02 volu-alfa volumio[4024]: verbose: CURRENT POSITION 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::syncState stateService play Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::syncState currentStatus play Jun 17 12:45:02 volu-alfa volumio[4024]: info: Received an update from plugin. extracting info from payload Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:02 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:45:02 volu-alfa volumio[4024]: info: ------------------------------ 146ms Jun 17 12:45:02 volu-alfa volumio[4024]: info: ------------------------------ 140ms Jun 17 12:45:02 volu-alfa volumio[4024]: info: ------------------------------ 139ms Jun 17 12:45:07 volu-alfa systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 17 12:45:07 volu-alfa dbus-daemon[516]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.63' (uid=0 pid=5228 comm="timedatectl show --property=NTPSynchronized --valu") Jun 17 12:45:07 volu-alfa systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 17 12:45:07 volu-alfa dbus-daemon[516]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 17 12:45:07 volu-alfa systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 17 12:45:07 volu-alfa setdatetime-helper.sh[5227]: Time is not synchronized. Attempting to sync... Jun 17 12:45:08 volu-alfa sudo[5238]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Tue, 17 Jun 2025 05:45:08 GMT#015' Jun 17 12:45:08 volu-alfa sudo[5238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 17 12:45:08 volu-alfa setdatetime-helper.sh[5239]: Tue Jun 17 12:45:08 +07 2025 Jun 17 12:45:08 volu-alfa systemd-journald[330]: Time jumped backwards, rotating. Jun 17 12:45:08 volu-alfa sudo[5238]: pam_unix(sudo:session): session closed for user root Jun 17 12:45:08 volu-alfa setdatetime-helper.sh[5227]: Time synchronized successfully. Jun 17 12:45:08 volu-alfa systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 17 12:45:08 volu-alfa systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 17 12:45:13 volu-alfa volumio[4024]: info: Preload queue cleared Jun 17 12:45:13 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 17 12:45:13 volu-alfa volumio[4024]: info: CoreStateMachine::ClearQueue Jun 17 12:45:13 volu-alfa volumio[4024]: info: CoreStateMachine::stop Jun 17 12:45:13 volu-alfa volumio[4024]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 12:45:13 volu-alfa volumio[4024]: info: CoreStateMachine::stPlaybackTimer Jun 17 12:45:13 volu-alfa volumio[4024]: info: CoreStateMachine::updateTrackBlock Jun 17 12:45:13 volu-alfa volumio[4024]: info: CorePlayQueue::getTrackBlock Jun 17 12:45:13 volu-alfa volumio[4024]: info: CoreStateMachine::pushState Jun 17 12:45:13 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:13 volu-alfa volumio[4024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 12:45:13 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushState Jun 17 12:45:13 volu-alfa volumio[4024]: info: CoreStateMachine::serviceStop Jun 17 12:45:13 volu-alfa volumio[4024]: info: CorePlayQueue::getTrack 0 Jun 17 12:45:13 volu-alfa volumio[4024]: info: CoreCommandRouter::serviceStop Jun 17 12:45:13 volu-alfa volumio[4024]: info: ControllerMpd::stop Jun 17 12:45:13 volu-alfa volumio[4024]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 12:45:13 volu-alfa volumio[4024]: info: CorePlayQueue::clearPlayQueue Jun 17 12:45:13 volu-alfa volumio[4024]: info: CorePlayQueue::saveQueue Jun 17 12:45:13 volu-alfa volumio[4024]: info: CoreCommandRouter::volumioPushQueue Jun 17 12:45:13 volu-alfa volumio[4024]: info: CoreStateMachine::addQueueItems Jun 17 12:45:13 volu-alfa volumio[4024]: info: CorePlayQueue::addQueueItems Jun 17 12:45:13 volu-alfa volumio[4024]: info: Preload queue cleared Jun 17 12:45:13 volu-alfa volumio[4024]: info: Adding Item to queue: music-library/USB/2203-6DCD/Mark Levinson - The Soul of Analog is Back Jun 17 12:45:13 volu-alfa volumio[4024]: info: Exploding uri music-library/USB/2203-6DCD/Mark Levinson - The Soul of Analog is Back in service mpd Jun 17 12:45:13 volu-alfa volumio[4024]: info: Jun 17 12:45:13 volu-alfa volumio[4024]: ---------------------------- MPD announces state update: player Jun 17 12:45:13 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=Mark%20Levinson/The%20Soul%20of%20Analog%20is%20Back/medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FMark%20Levinson%20-%20The%20Soul%20of%20Analog%20is%20Back%2FMark%20Levinson%20-%20The%20Soul%20of%20Analog%20is%20Back.cue&metadata=false Jun 17 12:45:13 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Mark Levinson - The Soul of Analog is Back/Mark Levinson - The Soul of Analog is Back.cue Jun 17 12:45:13 volu-alfa volumio[4024]: info: ALBUMART /albumart?cacheid=982&web=Mark%20Levinson/The%20Soul%20of%20Analog%20is%20Back/medium&path=%2Fmnt%2FUSB%2F2203-6DCD%2FMark%20Levinson%20-%20The%20Soul%20of%20Analog%20is%20Back%2FMark%20Levinson%20-%20The%20Soul%20of%20Analog%20is%20Back.cue&metadata=false Jun 17 12:45:13 volu-alfa volumio[4024]: info: URI /mnt/USB/2203-6DCD/Mark Levinson - The Soul of Analog is Back/Mark Levinson - The Soul of Analog is Back.cue Jun 17 12:45:13 volu-alfa volumio[4024]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 12:45:13 volu-alfa volumio[4024]: Error: Unable to resolve or reject the same promise twice Jun 17 12:45:13 volu-alfa volumio[4024]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 17 12:45:13 volu-alfa volumio[4024]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Jun 17 12:45:13 volu-alfa volumio[4024]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jun 17 12:45:13 volu-alfa volumio[4024]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jun 17 12:45:13 volu-alfa volumio[4024]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jun 17 12:45:13 volu-alfa volumio[4024]: at Socket.emit (node:events:514:28) Jun 17 12:45:13 volu-alfa volumio[4024]: at addChunk (node:internal/streams/readable:343:12) Jun 17 12:45:13 volu-alfa volumio[4024]: at readableAddChunk (node:internal/streams/readable:312:11) Jun 17 12:45:13 volu-alfa volumio[4024]: at Readable.push (node:internal/streams/readable:253:10) Jun 17 12:45:13 volu-alfa volumio[4024]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jun 17 12:45:13 volu-alfa volumio[4024]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 12:45:14 volu-alfa sudo[5281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-17 12:44' Jun 17 12:45:14 volu-alfa sudo[5281]: 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="cee9bb5b0ee50e4898478097a64b5c6c77d5a212" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 14 07:24:14 UTC 2025" VOLUMIO_VERSION="4.012" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b365b054264e3ebc2709665da2b5d1ee"