-- Logs begin at Wed 2025-05-14 22:56:17 CST, end at Wed 2025-05-14 23:02:53 CST. -- May 14 23:01:01 volumio volumio[1235]: info: Preload queue cleared May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::volumioReplaceandPlayItems May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::ClearQueue May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::stop May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::stPlaybackTimer May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::updateTrackBlock May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrackBlock May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::serviceStop May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::serviceStop May 14 23:01:01 volumio volumio[1235]: info: ControllerMpd::stop May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand stop May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::clearPlayQueue May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::saveQueue May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::volumioPushQueue May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::addQueueItems May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::addQueueItems May 14 23:01:01 volumio volumio[1235]: info: Preload queue cleared May 14 23:01:01 volumio volumio[1235]: info: Adding Item to queue: music-library/USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256 May 14 23:01:01 volumio volumio[1235]: info: Exploding uri music-library/USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256 in service mpd May 14 23:01:01 volumio volumio[1235]: info: ALBUMART /albumart?cacheid=186&web=Mitsutaka%20Shiraishi/Nino%20Rota%20%26%20Joe%20Hisaishi%20PIANO%20WORKS%20%2F%20Mitsutaka%20Shiraishi%2C%20piano%EF%BC%8811.2MHz%20DSD%EF%BC%89/extralarge&path=%2Fmnt%2FUSB%2F0D120A8D0D120A8D%2F%E5%90%84%E7%A7%8D%E7%A0%81%E7%8E%87%E9%9F%B3%E9%A2%91%E6%96%87%E4%BB%B6%2Fdsd%2F256%2Fdsd256-%E9%A6%96%E5%89%8D%E5%A5%8F%E6%9B%B2%20No.%202%3B%20%E5%BF%AB%E6%9D%BF%2C%20%E6%85%A2%E6%9D%BF%E5%92%8C%E5%B0%8F%E8%B0%83.dsf&metadata=false May 14 23:01:01 volumio volumio[1235]: info: URI /mnt/USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 2; 快板, 慢板和小调.dsf May 14 23:01:01 volumio volumio[1235]: info: ALBUMART /albumart?cacheid=186&web=Mitsutaka%20Shiraishi/Nino%20Rota%20%26%20Joe%20Hisaishi%20PIANO%20WORKS%20%2F%20Mitsutaka%20Shiraishi%2C%20piano%EF%BC%8811.2MHz%20DSD%EF%BC%89/extralarge&path=%2Fmnt%2FUSB%2F0D120A8D0D120A8D%2F%E5%90%84%E7%A7%8D%E7%A0%81%E7%8E%87%E9%9F%B3%E9%A2%91%E6%96%87%E4%BB%B6%2Fdsd%2F256%2Fdsd256-%E9%A6%96%E5%89%8D%E5%A5%8F%E6%9B%B2%20No.%206%3B%20%E8%A1%8C%E6%9D%BF.dsf&metadata=false May 14 23:01:01 volumio volumio[1235]: info: URI /mnt/USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 6; 行板.dsf May 14 23:01:01 volumio volumio[1235]: info: May 14 23:01:01 volumio volumio[1235]: ---------------------------- MPD announces state update: player May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand stop took 66 milliseconds May 14 23:01:01 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::volumioPushQueue May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::saveQueue May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::updateTrackBlock May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrackBlock May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::volumioPlay May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::play index 0 May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::stop May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::play index undefined May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::startPlaybackTimer May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::clearAddPlayTracks USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 2; 快板, 慢板和小调.dsf May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand stop May 14 23:01:01 volumio volumio[1235]: info: May 14 23:01:01 volumio volumio[1235]: ---------------------------- MPD announces state update: player May 14 23:01:01 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:01:01 volumio volumio[1235]: info: May 14 23:01:01 volumio volumio[1235]: ---------------------------- MPD announces state update: player May 14 23:01:01 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand status took 16 milliseconds May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand stop took 4 milliseconds May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand status took 4 milliseconds May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand status took 2 milliseconds May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand clear May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 23:01:01 volumio volumio[1235]: info: May 14 23:01:01 volumio volumio[1235]: ---------------------------- MPD announces system playlist update May 14 23:01:01 volumio volumio[1235]: info: Ignoring MPD Status Update May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand playlistinfo took 4 milliseconds May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand clear took 8 milliseconds May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand playlistinfo took 8 milliseconds May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand playlistinfo took 8 milliseconds May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::parseTrackInfo May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand add "USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 2; 快板, 慢板和小调.dsf" May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::parseTrackInfo May 14 23:01:01 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: 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":"Jeep's Blues","artist":"Various Artists","album":"Jazz at the Pawnshop","uri":"USB/0D120A8D0D120A8D/各种码率音频文件/dsd/512/dsd512_Jeeps-Blues_stereo512.dsf","trackType":"dsf"} May 14 23:01:01 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::syncState stateService stop May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus stop May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:01:01 volumio volumio[1235]: info: No code May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:01:01 volumio volumio[1235]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 14 23:01:01 volumio volumio[1235]: info: ------------------------------ 61ms May 14 23:01:01 volumio volumio[1235]: info: ------------------------------ 49ms May 14 23:01:01 volumio volumio[1235]: info: ------------------------------ 47ms May 14 23:01:01 volumio volumio[1235]: info: May 14 23:01:01 volumio volumio[1235]: ---------------------------- MPD announces system playlist update May 14 23:01:01 volumio volumio[1235]: info: Ignoring MPD Status Update May 14 23:01:01 volumio volumio[1235]: info: May 14 23:01:01 volumio volumio[1235]: ---------------------------- MPD announces system playlist update May 14 23:01:01 volumio volumio[1235]: info: Ignoring MPD Status Update May 14 23:01:01 volumio volumio[1235]: error: updateQueue error: null May 14 23:01:01 volumio volumio[1235]: info: May 14 23:01:01 volumio volumio[1235]: ---------------------------- MPD announces system playlist update May 14 23:01:01 volumio volumio[1235]: info: Ignoring MPD Status Update May 14 23:01:01 volumio volumio[1235]: info: ------------------------------ 50ms May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand add "USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 2; 快板, 慢板和小调.dsf" took 44 milliseconds May 14 23:01:01 volumio volumio[1235]: info: ------------------------------ 9ms May 14 23:01:01 volumio volumio[1235]: info: ------------------------------ 8ms May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand play May 14 23:01:01 volumio volumio[1235]: info: May 14 23:01:01 volumio volumio[1235]: ---------------------------- MPD announces system playlist update May 14 23:01:01 volumio volumio[1235]: info: Ignoring MPD Status Update May 14 23:01:01 volumio volumio[1235]: info: May 14 23:01:01 volumio volumio[1235]: ---------------------------- MPD announces system playlist update May 14 23:01:01 volumio volumio[1235]: info: Ignoring MPD Status Update May 14 23:01:01 volumio volumio[1235]: info: May 14 23:01:01 volumio volumio[1235]: ---------------------------- MPD announces state update: player May 14 23:01:01 volumio volumio[1235]: info: ------------------------------ 22ms May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand play took 17 milliseconds May 14 23:01:01 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:01:01 volumio volumio[1235]: info: ------------------------------ 14ms May 14 23:01:01 volumio volumio[1235]: info: May 14 23:01:01 volumio volumio[1235]: ---------------------------- MPD announces state update: player May 14 23:01:01 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:01:01 volumio volumio[1235]: info: May 14 23:01:01 volumio volumio[1235]: ---------------------------- MPD announces state update: player May 14 23:01:01 volumio volumio[1235]: info: ------------------------------ 85ms May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand status took 73 milliseconds May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand status took 71 milliseconds May 14 23:01:01 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 23:01:01 volumio volumio[1235]: info: May 14 23:01:01 volumio volumio[1235]: ---------------------------- MPD announces state update: player May 14 23:01:01 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:01:01 volumio volumio[1235]: info: May 14 23:01:01 volumio volumio[1235]: ---------------------------- MPD announces state update: player May 14 23:01:01 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand status took 21 milliseconds May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand playlistinfo took 22 milliseconds May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand playlistinfo took 21 milliseconds May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand status took 20 milliseconds May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand status took 9 milliseconds May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::parseTrackInfo May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 23:01:01 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":76,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"15 Preludes No. 2; Allegro, ma espressivo e delicato","artist":"Mitsutaka Shiraishi","album":"Nino Rota & Joe Hisaishi PIANO WORKS / Mitsutaka Shiraishi, piano（11.2MHz DSD）","uri":"USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 2; 快板, 慢板和小调.dsf","trackType":"dsf"} May 14 23:01:01 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::syncState stateService play May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus stop May 14 23:01:01 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":76,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"15 Preludes No. 2; Allegro, ma espressivo e delicato","artist":"Mitsutaka Shiraishi","album":"Nino Rota & Joe Hisaishi PIANO WORKS / Mitsutaka Shiraishi, piano（11.2MHz DSD）","uri":"USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 2; 快板, 慢板和小调.dsf","trackType":"dsf"} May 14 23:01:01 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::syncState stateService play May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus play May 14 23:01:01 volumio volumio[1235]: info: Received an update from plugin. extracting info from payload May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:01:01 volumio volumio[1235]: info: ------------------------------ 122ms May 14 23:01:01 volumio volumio[1235]: info: ------------------------------ 141ms May 14 23:01:01 volumio volumio[1235]: info: May 14 23:01:01 volumio volumio[1235]: ---------------------------- MPD announces state update: player May 14 23:01:01 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand playlistinfo took 47 milliseconds May 14 23:01:01 volumio volumio[1235]: info: sendMpdCommand status took 2 milliseconds May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::parseTrackInfo May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:01:01 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 23:01:01 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":76,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"15 Preludes No. 2; Allegro, ma espressivo e delicato","artist":"Mitsutaka Shiraishi","album":"Nino Rota & Joe Hisaishi PIANO WORKS / Mitsutaka Shiraishi, piano（11.2MHz DSD）","uri":"USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 2; 快板, 慢板和小调.dsf","trackType":"dsf"} May 14 23:01:01 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::syncState stateService play May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus play May 14 23:01:01 volumio volumio[1235]: info: Received an update from plugin. extracting info from payload May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:01:01 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1,"duration":76,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"15 Preludes No. 2; Allegro, ma espressivo e delicato","artist":"Mitsutaka Shiraishi","album":"Nino Rota & Joe Hisaishi PIANO WORKS / Mitsutaka Shiraishi, piano（11.2MHz DSD）","uri":"USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 2; 快板, 慢板和小调.dsf","trackType":"dsf"} May 14 23:01:01 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::syncState stateService play May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus play May 14 23:01:01 volumio volumio[1235]: info: Received an update from plugin. extracting info from payload May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:01:01 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5,"duration":76,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"15 Preludes No. 2; Allegro, ma espressivo e delicato","artist":"Mitsutaka Shiraishi","album":"Nino Rota & Joe Hisaishi PIANO WORKS / Mitsutaka Shiraishi, piano（11.2MHz DSD）","uri":"USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 2; 快板, 慢板和小调.dsf","trackType":"dsf"} May 14 23:01:01 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::syncState stateService play May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus play May 14 23:01:01 volumio volumio[1235]: info: Received an update from plugin. extracting info from payload May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:01:01 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:01:01 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:01 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:01:02 volumio volumio[1235]: info: ------------------------------ 188ms May 14 23:01:02 volumio volumio[1235]: info: ------------------------------ 187ms May 14 23:01:02 volumio volumio[1235]: info: ------------------------------ 185ms May 14 23:01:02 volumio volumio[1235]: info: sendMpdCommand playlistinfo took 148 milliseconds May 14 23:01:02 volumio volumio[1235]: verbose: ControllerMpd::parseTrackInfo May 14 23:01:02 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:01:02 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:01:02 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:02 volumio volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":76,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"15 Preludes No. 2; Allegro, ma espressivo e delicato","artist":"Mitsutaka Shiraishi","album":"Nino Rota & Joe Hisaishi PIANO WORKS / Mitsutaka Shiraishi, piano（11.2MHz DSD）","uri":"USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 2; 快板, 慢板和小调.dsf","trackType":"dsf"} May 14 23:01:02 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:01:02 volumio volumio[1235]: info: CoreStateMachine::syncState stateService play May 14 23:01:02 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus play May 14 23:01:02 volumio volumio[1235]: info: Received an update from plugin. extracting info from payload May 14 23:01:02 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:01:02 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:02 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 23:01:02 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:01:02 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:01:02 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:02 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:01:02 volumio volumio[1235]: info: ------------------------------ 198ms May 14 23:01:03 volumio startx[1241]: (EE) client bug: timer event3 debounce: offset negative (-10ms) May 14 23:01:03 volumio startx[1241]: (EE) client bug: timer event3 debounce short: offset negative (-23ms) May 14 23:01:06 volumio volumio[1235]: info: VolumeController::SetAlsaVolume92 May 14 23:01:06 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:01:06 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:06 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 23:01:06 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:01:24 volumio volumio[1235]: info: VolumeController::SetAlsaVolume98 May 14 23:01:24 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:01:24 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:01:24 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 23:01:24 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:02 volumio startx[1241]: (EE) client bug: timer event3 debounce short: offset negative (-2ms) May 14 23:02:13 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:13 volumio volumio[1235]: info: CorePlayQueue::getTrack 1 May 14 23:02:13 volumio volumio[1235]: info: Prefetching next song May 14 23:02:13 volumio volumio[1235]: info: DOING PREFETCH IN MPD May 14 23:02:13 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand add "USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 6; 行板.dsf" May 14 23:02:13 volumio volumio[1235]: info: May 14 23:02:13 volumio volumio[1235]: ---------------------------- MPD announces system playlist update May 14 23:02:13 volumio volumio[1235]: info: Ignoring MPD Status Update May 14 23:02:13 volumio volumio[1235]: info: sendMpdCommand add "USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 6; 行板.dsf" took 2 milliseconds May 14 23:02:13 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand consume 1 May 14 23:02:13 volumio volumio[1235]: info: ------------------------------ 6ms May 14 23:02:13 volumio volumio[1235]: info: May 14 23:02:13 volumio volumio[1235]: ---------------------------- MPD announces system playlist update May 14 23:02:13 volumio volumio[1235]: info: Ignoring MPD Status Update May 14 23:02:13 volumio volumio[1235]: info: May 14 23:02:13 volumio volumio[1235]: ---------------------------- MPD announces system playlist update May 14 23:02:13 volumio volumio[1235]: info: Ignoring MPD Status Update May 14 23:02:13 volumio volumio[1235]: info: May 14 23:02:13 volumio volumio[1235]: ---------------------------- MPD announces state update: options May 14 23:02:13 volumio volumio[1235]: info: sendMpdCommand consume 1 took 14 milliseconds May 14 23:02:13 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:02:13 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:13 volumio volumio[1235]: info: ------------------------------ 9ms May 14 23:02:13 volumio volumio[1235]: info: ------------------------------ 7ms May 14 23:02:13 volumio volumio[1235]: info: May 14 23:02:13 volumio volumio[1235]: ---------------------------- MPD announces state update: options May 14 23:02:13 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:02:13 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:13 volumio volumio[1235]: info: May 14 23:02:13 volumio volumio[1235]: ---------------------------- MPD announces state update: options May 14 23:02:13 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:02:13 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:13 volumio volumio[1235]: info: sendMpdCommand status took 7 milliseconds May 14 23:02:13 volumio volumio[1235]: info: sendMpdCommand status took 4 milliseconds May 14 23:02:13 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:13 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 23:02:13 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:13 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 23:02:13 volumio volumio[1235]: info: sendMpdCommand status took 7 milliseconds May 14 23:02:13 volumio volumio[1235]: info: sendMpdCommand playlistinfo took 5 milliseconds May 14 23:02:13 volumio volumio[1235]: info: sendMpdCommand playlistinfo took 6 milliseconds May 14 23:02:13 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:13 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 23:02:13 volumio volumio[1235]: verbose: ControllerMpd::parseTrackInfo May 14 23:02:13 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:02:13 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:02:13 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:13 volumio volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":70999,"duration":76,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"15 Preludes No. 2; Allegro, ma espressivo e delicato","artist":"Mitsutaka Shiraishi","album":"Nino Rota & Joe Hisaishi PIANO WORKS / Mitsutaka Shiraishi, piano（11.2MHz DSD）","uri":"USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 2; 快板, 慢板和小调.dsf","trackType":"dsf"} May 14 23:02:13 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:02:13 volumio volumio[1235]: info: CoreStateMachine::syncState stateService play May 14 23:02:13 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus play May 14 23:02:13 volumio volumio[1235]: info: Received an update from plugin. extracting info from payload May 14 23:02:13 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:13 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:13 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 23:02:13 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:13 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:13 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:13 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:13 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:02:13 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:02:13 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:13 volumio volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":70999,"duration":76,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"15 Preludes No. 2; Allegro, ma espressivo e delicato","artist":"Mitsutaka Shiraishi","album":"Nino Rota & Joe Hisaishi PIANO WORKS / Mitsutaka Shiraishi, piano（11.2MHz DSD）","uri":"USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 2; 快板, 慢板和小调.dsf","trackType":"dsf"} May 14 23:02:13 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:02:13 volumio volumio[1235]: info: CoreStateMachine::syncState stateService play May 14 23:02:13 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus play May 14 23:02:13 volumio volumio[1235]: info: Received an update from plugin. extracting info from payload May 14 23:02:13 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:13 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:13 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:13 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:13 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:13 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:13 volumio volumio[1235]: info: ------------------------------ 136ms May 14 23:02:13 volumio volumio[1235]: info: ------------------------------ 132ms May 14 23:02:13 volumio volumio[1235]: info: sendMpdCommand playlistinfo took 122 milliseconds May 14 23:02:13 volumio volumio[1235]: verbose: ControllerMpd::parseTrackInfo May 14 23:02:13 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:02:13 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:02:13 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:13 volumio volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":70999,"duration":76,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"15 Preludes No. 2; Allegro, ma espressivo e delicato","artist":"Mitsutaka Shiraishi","album":"Nino Rota & Joe Hisaishi PIANO WORKS / Mitsutaka Shiraishi, piano（11.2MHz DSD）","uri":"USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 2; 快板, 慢板和小调.dsf","trackType":"dsf"} May 14 23:02:13 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:02:13 volumio volumio[1235]: info: CoreStateMachine::syncState stateService play May 14 23:02:13 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus play May 14 23:02:13 volumio volumio[1235]: info: Received an update from plugin. extracting info from payload May 14 23:02:13 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:13 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:13 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:13 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:13 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:13 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:13 volumio volumio[1235]: info: ------------------------------ 219ms May 14 23:02:17 volumio volumio[1235]: info: CoreStateMachine::startPlaybackTimer May 14 23:02:17 volumio volumio[1235]: info: CorePlayQueue::getTrack 1 May 14 23:02:17 volumio volumio[1235]: info: May 14 23:02:17 volumio volumio[1235]: ---------------------------- MPD announces system playlist update May 14 23:02:17 volumio volumio[1235]: info: Ignoring MPD Status Update May 14 23:02:17 volumio volumio[1235]: info: May 14 23:02:17 volumio volumio[1235]: ---------------------------- MPD announces state update: player May 14 23:02:17 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:02:17 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:17 volumio volumio[1235]: info: May 14 23:02:17 volumio volumio[1235]: ---------------------------- MPD announces system playlist update May 14 23:02:17 volumio volumio[1235]: info: Ignoring MPD Status Update May 14 23:02:17 volumio volumio[1235]: info: May 14 23:02:17 volumio volumio[1235]: ---------------------------- MPD announces state update: player May 14 23:02:17 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:02:17 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:17 volumio volumio[1235]: info: May 14 23:02:17 volumio volumio[1235]: ---------------------------- MPD announces system playlist update May 14 23:02:17 volumio volumio[1235]: info: Ignoring MPD Status Update May 14 23:02:17 volumio volumio[1235]: info: May 14 23:02:17 volumio volumio[1235]: ---------------------------- MPD announces state update: player May 14 23:02:17 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:02:17 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:17 volumio volumio[1235]: info: ------------------------------ 40ms May 14 23:02:17 volumio volumio[1235]: info: sendMpdCommand status took 15 milliseconds May 14 23:02:17 volumio volumio[1235]: info: ------------------------------ 15ms May 14 23:02:17 volumio volumio[1235]: info: sendMpdCommand status took 14 milliseconds May 14 23:02:17 volumio volumio[1235]: info: ------------------------------ 11ms May 14 23:02:17 volumio volumio[1235]: info: sendMpdCommand status took 6 milliseconds May 14 23:02:17 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:17 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 23:02:17 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:17 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 23:02:17 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:17 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 23:02:17 volumio volumio[1235]: info: sendMpdCommand playlistinfo took 7 milliseconds May 14 23:02:17 volumio volumio[1235]: info: sendMpdCommand playlistinfo took 8 milliseconds May 14 23:02:17 volumio volumio[1235]: info: sendMpdCommand playlistinfo took 7 milliseconds May 14 23:02:17 volumio volumio[1235]: verbose: ControllerMpd::parseTrackInfo May 14 23:02:17 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:02:17 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:02:17 volumio volumio[1235]: info: CorePlayQueue::getTrack 1 May 14 23:02:17 volumio volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":85,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"15 Preludes No. 6; Andante","artist":"Mitsutaka Shiraishi","album":"Nino Rota & Joe Hisaishi PIANO WORKS / Mitsutaka Shiraishi, piano（11.2MHz DSD）","uri":"USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 6; 行板.dsf","trackType":"dsf"} May 14 23:02:17 volumio volumio[1235]: verbose: CURRENT POSITION 1 May 14 23:02:17 volumio volumio[1235]: info: CoreStateMachine::syncState stateService play May 14 23:02:17 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus play May 14 23:02:17 volumio volumio[1235]: info: Received an update from plugin. extracting info from payload May 14 23:02:17 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:17 volumio volumio[1235]: info: CorePlayQueue::getTrack 1 May 14 23:02:17 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 23:02:17 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:17 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:17 volumio volumio[1235]: info: CorePlayQueue::getTrack 1 May 14 23:02:17 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:17 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:02:17 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:02:17 volumio volumio[1235]: info: CorePlayQueue::getTrack 1 May 14 23:02:17 volumio volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69,"duration":85,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"15 Preludes No. 6; Andante","artist":"Mitsutaka Shiraishi","album":"Nino Rota & Joe Hisaishi PIANO WORKS / Mitsutaka Shiraishi, piano（11.2MHz DSD）","uri":"USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 6; 行板.dsf","trackType":"dsf"} May 14 23:02:17 volumio volumio[1235]: verbose: CURRENT POSITION 1 May 14 23:02:17 volumio volumio[1235]: info: CoreStateMachine::syncState stateService play May 14 23:02:17 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus play May 14 23:02:17 volumio volumio[1235]: info: Received an update from plugin. extracting info from payload May 14 23:02:17 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:17 volumio volumio[1235]: info: CorePlayQueue::getTrack 1 May 14 23:02:17 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 23:02:17 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:17 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:17 volumio volumio[1235]: info: CorePlayQueue::getTrack 1 May 14 23:02:17 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:17 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:02:17 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:02:17 volumio volumio[1235]: info: CorePlayQueue::getTrack 1 May 14 23:02:17 volumio volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69,"duration":85,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"15 Preludes No. 6; Andante","artist":"Mitsutaka Shiraishi","album":"Nino Rota & Joe Hisaishi PIANO WORKS / Mitsutaka Shiraishi, piano（11.2MHz DSD）","uri":"USB/0D120A8D0D120A8D/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 6; 行板.dsf","trackType":"dsf"} May 14 23:02:17 volumio volumio[1235]: verbose: CURRENT POSITION 1 May 14 23:02:17 volumio volumio[1235]: info: CoreStateMachine::syncState stateService play May 14 23:02:17 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus play May 14 23:02:17 volumio volumio[1235]: info: Received an update from plugin. extracting info from payload May 14 23:02:17 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:17 volumio volumio[1235]: info: CorePlayQueue::getTrack 1 May 14 23:02:17 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:17 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:17 volumio volumio[1235]: info: CorePlayQueue::getTrack 1 May 14 23:02:17 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:18 volumio volumio[1235]: info: ------------------------------ 259ms May 14 23:02:18 volumio volumio[1235]: info: ------------------------------ 238ms May 14 23:02:18 volumio volumio[1235]: info: ------------------------------ 235ms May 14 23:02:18 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:18 volumio volumio[1235]: info: CorePlayQueue::getTrack 1 May 14 23:02:18 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 23:02:18 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:19 volumio ntpd[1224]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 14 23:02:35 volumio kernel: usb 4-1: USB disconnect, device number 3 May 14 23:02:35 volumio kernel: device offline error, dev sdb, sector 594744 op 0x0:(READ) flags 0x80700 phys_seg 32 prio class 2 May 14 23:02:35 volumio kernel: device offline error, dev sdb, sector 595000 op 0x0:(READ) flags 0x80700 phys_seg 32 prio class 2 May 14 23:02:35 volumio kernel: device offline error, dev sdb, sector 594744 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 14 23:02:35 volumio kernel: Buffer I/O error on dev sdb1, logical block 74087, async page read May 14 23:02:35 volumio kernel: device offline error, dev sdb, sector 594744 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 14 23:02:35 volumio kernel: Buffer I/O error on dev sdb1, logical block 74087, async page read May 14 23:02:35 volumio kernel: device offline error, dev sdb, sector 594744 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 14 23:02:35 volumio kernel: Buffer I/O error on dev sdb1, logical block 74087, async page read May 14 23:02:35 volumio kernel: device offline error, dev sdb, sector 594744 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 14 23:02:35 volumio kernel: Buffer I/O error on dev sdb1, logical block 74087, async page read May 14 23:02:35 volumio ntfs-3g[2642]: ntfs_attr_pread error reading '/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 6; 行板.dsf' at offset 52543488: 131072 <> 98304: Input/output error May 14 23:02:35 volumio ntfs-3g[2642]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 14 23:02:35 volumio ntfs-3g[2642]: ntfs_attr_pread error reading '/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 6; 行板.dsf' at offset 52641792: 32768 <> -1: Input/output error May 14 23:02:35 volumio ntfs-3g[2642]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 14 23:02:35 volumio ntfs-3g[2642]: ntfs_attr_pread error reading '/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 6; 行板.dsf' at offset 52674560: 131072 <> -1: Input/output error May 14 23:02:35 volumio ntfs-3g[2642]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 14 23:02:35 volumio ntfs-3g[2642]: ntfs_attr_pread error reading '/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 6; 行板.dsf' at offset 52805632: 131072 <> -1: Input/output error May 14 23:02:35 volumio kernel: device offline error, dev sdb, sector 594808 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 14 23:02:35 volumio kernel: Buffer I/O error on dev sdb1, logical block 74095, async page read May 14 23:02:35 volumio kernel: device offline error, dev sdb, sector 595064 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 14 23:02:35 volumio kernel: Buffer I/O error on dev sdb1, logical block 74127, async page read May 14 23:02:35 volumio ntfs-3g[2642]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 14 23:02:35 volumio kernel: device offline error, dev sdb, sector 594744 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 14 23:02:35 volumio kernel: Buffer I/O error on dev sdb1, logical block 74087, async page read May 14 23:02:35 volumio ntfs-3g[2642]: ntfs_attr_pread error reading '/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 6; 行板.dsf' at offset 52641792: 4096 <> -1: Input/output error May 14 23:02:35 volumio ntfs-3g[2642]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 14 23:02:35 volumio ntfs-3g[2642]: ntfs_attr_pread error reading '/各种码率音频文件/dsd/256/dsd256-首前奏曲 No. 6; 行板.dsf' at offset 52641792: 4096 <> -1: Input/output error May 14 23:02:35 volumio kernel: device offline error, dev sdb, sector 594744 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 14 23:02:35 volumio kernel: Buffer I/O error on dev sdb1, logical block 74087, async page read May 14 23:02:35 volumio volumio[1235]: info: Partition removed: {"syspath":"/sys/devices/pci0000:00/0000:00:13.2/usb4/4-1/4-1:1.0/host3/target3:0:0/3:0:0:0/block/sdb/sdb1","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/b3816a38-01 /dev/disk/by-uuid/0D120A8D0D120A8D /dev/disk/by-id/usb-Lenovo_SSD_ST510_120G_C345512017106442874-0:0-part1 /dev/disk/by-path/pci-0000:00:13.2-usb-0:1:1.0-scsi-0:0:0:0-part1","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/pci0000:00/0000:00:13.2/usb4/4-1/4-1:1.0/host3/target3:0:0/3:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"11","ID_BUS":"usb","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"0D120A8D0D120A8D","ID_FS_UUID_ENC":"0D120A8D0D120A8D","ID_INSTANCE":"0:0","ID_MODEL":"SSD_ST510_120G","ID_MODEL_ENC":"SSD\\x20\\x20ST510\\x20\\x20120G","ID_MODEL_ID":"0578","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"234434497","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"b3816a38-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"b3816a38","ID_PATH":"pci-0000:00:13.2-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"pci-0000_00_13_2-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"3202","ID_SERIAL":"Lenovo_SSD_ST510_120G_C345512017106442874-0:0","ID_SERIAL_SHORT":"C345512017106442874","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Lenovo","ID_VENDOR_ENC":"Lenovo\\x20\\x20","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"3159","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"229605249"} May 14 23:02:35 volumio kernel: sd 3:0:0:0: [sdb] Synchronizing SCSI cache May 14 23:02:36 volumio sudo[3438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1 May 14 23:02:36 volumio sudo[3438]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 23:02:36 volumio kernel: sd 3:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=DID_ERROR driverbyte=DRIVER_OK May 14 23:02:36 volumio ntfs-3g[2642]: Unmounting /dev/sdb1 () May 14 23:02:36 volumio ntfs-3g[2642]: Failed to sync device /dev/sdb1: Input/output error May 14 23:02:36 volumio ntfs-3g[2642]: Failed to close volume /dev/sdb1: Input/output error May 14 23:02:36 volumio systemd[1]: media-0D120A8D0D120A8D.mount: Succeeded. May 14 23:02:36 volumio sudo[3438]: pam_unix(sudo:session): session closed for user root May 14 23:02:37 volumio volumio[1235]: info: May 14 23:02:37 volumio volumio[1235]: ---------------------------- MPD announces system playlist update May 14 23:02:37 volumio volumio[1235]: info: Ignoring MPD Status Update May 14 23:02:37 volumio volumio[1235]: info: May 14 23:02:37 volumio volumio[1235]: ---------------------------- MPD announces state update: player May 14 23:02:37 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:02:37 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:37 volumio volumio[1235]: info: May 14 23:02:37 volumio volumio[1235]: ---------------------------- MPD announces system playlist update May 14 23:02:37 volumio volumio[1235]: info: Ignoring MPD Status Update May 14 23:02:37 volumio volumio[1235]: info: May 14 23:02:37 volumio volumio[1235]: ---------------------------- MPD announces state update: player May 14 23:02:37 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:02:37 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:37 volumio volumio[1235]: info: May 14 23:02:37 volumio volumio[1235]: ---------------------------- MPD announces system playlist update May 14 23:02:37 volumio volumio[1235]: info: Ignoring MPD Status Update May 14 23:02:37 volumio volumio[1235]: info: May 14 23:02:37 volumio volumio[1235]: ---------------------------- MPD announces state update: player May 14 23:02:37 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:02:37 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:37 volumio volumio[1235]: error: updateQueue error: null May 14 23:02:37 volumio volumio[1235]: error: updateQueue error: null May 14 23:02:37 volumio volumio[1235]: error: updateQueue error: null May 14 23:02:37 volumio volumio[1235]: info: ------------------------------ 11ms May 14 23:02:37 volumio volumio[1235]: info: sendMpdCommand status took 9 milliseconds May 14 23:02:37 volumio volumio[1235]: info: ------------------------------ 10ms May 14 23:02:37 volumio volumio[1235]: info: sendMpdCommand status took 9 milliseconds May 14 23:02:37 volumio volumio[1235]: info: ------------------------------ 8ms May 14 23:02:37 volumio volumio[1235]: info: sendMpdCommand status took 4 milliseconds May 14 23:02:37 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:37 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:37 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:37 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:02:37 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:02:37 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:37 volumio volumio[1235]: info: CorePlayQueue::getTrack 1 May 14 23:02:37 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 23:02:37 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:37 volumio volumio[1235]: info: CorePlayQueue::getTrack 1 May 14 23:02:37 volumio volumio[1235]: 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 14 23:02:37 volumio volumio[1235]: verbose: CURRENT POSITION 1 May 14 23:02:37 volumio volumio[1235]: info: CoreStateMachine::syncState stateService stop May 14 23:02:37 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus play May 14 23:02:37 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:37 volumio volumio[1235]: info: CoreStateMachine::stPlaybackTimer May 14 23:02:37 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:02:37 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:02:37 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:37 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:37 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 23:02:37 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:37 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:37 volumio volumio[1235]: 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 14 23:02:37 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:02:37 volumio volumio[1235]: info: CoreStateMachine::syncState stateService stop May 14 23:02:37 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus stop May 14 23:02:37 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:37 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:37 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:37 volumio volumio[1235]: info: No code May 14 23:02:37 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:37 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:37 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:37 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:02:37 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:02:37 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:37 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:37 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:37 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:37 volumio volumio[1235]: 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 14 23:02:37 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:02:37 volumio volumio[1235]: info: CoreStateMachine::syncState stateService stop May 14 23:02:37 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus stop May 14 23:02:37 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:37 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:37 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:37 volumio volumio[1235]: info: No code May 14 23:02:37 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:37 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:37 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:37 volumio volumio[1235]: info: ------------------------------ 87ms May 14 23:02:37 volumio volumio[1235]: info: ------------------------------ 86ms May 14 23:02:37 volumio volumio[1235]: info: ------------------------------ 82ms May 14 23:02:40 volumio volumio[1235]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/0D120A8D0D120A8D May 14 23:02:41 volumio volumio[1235]: info: Scanning removed location : "USB/0D120A8D0D120A8D" May 14 23:02:41 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:41 volumio volumio[1235]: info: May 14 23:02:41 volumio volumio[1235]: ---------------------------- MPD announces state update: update May 14 23:02:41 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:02:41 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:41 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:41 volumio volumio[1235]: info: May 14 23:02:41 volumio volumio[1235]: ---------------------------- MPD announces state update: update May 14 23:02:41 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:02:41 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:41 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:41 volumio volumio[1235]: info: May 14 23:02:41 volumio volumio[1235]: ---------------------------- MPD announces state update: update May 14 23:02:41 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:02:41 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:41 volumio volumio[1235]: info: sendMpdCommand status took 7 milliseconds May 14 23:02:41 volumio volumio[1235]: info: sendMpdCommand status took 5 milliseconds May 14 23:02:41 volumio volumio[1235]: info: sendMpdCommand status took 10 milliseconds May 14 23:02:41 volumio volumio[1235]: info: sendMpdCommand status took 8 milliseconds May 14 23:02:41 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:41 volumio volumio[1235]: info: Command Router : Notfying DB Updatetrue May 14 23:02:41 volumio volumio[1235]: info: CoreCommandRouter::Close All Modals sent May 14 23:02:41 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:41 volumio volumio[1235]: info: Command Router : Notfying DB Updatetrue May 14 23:02:41 volumio volumio[1235]: info: CoreCommandRouter::Close All Modals sent May 14 23:02:41 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:41 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:02:41 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:02:41 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:41 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:41 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 23:02:41 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:41 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:41 volumio volumio[1235]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 14 23:02:41 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:02:41 volumio volumio[1235]: info: CoreStateMachine::syncState stateService stop May 14 23:02:41 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus stop May 14 23:02:41 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:41 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:41 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 23:02:41 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:41 volumio volumio[1235]: info: No code May 14 23:02:41 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:41 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:41 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:41 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:02:41 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:02:41 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:41 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:41 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:41 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:41 volumio volumio[1235]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 14 23:02:41 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:02:41 volumio volumio[1235]: info: CoreStateMachine::syncState stateService stop May 14 23:02:41 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus stop May 14 23:02:41 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:41 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:41 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:41 volumio volumio[1235]: info: No code May 14 23:02:41 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:41 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:41 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:41 volumio volumio[1235]: info: ------------------------------ 414ms May 14 23:02:41 volumio volumio[1235]: info: ------------------------------ 414ms May 14 23:02:41 volumio volumio[1235]: info: MPD Database updated - AlbumList cache refreshed May 14 23:02:41 volumio volumio[1235]: info: May 14 23:02:41 volumio volumio[1235]: ---------------------------- MPD announces state update: database May 14 23:02:41 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:41 volumio volumio[1235]: info: May 14 23:02:41 volumio volumio[1235]: ---------------------------- MPD announces state update: update May 14 23:02:41 volumio volumio[1235]: info: sendMpdCommand status took 415 milliseconds May 14 23:02:41 volumio volumio[1235]: info: sendMpdCommand status took 415 milliseconds May 14 23:02:41 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:02:41 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:41 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:02:41 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:41 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:42 volumio volumio[1235]: info: Command Router : Notfying DB Updatetrue May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::Close All Modals sent May 14 23:02:42 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:42 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 14 23:02:42 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::syncState stateService stop May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus stop May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: No code May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: ------------------------------ 563ms May 14 23:02:42 volumio volumio[1235]: info: MPD Database updated - AlbumList cache refreshed May 14 23:02:42 volumio volumio[1235]: info: May 14 23:02:42 volumio volumio[1235]: ---------------------------- MPD announces state update: database May 14 23:02:42 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:42 volumio volumio[1235]: info: May 14 23:02:42 volumio volumio[1235]: ---------------------------- MPD announces state update: update May 14 23:02:42 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:02:42 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:42 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:02:42 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:42 volumio volumio[1235]: info: MPD Database updated - AlbumList cache refreshed May 14 23:02:42 volumio volumio[1235]: info: May 14 23:02:42 volumio volumio[1235]: ---------------------------- MPD announces state update: database May 14 23:02:42 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:42 volumio volumio[1235]: info: May 14 23:02:42 volumio volumio[1235]: ---------------------------- MPD announces state update: update May 14 23:02:42 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:02:42 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:42 volumio volumio[1235]: info: ControllerMpd::getState May 14 23:02:42 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status May 14 23:02:42 volumio volumio[1235]: info: sendMpdCommand status took 194 milliseconds May 14 23:02:42 volumio volumio[1235]: info: sendMpdCommand status took 195 milliseconds May 14 23:02:42 volumio volumio[1235]: info: sendMpdCommand status took 198 milliseconds May 14 23:02:42 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:42 volumio volumio[1235]: info: Command Router : Notfying DB Updatefalse May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::Close All Modals sent May 14 23:02:42 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:42 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:42 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: 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 14 23:02:42 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::syncState stateService stop May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus stop May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: No code May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: 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 14 23:02:42 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::syncState stateService stop May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus stop May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: No code May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: ------------------------------ 386ms May 14 23:02:42 volumio volumio[1235]: info: ------------------------------ 388ms May 14 23:02:42 volumio volumio[1235]: info: sendMpdCommand status took 270 milliseconds May 14 23:02:42 volumio volumio[1235]: info: sendMpdCommand status took 270 milliseconds May 14 23:02:42 volumio volumio[1235]: info: sendMpdCommand status took 270 milliseconds May 14 23:02:42 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:42 volumio volumio[1235]: info: Command Router : Notfying DB Updatefalse May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::Close All Modals sent May 14 23:02:42 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:42 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:42 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: 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 14 23:02:42 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::syncState stateService stop May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus stop May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: No code May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: 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 14 23:02:42 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::syncState stateService stop May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus stop May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: No code May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: ------------------------------ 430ms May 14 23:02:42 volumio volumio[1235]: info: ------------------------------ 432ms May 14 23:02:42 volumio volumio[1235]: info: sendMpdCommand status took 446 milliseconds May 14 23:02:42 volumio volumio[1235]: info: sendMpdCommand status took 443 milliseconds May 14 23:02:42 volumio volumio[1235]: info: sendMpdCommand status took 443 milliseconds May 14 23:02:42 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:42 volumio volumio[1235]: info: Command Router : Notfying DB Updatefalse May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::Close All Modals sent May 14 23:02:42 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:42 volumio volumio[1235]: verbose: ControllerMpd::parseState May 14 23:02:42 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: 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 14 23:02:42 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::syncState stateService stop May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus stop May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: No code May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: ControllerMpd::pushState May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::servicePushState May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: 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 14 23:02:42 volumio volumio[1235]: verbose: CURRENT POSITION 0 May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::syncState stateService stop May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus stop May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: No code May 14 23:02:42 volumio volumio[1235]: info: CoreStateMachine::pushState May 14 23:02:42 volumio volumio[1235]: info: CorePlayQueue::getTrack 0 May 14 23:02:42 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState May 14 23:02:42 volumio volumio[1235]: info: ------------------------------ 620ms May 14 23:02:42 volumio volumio[1235]: info: ------------------------------ 622ms May 14 23:02:46 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 14 23:02:46 volumio volumio[1235]: info: CURURI: music-library/NAS May 14 23:02:46 volumio volumio[1235]: info: Preload queue cleared May 14 23:02:53 volumio volumio[1235]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 23:02:53 volumio volumio[1235]: Error: connect ETIMEDOUT 31.13.84.2:443 May 14 23:02:53 volumio volumio[1235]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 14 23:02:53 volumio volumio[1235]: errno: -110, May 14 23:02:53 volumio volumio[1235]: code: 'ETIMEDOUT', May 14 23:02:53 volumio volumio[1235]: syscall: 'connect', May 14 23:02:53 volumio volumio[1235]: address: '31.13.84.2', May 14 23:02:53 volumio volumio[1235]: port: 443 May 14 23:02:53 volumio volumio[1235]: } May 14 23:02:53 volumio volumio[1235]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 23:02:53 volumio sudo[3501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-14 23:01 May 14 23:02:53 volumio sudo[3501]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 12:21:23 PM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a9588e47e4b05f1a05bf70b4acc7555c"